0s autopkgtest [16:00:09]: starting date and time: 2024-03-25 16:00:09+0000 0s autopkgtest [16:00:09]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [16:00:09]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.pqai33mz/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:ubuntu-advantage-tools,src:apt --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=ubuntu-advantage-tools/31.2.2 apt/2.7.14' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-s390x-5.secgroup --name adt-noble-s390x-wsl-pro-service-20240325-160008-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-s390x-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/ 122s autopkgtest [16:02:11]: testbed dpkg architecture: s390x 122s autopkgtest [16:02:11]: testbed apt version: 2.7.12 122s autopkgtest [16:02:11]: @@@@@@@@@@@@@@@@@@@@ test bed setup 123s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 124s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [7608 B] 124s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [497 kB] 124s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3992 kB] 124s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.8 kB] 124s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [693 kB] 124s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 124s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 124s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 124s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4174 kB] 125s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 125s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [48.3 kB] 125s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 128s Fetched 9597 kB in 3s (3007 kB/s) 128s Reading package lists... 131s Reading package lists... 131s Building dependency tree... 131s Reading state information... 131s Calculating upgrade... 132s The following packages will be REMOVED: 132s libapt-pkg6.0 libdb5.3 libgnutls30 libhogweed6 libnettle8 132s The following NEW packages will be installed: 132s libapt-pkg6.0t64 libdb5.3t64 libgnutls30t64 libhogweed6t64 libnettle8t64 132s The following packages will be upgraded: 132s apt apt-utils ubuntu-pro-client ubuntu-pro-client-l10n 132s 4 upgraded, 5 newly installed, 5 to remove and 0 not upgraded. 132s Need to get 5073 kB of archives. 132s After this operation, 686 kB of additional disk space will be used. 132s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libnettle8t64 s390x 3.9.1-2.2 [210 kB] 132s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libhogweed6t64 s390x 3.9.1-2.2 [204 kB] 133s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libgnutls30t64 s390x 3.8.3-1.1ubuntu2 [1044 kB] 133s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main s390x ubuntu-pro-client-l10n s390x 31.2.2 [19.4 kB] 133s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main s390x ubuntu-pro-client s390x 31.2.2 [214 kB] 133s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libdb5.3t64 s390x 5.3.28+dfsg2-6 [763 kB] 133s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libapt-pkg6.0t64 s390x 2.7.14 [1014 kB] 133s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main s390x apt s390x 2.7.14 [1390 kB] 133s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/main s390x apt-utils s390x 2.7.14 [214 kB] 134s Fetched 5073 kB in 2s (3248 kB/s) 134s dpkg: libnettle8:s390x: dependency problems, but removing anyway as you requested: 134s librtmp1:s390x depends on libnettle8. 134s libhogweed6:s390x depends on libnettle8. 134s libgnutls30:s390x depends on libnettle8 (>= 3.9~). 134s libcurl3-gnutls:s390x depends on libnettle8. 134s libarchive13:s390x depends on libnettle8. 134s 134s (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 ... 52170 files and directories currently installed.) 134s Removing libnettle8:s390x (3.9.1-2) ... 134s Selecting previously unselected package libnettle8t64:s390x. 134s (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 ... 52163 files and directories currently installed.) 134s Preparing to unpack .../libnettle8t64_3.9.1-2.2_s390x.deb ... 134s Unpacking libnettle8t64:s390x (3.9.1-2.2) ... 134s Setting up libnettle8t64:s390x (3.9.1-2.2) ... 134s dpkg: libhogweed6:s390x: dependency problems, but removing anyway as you requested: 134s librtmp1:s390x depends on libhogweed6. 134s libjcat1:s390x depends on libhogweed6. 134s libgnutls30:s390x depends on libhogweed6 (>= 3.6). 134s 134s (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 ... 52171 files and directories currently installed.) 134s Removing libhogweed6:s390x (3.9.1-2) ... 134s Selecting previously unselected package libhogweed6t64:s390x. 134s (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 ... 52166 files and directories currently installed.) 134s Preparing to unpack .../libhogweed6t64_3.9.1-2.2_s390x.deb ... 134s Unpacking libhogweed6t64:s390x (3.9.1-2.2) ... 134s Setting up libhogweed6t64:s390x (3.9.1-2.2) ... 134s dpkg: libgnutls30:s390x: dependency problems, but removing anyway as you requested: 134s librtmp1:s390x depends on libgnutls30 (>= 3.7.2). 134s libldap2:s390x depends on libgnutls30 (>= 3.8.2). 134s libjcat1:s390x depends on libgnutls30 (>= 3.7.3). 134s libcurl3-gnutls:s390x depends on libgnutls30 (>= 3.8.2). 134s fwupd depends on libgnutls30 (>= 3.7.3). 134s dirmngr depends on libgnutls30 (>= 3.8.1). 134s apt depends on libgnutls30 (>= 3.8.1). 134s 134s (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 ... 52172 files and directories currently installed.) 134s Removing libgnutls30:s390x (3.8.3-1ubuntu1) ... 134s Selecting previously unselected package libgnutls30t64:s390x. 134s (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 ... 52163 files and directories currently installed.) 134s Preparing to unpack .../libgnutls30t64_3.8.3-1.1ubuntu2_s390x.deb ... 134s Unpacking libgnutls30t64:s390x (3.8.3-1.1ubuntu2) ... 134s Setting up libgnutls30t64:s390x (3.8.3-1.1ubuntu2) ... 135s (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 ... 52191 files and directories currently installed.) 135s Preparing to unpack .../ubuntu-pro-client-l10n_31.2.2_s390x.deb ... 135s Unpacking ubuntu-pro-client-l10n (31.2.2) over (31.1) ... 135s Preparing to unpack .../ubuntu-pro-client_31.2.2_s390x.deb ... 135s Unpacking ubuntu-pro-client (31.2.2) over (31.1) ... 135s dpkg: libdb5.3:s390x: dependency problems, but removing anyway as you requested: 135s libsasl2-modules-db:s390x depends on libdb5.3. 135s libpython3.12-stdlib:s390x depends on libdb5.3. 135s libpython3.11-stdlib:s390x depends on libdb5.3. 135s libperl5.38:s390x depends on libdb5.3. 135s libpam-modules:s390x depends on libdb5.3. 135s iproute2 depends on libdb5.3. 135s apt-utils depends on libdb5.3. 135s 135s (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 ... 52191 files and directories currently installed.) 135s Removing libdb5.3:s390x (5.3.28+dfsg2-4) ... 135s Selecting previously unselected package libdb5.3t64:s390x. 135s (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 ... 52185 files and directories currently installed.) 135s Preparing to unpack .../libdb5.3t64_5.3.28+dfsg2-6_s390x.deb ... 135s Unpacking libdb5.3t64:s390x (5.3.28+dfsg2-6) ... 135s Setting up libdb5.3t64:s390x (5.3.28+dfsg2-6) ... 135s dpkg: libapt-pkg6.0:s390x: dependency problems, but removing anyway as you requested: 135s python3-apt depends on libapt-pkg6.0 (>= 2.7.11). 135s apt-utils depends on libapt-pkg6.0 (>= 2.7.12). 135s apt depends on libapt-pkg6.0 (>= 2.7.12). 135s 135s (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 ... 52191 files and directories currently installed.) 135s Removing libapt-pkg6.0:s390x (2.7.12) ... 135s Selecting previously unselected package libapt-pkg6.0t64:s390x. 135s (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 ... 52142 files and directories currently installed.) 135s Preparing to unpack .../libapt-pkg6.0t64_2.7.14_s390x.deb ... 135s Unpacking libapt-pkg6.0t64:s390x (2.7.14) ... 135s Setting up libapt-pkg6.0t64:s390x (2.7.14) ... 135s (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 ... 52192 files and directories currently installed.) 135s Preparing to unpack .../archives/apt_2.7.14_s390x.deb ... 135s Unpacking apt (2.7.14) over (2.7.12) ... 135s Setting up apt (2.7.14) ... 136s (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 ... 52192 files and directories currently installed.) 136s Preparing to unpack .../apt-utils_2.7.14_s390x.deb ... 136s Unpacking apt-utils (2.7.14) over (2.7.12) ... 136s Setting up apt-utils (2.7.14) ... 136s Setting up ubuntu-pro-client (31.2.2) ... 138s Setting up ubuntu-pro-client-l10n (31.2.2) ... 138s Processing triggers for man-db (2.12.0-3) ... 139s Processing triggers for libc-bin (2.39-0ubuntu6) ... 140s Reading package lists... 141s Building dependency tree... 141s Reading state information... 141s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 142s Unknown architecture, assuming PC-style ttyS0 142s sh: Attempting to set up Debian/Ubuntu apt sources automatically 142s sh: Distribution appears to be Ubuntu 143s Reading package lists... 143s Building dependency tree... 143s Reading state information... 144s eatmydata is already the newest version (131-1). 144s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 144s Reading package lists... 144s Building dependency tree... 144s Reading state information... 144s dbus is already the newest version (1.14.10-4ubuntu1). 144s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 144s Reading package lists... 144s Building dependency tree... 144s Reading state information... 145s rng-tools-debian is already the newest version (2.4). 145s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 145s Reading package lists... 145s Building dependency tree... 145s Reading state information... 145s The following packages will be REMOVED: 145s cloud-init* python3-configobj* python3-debconf* 145s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 145s After this operation, 3256 kB disk space will be freed. 145s (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 ... 52192 files and directories currently installed.) 145s Removing cloud-init (24.1.2-0ubuntu1) ... 146s Removing python3-configobj (5.0.8-3) ... 146s Removing python3-debconf (1.5.86) ... 146s Processing triggers for man-db (2.12.0-3) ... 146s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 51803 files and directories currently installed.) 146s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 147s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 147s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 147s invoke-rc.d: policy-rc.d denied execution of try-restart. 147s Reading package lists... 147s Building dependency tree... 147s Reading state information... 148s linux-generic is already the newest version (6.8.0-11.11+1). 148s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 148s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 148s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 148s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 150s Reading package lists... 150s Reading package lists... 150s Building dependency tree... 150s Reading state information... 150s Calculating upgrade... 151s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 151s Reading package lists... 151s Building dependency tree... 151s Reading state information... 151s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 151s autopkgtest [16:02:40]: rebooting testbed after setup commands that affected boot 169s autopkgtest [16:02:58]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 172s autopkgtest [16:03:01]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 175s Get:1 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.2 (dsc) [1420 B] 175s Get:2 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.2 (tar) [1746 kB] 175s gpgv: Signature made Thu Feb 29 15:15:37 2024 UTC 175s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 175s gpgv: Can't check signature: No public key 175s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.2.dsc: no acceptable signature found 176s autopkgtest [16:03:05]: testing package wsl-pro-service version 0.1.2 176s autopkgtest [16:03:05]: build not needed 184s autopkgtest [16:03:13]: test command1: preparing testbed 189s Reading package lists... 189s Building dependency tree... 189s Reading state information... 189s Starting pkgProblemResolver with broken count: 0 189s Starting 2 pkgProblemResolver with broken count: 0 189s Done 190s The following additional packages will be installed: 190s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 190s cpp-13-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit dh-apport 190s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 190s g++-13-s390x-linux-gnu g++-s390x-linux-gnu gcc gcc-13 gcc-13-s390x-linux-gnu 190s gcc-s390x-linux-gnu gettext golang-1.22-go golang-1.22-src golang-go 190s golang-src intltool-debian libarchive-zip-perl libasan8 libatomic1 190s libc-dev-bin libc6-dev libcc1-0 libcrypt-dev libdebhelper-perl libdw1 190s libfile-stripnondeterminism-perl libgcc-13-dev libgomp1 libisl23 libitm1 190s libmpc3 libstdc++-13-dev libsub-override-perl libtool libubsan1 190s linux-libc-dev m4 po-debconf rpcsvc-proto 190s Suggested packages: 190s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 190s cpp-13-doc dh-make g++-multilib g++-13-multilib gcc-13-doc gcc-multilib 190s manpages-dev flex bison gdb gcc-doc gcc-13-multilib gdb-s390x-linux-gnu 190s gettext-doc libasprintf-dev libgettextpo-dev bzr | brz git mercurial 190s subversion glibc-doc libstdc++-13-doc libtool-doc gfortran 190s | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 190s Recommended packages: 190s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 190s libltdl-dev libmail-sendmail-perl 190s The following NEW packages will be installed: 190s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 190s cpp cpp-13 cpp-13-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit 190s dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 190s g++-13-s390x-linux-gnu g++-s390x-linux-gnu gcc gcc-13 gcc-13-s390x-linux-gnu 190s gcc-s390x-linux-gnu gettext golang-1.22-go golang-1.22-src golang-go 190s golang-src intltool-debian libarchive-zip-perl libasan8 libatomic1 190s libc-dev-bin libc6-dev libcc1-0 libcrypt-dev libdebhelper-perl libdw1 190s libfile-stripnondeterminism-perl libgcc-13-dev libgomp1 libisl23 libitm1 190s libmpc3 libstdc++-13-dev libsub-override-perl libtool libubsan1 190s linux-libc-dev m4 po-debconf rpcsvc-proto 190s 0 upgraded, 54 newly installed, 0 to remove and 0 not upgraded. 190s Need to get 102 MB/102 MB of archives. 190s After this operation, 427 MB of additional disk space will be used. 190s Get:1 /tmp/autopkgtest.bz8sfS/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [760 B] 190s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x m4 s390x 1.4.19-4 [255 kB] 190s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x autoconf all 2.71-3 [339 kB] 191s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x autotools-dev all 20220109.1 [44.9 kB] 191s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x automake all 1:1.16.5-1.3ubuntu1 [558 kB] 191s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x autopoint all 0.21-14ubuntu1 [422 kB] 191s Get:7 http://ftpmaster.internal/ubuntu noble/main s390x libc-dev-bin s390x 2.39-0ubuntu6 [20.2 kB] 191s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x linux-libc-dev s390x 6.8.0-11.11 [1590 kB] 192s Get:9 http://ftpmaster.internal/ubuntu noble/main s390x libcrypt-dev s390x 1:4.4.36-4 [135 kB] 192s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x rpcsvc-proto s390x 1.4.2-0ubuntu6 [64.7 kB] 192s Get:11 http://ftpmaster.internal/ubuntu noble/main s390x libc6-dev s390x 2.39-0ubuntu6 [1629 kB] 193s Get:12 http://ftpmaster.internal/ubuntu noble/main s390x libisl23 s390x 0.26-3 [722 kB] 193s Get:13 http://ftpmaster.internal/ubuntu noble/main s390x libmpc3 s390x 1.3.1-1 [54.9 kB] 193s Get:14 http://ftpmaster.internal/ubuntu noble/main s390x cpp-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [9929 kB] 194s Get:15 http://ftpmaster.internal/ubuntu noble/main s390x cpp-13 s390x 13.2.0-17ubuntu2 [1026 B] 194s Get:16 http://ftpmaster.internal/ubuntu noble/main s390x cpp-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [5308 B] 194s Get:17 http://ftpmaster.internal/ubuntu noble/main s390x cpp s390x 4:13.2.0-7ubuntu1 [22.4 kB] 194s Get:18 http://ftpmaster.internal/ubuntu noble/main s390x libcc1-0 s390x 14-20240303-1ubuntu1 [49.9 kB] 194s Get:19 http://ftpmaster.internal/ubuntu noble/main s390x libgomp1 s390x 14-20240303-1ubuntu1 [151 kB] 194s Get:20 http://ftpmaster.internal/ubuntu noble/main s390x libitm1 s390x 14-20240303-1ubuntu1 [31.1 kB] 194s Get:21 http://ftpmaster.internal/ubuntu noble/main s390x libatomic1 s390x 14-20240303-1ubuntu1 [9392 B] 195s Get:22 http://ftpmaster.internal/ubuntu noble/main s390x libasan8 s390x 14-20240303-1ubuntu1 [2998 kB] 195s Get:23 http://ftpmaster.internal/ubuntu noble/main s390x libubsan1 s390x 14-20240303-1ubuntu1 [1186 kB] 195s Get:24 http://ftpmaster.internal/ubuntu noble/main s390x libgcc-13-dev s390x 13.2.0-17ubuntu2 [1003 kB] 195s Get:25 http://ftpmaster.internal/ubuntu noble/main s390x gcc-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [19.1 MB] 199s Get:26 http://ftpmaster.internal/ubuntu noble/main s390x gcc-13 s390x 13.2.0-17ubuntu2 [467 kB] 199s Get:27 http://ftpmaster.internal/ubuntu noble/main s390x gcc-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [1208 B] 199s Get:28 http://ftpmaster.internal/ubuntu noble/main s390x gcc s390x 4:13.2.0-7ubuntu1 [5014 B] 199s Get:29 http://ftpmaster.internal/ubuntu noble/main s390x libstdc++-13-dev s390x 13.2.0-17ubuntu2 [2430 kB] 200s Get:30 http://ftpmaster.internal/ubuntu noble/main s390x g++-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [11.3 MB] 202s Get:31 http://ftpmaster.internal/ubuntu noble/main s390x g++-13 s390x 13.2.0-17ubuntu2 [14.4 kB] 202s Get:32 http://ftpmaster.internal/ubuntu noble/main s390x g++-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [956 B] 202s Get:33 http://ftpmaster.internal/ubuntu noble/main s390x g++ s390x 4:13.2.0-7ubuntu1 [1096 B] 202s Get:34 http://ftpmaster.internal/ubuntu noble/main s390x build-essential s390x 12.10ubuntu1 [4930 B] 202s Get:35 http://ftpmaster.internal/ubuntu noble/main s390x libdebhelper-perl all 13.14.1ubuntu1 [89.5 kB] 202s Get:36 http://ftpmaster.internal/ubuntu noble/main s390x libtool all 2.4.7-7 [166 kB] 202s Get:37 http://ftpmaster.internal/ubuntu noble/main s390x dh-autoreconf all 20 [16.1 kB] 202s Get:38 http://ftpmaster.internal/ubuntu noble/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 202s Get:39 http://ftpmaster.internal/ubuntu noble/main s390x libsub-override-perl all 0.10-1 [10.0 kB] 202s Get:40 http://ftpmaster.internal/ubuntu noble/main s390x libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 202s Get:41 http://ftpmaster.internal/ubuntu noble/main s390x dh-strip-nondeterminism all 1.13.1-1 [5362 B] 202s Get:42 http://ftpmaster.internal/ubuntu noble/main s390x libdw1 s390x 0.190-1 [282 kB] 202s Get:43 http://ftpmaster.internal/ubuntu noble/main s390x debugedit s390x 1:5.0-5 [47.5 kB] 202s Get:44 http://ftpmaster.internal/ubuntu noble/main s390x dwz s390x 0.15-1 [108 kB] 203s Get:45 http://ftpmaster.internal/ubuntu noble/main s390x gettext s390x 0.21-14ubuntu1 [917 kB] 203s Get:46 http://ftpmaster.internal/ubuntu noble/main s390x intltool-debian all 0.35.0+20060710.6 [23.2 kB] 203s Get:47 http://ftpmaster.internal/ubuntu noble/main s390x po-debconf all 1.0.21+nmu1 [233 kB] 203s Get:48 http://ftpmaster.internal/ubuntu noble/main s390x debhelper all 13.14.1ubuntu1 [869 kB] 203s Get:49 http://ftpmaster.internal/ubuntu noble/universe s390x dh-apport all 2.28.0-0ubuntu1 [16.9 kB] 203s Get:50 http://ftpmaster.internal/ubuntu noble/main s390x golang-1.22-src all 1.22.1-1 [19.7 MB] 208s Get:51 http://ftpmaster.internal/ubuntu noble/main s390x golang-1.22-go s390x 1.22.1-1 [24.7 MB] 216s Get:52 http://ftpmaster.internal/ubuntu noble/main s390x golang-src all 2:1.22~2 [4992 B] 216s Get:53 http://ftpmaster.internal/ubuntu noble/main s390x golang-go s390x 2:1.22~2 [43.9 kB] 216s Get:54 http://ftpmaster.internal/ubuntu noble/main s390x dh-golang all 1.62 [25.2 kB] 217s Fetched 102 MB in 27s (3833 kB/s) 217s Selecting previously unselected package m4. 217s (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 ... 51748 files and directories currently installed.) 217s Preparing to unpack .../00-m4_1.4.19-4_s390x.deb ... 217s Unpacking m4 (1.4.19-4) ... 217s Selecting previously unselected package autoconf. 217s Preparing to unpack .../01-autoconf_2.71-3_all.deb ... 217s Unpacking autoconf (2.71-3) ... 217s Selecting previously unselected package autotools-dev. 217s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 217s Unpacking autotools-dev (20220109.1) ... 217s Selecting previously unselected package automake. 217s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 217s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 217s Selecting previously unselected package autopoint. 217s Preparing to unpack .../04-autopoint_0.21-14ubuntu1_all.deb ... 217s Unpacking autopoint (0.21-14ubuntu1) ... 217s Selecting previously unselected package libc-dev-bin. 217s Preparing to unpack .../05-libc-dev-bin_2.39-0ubuntu6_s390x.deb ... 217s Unpacking libc-dev-bin (2.39-0ubuntu6) ... 217s Selecting previously unselected package linux-libc-dev:s390x. 217s Preparing to unpack .../06-linux-libc-dev_6.8.0-11.11_s390x.deb ... 217s Unpacking linux-libc-dev:s390x (6.8.0-11.11) ... 218s Selecting previously unselected package libcrypt-dev:s390x. 218s Preparing to unpack .../07-libcrypt-dev_1%3a4.4.36-4_s390x.deb ... 218s Unpacking libcrypt-dev:s390x (1:4.4.36-4) ... 218s Selecting previously unselected package rpcsvc-proto. 218s Preparing to unpack .../08-rpcsvc-proto_1.4.2-0ubuntu6_s390x.deb ... 218s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 218s Selecting previously unselected package libc6-dev:s390x. 218s Preparing to unpack .../09-libc6-dev_2.39-0ubuntu6_s390x.deb ... 218s Unpacking libc6-dev:s390x (2.39-0ubuntu6) ... 218s Selecting previously unselected package libisl23:s390x. 218s Preparing to unpack .../10-libisl23_0.26-3_s390x.deb ... 218s Unpacking libisl23:s390x (0.26-3) ... 218s Selecting previously unselected package libmpc3:s390x. 218s Preparing to unpack .../11-libmpc3_1.3.1-1_s390x.deb ... 218s Unpacking libmpc3:s390x (1.3.1-1) ... 218s Selecting previously unselected package cpp-13-s390x-linux-gnu. 218s Preparing to unpack .../12-cpp-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 218s Unpacking cpp-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 218s Selecting previously unselected package cpp-13. 218s Preparing to unpack .../13-cpp-13_13.2.0-17ubuntu2_s390x.deb ... 218s Unpacking cpp-13 (13.2.0-17ubuntu2) ... 218s Selecting previously unselected package cpp-s390x-linux-gnu. 218s Preparing to unpack .../14-cpp-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 218s Unpacking cpp-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 218s Selecting previously unselected package cpp. 218s Preparing to unpack .../15-cpp_4%3a13.2.0-7ubuntu1_s390x.deb ... 218s Unpacking cpp (4:13.2.0-7ubuntu1) ... 218s Selecting previously unselected package libcc1-0:s390x. 218s Preparing to unpack .../16-libcc1-0_14-20240303-1ubuntu1_s390x.deb ... 218s Unpacking libcc1-0:s390x (14-20240303-1ubuntu1) ... 218s Selecting previously unselected package libgomp1:s390x. 218s Preparing to unpack .../17-libgomp1_14-20240303-1ubuntu1_s390x.deb ... 218s Unpacking libgomp1:s390x (14-20240303-1ubuntu1) ... 218s Selecting previously unselected package libitm1:s390x. 218s Preparing to unpack .../18-libitm1_14-20240303-1ubuntu1_s390x.deb ... 218s Unpacking libitm1:s390x (14-20240303-1ubuntu1) ... 218s Selecting previously unselected package libatomic1:s390x. 218s Preparing to unpack .../19-libatomic1_14-20240303-1ubuntu1_s390x.deb ... 218s Unpacking libatomic1:s390x (14-20240303-1ubuntu1) ... 218s Selecting previously unselected package libasan8:s390x. 218s Preparing to unpack .../20-libasan8_14-20240303-1ubuntu1_s390x.deb ... 218s Unpacking libasan8:s390x (14-20240303-1ubuntu1) ... 219s Selecting previously unselected package libubsan1:s390x. 219s Preparing to unpack .../21-libubsan1_14-20240303-1ubuntu1_s390x.deb ... 219s Unpacking libubsan1:s390x (14-20240303-1ubuntu1) ... 219s Selecting previously unselected package libgcc-13-dev:s390x. 219s Preparing to unpack .../22-libgcc-13-dev_13.2.0-17ubuntu2_s390x.deb ... 219s Unpacking libgcc-13-dev:s390x (13.2.0-17ubuntu2) ... 219s Selecting previously unselected package gcc-13-s390x-linux-gnu. 219s Preparing to unpack .../23-gcc-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 219s Unpacking gcc-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 219s Selecting previously unselected package gcc-13. 219s Preparing to unpack .../24-gcc-13_13.2.0-17ubuntu2_s390x.deb ... 219s Unpacking gcc-13 (13.2.0-17ubuntu2) ... 219s Selecting previously unselected package gcc-s390x-linux-gnu. 219s Preparing to unpack .../25-gcc-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 219s Unpacking gcc-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 219s Selecting previously unselected package gcc. 219s Preparing to unpack .../26-gcc_4%3a13.2.0-7ubuntu1_s390x.deb ... 219s Unpacking gcc (4:13.2.0-7ubuntu1) ... 219s Selecting previously unselected package libstdc++-13-dev:s390x. 219s Preparing to unpack .../27-libstdc++-13-dev_13.2.0-17ubuntu2_s390x.deb ... 219s Unpacking libstdc++-13-dev:s390x (13.2.0-17ubuntu2) ... 220s Selecting previously unselected package g++-13-s390x-linux-gnu. 220s Preparing to unpack .../28-g++-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 220s Unpacking g++-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 220s Selecting previously unselected package g++-13. 220s Preparing to unpack .../29-g++-13_13.2.0-17ubuntu2_s390x.deb ... 220s Unpacking g++-13 (13.2.0-17ubuntu2) ... 220s Selecting previously unselected package g++-s390x-linux-gnu. 220s Preparing to unpack .../30-g++-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 220s Unpacking g++-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 220s Selecting previously unselected package g++. 220s Preparing to unpack .../31-g++_4%3a13.2.0-7ubuntu1_s390x.deb ... 220s Unpacking g++ (4:13.2.0-7ubuntu1) ... 220s Selecting previously unselected package build-essential. 220s Preparing to unpack .../32-build-essential_12.10ubuntu1_s390x.deb ... 220s Unpacking build-essential (12.10ubuntu1) ... 220s Selecting previously unselected package libdebhelper-perl. 220s Preparing to unpack .../33-libdebhelper-perl_13.14.1ubuntu1_all.deb ... 220s Unpacking libdebhelper-perl (13.14.1ubuntu1) ... 220s Selecting previously unselected package libtool. 220s Preparing to unpack .../34-libtool_2.4.7-7_all.deb ... 220s Unpacking libtool (2.4.7-7) ... 220s Selecting previously unselected package dh-autoreconf. 220s Preparing to unpack .../35-dh-autoreconf_20_all.deb ... 220s Unpacking dh-autoreconf (20) ... 220s Selecting previously unselected package libarchive-zip-perl. 220s Preparing to unpack .../36-libarchive-zip-perl_1.68-1_all.deb ... 220s Unpacking libarchive-zip-perl (1.68-1) ... 220s Selecting previously unselected package libsub-override-perl. 220s Preparing to unpack .../37-libsub-override-perl_0.10-1_all.deb ... 220s Unpacking libsub-override-perl (0.10-1) ... 220s Selecting previously unselected package libfile-stripnondeterminism-perl. 220s Preparing to unpack .../38-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 220s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 220s Selecting previously unselected package dh-strip-nondeterminism. 220s Preparing to unpack .../39-dh-strip-nondeterminism_1.13.1-1_all.deb ... 220s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 220s Selecting previously unselected package libdw1:s390x. 220s Preparing to unpack .../40-libdw1_0.190-1_s390x.deb ... 220s Unpacking libdw1:s390x (0.190-1) ... 220s Selecting previously unselected package debugedit. 220s Preparing to unpack .../41-debugedit_1%3a5.0-5_s390x.deb ... 220s Unpacking debugedit (1:5.0-5) ... 220s Selecting previously unselected package dwz. 220s Preparing to unpack .../42-dwz_0.15-1_s390x.deb ... 220s Unpacking dwz (0.15-1) ... 220s Selecting previously unselected package gettext. 221s Preparing to unpack .../43-gettext_0.21-14ubuntu1_s390x.deb ... 221s Unpacking gettext (0.21-14ubuntu1) ... 221s Selecting previously unselected package intltool-debian. 221s Preparing to unpack .../44-intltool-debian_0.35.0+20060710.6_all.deb ... 221s Unpacking intltool-debian (0.35.0+20060710.6) ... 221s Selecting previously unselected package po-debconf. 221s Preparing to unpack .../45-po-debconf_1.0.21+nmu1_all.deb ... 221s Unpacking po-debconf (1.0.21+nmu1) ... 221s Selecting previously unselected package debhelper. 221s Preparing to unpack .../46-debhelper_13.14.1ubuntu1_all.deb ... 221s Unpacking debhelper (13.14.1ubuntu1) ... 221s Selecting previously unselected package dh-apport. 221s Preparing to unpack .../47-dh-apport_2.28.0-0ubuntu1_all.deb ... 221s Unpacking dh-apport (2.28.0-0ubuntu1) ... 221s Selecting previously unselected package golang-1.22-src. 221s Preparing to unpack .../48-golang-1.22-src_1.22.1-1_all.deb ... 221s Unpacking golang-1.22-src (1.22.1-1) ... 224s Selecting previously unselected package golang-1.22-go. 225s Preparing to unpack .../49-golang-1.22-go_1.22.1-1_s390x.deb ... 225s Unpacking golang-1.22-go (1.22.1-1) ... 226s Selecting previously unselected package golang-src. 226s Preparing to unpack .../50-golang-src_2%3a1.22~2_all.deb ... 226s Unpacking golang-src (2:1.22~2) ... 226s Selecting previously unselected package golang-go:s390x. 226s Preparing to unpack .../51-golang-go_2%3a1.22~2_s390x.deb ... 226s Unpacking golang-go:s390x (2:1.22~2) ... 226s Selecting previously unselected package dh-golang. 226s Preparing to unpack .../52-dh-golang_1.62_all.deb ... 226s Unpacking dh-golang (1.62) ... 226s Selecting previously unselected package autopkgtest-satdep. 226s Preparing to unpack .../53-1-autopkgtest-satdep.deb ... 226s Unpacking autopkgtest-satdep (0) ... 226s Setting up libdw1:s390x (0.190-1) ... 226s Setting up libarchive-zip-perl (1.68-1) ... 226s Setting up libdebhelper-perl (13.14.1ubuntu1) ... 226s Setting up linux-libc-dev:s390x (6.8.0-11.11) ... 226s Setting up m4 (1.4.19-4) ... 226s Setting up libgomp1:s390x (14-20240303-1ubuntu1) ... 226s Setting up dh-apport (2.28.0-0ubuntu1) ... 226s Setting up autotools-dev (20220109.1) ... 226s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 226s Setting up golang-1.22-src (1.22.1-1) ... 226s Setting up libmpc3:s390x (1.3.1-1) ... 226s Setting up libatomic1:s390x (14-20240303-1ubuntu1) ... 226s Setting up autopoint (0.21-14ubuntu1) ... 226s Setting up autoconf (2.71-3) ... 226s Setting up libubsan1:s390x (14-20240303-1ubuntu1) ... 226s Setting up dwz (0.15-1) ... 226s Setting up libcrypt-dev:s390x (1:4.4.36-4) ... 226s Setting up libasan8:s390x (14-20240303-1ubuntu1) ... 226s Setting up debugedit (1:5.0-5) ... 226s Setting up libsub-override-perl (0.10-1) ... 226s Setting up libisl23:s390x (0.26-3) ... 226s Setting up libc-dev-bin (2.39-0ubuntu6) ... 226s Setting up golang-src (2:1.22~2) ... 226s Setting up libcc1-0:s390x (14-20240303-1ubuntu1) ... 226s Setting up libitm1:s390x (14-20240303-1ubuntu1) ... 226s Setting up automake (1:1.16.5-1.3ubuntu1) ... 226s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 226s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 226s Setting up gettext (0.21-14ubuntu1) ... 226s Setting up golang-1.22-go (1.22.1-1) ... 226s Setting up intltool-debian (0.35.0+20060710.6) ... 226s Setting up cpp-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 226s Setting up dh-strip-nondeterminism (1.13.1-1) ... 226s Setting up libgcc-13-dev:s390x (13.2.0-17ubuntu2) ... 226s Setting up libc6-dev:s390x (2.39-0ubuntu6) ... 226s Setting up libstdc++-13-dev:s390x (13.2.0-17ubuntu2) ... 226s Setting up cpp-13 (13.2.0-17ubuntu2) ... 226s Setting up cpp-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 226s Setting up golang-go:s390x (2:1.22~2) ... 226s Setting up po-debconf (1.0.21+nmu1) ... 226s Setting up gcc-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 226s Setting up gcc-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 226s Setting up g++-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 226s Setting up gcc-13 (13.2.0-17ubuntu2) ... 226s Setting up cpp (4:13.2.0-7ubuntu1) ... 226s Setting up g++-13 (13.2.0-17ubuntu2) ... 226s Setting up libtool (2.4.7-7) ... 226s Setting up g++-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 226s Setting up gcc (4:13.2.0-7ubuntu1) ... 226s Setting up dh-autoreconf (20) ... 226s Setting up g++ (4:13.2.0-7ubuntu1) ... 226s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 226s Setting up build-essential (12.10ubuntu1) ... 226s Setting up debhelper (13.14.1ubuntu1) ... 226s Setting up dh-golang (1.62) ... 226s Setting up autopkgtest-satdep (0) ... 226s Processing triggers for man-db (2.12.0-3) ... 228s Processing triggers for install-info (7.1-3) ... 228s Processing triggers for libc-bin (2.39-0ubuntu6) ... 231s (Reading database ... 69907 files and directories currently installed.) 231s Removing autopkgtest-satdep (0) ... 231s autopkgtest [16:04:00]: test command1: ./debian/tests/test 231s autopkgtest [16:04:00]: test command1: [----------------------- 336s === RUN TestRunSignal 336s === RUN TestRunSignal/Send_SIGINT_exits 336s INFO Starting WSL Pro Service version Dev 337s === RUN TestRunSignal/Send_SIGTERM_exits 337s INFO Starting WSL Pro Service version Dev 337s --- PASS: TestRunSignal (0.20s) 337s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 337s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 337s === RUN TestRun 337s === PAUSE TestRun 337s === CONT TestRun 337s === RUN TestRun/Run_and_exit_successfully 337s === PAUSE TestRun/Run_and_exit_successfully 337s === RUN TestRun/Run_and_return_error 337s === PAUSE TestRun/Run_and_return_error 337s === RUN TestRun/Run_and_return_usage_error 337s === PAUSE TestRun/Run_and_return_usage_error 337s === RUN TestRun/Run_and_usage_error_only_does_not_fail 337s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 337s === CONT TestRun/Run_and_exit_successfully 337s INFO Starting WSL Pro Service version Dev 337s === CONT TestRun/Run_and_usage_error_only_does_not_fail 337s INFO Starting WSL Pro Service version Dev 337s === CONT TestRun/Run_and_return_usage_error 337s INFO Starting WSL Pro Service version Dev 337s ERROR context.Background Error requested 337s === CONT TestRun/Run_and_return_error 337s INFO Starting WSL Pro Service version Dev 337s ERROR context.Background Error requested 337s --- PASS: TestRun (0.00s) 337s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 337s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 337s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 337s --- PASS: TestRun/Run_and_return_error (0.10s) 337s PASS 337s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.614s 339s === RUN TestHelp 339s --- PASS: TestHelp (0.00s) 339s === RUN TestCompletion 339s --- PASS: TestCompletion (0.00s) 339s === RUN TestVersion 339s --- PASS: TestVersion (0.00s) 339s === RUN TestNoUsageError 339s --- PASS: TestNoUsageError (0.00s) 339s === RUN TestUsageError 339s === PAUSE TestUsageError 339s === RUN TestCanQuitWhenExecute 339s === PAUSE TestCanQuitWhenExecute 339s === RUN TestCanQuitTwice 339s === PAUSE TestCanQuitTwice 339s === RUN TestAppCanQuitWithoutExecute 339s === PAUSE TestAppCanQuitWithoutExecute 339s === RUN TestAppRunFailsOnComponentsCreationAndQuit 339s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 339s === RUN TestAppGetRootCmd 339s === PAUSE TestAppGetRootCmd 339s === RUN TestWithProMock 339s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 339s --- SKIP: TestWithProMock (0.00s) 339s === RUN TestWithWslPathMock 339s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 339s --- SKIP: TestWithWslPathMock (0.00s) 339s === RUN TestWithWslInfoMock 339s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 339s --- SKIP: TestWithWslInfoMock (0.00s) 339s === RUN TestWithCmdExeMock 339s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 339s --- SKIP: TestWithCmdExeMock (0.00s) 339s === CONT TestUsageError 339s --- PASS: TestUsageError (0.00s) 339s === CONT TestAppGetRootCmd 339s --- PASS: TestAppGetRootCmd (0.00s) 339s === CONT TestAppRunFailsOnComponentsCreationAndQuit 345s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (6.10s) 345s === CONT TestAppCanQuitWithoutExecute 345s service_test.go:137: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 345s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 345s === CONT TestCanQuitTwice 345s time="2024-03-25T16:05:54Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 345s time="2024-03-25T16:05:54Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:63 New() Building new daemon" 345s time="2024-03-25T16:05:54Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/testutils/mock_agent.go:80 MockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42309\"" 348s time="2024-03-25T16:05:57Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 348s time="2024-03-25T16:05:57Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 350s time="2024-03-25T16:05:59Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/controlstream/session.go:25 newSession() Connecting to control stream at \"127.0.0.1:42309\"" 350s time="2024-03-25T16:05:59Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/controlstream/controlstream.go:75 (*ControlStream).Connect() Control stream: starting handshake" 350s time="2024-03-25T16:05:59Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/testutils/mock_agent.go:134 (*wslInstanceMockService).Connected() wslInstanceMockService: Received incoming connection" 352s time="2024-03-25T16:06:01Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/testutils/mock_agent.go:148 (*wslInstanceMockService).Connected() wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 352s time="2024-03-25T16:06:01Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/testutils/mock_agent.go:176 (*wslInstanceMockService).Connected() wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 37223" 352s time="2024-03-25T16:06:01Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/controlstream/controlstream.go:82 (*ControlStream).Connect() Control stream: completed handshake" 352s time="2024-03-25T16:06:01Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:176 (*Daemon).serveOnce() Connected to control stream" 352s time="2024-03-25T16:06:01Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/wslinstanceservice/wslinstanceservice.go:44 (*Service).RegisterGRPCService() Registering gRPC WSL instance service" 352s time="2024-03-25T16:06:01Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:223 (*Daemon).serve() Starting to serve gRPC requests" 352s time="2024-03-25T16:06:01Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:233 (*Daemon).serve() Serving gRPC requests on localhost:37223" 352s time="2024-03-25T16:06:01Z" level=error msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:142 (*Daemon).Serve() Serve error: WSL Pro Service stopped serving: grpc error: grpc: the server has been stopped" 352s time="2024-03-25T16:06:01Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 352s time="2024-03-25T16:06:01Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 352s time="2024-03-25T16:06:01Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 352s time="2024-03-25T16:06:01Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 352s time="2024-03-25T16:06:01Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 352s time="2024-03-25T16:06:01Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 352s time="2024-03-25T16:06:01Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 352s --- PASS: TestCanQuitTwice (7.58s) 352s === CONT TestCanQuitWhenExecute 352s time="2024-03-25T16:06:01Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 352s time="2024-03-25T16:06:01Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:63 New() Building new daemon" 352s time="2024-03-25T16:06:01Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/testutils/mock_agent.go:89 MockWindowsAgent.func1() MockWindowsAgent: Remove address file returned an error: remove /tmp/TestCanQuitTwice3032546320/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 352s time="2024-03-25T16:06:01Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/testutils/mock_agent.go:80 MockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36955\"" 356s time="2024-03-25T16:06:05Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 356s time="2024-03-25T16:06:05Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 357s time="2024-03-25T16:06:06Z" level=error msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:142 (*Daemon).Serve() Serve error: could not connect to Windows Agent via the control stream: could not get address: could not read agent port file \"/tmp/TestCanQuitWhenExecute2773536085/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestCanQuitWhenExecute2773536085/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 357s time="2024-03-25T16:06:06Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 357s time="2024-03-25T16:06:06Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 357s time="2024-03-25T16:06:06Z" level=info msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 357s time="2024-03-25T16:06:06Z" level=debug msg="/tmp/autopkgtest.bz8sfS/build.BgL/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 357s --- PASS: TestCanQuitWhenExecute (4.68s) 357s PASS 357s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 18.385s 357s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 357s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 358s === RUN TestNew 358s === PAUSE TestNew 358s === RUN TestConnect 358s === PAUSE TestConnect 358s === RUN TestSend 358s === PAUSE TestSend 358s === RUN TestReconnection 358s === PAUSE TestReconnection 358s === RUN TestWithProMock 358s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 358s --- SKIP: TestWithProMock (0.00s) 358s === RUN TestWithWslPathMock 358s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 358s --- SKIP: TestWithWslPathMock (0.00s) 358s === RUN TestWithWslInfoMock 358s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 358s --- SKIP: TestWithWslInfoMock (0.00s) 358s === RUN TestWithCmdExeMock 358s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 358s --- SKIP: TestWithCmdExeMock (0.00s) 358s === CONT TestNew 358s === RUN TestNew/Success 358s === PAUSE TestNew/Success 358s === RUN TestNew/Error_when_the_context_is_cancelled 358s === PAUSE TestNew/Error_when_the_context_is_cancelled 358s === RUN TestNew/Error_when_WslPath_returns_error 358s === PAUSE TestNew/Error_when_WslPath_returns_error 358s === CONT TestNew/Success 361s === CONT TestReconnection 361s === RUN TestReconnection/Success_connecting_after_failing_to_connect 361s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 361s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 361s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 361s === CONT TestSend 361s time="2024-03-25T16:06:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:37209\"" 365s time="2024-03-25T16:06:14Z" level=info msg="Connecting to control stream at \"127.0.0.1:37209\"" 365s time="2024-03-25T16:06:14Z" level=debug msg="Control stream: starting handshake" 365s time="2024-03-25T16:06:14Z" level=info msg="wslInstanceMockService: Received incoming connection" 366s time="2024-03-25T16:06:15Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 366s time="2024-03-25T16:06:15Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 37229" 366s time="2024-03-25T16:06:15Z" level=debug msg="Control stream: completed handshake" 371s time="2024-03-25T16:06:20Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 371s time="2024-03-25T16:06:20Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 372s time="2024-03-25T16:06:21Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 372s --- PASS: TestSend (11.50s) 372s === CONT TestConnect 372s === RUN TestConnect/Success 372s === PAUSE TestConnect/Success 372s === RUN TestConnect/No_connection_because_port_file_does_not_exist 372s === PAUSE TestConnect/No_connection_because_port_file_does_not_exist 372s === RUN TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 372s === PAUSE TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 372s === RUN TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 372s === PAUSE TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 372s === RUN TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 372s === PAUSE TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 372s === RUN TestConnect/No_connection_because_of_unreadable_port_file 372s === PAUSE TestConnect/No_connection_because_of_unreadable_port_file 372s === RUN TestConnect/No_connection_because_of_empty_port_file 372s === PAUSE TestConnect/No_connection_because_of_empty_port_file 372s === RUN TestConnect/No_connection_because_of_port_file_with_invalid_contents 372s === PAUSE TestConnect/No_connection_because_of_port_file_with_invalid_contents 372s === RUN TestConnect/Incomplete_handshake_because_Agent_never_receives 372s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_receives 372s === RUN TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 372s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 372s === RUN TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 372s === PAUSE TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 372s === CONT TestNew/Error_when_WslPath_returns_error 372s time="2024-03-25T16:06:21Z" level=info msg="wslInstanceMockService: dropped connection" 372s time="2024-03-25T16:06:21Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestSend3963117304/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 375s === CONT TestNew/Error_when_the_context_is_cancelled 375s --- PASS: TestNew (0.00s) 375s --- PASS: TestNew/Success (2.54s) 375s --- PASS: TestNew/Error_when_WslPath_returns_error (2.63s) 375s --- PASS: TestNew/Error_when_the_context_is_cancelled (0.00s) 375s === CONT TestReconnection/Success_connecting_after_failing_to_connect 378s time="2024-03-25T16:06:27Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34591\"" 380s time="2024-03-25T16:06:29Z" level=info msg="Connecting to control stream at \"127.0.0.1:34591\"" 380s time="2024-03-25T16:06:29Z" level=debug msg="Control stream: starting handshake" 380s time="2024-03-25T16:06:29Z" level=info msg="wslInstanceMockService: Received incoming connection" 381s time="2024-03-25T16:06:30Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 381s time="2024-03-25T16:06:30Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 37615" 381s time="2024-03-25T16:06:30Z" level=debug msg="Control stream: completed handshake" 381s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 384s time="2024-03-25T16:06:33Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45245\"" 386s time="2024-03-25T16:06:35Z" level=info msg="Connecting to control stream at \"127.0.0.1:45245\"" 386s time="2024-03-25T16:06:35Z" level=debug msg="Control stream: starting handshake" 386s time="2024-03-25T16:06:35Z" level=info msg="wslInstanceMockService: Received incoming connection" 387s time="2024-03-25T16:06:36Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 387s time="2024-03-25T16:06:36Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 39059" 387s time="2024-03-25T16:06:36Z" level=debug msg="Control stream: completed handshake" 387s time="2024-03-25T16:06:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34335\"" 388s time="2024-03-25T16:06:37Z" level=info msg="Connecting to control stream at \"127.0.0.1:34335\"" 388s time="2024-03-25T16:06:37Z" level=debug msg="Control stream: starting handshake" 388s time="2024-03-25T16:06:37Z" level=info msg="wslInstanceMockService: Received incoming connection" 390s time="2024-03-25T16:06:39Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 390s time="2024-03-25T16:06:39Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 36215" 390s time="2024-03-25T16:06:39Z" level=debug msg="Control stream: completed handshake" 390s --- PASS: TestReconnection (0.00s) 390s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (6.59s) 390s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (8.39s) 390s === CONT TestConnect/Success 390s time="2024-03-25T16:06:39Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:35491\"" 394s time="2024-03-25T16:06:43Z" level=info msg="Connecting to control stream at \"127.0.0.1:35491\"" 394s time="2024-03-25T16:06:43Z" level=debug msg="Control stream: starting handshake" 394s time="2024-03-25T16:06:43Z" level=info msg="wslInstanceMockService: Received incoming connection" 395s time="2024-03-25T16:06:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 395s time="2024-03-25T16:06:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 39081" 395s time="2024-03-25T16:06:44Z" level=debug msg="Control stream: completed handshake" 396s time="2024-03-25T16:06:45Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectSuccess4105200628/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 396s === CONT TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 396s time="2024-03-25T16:06:45Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42389\"" 400s time="2024-03-25T16:06:49Z" level=warning msg="Controlstream: assigning arbitrary connection ID because of error: could not obtain WSL distro name: could not get distro root path: exit status 1. Stdout: " 400s time="2024-03-25T16:06:49Z" level=info msg="Connecting to control stream at \"127.0.0.1:42389\"" 400s time="2024-03-25T16:06:49Z" level=debug msg="Control stream: starting handshake" 400s time="2024-03-25T16:06:49Z" level=info msg="wslInstanceMockService: Received incoming connection" 401s time="2024-03-25T16:06:50Z" level=warning msg="wslInstanceMockService: dropped connection: new connection: did not receive info from WSL distro: rpc error: code = Canceled desc = context canceled" 401s === CONT TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 401s time="2024-03-25T16:06:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:37815\"" 401s time="2024-03-25T16:06:50Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectError_when_system_cannot_retrieve_the_WSL_distro_name494399764/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 405s time="2024-03-25T16:06:54Z" level=info msg="Connecting to control stream at \"127.0.0.1:37815\"" 405s time="2024-03-25T16:06:54Z" level=debug msg="Control stream: starting handshake" 405s time="2024-03-25T16:06:54Z" level=info msg="wslInstanceMockService: Received incoming connection" 406s time="2024-03-25T16:06:55Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 406s time="2024-03-25T16:06:55Z" level=info msg="connection with \"TEST_DISTRO\": mock error: dropping stream before sending port" 406s time="2024-03-25T16:06:55Z" level=info msg="wslInstanceMockService: dropped connection" 406s === CONT TestConnect/Incomplete_handshake_because_Agent_never_receives 406s time="2024-03-25T16:06:55Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectIncomplete_handshake_because_Agent_never_sends_a_port3715629517/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 406s time="2024-03-25T16:06:55Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:35969\"" 406s time="2024-03-25T16:06:55Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:37615: connect: connection refused" 410s time="2024-03-25T16:06:58Z" level=info msg="Connecting to control stream at \"127.0.0.1:35969\"" 410s time="2024-03-25T16:06:58Z" level=debug msg="Control stream: starting handshake" 410s time="2024-03-25T16:06:58Z" level=info msg="wslInstanceMockService: Received incoming connection" 410s time="2024-03-25T16:06:58Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 410s time="2024-03-25T16:06:58Z" level=info msg="wslInstanceMockService: dropped connection" 411s === CONT TestConnect/No_connection_because_of_port_file_with_invalid_contents 411s time="2024-03-25T16:07:00Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectIncomplete_handshake_because_Agent_never_receives3625026617/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 411s time="2024-03-25T16:07:00Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45091\"" 412s time="2024-03-25T16:07:01Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:39059: connect: connection refused" 414s === CONT TestConnect/No_connection_because_of_empty_port_file 414s time="2024-03-25T16:07:03Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_port_file_with_invalid_contents1730675051/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 414s time="2024-03-25T16:07:03Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:37221\"" 415s time="2024-03-25T16:07:04Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:36215: connect: connection refused" 418s === CONT TestConnect/No_connection_because_of_unreadable_port_file 418s time="2024-03-25T16:07:07Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_empty_port_file1428399405/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 418s time="2024-03-25T16:07:07Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36591\"" 420s time="2024-03-25T16:07:09Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:39081: connect: connection refused" 422s === CONT TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 422s time="2024-03-25T16:07:11Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_unreadable_port_file961368152/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 422s time="2024-03-25T16:07:11Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33493\"" 426s time="2024-03-25T16:07:15Z" level=info msg="Connecting to control stream at \"127.0.0.1:33493\"" 426s time="2024-03-25T16:07:15Z" level=debug msg="Control stream: starting handshake" 426s time="2024-03-25T16:07:15Z" level=info msg="wslInstanceMockService: Received incoming connection" 427s time="2024-03-25T16:07:16Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 427s time="2024-03-25T16:07:16Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Sending bad port 0" 427s === CONT TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 427s time="2024-03-25T16:07:16Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectIncomplete_handshake_because_Agent_sends_port_02572100635/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 427s time="2024-03-25T16:07:16Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42529\"" 431s === CONT TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 431s time="2024-03-25T16:07:20Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectError_because_WindowsForwardedLocalhost_returns_error1925689153/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 431s time="2024-03-25T16:07:20Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33329\"" 435s time="2024-03-25T16:07:24Z" level=info msg="Connecting to control stream at \"127.0.0.1:35757\"" 435s === CONT TestConnect/No_connection_because_port_file_does_not_exist 435s time="2024-03-25T16:07:24Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_port_file_contains_the_wrong_port1912655728/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 435s time="2024-03-25T16:07:24Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42087\"" 439s --- PASS: TestConnect (0.00s) 439s --- PASS: TestConnect/Success (6.22s) 439s --- PASS: TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name (5.39s) 439s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port (4.66s) 439s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_receives (4.58s) 439s --- PASS: TestConnect/No_connection_because_of_port_file_with_invalid_contents (3.67s) 439s --- PASS: TestConnect/No_connection_because_of_empty_port_file (3.81s) 439s --- PASS: TestConnect/No_connection_because_of_unreadable_port_file (3.91s) 439s --- PASS: TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 (4.75s) 439s --- PASS: TestConnect/Error_because_WindowsForwardedLocalhost_returns_error (4.24s) 439s --- PASS: TestConnect/No_connection_because_of_port_file_contains_the_wrong_port (3.78s) 439s --- PASS: TestConnect/No_connection_because_port_file_does_not_exist (3.87s) 439s time="2024-03-25T16:07:28Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_port_file_does_not_exist3657116273/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 439s PASS 439s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/controlstream 80.537s 440s === RUN TestNew 440s === PAUSE TestNew 440s === RUN TestServe 440s === PAUSE TestServe 440s === RUN TestServeAndQuit 440s === PAUSE TestServeAndQuit 440s === RUN TestReconnection 440s === PAUSE TestReconnection 440s === RUN TestWithProMock 440s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 440s --- SKIP: TestWithProMock (0.00s) 440s === RUN TestWithWslPathMock 440s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 440s --- SKIP: TestWithWslPathMock (0.00s) 440s === RUN TestWithWslInfoMock 440s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 440s --- SKIP: TestWithWslInfoMock (0.00s) 440s === RUN TestWithCmdExeMock 440s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 440s --- SKIP: TestWithCmdExeMock (0.00s) 440s === CONT TestNew 440s === RUN TestNew/Success 440s === PAUSE TestNew/Success 440s === RUN TestNew/Error_when_WslPath_returns_error 440s === PAUSE TestNew/Error_when_WslPath_returns_error 440s === CONT TestNew/Success 440s time="2024-03-25T16:07:29Z" level=debug msg="Building new daemon" 442s === CONT TestReconnection 442s === RUN TestReconnection/Success_connecting_after_failing_to_connect 442s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 442s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 442s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 442s === CONT TestServeAndQuit 442s === RUN TestServeAndQuit/Success_with_double_quit 442s === PAUSE TestServeAndQuit/Success_with_double_quit 442s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 442s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 442s === RUN TestServeAndQuit/Success_with_graceful_quit 442s === PAUSE TestServeAndQuit/Success_with_graceful_quit 442s === RUN TestServeAndQuit/Success_with_forceful_quit 442s === PAUSE TestServeAndQuit/Success_with_forceful_quit 442s === CONT TestServe 442s === RUN TestServe/Error_because_of_notifier_returning_error 442s === PAUSE TestServe/Error_because_of_notifier_returning_error 442s === RUN TestServe/Error_because_WindowsHostAddress_returns_error 442s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_error 442s === RUN TestServe/Error_because_of_context_cancelled 442s === PAUSE TestServe/Error_because_of_context_cancelled 442s === RUN TestServe/Success 442s === PAUSE TestServe/Success 442s === RUN TestServe/Success_with_systemd_notifier_returning_true 442s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 442s === RUN TestServe/No_connection_because_port_file_does_not_exist 442s === PAUSE TestServe/No_connection_because_port_file_does_not_exist 442s === RUN TestServe/No_connection_because_of_faulty_agent 442s === PAUSE TestServe/No_connection_because_of_faulty_agent 442s === CONT TestNew/Error_when_WslPath_returns_error 442s time="2024-03-25T16:07:31Z" level=debug msg="Building new daemon" 445s --- PASS: TestNew (0.00s) 445s --- PASS: TestNew/Success (2.45s) 445s --- PASS: TestNew/Error_when_WslPath_returns_error (2.51s) 445s === CONT TestReconnection/Success_connecting_after_failing_to_connect 445s time="2024-03-25T16:07:34Z" level=debug msg="Building new daemon" 447s time="2024-03-25T16:07:36Z" level=debug msg="Ready state sent to systemd" 448s time="2024-03-25T16:07:37Z" level=error msg="Serve error: could not connect to Windows Agent via the control stream: could not get address: could not read agent port file \"/tmp/TestReconnectionSuccess_connecting_after_failing_to_connect840079427/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect840079427/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 448s time="2024-03-25T16:07:37Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 448s time="2024-03-25T16:07:37Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44459\"" 451s time="2024-03-25T16:07:39Z" level=info msg="Retrying connection to control stream" 451s time="2024-03-25T16:07:39Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 451s time="2024-03-25T16:07:40Z" level=info msg="Connecting to control stream at \"127.0.0.1:44459\"" 451s time="2024-03-25T16:07:40Z" level=debug msg="Control stream: starting handshake" 451s time="2024-03-25T16:07:40Z" level=info msg="wslInstanceMockService: Received incoming connection" 453s time="2024-03-25T16:07:42Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 453s time="2024-03-25T16:07:42Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 44235" 453s time="2024-03-25T16:07:42Z" level=debug msg="Control stream: completed handshake" 453s time="2024-03-25T16:07:42Z" level=info msg="Connected to control stream" 453s time="2024-03-25T16:07:42Z" level=debug msg="Starting to serve gRPC requests" 453s time="2024-03-25T16:07:42Z" level=info msg="Serving gRPC requests on localhost:44235" 453s time="2024-03-25T16:07:42Z" level=debug msg="Updated systemd status to \"Serving\"" 458s time="2024-03-25T16:07:47Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 458s time="2024-03-25T16:07:47Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 458s time="2024-03-25T16:07:47Z" level=info msg="wslInstanceMockService: dropped connection" 458s time="2024-03-25T16:07:47Z" level=info msg="Stopping daemon requested." 458s time="2024-03-25T16:07:47Z" level=error msg="Serve error: lost connection to Windows Agent" 458s time="2024-03-25T16:07:47Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 458s time="2024-03-25T16:07:47Z" level=debug msg="Updated systemd status to \"Stopped\"" 458s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 458s time="2024-03-25T16:07:47Z" level=debug msg="Building new daemon" 461s time="2024-03-25T16:07:50Z" level=debug msg="Ready state sent to systemd" 461s time="2024-03-25T16:07:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41501\"" 462s time="2024-03-25T16:07:51Z" level=info msg="Connecting to control stream at \"127.0.0.1:41501\"" 462s time="2024-03-25T16:07:51Z" level=debug msg="Control stream: starting handshake" 462s time="2024-03-25T16:07:51Z" level=info msg="wslInstanceMockService: Received incoming connection" 463s time="2024-03-25T16:07:52Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 463s time="2024-03-25T16:07:52Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 35031" 463s time="2024-03-25T16:07:52Z" level=debug msg="Control stream: completed handshake" 463s time="2024-03-25T16:07:52Z" level=info msg="Connected to control stream" 463s time="2024-03-25T16:07:52Z" level=debug msg="Starting to serve gRPC requests" 463s time="2024-03-25T16:07:52Z" level=info msg="Serving gRPC requests on localhost:35031" 463s time="2024-03-25T16:07:52Z" level=debug msg="Updated systemd status to \"Serving\"" 464s time="2024-03-25T16:07:53Z" level=error msg="Serve error: lost connection to Windows Agent" 464s time="2024-03-25T16:07:53Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 464s time="2024-03-25T16:07:53Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41157\"" 466s time="2024-03-25T16:07:55Z" level=info msg="Retrying connection to control stream" 466s time="2024-03-25T16:07:55Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 467s time="2024-03-25T16:07:56Z" level=info msg="Connecting to control stream at \"127.0.0.1:41157\"" 467s time="2024-03-25T16:07:56Z" level=debug msg="Control stream: starting handshake" 467s time="2024-03-25T16:07:56Z" level=info msg="wslInstanceMockService: Received incoming connection" 468s time="2024-03-25T16:07:57Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 468s time="2024-03-25T16:07:57Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 42349" 468s time="2024-03-25T16:07:57Z" level=debug msg="Control stream: completed handshake" 468s time="2024-03-25T16:07:57Z" level=info msg="Connected to control stream" 468s time="2024-03-25T16:07:57Z" level=debug msg="Starting to serve gRPC requests" 468s time="2024-03-25T16:07:57Z" level=info msg="Serving gRPC requests on localhost:42349" 468s time="2024-03-25T16:07:57Z" level=debug msg="Updated systemd status to \"Serving\"" 473s time="2024-03-25T16:08:02Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 474s time="2024-03-25T16:08:03Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 474s time="2024-03-25T16:08:03Z" level=info msg="wslInstanceMockService: dropped connection" 474s time="2024-03-25T16:08:03Z" level=error msg="Serve error: lost connection to Windows Agent" 474s time="2024-03-25T16:08:03Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 474s time="2024-03-25T16:08:03Z" level=info msg="Stopping daemon requested." 474s time="2024-03-25T16:08:03Z" level=debug msg="Updated systemd status to \"Stopped\"" 474s --- PASS: TestReconnection (0.00s) 474s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (13.53s) 474s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (15.52s) 474s === CONT TestServeAndQuit/Success_with_double_quit 474s time="2024-03-25T16:08:03Z" level=debug msg="Building new daemon" 474s time="2024-03-25T16:08:03Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45621\"" 476s time="2024-03-25T16:08:05Z" level=debug msg="Ready state sent to systemd" 477s time="2024-03-25T16:08:06Z" level=info msg="Connecting to control stream at \"127.0.0.1:45621\"" 477s time="2024-03-25T16:08:06Z" level=debug msg="Control stream: starting handshake" 477s time="2024-03-25T16:08:06Z" level=info msg="wslInstanceMockService: Received incoming connection" 479s time="2024-03-25T16:08:08Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 479s time="2024-03-25T16:08:08Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 42231" 479s time="2024-03-25T16:08:08Z" level=debug msg="Control stream: completed handshake" 479s time="2024-03-25T16:08:08Z" level=info msg="Connected to control stream" 479s time="2024-03-25T16:08:08Z" level=debug msg="Starting to serve gRPC requests" 479s time="2024-03-25T16:08:08Z" level=info msg="Serving gRPC requests on localhost:42231" 479s time="2024-03-25T16:08:08Z" level=debug msg="Updated systemd status to \"Serving\"" 479s time="2024-03-25T16:08:08Z" level=info msg="Stopping daemon requested." 479s time="2024-03-25T16:08:08Z" level=info msg="Waiting for active requests to close." 479s time="2024-03-25T16:08:08Z" level=debug msg="All connections have now ended." 479s time="2024-03-25T16:08:08Z" level=debug msg="Updated systemd status to \"Stopped\"" 479s time="2024-03-25T16:08:08Z" level=info msg="Stopping daemon requested." 479s time="2024-03-25T16:08:08Z" level=info msg="Waiting for active requests to close." 479s time="2024-03-25T16:08:08Z" level=debug msg="All connections have now ended." 479s time="2024-03-25T16:08:08Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_double_quit4213299603/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 479s === CONT TestServeAndQuit/Success_with_forceful_quit 479s time="2024-03-25T16:08:08Z" level=debug msg="Building new daemon" 479s time="2024-03-25T16:08:08Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42403\"" 482s time="2024-03-25T16:08:11Z" level=debug msg="Ready state sent to systemd" 483s time="2024-03-25T16:08:12Z" level=info msg="Connecting to control stream at \"127.0.0.1:42403\"" 483s time="2024-03-25T16:08:12Z" level=debug msg="Control stream: starting handshake" 483s time="2024-03-25T16:08:12Z" level=info msg="wslInstanceMockService: Received incoming connection" 484s time="2024-03-25T16:08:13Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 484s time="2024-03-25T16:08:13Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 37283" 484s time="2024-03-25T16:08:13Z" level=debug msg="Control stream: completed handshake" 484s time="2024-03-25T16:08:13Z" level=info msg="Connected to control stream" 484s time="2024-03-25T16:08:13Z" level=debug msg="Starting to serve gRPC requests" 484s time="2024-03-25T16:08:13Z" level=info msg="Serving gRPC requests on localhost:37283" 484s time="2024-03-25T16:08:13Z" level=debug msg="Updated systemd status to \"Serving\"" 485s time="2024-03-25T16:08:14Z" level=info msg="Stopping daemon requested." 485s time="2024-03-25T16:08:14Z" level=debug msg="Updated systemd status to \"Stopped\"" 485s === CONT TestServeAndQuit/Success_with_graceful_quit 485s time="2024-03-25T16:08:14Z" level=debug msg="Building new daemon" 485s time="2024-03-25T16:08:14Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_forceful_quit777209360/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 485s time="2024-03-25T16:08:14Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42615\"" 487s time="2024-03-25T16:08:16Z" level=debug msg="Ready state sent to systemd" 488s time="2024-03-25T16:08:17Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:35031: connect: connection refused" 488s time="2024-03-25T16:08:17Z" level=info msg="Connecting to control stream at \"127.0.0.1:42615\"" 488s time="2024-03-25T16:08:17Z" level=debug msg="Control stream: starting handshake" 488s time="2024-03-25T16:08:17Z" level=info msg="wslInstanceMockService: Received incoming connection" 489s time="2024-03-25T16:08:18Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 489s time="2024-03-25T16:08:18Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 39195" 489s time="2024-03-25T16:08:18Z" level=debug msg="Control stream: completed handshake" 489s time="2024-03-25T16:08:18Z" level=info msg="Connected to control stream" 489s time="2024-03-25T16:08:18Z" level=debug msg="Starting to serve gRPC requests" 489s time="2024-03-25T16:08:18Z" level=info msg="Serving gRPC requests on localhost:39195" 489s time="2024-03-25T16:08:18Z" level=debug msg="Updated systemd status to \"Serving\"" 490s time="2024-03-25T16:08:19Z" level=info msg="Stopping daemon requested." 490s time="2024-03-25T16:08:19Z" level=info msg="Waiting for active requests to close." 490s time="2024-03-25T16:08:19Z" level=debug msg="Updated systemd status to \"Stopped\"" 490s time="2024-03-25T16:08:19Z" level=debug msg="All connections have now ended." 490s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 490s time="2024-03-25T16:08:19Z" level=debug msg="Building new daemon" 490s time="2024-03-25T16:08:19Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_graceful_quit2618191106/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 490s time="2024-03-25T16:08:19Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34925\"" 492s time="2024-03-25T16:08:21Z" level=debug msg="Updated systemd status to \"Stopped\"" 492s --- PASS: TestServeAndQuit (0.00s) 492s --- PASS: TestServeAndQuit/Success_with_double_quit (5.32s) 492s --- PASS: TestServeAndQuit/Success_with_forceful_quit (5.56s) 492s --- PASS: TestServeAndQuit/Success_with_graceful_quit (5.47s) 492s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (2.16s) 492s time="2024-03-25T16:08:21Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitError_due_to_quitting_before_serving1730529214/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 492s === CONT TestServe/Error_because_of_notifier_returning_error 492s time="2024-03-25T16:08:21Z" level=debug msg="Building new daemon" 492s time="2024-03-25T16:08:21Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41705\"" 495s time="2024-03-25T16:08:24Z" level=warning msg="Could not change systemd status: couldn't update status to systemd: mock error" 495s time="2024-03-25T16:08:24Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeError_because_of_notifier_returning_error673392618/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 495s === CONT TestServe/No_connection_because_of_faulty_agent 495s time="2024-03-25T16:08:24Z" level=debug msg="Building new daemon" 495s time="2024-03-25T16:08:24Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34505\"" 498s time="2024-03-25T16:08:27Z" level=info msg="Connecting to control stream at \"127.0.0.1:34505\"" 498s time="2024-03-25T16:08:27Z" level=debug msg="Control stream: starting handshake" 498s time="2024-03-25T16:08:27Z" level=info msg="wslInstanceMockService: Received incoming connection" 498s time="2024-03-25T16:08:27Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 498s time="2024-03-25T16:08:27Z" level=info msg="wslInstanceMockService: dropped connection" 499s time="2024-03-25T16:08:28Z" level=error msg="Serve error: could not connect to Windows Agent via the control stream: could not complete handshake: could not send system info: EOF" 501s time="2024-03-25T16:08:30Z" level=info msg="Retrying connection to control stream" 502s time="2024-03-25T16:08:31Z" level=info msg="Connecting to control stream at \"127.0.0.1:34505\"" 502s time="2024-03-25T16:08:31Z" level=debug msg="Control stream: starting handshake" 502s time="2024-03-25T16:08:31Z" level=info msg="wslInstanceMockService: Received incoming connection" 502s time="2024-03-25T16:08:31Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 502s time="2024-03-25T16:08:31Z" level=info msg="wslInstanceMockService: dropped connection" 504s time="2024-03-25T16:08:33Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:42231: connect: connection refused" 504s time="2024-03-25T16:08:33Z" level=error msg="Serve error: could not connect to Windows Agent via the control stream: could not complete handshake: could not send system info: EOF" 505s time="2024-03-25T16:08:34Z" level=info msg="Stopping daemon requested." 507s time="2024-03-25T16:08:36Z" level=info msg="Stopping daemon requested." 507s === CONT TestServe/No_connection_because_port_file_does_not_exist 507s time="2024-03-25T16:08:36Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeNo_connection_because_of_faulty_agent1063985145/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 507s time="2024-03-25T16:08:36Z" level=debug msg="Building new daemon" 507s time="2024-03-25T16:08:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42579\"" 509s time="2024-03-25T16:08:38Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:37283: connect: connection refused" 511s time="2024-03-25T16:08:40Z" level=error msg="Serve error: could not connect to Windows Agent via the control stream: could not get address: could not read agent port file \"/tmp/TestServeNo_connection_because_port_file_does_not_exist320769742/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist320769742/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 513s time="2024-03-25T16:08:42Z" level=info msg="Retrying connection to control stream" 514s time="2024-03-25T16:08:43Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:39195: connect: connection refused" 514s time="2024-03-25T16:08:43Z" level=error msg="Serve error: could not connect to Windows Agent via the control stream: could not get address: could not read agent port file \"/tmp/TestServeNo_connection_because_port_file_does_not_exist320769742/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist320769742/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 518s time="2024-03-25T16:08:47Z" level=info msg="Retrying connection to control stream" 520s time="2024-03-25T16:08:49Z" level=info msg="Stopping daemon requested." 520s time="2024-03-25T16:08:49Z" level=error msg="Serve error: could not connect to Windows Agent via the control stream: could not get address: could not read agent port file \"/tmp/TestServeNo_connection_because_port_file_does_not_exist320769742/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist320769742/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 520s time="2024-03-25T16:08:49Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeNo_connection_because_port_file_does_not_exist320769742/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 520s === CONT TestServe/Success_with_systemd_notifier_returning_true 520s time="2024-03-25T16:08:49Z" level=debug msg="Building new daemon" 520s time="2024-03-25T16:08:49Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44351\"" 523s time="2024-03-25T16:08:52Z" level=debug msg="Ready state sent to systemd" 524s time="2024-03-25T16:08:53Z" level=info msg="Connecting to control stream at \"127.0.0.1:44351\"" 524s time="2024-03-25T16:08:53Z" level=debug msg="Control stream: starting handshake" 524s time="2024-03-25T16:08:53Z" level=info msg="wslInstanceMockService: Received incoming connection" 526s time="2024-03-25T16:08:55Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 526s time="2024-03-25T16:08:55Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 39529" 526s time="2024-03-25T16:08:55Z" level=debug msg="Control stream: completed handshake" 526s time="2024-03-25T16:08:55Z" level=info msg="Connected to control stream" 526s time="2024-03-25T16:08:55Z" level=debug msg="Starting to serve gRPC requests" 526s time="2024-03-25T16:08:55Z" level=info msg="Serving gRPC requests on localhost:39529" 526s time="2024-03-25T16:08:55Z" level=debug msg="Updated systemd status to \"Serving\"" 532s time="2024-03-25T16:09:00Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 533s time="2024-03-25T16:09:02Z" level=info msg="Stopping daemon requested." 651s time="2024-03-25T16:11:00Z" level=debug msg="Updated systemd status to \"Stopped\"" 651s time="2024-03-25T16:11:00Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 651s time="2024-03-25T16:11:00Z" level=info msg="wslInstanceMockService: dropped connection" 651s === CONT TestServe/Success 651s time="2024-03-25T16:11:00Z" level=debug msg="Building new daemon" 651s time="2024-03-25T16:11:00Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeSuccess_with_systemd_notifier_returning_true3559870128/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 651s time="2024-03-25T16:11:00Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39261\"" 654s time="2024-03-25T16:11:03Z" level=info msg="Connecting to control stream at \"127.0.0.1:39261\"" 654s time="2024-03-25T16:11:03Z" level=debug msg="Control stream: starting handshake" 654s time="2024-03-25T16:11:03Z" level=info msg="wslInstanceMockService: Received incoming connection" 655s time="2024-03-25T16:11:04Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 655s time="2024-03-25T16:11:04Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 38475" 655s time="2024-03-25T16:11:04Z" level=debug msg="Control stream: completed handshake" 655s time="2024-03-25T16:11:04Z" level=info msg="Connected to control stream" 655s time="2024-03-25T16:11:04Z" level=debug msg="Starting to serve gRPC requests" 655s time="2024-03-25T16:11:04Z" level=info msg="Serving gRPC requests on localhost:38475" 661s time="2024-03-25T16:11:09Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 663s time="2024-03-25T16:11:12Z" level=info msg="Stopping daemon requested." 780s time="2024-03-25T16:13:09Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 780s time="2024-03-25T16:13:09Z" level=info msg="wslInstanceMockService: dropped connection" 780s === CONT TestServe/Error_because_of_context_cancelled 780s time="2024-03-25T16:13:09Z" level=debug msg="Building new daemon" 781s time="2024-03-25T16:13:09Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeSuccess4152240061/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 781s time="2024-03-25T16:13:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39405\"" 783s === CONT TestServe/Error_because_WindowsHostAddress_returns_error 783s time="2024-03-25T16:13:12Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeError_because_of_context_cancelled2178678922/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 783s time="2024-03-25T16:13:12Z" level=debug msg="Building new daemon" 783s time="2024-03-25T16:13:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:38339\"" 786s --- PASS: TestServe (0.00s) 786s --- PASS: TestServe/Error_because_of_notifier_returning_error (2.27s) 786s --- PASS: TestServe/No_connection_because_of_faulty_agent (12.35s) 786s --- PASS: TestServe/No_connection_because_port_file_does_not_exist (12.92s) 786s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (131.07s) 786s --- PASS: TestServe/Success (129.58s) 786s --- PASS: TestServe/Error_because_of_context_cancelled (2.46s) 786s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_error (3.54s) 786s PASS 786s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 346.721s 788s === RUN TestInfo 788s === PAUSE TestInfo 788s === RUN TestWslDistroName 788s === PAUSE TestWslDistroName 788s === RUN TestUserProfileDir 788s === PAUSE TestUserProfileDir 788s === RUN TestProStatus 788s === PAUSE TestProStatus 788s === RUN TestProAttach 788s === PAUSE TestProAttach 788s === RUN TestProDetach 788s === PAUSE TestProDetach 788s === RUN TestLandscapeEnable 788s === PAUSE TestLandscapeEnable 788s === RUN TestWindowsHostAddress 788s === PAUSE TestWindowsHostAddress 788s === RUN TestLandscapeDisable 788s === PAUSE TestLandscapeDisable 788s === RUN TestWithProMock 788s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 788s --- SKIP: TestWithProMock (0.00s) 788s === RUN TestWithLandscapeConfigMock 788s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 788s --- SKIP: TestWithLandscapeConfigMock (0.00s) 788s === RUN TestWithWslPathMock 788s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 788s --- SKIP: TestWithWslPathMock (0.00s) 788s === RUN TestWithWslInfoMock 788s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 788s --- SKIP: TestWithWslInfoMock (0.00s) 788s === RUN TestWithCmdExeMock 788s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 788s --- SKIP: TestWithCmdExeMock (0.00s) 788s === CONT TestInfo 788s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 788s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 788s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 788s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 788s === RUN TestInfo/Success 788s === PAUSE TestInfo/Success 788s === RUN TestInfo/Error_when_WslDistroName_fails 788s === PAUSE TestInfo/Error_when_WslDistroName_fails 788s === RUN TestInfo/Error_when_pro_status_command_fails 788s === PAUSE TestInfo/Error_when_pro_status_command_fails 788s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 788s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 788s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 788s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 788s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 789s === CONT TestLandscapeDisable 789s === RUN TestLandscapeDisable/Success 789s === PAUSE TestLandscapeDisable/Success 789s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 789s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 789s === CONT TestWindowsHostAddress 789s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 789s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 789s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 789s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 789s === RUN TestWindowsHostAddress/Success_without_NAT 789s === PAUSE TestWindowsHostAddress/Success_without_NAT 789s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 789s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 789s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 789s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 789s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 789s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 789s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 789s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 789s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 789s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 789s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 789s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 789s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 789s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 789s === CONT TestLandscapeEnable 789s === RUN TestLandscapeEnable/Success_overriding_computer_title 789s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 789s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 789s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 789s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 789s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 789s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 789s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 789s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 789s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 789s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 789s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 789s === RUN TestLandscapeEnable/Success 789s === PAUSE TestLandscapeEnable/Success 789s === CONT TestProDetach 789s === RUN TestProDetach/success_on_unattached_distro 789s === PAUSE TestProDetach/success_on_unattached_distro 789s === RUN TestProDetach/success_on_attached_distro 789s === PAUSE TestProDetach/success_on_attached_distro 789s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 789s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 789s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 789s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 789s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 789s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 789s === CONT TestProAttach 789s === RUN TestProAttach/success 789s === PAUSE TestProAttach/success 789s === RUN TestProAttach/error_on_'pro_attach'_error 789s === PAUSE TestProAttach/error_on_'pro_attach'_error 789s === CONT TestProStatus 789s === RUN TestProStatus/success_on_unattached_distro 789s === PAUSE TestProStatus/success_on_unattached_distro 789s === RUN TestProStatus/success_on_attached_distro 789s === PAUSE TestProStatus/success_on_attached_distro 789s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 789s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 789s === RUN TestProStatus/error_on_'pro_attach'_error 789s === PAUSE TestProStatus/error_on_'pro_attach'_error 789s === CONT TestUserProfileDir 789s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 789s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 789s === RUN TestUserProfileDir/Error_on_cmd.exe_error 789s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 789s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 789s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 789s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 789s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 789s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 789s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 789s === RUN TestUserProfileDir/Error_on_wslpath_error 789s === PAUSE TestUserProfileDir/Error_on_wslpath_error 789s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 789s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 789s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 789s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 789s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 789s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 789s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 789s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 789s === CONT TestWslDistroName 789s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 789s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 789s === RUN TestWslDistroName/Success_using_wslpath 789s === PAUSE TestWslDistroName/Success_using_wslpath 789s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 789s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 789s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 789s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 789s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 790s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 792s === CONT TestInfo/Error_when_pro_status_command_fails 795s === CONT TestInfo/Error_when_WslDistroName_fails 795s === CONT TestInfo/Success 796s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 797s --- PASS: TestInfo (0.00s) 797s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (1.19s) 797s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (1.25s) 797s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (1.40s) 797s --- PASS: TestInfo/Error_when_pro_status_command_fails (1.37s) 797s --- PASS: TestInfo/Error_when_WslDistroName_fails (1.26s) 797s --- PASS: TestInfo/Success (1.20s) 797s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (1.11s) 797s === CONT TestLandscapeDisable/Success 798s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 799s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 801s --- PASS: TestLandscapeDisable (0.00s) 801s --- PASS: TestLandscapeDisable/Success (1.04s) 801s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (1.12s) 801s === CONT TestLandscapeEnable/Success_overriding_computer_title 801s time="2024-03-25T16:13:29Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden with TEST_DISTRO" 801s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 801s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 802s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 803s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 804s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 806s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 807s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 808s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 810s === CONT TestWindowsHostAddress/Success_without_NAT 811s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 812s --- PASS: TestWindowsHostAddress (0.00s) 812s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (1.08s) 812s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (1.20s) 812s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (1.08s) 812s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (1.11s) 812s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (1.10s) 812s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (1.40s) 812s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (1.38s) 812s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (1.14s) 812s --- PASS: TestWindowsHostAddress/Success_without_NAT (1.26s) 812s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (1.12s) 812s === CONT TestProDetach/success_on_unattached_distro 813s === CONT TestLandscapeEnable/Success 815s system_test.go:456: testdata/TestLandscapeEnable/golden/success 815s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 816s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 817s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 817s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 817s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 820s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 820s --- PASS: TestLandscapeEnable (0.00s) 820s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (1.23s) 820s --- PASS: TestLandscapeEnable/Success (1.34s) 820s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (1.22s) 820s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (1.52s) 820s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.00s) 820s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.00s) 820s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (2.63s) 820s === CONT TestProAttach/success 821s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 823s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 824s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 825s === CONT TestProDetach/success_on_attached_distro 826s --- PASS: TestProDetach (0.00s) 826s --- PASS: TestProDetach/success_on_unattached_distro (1.28s) 826s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (1.49s) 826s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (1.33s) 826s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (1.29s) 826s --- PASS: TestProDetach/success_on_attached_distro (1.09s) 826s === CONT TestProStatus/success_on_unattached_distro 827s === CONT TestProAttach/error_on_'pro_attach'_error 829s --- PASS: TestProAttach (0.00s) 829s --- PASS: TestProAttach/success (1.31s) 829s --- PASS: TestProAttach/error_on_'pro_attach'_error (1.05s) 829s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 829s system_test.go:209: Removing default proc/mounts 829s === CONT TestProStatus/error_on_'pro_attach'_error 830s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 831s === CONT TestProStatus/success_on_attached_distro 832s --- PASS: TestProStatus (0.00s) 832s --- PASS: TestProStatus/success_on_unattached_distro (1.08s) 832s --- PASS: TestProStatus/error_on_'pro_attach'_error (1.03s) 832s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (1.09s) 832s --- PASS: TestProStatus/success_on_attached_distro (1.21s) 832s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 832s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 832s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 832s system_test.go:209: Removing default proc/mounts 832s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 834s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 837s === CONT TestUserProfileDir/Error_on_wslpath_error 839s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 841s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 844s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 846s === CONT TestUserProfileDir/Error_on_cmd.exe_error 847s --- PASS: TestUserProfileDir (0.00s) 847s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 847s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 847s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 847s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (2.39s) 847s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (2.52s) 847s --- PASS: TestUserProfileDir/Error_on_wslpath_error (2.40s) 847s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (2.29s) 847s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (2.28s) 847s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (2.30s) 847s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (1.21s) 847s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 848s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 850s === CONT TestWslDistroName/Success_using_wslpath 852s --- PASS: TestWslDistroName (0.00s) 852s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.00s) 852s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (1.19s) 852s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (1.13s) 852s --- PASS: TestWslDistroName/Success_using_wslpath (1.36s) 852s PASS 852s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 63.115s 852s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 852s === RUN TestApplyProToken 852s === PAUSE TestApplyProToken 852s === RUN TestApplyLandscapeConfig 852s === PAUSE TestApplyLandscapeConfig 852s === RUN TestWithProMock 852s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 852s --- SKIP: TestWithProMock (0.00s) 852s === RUN TestWithLandscapeConfigMock 852s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 852s --- SKIP: TestWithLandscapeConfigMock (0.00s) 852s === RUN TestWithWslPathMock 852s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 852s --- SKIP: TestWithWslPathMock (0.00s) 852s === RUN TestWithWslInfoMock 852s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 852s --- SKIP: TestWithWslInfoMock (0.00s) 852s === RUN TestWithCmdExeMock 852s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 852s --- SKIP: TestWithCmdExeMock (0.00s) 852s === CONT TestApplyProToken 852s === RUN TestApplyProToken/success_attaching_non-attached_machine 852s === PAUSE TestApplyProToken/success_attaching_non-attached_machine 852s === RUN TestApplyProToken/success_detaching_attached_machine 852s === PAUSE TestApplyProToken/success_detaching_attached_machine 852s === RUN TestApplyProToken/success_detaching_non-attached_machine 852s === PAUSE TestApplyProToken/success_detaching_non-attached_machine 852s === RUN TestApplyProToken/Error_calling_pro_attach 852s === PAUSE TestApplyProToken/Error_calling_pro_attach 852s === RUN TestApplyProToken/success_attaching_attached_machine 852s === PAUSE TestApplyProToken/success_attaching_attached_machine 852s === RUN TestApplyProToken/Error_detaching_pro 852s === PAUSE TestApplyProToken/Error_detaching_pro 852s === RUN TestApplyProToken/Error_calling_pro_status 852s === PAUSE TestApplyProToken/Error_calling_pro_status 852s === RUN TestApplyProToken/Error_getting_system_info 852s === PAUSE TestApplyProToken/Error_getting_system_info 852s === RUN TestApplyProToken/Error_cannot_send_info_to_stream 852s === PAUSE TestApplyProToken/Error_cannot_send_info_to_stream 852s === CONT TestApplyProToken/success_attaching_non-attached_machine 852s time="2024-03-25T16:14:21Z" level=debug msg="Registering gRPC WSL instance service" 852s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:45107 852s wslinstanceservice_test.go:108: Client connected to WslInstanceService 852s time="2024-03-25T16:14:21Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 856s === CONT TestApplyLandscapeConfig 856s === RUN TestApplyLandscapeConfig/Success_enabling 856s === PAUSE TestApplyLandscapeConfig/Success_enabling 856s === RUN TestApplyLandscapeConfig/Success_disabling 856s === PAUSE TestApplyLandscapeConfig/Success_disabling 856s === RUN TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 856s === PAUSE TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 856s === RUN TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 856s === PAUSE TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 856s === CONT TestApplyProToken/Error_cannot_send_info_to_stream 856s time="2024-03-25T16:14:25Z" level=debug msg="Registering gRPC WSL instance service" 856s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:34013 856s wslinstanceservice_test.go:108: Client connected to WslInstanceService 856s time="2024-03-25T16:14:25Z" level=info msg="ApplyProToken: Received empty token: detaching" 858s time="2024-03-25T16:14:27Z" level=warning msg="ApplyProToken: could not send update via control stream: could not send system info: test error" 858s === CONT TestApplyProToken/Error_getting_system_info 858s time="2024-03-25T16:14:27Z" level=debug msg="Registering gRPC WSL instance service" 858s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:37105 858s wslinstanceservice_test.go:108: Client connected to WslInstanceService 858s time="2024-03-25T16:14:27Z" level=info msg="ApplyProToken: Received empty token: detaching" 860s time="2024-03-25T16:14:29Z" level=warning msg="ApplyProToken: could not send update via control stream: could not gather system info: could not read /etc/os-release: open /tmp/TestApplyProTokenError_getting_system_info1423895542/001/etc/os-release: no such file or directory" 861s === CONT TestApplyProToken/Error_calling_pro_status 861s time="2024-03-25T16:14:29Z" level=debug msg="Registering gRPC WSL instance service" 861s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:42861 861s wslinstanceservice_test.go:108: Client connected to WslInstanceService 861s time="2024-03-25T16:14:29Z" level=info msg="ApplyProToken: Received empty token: detaching" 863s time="2024-03-25T16:14:32Z" level=warning msg="ApplyProToken: could not send update via control stream: could not gather system info: could not obtain pro status: pro status: command returned error: exit status 1\nStdout:" 863s === CONT TestApplyProToken/Error_detaching_pro 863s time="2024-03-25T16:14:32Z" level=debug msg="Registering gRPC WSL instance service" 863s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:43479 863s wslinstanceservice_test.go:108: Client connected to WslInstanceService 863s time="2024-03-25T16:14:32Z" level=info msg="ApplyProToken: Received empty token: detaching" 866s === CONT TestApplyProToken/success_attaching_attached_machine 866s time="2024-03-25T16:14:35Z" level=debug msg="Registering gRPC WSL instance service" 866s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:34909 866s wslinstanceservice_test.go:108: Client connected to WslInstanceService 866s time="2024-03-25T16:14:35Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 869s === CONT TestApplyProToken/Error_calling_pro_attach 869s time="2024-03-25T16:14:38Z" level=debug msg="Registering gRPC WSL instance service" 869s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:36451 869s wslinstanceservice_test.go:108: Client connected to WslInstanceService 869s time="2024-03-25T16:14:38Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 873s === CONT TestApplyProToken/success_detaching_non-attached_machine 873s time="2024-03-25T16:14:42Z" level=debug msg="Registering gRPC WSL instance service" 873s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:35509 873s wslinstanceservice_test.go:108: Client connected to WslInstanceService 873s time="2024-03-25T16:14:42Z" level=info msg="ApplyProToken: Received empty token: detaching" 876s === CONT TestApplyProToken/success_detaching_attached_machine 876s time="2024-03-25T16:14:45Z" level=debug msg="Registering gRPC WSL instance service" 876s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:34931 876s wslinstanceservice_test.go:108: Client connected to WslInstanceService 876s time="2024-03-25T16:14:45Z" level=info msg="ApplyProToken: Received empty token: detaching" 879s --- PASS: TestApplyProToken (0.00s) 879s --- PASS: TestApplyProToken/success_attaching_non-attached_machine (3.85s) 879s --- PASS: TestApplyProToken/Error_cannot_send_info_to_stream (2.29s) 879s --- PASS: TestApplyProToken/Error_getting_system_info (2.52s) 879s --- PASS: TestApplyProToken/Error_calling_pro_status (2.70s) 879s --- PASS: TestApplyProToken/Error_detaching_pro (2.37s) 879s --- PASS: TestApplyProToken/success_attaching_attached_machine (3.59s) 879s --- PASS: TestApplyProToken/Error_calling_pro_attach (3.93s) 879s --- PASS: TestApplyProToken/success_detaching_non-attached_machine (2.81s) 879s --- PASS: TestApplyProToken/success_detaching_attached_machine (2.83s) 879s === CONT TestApplyLandscapeConfig/Success_enabling 879s time="2024-03-25T16:14:48Z" level=debug msg="Registering gRPC WSL instance service" 879s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:34543 879s wslinstanceservice_test.go:167: Client connected to WslInstanceService 879s time="2024-03-25T16:14:48Z" level=info msg="ApplyLandscapeConfig: received config: registering" 880s wslinstanceservice_test.go:192: testdata/TestApplyLandscapeConfig/golden/success_enabling 880s === CONT TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 880s time="2024-03-25T16:14:49Z" level=debug msg="Registering gRPC WSL instance service" 880s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:35649 880s wslinstanceservice_test.go:167: Client connected to WslInstanceService 880s time="2024-03-25T16:14:49Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 882s === CONT TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 882s time="2024-03-25T16:14:50Z" level=debug msg="Registering gRPC WSL instance service" 882s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:38069 882s wslinstanceservice_test.go:167: Client connected to WslInstanceService 882s time="2024-03-25T16:14:50Z" level=info msg="ApplyLandscapeConfig: received config: registering" 883s === CONT TestApplyLandscapeConfig/Success_disabling 883s time="2024-03-25T16:14:52Z" level=debug msg="Registering gRPC WSL instance service" 883s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:34467 883s wslinstanceservice_test.go:167: Client connected to WslInstanceService 883s time="2024-03-25T16:14:52Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 884s --- PASS: TestApplyLandscapeConfig (0.00s) 884s --- PASS: TestApplyLandscapeConfig/Success_enabling (1.35s) 884s --- PASS: TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails (1.22s) 884s --- PASS: TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails (1.38s) 884s --- PASS: TestApplyLandscapeConfig/Success_disabling (1.15s) 884s PASS 884s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/wslinstanceservice 31.975s 884s autopkgtest [16:14:53]: test command1: -----------------------] 885s autopkgtest [16:14:54]: test command1: - - - - - - - - - - results - - - - - - - - - - 885s command1 PASS 886s autopkgtest [16:14:55]: @@@@@@@@@@@@@@@@@@@@ summary 886s command1 PASS 899s Creating nova instance adt-noble-s390x-wsl-pro-service-20240325-160008-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240325.img (UUID 9f25d9bc-613c-4979-9452-2ea8e4d84cd0)...