0s autopkgtest [23:33:48]: starting date: 2024-02-18 0s autopkgtest [23:33:48]: git checkout: d9c0295 adt_testbed.py: supress warnings from apt using a shell pipeline 0s autopkgtest [23:33:48]: host juju-7f2275-prod-proposed-migration-environment-4; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.e2f1eo9f/out --timeout-copy=6000 --setup-commands 'sed -i "s/ports.ubuntu.com/ftpmaster.internal/; s/ubuntu-ports/ubuntu/" /etc/apt/sources.list `ls /etc/apt/sources.list.d/*.list 2>/dev/null || true`; ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com\n" >> /etc/environment' --apt-pocket=proposed=src:ubuntu-advantage-tools --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=ubuntu-advantage-tools/31.1 -- lxd -r lxd-armhf-10.44.124.112 lxd-armhf-10.44.124.112:autopkgtest/ubuntu/noble/armhf 34s autopkgtest [23:34:22]: @@@@@@@@@@@@@@@@@@@@ test bed setup 36s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 37s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [15.0 kB] 37s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [9892 B] 37s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [99.4 kB] 37s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [735 kB] 38s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main armhf Packages [192 kB] 38s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main armhf c-n-f Metadata [2492 B] 38s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf Packages [1180 B] 38s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf c-n-f Metadata [116 B] 38s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf Packages [913 kB] 38s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf c-n-f Metadata [7776 B] 38s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf Packages [3084 B] 38s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf c-n-f Metadata [116 B] 43s Fetched 2096 kB in 2s (969 kB/s) 43s Reading package lists... 56s tee: /proc/self/fd/2: Permission denied 83s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 83s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 83s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 83s Hit:4 http://ftpmaster.internal/ubuntu noble-proposed InRelease 86s Reading package lists... 86s Reading package lists... 87s Building dependency tree... 87s Reading state information... 88s Calculating upgrade... 89s The following packages were automatically installed and are no longer required: 89s libperl5.36 perl-modules-5.36 89s Use 'apt autoremove' to remove them. 89s The following NEW packages will be installed: 89s apport-core-dump-handler libperl5.38 perl-modules-5.38 ubuntu-pro-client 89s The following packages will be upgraded: 89s apparmor apport apt apt-utils byobu ca-certificates curl dhcpcd-base 89s dracut-install ethtool info install-info iptables libapparmor1 libapt-pkg6.0 89s libcap-ng0 libcurl3-gnutls libcurl4 libdrm-common libdrm2 libidn2-0 89s libip4tc2 libip6tc2 libkeyutils1 libldap-common libldap2 89s liblocale-gettext-perl libnghttp2-14 libnss-systemd libpam-systemd 89s libplymouth5 libpython3.11-minimal libpython3.11-stdlib libseccomp2 89s libselinux1 libsemanage-common libsemanage2 libsqlite3-0 libssl3 89s libsystemd-shared libsystemd0 libtext-charwidth-perl libtext-iconv-perl 89s libudev1 libuv1 libxtables12 openssl perl perl-base plymouth 89s plymouth-theme-ubuntu-text psmisc python-apt-common python3-apport 89s python3-apt python3-idna python3-jinja2 python3-markupsafe 89s python3-problem-report python3-urllib3 python3.11 python3.11-minimal sudo 89s systemd systemd-dev systemd-resolved systemd-sysv systemd-timesyncd tmux 89s tzdata ubuntu-advantage-tools ubuntu-pro-client-l10n udev ufw usb.ids 89s vim-common vim-tiny xxd 89s 78 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 89s Need to get 36.6 MB of archives. 89s After this operation, 44.4 MB of additional disk space will be used. 89s Get:1 http://ftpmaster.internal/ubuntu noble/main armhf perl-modules-5.38 all 5.38.2-3 [3110 kB] 90s Get:2 http://ftpmaster.internal/ubuntu noble/main armhf libperl5.38 armhf 5.38.2-3 [4105 kB] 90s Get:3 http://ftpmaster.internal/ubuntu noble/main armhf perl armhf 5.38.2-3 [231 kB] 90s Get:4 http://ftpmaster.internal/ubuntu noble/main armhf perl-base armhf 5.38.2-3 [1670 kB] 90s Get:5 http://ftpmaster.internal/ubuntu noble/main armhf liblocale-gettext-perl armhf 1.07-6build1 [14.8 kB] 90s Get:6 http://ftpmaster.internal/ubuntu noble/main armhf libtext-iconv-perl armhf 1.7-8build1 [12.6 kB] 90s Get:7 http://ftpmaster.internal/ubuntu noble/main armhf libtext-charwidth-perl armhf 0.04-11build1 [8894 B] 90s Get:8 http://ftpmaster.internal/ubuntu noble/main armhf systemd-timesyncd armhf 255.2-3ubuntu2 [35.9 kB] 90s Get:9 http://ftpmaster.internal/ubuntu noble/main armhf libssl3 armhf 3.0.10-1ubuntu4 [1553 kB] 90s Get:10 http://ftpmaster.internal/ubuntu noble/main armhf systemd-dev all 255.2-3ubuntu2 [101 kB] 90s Get:11 http://ftpmaster.internal/ubuntu noble/main armhf systemd armhf 255.2-3ubuntu2 [3495 kB] 90s Get:12 http://ftpmaster.internal/ubuntu noble/main armhf udev armhf 255.2-3ubuntu2 [1852 kB] 90s Get:13 http://ftpmaster.internal/ubuntu noble/main armhf libnss-systemd armhf 255.2-3ubuntu2 [148 kB] 90s Get:14 http://ftpmaster.internal/ubuntu noble/main armhf systemd-sysv armhf 255.2-3ubuntu2 [11.9 kB] 90s Get:15 http://ftpmaster.internal/ubuntu noble/main armhf libpam-systemd armhf 255.2-3ubuntu2 [216 kB] 90s Get:16 http://ftpmaster.internal/ubuntu noble/main armhf systemd-resolved armhf 255.2-3ubuntu2 [288 kB] 90s Get:17 http://ftpmaster.internal/ubuntu noble/main armhf libapparmor1 armhf 4.0.0~alpha4-0ubuntu1 [44.4 kB] 90s Get:18 http://ftpmaster.internal/ubuntu noble/main armhf libseccomp2 armhf 2.5.5-1ubuntu1 [49.4 kB] 90s Get:19 http://ftpmaster.internal/ubuntu noble/main armhf libselinux1 armhf 3.5-2build1 [70.3 kB] 90s Get:20 http://ftpmaster.internal/ubuntu noble/main armhf libsystemd-shared armhf 255.2-3ubuntu2 [2006 kB] 91s Get:21 http://ftpmaster.internal/ubuntu noble/main armhf libudev1 armhf 255.2-3ubuntu2 [164 kB] 91s Get:22 http://ftpmaster.internal/ubuntu noble/main armhf libsystemd0 armhf 255.2-3ubuntu2 [408 kB] 91s Get:23 http://ftpmaster.internal/ubuntu noble/main armhf libapt-pkg6.0 armhf 2.7.11 [994 kB] 91s Get:24 http://ftpmaster.internal/ubuntu noble/main armhf apt armhf 2.7.11 [1363 kB] 91s Get:25 http://ftpmaster.internal/ubuntu noble/main armhf apt-utils armhf 2.7.11 [211 kB] 91s Get:26 http://ftpmaster.internal/ubuntu noble/main armhf python3.11 armhf 3.11.8-1 [589 kB] 91s Get:27 http://ftpmaster.internal/ubuntu noble/main armhf libpython3.11-stdlib armhf 3.11.8-1 [1819 kB] 91s Get:28 http://ftpmaster.internal/ubuntu noble/main armhf python3.11-minimal armhf 3.11.8-1 [1904 kB] 91s Get:29 http://ftpmaster.internal/ubuntu noble/main armhf libpython3.11-minimal armhf 3.11.8-1 [825 kB] 91s Get:30 http://ftpmaster.internal/ubuntu noble/main armhf tzdata all 2024a-1ubuntu1 [273 kB] 91s Get:31 http://ftpmaster.internal/ubuntu noble/main armhf libsqlite3-0 armhf 3.45.1-1 [590 kB] 91s Get:32 http://ftpmaster.internal/ubuntu noble/main armhf python-apt-common all 2.7.5 [19.8 kB] 91s Get:33 http://ftpmaster.internal/ubuntu noble/main armhf python3-apt armhf 2.7.5 [199 kB] 91s Get:34 http://ftpmaster.internal/ubuntu noble/main armhf python3-problem-report all 2.27.0-0ubuntu7 [21.9 kB] 91s Get:35 http://ftpmaster.internal/ubuntu noble/main armhf python3-apport all 2.27.0-0ubuntu7 [97.3 kB] 91s Get:36 http://ftpmaster.internal/ubuntu noble/main armhf apport all 2.27.0-0ubuntu7 [82.4 kB] 91s Get:37 http://ftpmaster.internal/ubuntu noble/main armhf apport-core-dump-handler all 2.27.0-0ubuntu7 [16.4 kB] 91s Get:38 http://ftpmaster.internal/ubuntu noble/main armhf libcap-ng0 armhf 0.8.4-2 [13.4 kB] 91s Get:39 http://ftpmaster.internal/ubuntu noble/main armhf libsemanage-common all 3.5-1build2 [10.0 kB] 91s Get:40 http://ftpmaster.internal/ubuntu noble/main armhf libsemanage2 armhf 3.5-1build2 [84.3 kB] 91s Get:41 http://ftpmaster.internal/ubuntu noble/main armhf libidn2-0 armhf 2.3.7-2 [96.1 kB] 91s Get:42 http://ftpmaster.internal/ubuntu noble/main armhf install-info armhf 7.1-3 [60.4 kB] 91s Get:43 http://ftpmaster.internal/ubuntu noble/main armhf openssl armhf 3.0.10-1ubuntu4 [973 kB] 91s Get:44 http://ftpmaster.internal/ubuntu noble/main armhf ca-certificates all 20240203 [159 kB] 91s Get:45 http://ftpmaster.internal/ubuntu noble/main armhf dhcpcd-base armhf 1:10.0.6-1ubuntu1 [185 kB] 91s Get:46 http://ftpmaster.internal/ubuntu noble/main armhf iptables armhf 1.8.10-3ubuntu1 [338 kB] 91s Get:47 http://ftpmaster.internal/ubuntu noble/main armhf libip6tc2 armhf 1.8.10-3ubuntu1 [21.6 kB] 91s Get:48 http://ftpmaster.internal/ubuntu noble/main armhf libxtables12 armhf 1.8.10-3ubuntu1 [32.3 kB] 91s Get:49 http://ftpmaster.internal/ubuntu noble/main armhf libip4tc2 armhf 1.8.10-3ubuntu1 [21.2 kB] 91s Get:50 http://ftpmaster.internal/ubuntu noble/main armhf libkeyutils1 armhf 1.6.3-3 [8120 B] 91s Get:51 http://ftpmaster.internal/ubuntu noble/main armhf sudo armhf 1.9.15p5-3ubuntu1 [932 kB] 91s Get:52 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-advantage-tools all 31.1 [10.7 kB] 91s Get:53 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client armhf 31.1 [216 kB] 91s Get:54 http://ftpmaster.internal/ubuntu noble-proposed/main armhf ubuntu-pro-client-l10n armhf 31.1 [19.4 kB] 91s Get:55 http://ftpmaster.internal/ubuntu noble/main armhf vim-tiny armhf 2:9.1.0016-1ubuntu2 [665 kB] 91s Get:56 http://ftpmaster.internal/ubuntu noble/main armhf vim-common all 2:9.1.0016-1ubuntu2 [385 kB] 91s Get:57 http://ftpmaster.internal/ubuntu noble/main armhf xxd armhf 2:9.1.0016-1ubuntu2 [62.4 kB] 91s Get:58 http://ftpmaster.internal/ubuntu noble/main armhf apparmor armhf 4.0.0~alpha4-0ubuntu1 [549 kB] 91s Get:59 http://ftpmaster.internal/ubuntu noble/main armhf info armhf 7.1-3 [126 kB] 91s Get:60 http://ftpmaster.internal/ubuntu noble/main armhf libdrm-common all 2.4.120-2 [7998 B] 91s Get:61 http://ftpmaster.internal/ubuntu noble/main armhf libdrm2 armhf 2.4.120-2 [36.5 kB] 91s Get:62 http://ftpmaster.internal/ubuntu noble/main armhf libnghttp2-14 armhf 1.59.0-1 [68.1 kB] 91s Get:63 http://ftpmaster.internal/ubuntu noble/main armhf libplymouth5 armhf 24.004.60-1ubuntu1 [122 kB] 91s Get:64 http://ftpmaster.internal/ubuntu noble/main armhf libuv1 armhf 1.46.0-3ubuntu1 [80.7 kB] 91s Get:65 http://ftpmaster.internal/ubuntu noble/main armhf plymouth-theme-ubuntu-text armhf 24.004.60-1ubuntu1 [8832 B] 91s Get:66 http://ftpmaster.internal/ubuntu noble/main armhf plymouth armhf 24.004.60-1ubuntu1 [127 kB] 91s Get:67 http://ftpmaster.internal/ubuntu noble/main armhf psmisc armhf 23.6-2 [176 kB] 91s Get:68 http://ftpmaster.internal/ubuntu noble/main armhf ufw all 0.36.2-5 [169 kB] 91s Get:69 http://ftpmaster.internal/ubuntu noble/main armhf usb.ids all 2024.01.30-1 [222 kB] 91s Get:70 http://ftpmaster.internal/ubuntu noble/main armhf tmux armhf 3.4-1 [399 kB] 91s Get:71 http://ftpmaster.internal/ubuntu noble/main armhf byobu all 6.11-0ubuntu1 [103 kB] 91s Get:72 http://ftpmaster.internal/ubuntu noble/main armhf libldap2 armhf 2.6.7+dfsg-1~exp1ubuntu1 [170 kB] 91s Get:73 http://ftpmaster.internal/ubuntu noble/main armhf curl armhf 8.5.0-2ubuntu2 [219 kB] 91s Get:74 http://ftpmaster.internal/ubuntu noble/main armhf libcurl4 armhf 8.5.0-2ubuntu2 [295 kB] 91s Get:75 http://ftpmaster.internal/ubuntu noble/main armhf dracut-install armhf 060+5-1ubuntu2 [33.9 kB] 91s Get:76 http://ftpmaster.internal/ubuntu noble/main armhf ethtool armhf 1:6.7-1 [218 kB] 91s Get:77 http://ftpmaster.internal/ubuntu noble/main armhf libcurl3-gnutls armhf 8.5.0-2ubuntu2 [289 kB] 91s Get:78 http://ftpmaster.internal/ubuntu noble/main armhf libldap-common all 2.6.7+dfsg-1~exp1ubuntu1 [31.0 kB] 92s Get:79 http://ftpmaster.internal/ubuntu noble/main armhf python3-idna all 3.6-2 [46.0 kB] 92s Get:80 http://ftpmaster.internal/ubuntu noble/main armhf python3-markupsafe armhf 2.1.5-1 [13.2 kB] 92s Get:81 http://ftpmaster.internal/ubuntu noble/main armhf python3-jinja2 all 3.1.2-1ubuntu1 [107 kB] 92s Get:82 http://ftpmaster.internal/ubuntu noble/main armhf python3-urllib3 all 2.0.7-1 [113 kB] 93s Preconfiguring packages ... 94s Fetched 36.6 MB in 2s (15.6 MB/s) 95s (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 ... 57261 files and directories currently installed.) 95s Preparing to unpack .../perl_5.38.2-3_armhf.deb ... 95s Unpacking perl (5.38.2-3) over (5.36.0-10ubuntu1) ... 95s Selecting previously unselected package perl-modules-5.38. 95s Preparing to unpack .../perl-modules-5.38_5.38.2-3_all.deb ... 95s Unpacking perl-modules-5.38 (5.38.2-3) ... 95s Selecting previously unselected package libperl5.38:armhf. 95s Preparing to unpack .../libperl5.38_5.38.2-3_armhf.deb ... 95s Unpacking libperl5.38:armhf (5.38.2-3) ... 96s Preparing to unpack .../perl-base_5.38.2-3_armhf.deb ... 96s Unpacking perl-base (5.38.2-3) over (5.36.0-10ubuntu1) ... 96s Setting up perl-base (5.38.2-3) ... 96s (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 ... 59195 files and directories currently installed.) 96s Preparing to unpack .../0-liblocale-gettext-perl_1.07-6build1_armhf.deb ... 96s Unpacking liblocale-gettext-perl (1.07-6build1) over (1.07-6) ... 96s Preparing to unpack .../1-libtext-iconv-perl_1.7-8build1_armhf.deb ... 96s Unpacking libtext-iconv-perl:armhf (1.7-8build1) over (1.7-8) ... 96s Preparing to unpack .../2-libtext-charwidth-perl_0.04-11build1_armhf.deb ... 96s Unpacking libtext-charwidth-perl:armhf (0.04-11build1) over (0.04-11) ... 97s Preparing to unpack .../3-systemd-timesyncd_255.2-3ubuntu2_armhf.deb ... 97s Unpacking systemd-timesyncd (255.2-3ubuntu2) over (253.5-1ubuntu7) ... 97s dpkg: warning: unable to delete old directory '/lib/systemd/ntp-units.d': Directory not empty 97s Preparing to unpack .../4-libssl3_3.0.10-1ubuntu4_armhf.deb ... 97s Unpacking libssl3:armhf (3.0.10-1ubuntu4) over (3.0.10-1ubuntu3) ... 97s Preparing to unpack .../5-systemd-dev_255.2-3ubuntu2_all.deb ... 97s Unpacking systemd-dev (255.2-3ubuntu2) over (253.5-1ubuntu7) ... 97s Setting up libssl3:armhf (3.0.10-1ubuntu4) ... 97s (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 ... 59236 files and directories currently installed.) 97s Preparing to unpack .../systemd_255.2-3ubuntu2_armhf.deb ... 97s Unpacking systemd (255.2-3ubuntu2) over (253.5-1ubuntu7) ... 98s dpkg: warning: unable to delete old directory '/usr/lib/modules-load.d': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system-shutdown': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system-preset': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/user@0.service.d': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/user@.service.d': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/user-.slice.d': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/timers.target.wants': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/systemd-localed.service.d': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/rescue.target.wants': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/rc-local.service.d': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/multi-user.target.wants': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/initrd.target.wants': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/initrd-root-fs.target.wants': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/initrd-root-device.target.wants': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/graphical.target.wants': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/getty.target.wants': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/journald.conf.d': Directory not empty 98s Preparing to unpack .../udev_255.2-3ubuntu2_armhf.deb ... 98s Unpacking udev (255.2-3ubuntu2) over (253.5-1ubuntu7) ... 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/systemd-udevd.service.d': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/sysinit.target.wants': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/system/sockets.target.wants': Directory not empty 98s dpkg: warning: unable to delete old directory '/lib/systemd/network': Directory not empty 98s Preparing to unpack .../libnss-systemd_255.2-3ubuntu2_armhf.deb ... 98s Unpacking libnss-systemd:armhf (255.2-3ubuntu2) over (253.5-1ubuntu7) ... 98s Preparing to unpack .../libsystemd-shared_255.2-3ubuntu2_armhf.deb ... 98s Unpacking libsystemd-shared:armhf (255.2-3ubuntu2) over (253.5-1ubuntu7) ... 98s Setting up libsystemd-shared:armhf (255.2-3ubuntu2) ... 98s (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 ... 59360 files and directories currently installed.) 98s Preparing to unpack .../libsystemd0_255.2-3ubuntu2_armhf.deb ... 98s Unpacking libsystemd0:armhf (255.2-3ubuntu2) over (253.5-1ubuntu7) ... 98s Setting up libsystemd0:armhf (255.2-3ubuntu2) ... 98s Setting up systemd-dev (255.2-3ubuntu2) ... 98s Setting up systemd (255.2-3ubuntu2) ... 98s Installing new version of config file /etc/systemd/journald.conf ... 98s Installing new version of config file /etc/systemd/logind.conf ... 98s Installing new version of config file /etc/systemd/networkd.conf ... 98s Installing new version of config file /etc/systemd/pstore.conf ... 98s Installing new version of config file /etc/systemd/sleep.conf ... 98s Installing new version of config file /etc/systemd/system.conf ... 98s Installing new version of config file /etc/systemd/user.conf ... 100s (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 ... 59360 files and directories currently installed.) 100s Preparing to unpack .../systemd-sysv_255.2-3ubuntu2_armhf.deb ... 100s Unpacking systemd-sysv (255.2-3ubuntu2) over (253.5-1ubuntu7) ... 100s Preparing to unpack .../libpam-systemd_255.2-3ubuntu2_armhf.deb ... 100s Unpacking libpam-systemd:armhf (255.2-3ubuntu2) over (253.5-1ubuntu7) ... 100s Preparing to unpack .../systemd-resolved_255.2-3ubuntu2_armhf.deb ... 100s Unpacking systemd-resolved (255.2-3ubuntu2) over (253.5-1ubuntu7) ... 100s Preparing to unpack .../libapparmor1_4.0.0~alpha4-0ubuntu1_armhf.deb ... 100s Unpacking libapparmor1:armhf (4.0.0~alpha4-0ubuntu1) over (4.0.0~alpha2-0ubuntu8) ... 100s Preparing to unpack .../libseccomp2_2.5.5-1ubuntu1_armhf.deb ... 100s Unpacking libseccomp2:armhf (2.5.5-1ubuntu1) over (2.5.4-2ubuntu2) ... 100s Setting up libseccomp2:armhf (2.5.5-1ubuntu1) ... 100s (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 ... 59362 files and directories currently installed.) 100s Preparing to unpack .../libselinux1_3.5-2build1_armhf.deb ... 100s Unpacking libselinux1:armhf (3.5-2build1) over (3.5-1build2) ... 101s Setting up libselinux1:armhf (3.5-2build1) ... 101s (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 ... 59363 files and directories currently installed.) 101s Preparing to unpack .../libudev1_255.2-3ubuntu2_armhf.deb ... 101s Unpacking libudev1:armhf (255.2-3ubuntu2) over (253.5-1ubuntu7) ... 101s Setting up libudev1:armhf (255.2-3ubuntu2) ... 101s (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 ... 59363 files and directories currently installed.) 101s Preparing to unpack .../libapt-pkg6.0_2.7.11_armhf.deb ... 101s Unpacking libapt-pkg6.0:armhf (2.7.11) over (2.7.10) ... 101s Setting up libapt-pkg6.0:armhf (2.7.11) ... 101s (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 ... 59363 files and directories currently installed.) 101s Preparing to unpack .../archives/apt_2.7.11_armhf.deb ... 101s Unpacking apt (2.7.11) over (2.7.10) ... 101s Setting up apt (2.7.11) ... 103s (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 ... 59363 files and directories currently installed.) 103s Preparing to unpack .../00-apt-utils_2.7.11_armhf.deb ... 103s Unpacking apt-utils (2.7.11) over (2.7.10) ... 103s Preparing to unpack .../01-python3.11_3.11.8-1_armhf.deb ... 103s Unpacking python3.11 (3.11.8-1) over (3.11.7-2) ... 103s Preparing to unpack .../02-libpython3.11-stdlib_3.11.8-1_armhf.deb ... 103s Unpacking libpython3.11-stdlib:armhf (3.11.8-1) over (3.11.7-2) ... 103s Preparing to unpack .../03-python3.11-minimal_3.11.8-1_armhf.deb ... 103s Unpacking python3.11-minimal (3.11.8-1) over (3.11.7-2) ... 104s Preparing to unpack .../04-libpython3.11-minimal_3.11.8-1_armhf.deb ... 104s Unpacking libpython3.11-minimal:armhf (3.11.8-1) over (3.11.7-2) ... 104s Preparing to unpack .../05-tzdata_2024a-1ubuntu1_all.deb ... 104s Unpacking tzdata (2024a-1ubuntu1) over (2023d-1ubuntu2) ... 104s Preparing to unpack .../06-libsqlite3-0_3.45.1-1_armhf.deb ... 104s Unpacking libsqlite3-0:armhf (3.45.1-1) over (3.45.0-1) ... 104s Preparing to unpack .../07-python-apt-common_2.7.5_all.deb ... 104s Unpacking python-apt-common (2.7.5) over (2.7.0) ... 105s Preparing to unpack .../08-python3-apt_2.7.5_armhf.deb ... 105s Unpacking python3-apt (2.7.5) over (2.7.0) ... 105s Preparing to unpack .../09-python3-problem-report_2.27.0-0ubuntu7_all.deb ... 105s Unpacking python3-problem-report (2.27.0-0ubuntu7) over (2.27.0-0ubuntu6) ... 105s Preparing to unpack .../10-python3-apport_2.27.0-0ubuntu7_all.deb ... 106s Unpacking python3-apport (2.27.0-0ubuntu7) over (2.27.0-0ubuntu6) ... 106s Preparing to unpack .../11-apport_2.27.0-0ubuntu7_all.deb ... 106s Unpacking apport (2.27.0-0ubuntu7) over (2.27.0-0ubuntu6) ... 106s Selecting previously unselected package apport-core-dump-handler. 106s Preparing to unpack .../12-apport-core-dump-handler_2.27.0-0ubuntu7_all.deb ... 106s Unpacking apport-core-dump-handler (2.27.0-0ubuntu7) ... 106s Preparing to unpack .../13-libcap-ng0_0.8.4-2_armhf.deb ... 106s Unpacking libcap-ng0:armhf (0.8.4-2) over (0.8.4-1) ... 106s Setting up libcap-ng0:armhf (0.8.4-2) ... 106s (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 ... 59366 files and directories currently installed.) 106s Preparing to unpack .../libsemanage-common_3.5-1build2_all.deb ... 106s Unpacking libsemanage-common (3.5-1build2) over (3.5-1build1) ... 106s Setting up libsemanage-common (3.5-1build2) ... 106s (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 ... 59366 files and directories currently installed.) 106s Preparing to unpack .../libsemanage2_3.5-1build2_armhf.deb ... 106s Unpacking libsemanage2:armhf (3.5-1build2) over (3.5-1build1) ... 106s Setting up libsemanage2:armhf (3.5-1build2) ... 106s (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 ... 59366 files and directories currently installed.) 106s Preparing to unpack .../libidn2-0_2.3.7-2_armhf.deb ... 106s Unpacking libidn2-0:armhf (2.3.7-2) over (2.3.4-1build1) ... 107s Setting up libidn2-0:armhf (2.3.7-2) ... 107s (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 ... 59366 files and directories currently installed.) 107s Preparing to unpack .../install-info_7.1-3_armhf.deb ... 107s Unpacking install-info (7.1-3) over (7.1-2) ... 107s Setting up install-info (7.1-3) ... 107s (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 ... 59366 files and directories currently installed.) 107s Preparing to unpack .../00-openssl_3.0.10-1ubuntu4_armhf.deb ... 107s Unpacking openssl (3.0.10-1ubuntu4) over (3.0.10-1ubuntu3) ... 107s Preparing to unpack .../01-ca-certificates_20240203_all.deb ... 107s Unpacking ca-certificates (20240203) over (20230311ubuntu1) ... 107s Preparing to unpack .../02-dhcpcd-base_1%3a10.0.6-1ubuntu1_armhf.deb ... 107s Unpacking dhcpcd-base (1:10.0.6-1ubuntu1) over (1:10.0.6-1) ... 107s Preparing to unpack .../03-iptables_1.8.10-3ubuntu1_armhf.deb ... 107s Unpacking iptables (1.8.10-3ubuntu1) over (1.8.9-2ubuntu2) ... 108s Preparing to unpack .../04-libip6tc2_1.8.10-3ubuntu1_armhf.deb ... 108s Unpacking libip6tc2:armhf (1.8.10-3ubuntu1) over (1.8.9-2ubuntu2) ... 108s Preparing to unpack .../05-libxtables12_1.8.10-3ubuntu1_armhf.deb ... 108s Unpacking libxtables12:armhf (1.8.10-3ubuntu1) over (1.8.9-2ubuntu2) ... 108s Preparing to unpack .../06-libip4tc2_1.8.10-3ubuntu1_armhf.deb ... 108s Unpacking libip4tc2:armhf (1.8.10-3ubuntu1) over (1.8.9-2ubuntu2) ... 108s Preparing to unpack .../07-libkeyutils1_1.6.3-3_armhf.deb ... 108s Unpacking libkeyutils1:armhf (1.6.3-3) over (1.6.3-2) ... 108s Preparing to unpack .../08-sudo_1.9.15p5-3ubuntu1_armhf.deb ... 108s Unpacking sudo (1.9.15p5-3ubuntu1) over (1.9.14p2-1ubuntu1) ... 108s Preparing to unpack .../09-ubuntu-advantage-tools_31.1_all.deb ... 108s Unpacking ubuntu-advantage-tools (31.1) over (30.1) ... 108s dpkg: warning: unable to delete old directory '/var/lib/ubuntu-advantage': Directory not empty 108s dpkg: warning: unable to delete old directory '/etc/update-manager/release-upgrades.d': Directory not empty 108s dpkg: warning: unable to delete old directory '/etc/update-manager': Directory not empty 108s dpkg: warning: unable to delete old directory '/etc/ubuntu-advantage': Directory not empty 109s Selecting previously unselected package ubuntu-pro-client. 109s Preparing to unpack .../10-ubuntu-pro-client_31.1_armhf.deb ... 109s Unpacking ubuntu-pro-client (31.1) ... 109s Preparing to unpack .../11-ubuntu-pro-client-l10n_31.1_armhf.deb ... 109s Unpacking ubuntu-pro-client-l10n (31.1) over (30.1) ... 109s Preparing to unpack .../12-vim-tiny_2%3a9.1.0016-1ubuntu2_armhf.deb ... 109s Unpacking vim-tiny (2:9.1.0016-1ubuntu2) over (2:9.1.0-1ubuntu1) ... 109s Preparing to unpack .../13-vim-common_2%3a9.1.0016-1ubuntu2_all.deb ... 109s Unpacking vim-common (2:9.1.0016-1ubuntu2) over (2:9.1.0-1ubuntu1) ... 109s Preparing to unpack .../14-xxd_2%3a9.1.0016-1ubuntu2_armhf.deb ... 109s Unpacking xxd (2:9.1.0016-1ubuntu2) over (2:9.1.0-1ubuntu1) ... 109s Preparing to unpack .../15-apparmor_4.0.0~alpha4-0ubuntu1_armhf.deb ... 112s Unpacking apparmor (4.0.0~alpha4-0ubuntu1) over (4.0.0~alpha2-0ubuntu8) ... 113s Preparing to unpack .../16-info_7.1-3_armhf.deb ... 113s Unpacking info (7.1-3) over (7.1-2) ... 113s Preparing to unpack .../17-libdrm-common_2.4.120-2_all.deb ... 113s Unpacking libdrm-common (2.4.120-2) over (2.4.117-1) ... 113s Preparing to unpack .../18-libdrm2_2.4.120-2_armhf.deb ... 113s Unpacking libdrm2:armhf (2.4.120-2) over (2.4.117-1) ... 113s Preparing to unpack .../19-libnghttp2-14_1.59.0-1_armhf.deb ... 113s Unpacking libnghttp2-14:armhf (1.59.0-1) over (1.58.0-1) ... 113s Preparing to unpack .../20-libplymouth5_24.004.60-1ubuntu1_armhf.deb ... 113s Unpacking libplymouth5:armhf (24.004.60-1ubuntu1) over (23.360.11-0ubuntu3) ... 113s Preparing to unpack .../21-libuv1_1.46.0-3ubuntu1_armhf.deb ... 113s Unpacking libuv1:armhf (1.46.0-3ubuntu1) over (1.46.0-3) ... 113s Preparing to unpack .../22-plymouth-theme-ubuntu-text_24.004.60-1ubuntu1_armhf.deb ... 113s Unpacking plymouth-theme-ubuntu-text (24.004.60-1ubuntu1) over (23.360.11-0ubuntu3) ... 113s Preparing to unpack .../23-plymouth_24.004.60-1ubuntu1_armhf.deb ... 113s Unpacking plymouth (24.004.60-1ubuntu1) over (23.360.11-0ubuntu3) ... 113s Preparing to unpack .../24-psmisc_23.6-2_armhf.deb ... 113s Unpacking psmisc (23.6-2) over (23.6-1) ... 114s Preparing to unpack .../25-ufw_0.36.2-5_all.deb ... 114s Unpacking ufw (0.36.2-5) over (0.36.2-3) ... 114s dpkg: warning: unable to delete old directory '/lib/ufw': Directory not empty 114s Preparing to unpack .../26-usb.ids_2024.01.30-1_all.deb ... 114s Unpacking usb.ids (2024.01.30-1) over (2024.01.20-1) ... 114s Preparing to unpack .../27-tmux_3.4-1_armhf.deb ... 114s Unpacking tmux (3.4-1) over (3.3a-5) ... 114s Preparing to unpack .../28-byobu_6.11-0ubuntu1_all.deb ... 114s Unpacking byobu (6.11-0ubuntu1) over (6.10-0ubuntu1) ... 114s Preparing to unpack .../29-libldap2_2.6.7+dfsg-1~exp1ubuntu1_armhf.deb ... 114s Unpacking libldap2:armhf (2.6.7+dfsg-1~exp1ubuntu1) over (2.6.6+dfsg-1~exp1ubuntu1) ... 114s Preparing to unpack .../30-curl_8.5.0-2ubuntu2_armhf.deb ... 114s Unpacking curl (8.5.0-2ubuntu2) over (8.5.0-2ubuntu1) ... 115s Preparing to unpack .../31-libcurl4_8.5.0-2ubuntu2_armhf.deb ... 115s Unpacking libcurl4:armhf (8.5.0-2ubuntu2) over (8.5.0-2ubuntu1) ... 115s Preparing to unpack .../32-dracut-install_060+5-1ubuntu2_armhf.deb ... 115s Unpacking dracut-install (060+5-1ubuntu2) over (059-4ubuntu2) ... 115s Preparing to unpack .../33-ethtool_1%3a6.7-1_armhf.deb ... 115s Unpacking ethtool (1:6.7-1) over (1:6.6-1ubuntu1) ... 115s Preparing to unpack .../34-libcurl3-gnutls_8.5.0-2ubuntu2_armhf.deb ... 115s Unpacking libcurl3-gnutls:armhf (8.5.0-2ubuntu2) over (8.5.0-2ubuntu1) ... 115s Preparing to unpack .../35-libldap-common_2.6.7+dfsg-1~exp1ubuntu1_all.deb ... 115s Unpacking libldap-common (2.6.7+dfsg-1~exp1ubuntu1) over (2.6.6+dfsg-1~exp1ubuntu1) ... 115s Preparing to unpack .../36-python3-idna_3.6-2_all.deb ... 115s Unpacking python3-idna (3.6-2) over (3.3-2) ... 115s Preparing to unpack .../37-python3-markupsafe_2.1.5-1_armhf.deb ... 115s Unpacking python3-markupsafe (2.1.5-1) over (2.1.3-1build1) ... 115s Preparing to unpack .../38-python3-jinja2_3.1.2-1ubuntu1_all.deb ... 115s Unpacking python3-jinja2 (3.1.2-1ubuntu1) over (3.1.2-1) ... 115s Preparing to unpack .../39-python3-urllib3_2.0.7-1_all.deb ... 116s Unpacking python3-urllib3 (2.0.7-1) over (1.26.18-1) ... 116s Setting up libip4tc2:armhf (1.8.10-3ubuntu1) ... 116s Setting up libtext-iconv-perl:armhf (1.7-8build1) ... 116s Setting up libtext-charwidth-perl:armhf (0.04-11build1) ... 116s Setting up systemd-sysv (255.2-3ubuntu2) ... 116s Setting up libkeyutils1:armhf (1.6.3-3) ... 116s Setting up libapparmor1:armhf (4.0.0~alpha4-0ubuntu1) ... 116s Setting up apt-utils (2.7.11) ... 116s Setting up psmisc (23.6-2) ... 116s Setting up python3-problem-report (2.27.0-0ubuntu7) ... 116s Setting up libip6tc2:armhf (1.8.10-3ubuntu1) ... 116s Setting up libsqlite3-0:armhf (3.45.1-1) ... 116s Setting up libnghttp2-14:armhf (1.59.0-1) ... 116s Setting up libnss-systemd:armhf (255.2-3ubuntu2) ... 116s Setting up libldap-common (2.6.7+dfsg-1~exp1ubuntu1) ... 116s Setting up python3-markupsafe (2.1.5-1) ... 116s Setting up xxd (2:9.1.0016-1ubuntu2) ... 116s Setting up tzdata (2024a-1ubuntu1) ... 117s 117s Current default time zone: 'Etc/UTC' 117s Local time is now: Sun Feb 18 23:35:45 UTC 2024. 117s Universal Time is now: Sun Feb 18 23:35:45 UTC 2024. 117s Run 'dpkg-reconfigure tzdata' if you wish to change it. 117s 117s Setting up apparmor (4.0.0~alpha4-0ubuntu1) ... 117s Installing new version of config file /etc/apparmor.d/abstractions/audio ... 117s Installing new version of config file /etc/apparmor.d/abstractions/nameservice ... 117s Installing new version of config file /etc/apparmor.d/abstractions/snap_browsers ... 117s Installing new version of config file /etc/apparmor.d/abstractions/ubuntu-browsers.d/kde ... 117s Installing new version of config file /etc/apparmor.d/abstractions/wutmp ... 117s Installing new version of config file /etc/apparmor.d/local/README ... 117s Installing new version of config file /etc/apparmor.d/steam ... 117s apparmor_parser: Unable to replace "lsb_release". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 117s 117s apparmor_parser: Unable to replace "kmod". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 117s 117s apparmor_parser: Unable to replace "nvidia_modprobe". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 117s 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.ch-checkns ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.ch-run ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.crun ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.flatpak ... 117s Removing obsolete conffile /etc/apparmor.d/usr.libexec.multiarch.bazel.linux-sandbox ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.busybox ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.buildah ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.cam ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.ipa_verify ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.lc-compliance ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.libcamerify ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.qcam ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.podman ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.lxc-attach ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.lxc-create ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.lxc-destroy ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.lxc-execute ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.lxc-stop ... 117s Removing obsolete conffile /etc/apparmor.d/usr.bin.lxc-unshare ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.lxc-usernsexec ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.mmdebstrap ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.vpnns ... 118s Removing obsolete conffile /etc/apparmor.d/usr.lib.qt6.libexec.QtWebEngineProcess ... 118s Removing obsolete conffile /etc/apparmor.d/usr.lib.multiarch.qt5.libexec.QtWebEngineProcess ... 118s Removing obsolete conffile /etc/apparmor.d/usr.lib.systemd.systemd-coredump ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.rootlesskit ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.rpm ... 118s Removing obsolete conffile /etc/apparmor.d/usr.sbin.runc ... 118s Removing obsolete conffile /etc/apparmor.d/usr.libexec.virtiofsd ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild-abort ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild-apt ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild-checkpackages ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild-clean ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild-createchroot ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild-distupgrade ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild-hold ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild-shell ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild-unhold ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild-update ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.sbuild-upgrade ... 118s Removing obsolete conffile /etc/apparmor.d/usr.sbin.sbuild-adduser ... 118s Removing obsolete conffile /etc/apparmor.d/usr.sbin.sbuild-destroychroot ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.slirp4netns ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.stress-ng ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.thunderbird ... 118s Removing obsolete conffile /etc/apparmor.d/bin.toybox ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.trinity ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.tup ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.userbindmount ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.uwsgi-core ... 118s Removing obsolete conffile /etc/apparmor.d/usr.bin.vdens ... 118s Removing obsolete conffile /etc/apparmor.d/opt.google.chrome.chrome ... 118s Removing obsolete conffile /etc/apparmor.d/opt.microsoft.msedge.msedge ... 118s Removing obsolete conffile /etc/apparmor.d/opt.brave.com.brave.brave ... 118s Removing obsolete conffile /etc/apparmor.d/opt.vivaldi.vivaldi-bin ... 119s sysctl: cannot stat /proc/sys/kernel/apparmor_restrict_unprivileged_userns: No such file or directory 119s Reloading AppArmor profiles 119s /sbin/apparmor_parser: Unable to replace "1password". /sbin/apparmor_parser: Unable to replace "Discord". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "MongoDB Compass". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "QtWebEngineProcess". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "brave". /sbin/apparmor_parser: Unable to replace "buildah". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "busybox". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "ch-checkns". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "cam". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "ch-run". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "chrome". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "code". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "crun". /sbin/apparmor_parser: Unable to replace "epiphany". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "devhelp". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "evolution". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "firefox". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "github-desktop". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "flatpak". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "keybase". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "lc-compliance". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "ipa_verify". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "linux-sandbox". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "libcamerify". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "lxc-attach". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "lxc-destroy". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "lxc-execute". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "lxc-create". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "lxc-stop". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "lxc-unshare". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "lxc-usernsexec". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "msedge". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "obsidian". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "opera". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "opam". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "qcam". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "rootlesskit". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "rpm". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "runc". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "polypane". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "podman". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-abort". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-adduser". /sbin/apparmor_parser: Unable to replace "mmdebstrap". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "QtWebEngineProcess". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "plasmashell". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-apt". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-checkpackages". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-clean". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-createchroot". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-hold". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-shell". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-distupgrade". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-unhold". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "signal-desktop". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-upgrade". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-update". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "slack". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "steam". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "stress-ng". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "sbuild-destroychroot". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "slirp4netns". /sbin/apparmor_parser: Unable to replace "systemd-coredump". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "surfshark". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "thunderbird". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "trinity". /sbin/apparmor_parser: Unable to replace "tup". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "lsb_release". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "userbindmount". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "toybox". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "unprivileged_userns". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "uwsgi-core". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "vdens". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "virtiofsd". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "vpnns". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "wpcom". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "kmod". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "nvidia_modprobe". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "vivaldi-bin". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "rsyslogd". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "/usr/bin/man". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "/usr/lib/NetworkManager/nm-dhcp-client.action". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s /sbin/apparmor_parser: Unable to replace "tcpdump". /sbin/apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 119s 119s Error: At least one profile failed to load 119s Setting up python3-jinja2 (3.1.2-1ubuntu1) ... 120s Setting up libuv1:armhf (1.46.0-3ubuntu1) ... 120s Setting up vim-common (2:9.1.0016-1ubuntu2) ... 120s Setting up perl-modules-5.38 (5.38.2-3) ... 120s Setting up systemd-timesyncd (255.2-3ubuntu2) ... 120s Installing new version of config file /etc/dhcp/dhclient-exit-hooks.d/timesyncd ... 120s Installing new version of config file /etc/systemd/timesyncd.conf ... 121s Setting up udev (255.2-3ubuntu2) ... 122s Removing obsolete conffile /etc/init.d/udev ... 123s Setting up python3-idna (3.6-2) ... 123s Setting up usb.ids (2024.01.30-1) ... 123s Setting up sudo (1.9.15p5-3ubuntu1) ... 123s Setting up dhcpcd-base (1:10.0.6-1ubuntu1) ... 123s Setting up libxtables12:armhf (1.8.10-3ubuntu1) ... 123s Setting up python3-urllib3 (2.0.7-1) ... 123s Setting up python-apt-common (2.7.5) ... 123s Setting up dracut-install (060+5-1ubuntu2) ... 123s Setting up libplymouth5:armhf (24.004.60-1ubuntu1) ... 123s Setting up libpam-systemd:armhf (255.2-3ubuntu2) ... 124s Setting up libpython3.11-minimal:armhf (3.11.8-1) ... 124s Setting up openssl (3.0.10-1ubuntu4) ... 124s Setting up libdrm-common (2.4.120-2) ... 124s Setting up tmux (3.4-1) ... 124s Setting up libldap2:armhf (2.6.7+dfsg-1~exp1ubuntu1) ... 124s Setting up info (7.1-3) ... 124s Setting up liblocale-gettext-perl (1.07-6build1) ... 124s Setting up ethtool (1:6.7-1) ... 124s Setting up systemd-resolved (255.2-3ubuntu2) ... 124s Installing new version of config file /etc/systemd/resolved.conf ... 125s Setting up python3.11-minimal (3.11.8-1) ... 127s Setting up byobu (6.11-0ubuntu1) ... 127s Setting up iptables (1.8.10-3ubuntu1) ... 127s Setting up python3-apt (2.7.5) ... 127s Setting up libpython3.11-stdlib:armhf (3.11.8-1) ... 127s Setting up libperl5.38:armhf (5.38.2-3) ... 127s Setting up libcurl3-gnutls:armhf (8.5.0-2ubuntu2) ... 127s Setting up ufw (0.36.2-5) ... 127s Installing new version of config file /etc/init.d/ufw ... 129s Setting up vim-tiny (2:9.1.0016-1ubuntu2) ... 129s Setting up python3-apport (2.27.0-0ubuntu7) ... 130s Setting up ca-certificates (20240203) ... 133s Updating certificates in /etc/ssl/certs... 136s rehash: warning: skipping ca-certificates.crt,it does not contain exactly one certificate or CRL 136s 14 added, 5 removed; done. 136s Setting up perl (5.38.2-3) ... 136s Setting up libdrm2:armhf (2.4.120-2) ... 136s Setting up libcurl4:armhf (8.5.0-2ubuntu2) ... 136s Setting up curl (8.5.0-2ubuntu2) ... 136s Setting up ubuntu-pro-client (31.1) ... 136s Installing new version of config file /etc/apt/preferences.d/ubuntu-pro-esm-apps ... 136s Installing new version of config file /etc/apt/preferences.d/ubuntu-pro-esm-infra ... 136s Installing new version of config file /etc/update-manager/release-upgrades.d/ubuntu-advantage-upgrades.cfg ... 136s apparmor_parser: Unable to replace "ubuntu_pro_apt_news". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 136s 139s Setting up ubuntu-pro-client-l10n (31.1) ... 139s Setting up python3.11 (3.11.8-1) ... 140s Setting up plymouth (24.004.60-1ubuntu1) ... 140s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 141s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 141s Setting up plymouth-theme-ubuntu-text (24.004.60-1ubuntu1) ... 141s update-initramfs: deferring update (trigger activated) 141s Setting up ubuntu-advantage-tools (31.1) ... 144s Setting up apport-core-dump-handler (2.27.0-0ubuntu7) ... 146s Setting up apport (2.27.0-0ubuntu7) ... 147s apport-autoreport.service is a disabled or a static unit not running, not starting it. 147s Processing triggers for dbus (1.14.10-3ubuntu1) ... 147s Processing triggers for debianutils (5.16) ... 148s Processing triggers for install-info (7.1-3) ... 148s Processing triggers for initramfs-tools (0.142ubuntu19) ... 148s Processing triggers for libc-bin (2.38-3ubuntu1) ... 148s Processing triggers for rsyslog (8.2312.0-3ubuntu2) ... 148s Processing triggers for systemd (255.2-3ubuntu2) ... 148s Processing triggers for man-db (2.12.0-3) ... 150s Processing triggers for ca-certificates (20240203) ... 150s Updating certificates in /etc/ssl/certs... 152s 0 added, 0 removed; done. 152s Running hooks in /etc/ca-certificates/update.d... 152s done. 156s Reading package lists... 157s Building dependency tree... 157s Reading state information... 158s The following packages will be REMOVED: 158s libperl5.36* perl-modules-5.36* 158s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 158s After this operation, 39.8 MB disk space will be freed. 159s (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 ... 59387 files and directories currently installed.) 159s Removing libperl5.36:armhf (5.36.0-10ubuntu1) ... 159s Removing perl-modules-5.36 (5.36.0-10ubuntu1) ... 159s Processing triggers for man-db (2.12.0-3) ... 159s Processing triggers for libc-bin (2.38-3ubuntu1) ... 162s autopkgtest [23:36:30]: rebooting testbed after setup commands that affected boot 187s autopkgtest [23:36:55]: testbed running kernel: Linux 5.4.0-170-generic #188-Ubuntu SMP Wed Jan 10 09:51:10 UTC 2024 190s autopkgtest [23:36:58]: testbed dpkg architecture: armhf 203s autopkgtest [23:37:11]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 209s Get:1 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.1 (dsc) [1420 B] 209s Get:2 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.1 (tar) [1763 kB] 210s gpgv: Signature made Mon Feb 5 09:51:14 2024 UTC 210s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 210s gpgv: Can't check signature: No public key 210s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.1.dsc: no acceptable signature found 210s autopkgtest [23:37:18]: testing package wsl-pro-service version 0.1.1 210s autopkgtest [23:37:18]: build not needed 264s autopkgtest [23:38:12]: test command1: preparing testbed 276s Reading package lists... 277s Building dependency tree... 277s Reading state information... 277s Correcting dependencies...Starting pkgProblemResolver with broken count: 0 278s Starting 2 pkgProblemResolver with broken count: 0 278s Done 278s Done 279s Starting pkgProblemResolver with broken count: 0 279s Starting 2 pkgProblemResolver with broken count: 0 279s Done 281s The following additional packages will be installed: 281s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 281s cpp-13-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper debugedit 281s dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 281s g++-13-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-13 281s gcc-13-arm-linux-gnueabihf gcc-arm-linux-gnueabihf gettext golang-1.21-go 281s golang-1.21-src golang-go golang-src intltool-debian libarchive-zip-perl 281s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 281s libdebhelper-perl libdw1 libfile-stripnondeterminism-perl libgcc-13-dev 281s libgomp1 libisl23 libmpc3 libnsl-dev libstdc++-13-dev libsub-override-perl 281s libtirpc-dev libtool libubsan1 linux-libc-dev m4 po-debconf rpcsvc-proto 281s Suggested packages: 281s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 281s cpp-13-doc dh-make gcc-13-doc gcc-multilib manpages-dev flex bison gdb 281s gcc-doc gdb-arm-linux-gnueabihf gettext-doc libasprintf-dev libgettextpo-dev 281s bzr | brz git mercurial subversion glibc-doc libstdc++-13-doc libtool-doc 281s gfortran | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 281s Recommended packages: 281s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 281s libltdl-dev libmail-sendmail-perl 281s The following NEW packages will be installed: 281s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 281s cpp-13-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper debugedit 281s dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 281s g++-13-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-13 281s gcc-13-arm-linux-gnueabihf gcc-arm-linux-gnueabihf gettext golang-1.21-go 281s golang-1.21-src golang-go golang-src intltool-debian libarchive-zip-perl 281s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 281s libdebhelper-perl libdw1 libfile-stripnondeterminism-perl libgcc-13-dev 281s libgomp1 libisl23 libmpc3 libnsl-dev libstdc++-13-dev libsub-override-perl 281s libtirpc-dev libtool libubsan1 linux-libc-dev m4 po-debconf rpcsvc-proto 281s 0 upgraded, 54 newly installed, 0 to remove and 0 not upgraded. 281s 1 not fully installed or removed. 281s Need to get 94.3 MB of archives. 281s After this operation, 371 MB of additional disk space will be used. 281s Get:1 http://ftpmaster.internal/ubuntu noble/main armhf autotools-dev all 20220109.1 [44.9 kB] 281s Get:2 http://ftpmaster.internal/ubuntu noble/main armhf m4 armhf 1.4.19-4 [235 kB] 281s Get:3 http://ftpmaster.internal/ubuntu noble/main armhf autoconf all 2.71-3 [339 kB] 281s Get:4 http://ftpmaster.internal/ubuntu noble/main armhf automake all 1:1.16.5-1.3ubuntu1 [558 kB] 281s Get:5 http://ftpmaster.internal/ubuntu noble/main armhf autopoint all 0.21-14ubuntu1 [422 kB] 281s Get:6 http://ftpmaster.internal/ubuntu noble/main armhf libdebhelper-perl all 13.13ubuntu1 [88.9 kB] 281s Get:7 http://ftpmaster.internal/ubuntu noble/main armhf libisl23 armhf 0.26-3 [595 kB] 281s Get:8 http://ftpmaster.internal/ubuntu noble/main armhf libmpc3 armhf 1.3.1-1 [46.4 kB] 281s Get:9 http://ftpmaster.internal/ubuntu noble/main armhf cpp-13-arm-linux-gnueabihf armhf 13.2.0-13ubuntu1 [8754 kB] 282s Get:10 http://ftpmaster.internal/ubuntu noble/main armhf cpp-13 armhf 13.2.0-13ubuntu1 [1032 B] 282s Get:11 http://ftpmaster.internal/ubuntu noble/main armhf cpp-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [5320 B] 282s Get:12 http://ftpmaster.internal/ubuntu noble/main armhf cpp armhf 4:13.2.0-7ubuntu1 [22.4 kB] 282s Get:13 http://ftpmaster.internal/ubuntu noble/main armhf libcc1-0 armhf 14-20240201-3ubuntu1 [39.1 kB] 282s Get:14 http://ftpmaster.internal/ubuntu noble/main armhf libgomp1 armhf 14-20240201-3ubuntu1 [124 kB] 282s Get:15 http://ftpmaster.internal/ubuntu noble/main armhf libatomic1 armhf 14-20240201-3ubuntu1 [7802 B] 282s Get:16 http://ftpmaster.internal/ubuntu noble/main armhf libasan8 armhf 14-20240201-3ubuntu1 [2938 kB] 282s Get:17 http://ftpmaster.internal/ubuntu noble/main armhf libubsan1 armhf 14-20240201-3ubuntu1 [1151 kB] 282s Get:18 http://ftpmaster.internal/ubuntu noble/main armhf libgcc-13-dev armhf 13.2.0-13ubuntu1 [901 kB] 282s Get:19 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13-arm-linux-gnueabihf armhf 13.2.0-13ubuntu1 [16.8 MB] 282s Get:20 http://ftpmaster.internal/ubuntu noble/main armhf gcc-13 armhf 13.2.0-13ubuntu1 [443 kB] 282s Get:21 http://ftpmaster.internal/ubuntu noble/main armhf gcc-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [1220 B] 282s Get:22 http://ftpmaster.internal/ubuntu noble/main armhf gcc armhf 4:13.2.0-7ubuntu1 [5022 B] 282s Get:23 http://ftpmaster.internal/ubuntu noble/main armhf libc-dev-bin armhf 2.38-3ubuntu1 [18.9 kB] 282s Get:24 http://ftpmaster.internal/ubuntu noble/main armhf linux-libc-dev armhf 6.6.0-14.14 [1539 kB] 282s Get:25 http://ftpmaster.internal/ubuntu noble/main armhf libcrypt-dev armhf 1:4.4.36-4 [136 kB] 282s Get:26 http://ftpmaster.internal/ubuntu noble/main armhf libtirpc-dev armhf 1.3.4+ds-1build1 [207 kB] 282s Get:27 http://ftpmaster.internal/ubuntu noble/main armhf libnsl-dev armhf 1.3.0-3 [65.8 kB] 282s Get:28 http://ftpmaster.internal/ubuntu noble/main armhf rpcsvc-proto armhf 1.4.2-0ubuntu6 [63.7 kB] 282s Get:29 http://ftpmaster.internal/ubuntu noble/main armhf libc6-dev armhf 2.38-3ubuntu1 [1347 kB] 282s Get:30 http://ftpmaster.internal/ubuntu noble/main armhf libtool all 2.4.7-7 [166 kB] 282s Get:31 http://ftpmaster.internal/ubuntu noble/main armhf dh-autoreconf all 20 [16.1 kB] 282s Get:32 http://ftpmaster.internal/ubuntu noble/main armhf libarchive-zip-perl all 1.68-1 [90.2 kB] 282s Get:33 http://ftpmaster.internal/ubuntu noble/main armhf libsub-override-perl all 0.10-1 [10.0 kB] 282s Get:34 http://ftpmaster.internal/ubuntu noble/main armhf libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 282s Get:35 http://ftpmaster.internal/ubuntu noble/main armhf dh-strip-nondeterminism all 1.13.1-1 [5362 B] 282s Get:36 http://ftpmaster.internal/ubuntu noble/main armhf libdw1 armhf 0.190-1 [235 kB] 282s Get:37 http://ftpmaster.internal/ubuntu noble/main armhf debugedit armhf 1:5.0-5 [42.8 kB] 282s Get:38 http://ftpmaster.internal/ubuntu noble/main armhf dwz armhf 0.15-1 [109 kB] 283s Get:39 http://ftpmaster.internal/ubuntu noble/main armhf gettext armhf 0.21-14ubuntu1 [800 kB] 283s Get:40 http://ftpmaster.internal/ubuntu noble/main armhf intltool-debian all 0.35.0+20060710.6 [23.2 kB] 283s Get:41 http://ftpmaster.internal/ubuntu noble/main armhf po-debconf all 1.0.21+nmu1 [233 kB] 283s Get:42 http://ftpmaster.internal/ubuntu noble/main armhf debhelper all 13.13ubuntu1 [868 kB] 283s Get:43 http://ftpmaster.internal/ubuntu noble/universe armhf dh-apport all 2.27.0-0ubuntu7 [16.7 kB] 283s Get:44 http://ftpmaster.internal/ubuntu noble/main armhf dh-golang all 1.62 [25.2 kB] 283s Get:45 http://ftpmaster.internal/ubuntu noble/main armhf golang-1.21-src all 1.21.6-1 [19.4 MB] 283s Get:46 http://ftpmaster.internal/ubuntu noble/main armhf golang-1.21-go armhf 1.21.6-1 [23.0 MB] 284s Get:47 http://ftpmaster.internal/ubuntu noble/main armhf golang-src all 2:1.21~2 [4836 B] 284s Get:48 http://ftpmaster.internal/ubuntu noble/main armhf golang-go armhf 2:1.21~2 [43.7 kB] 284s Get:49 http://ftpmaster.internal/ubuntu noble/main armhf libstdc++-13-dev armhf 13.2.0-13ubuntu1 [2401 kB] 284s Get:50 http://ftpmaster.internal/ubuntu noble/main armhf g++-13-arm-linux-gnueabihf armhf 13.2.0-13ubuntu1 [9933 kB] 284s Get:51 http://ftpmaster.internal/ubuntu noble/main armhf g++-13 armhf 13.2.0-13ubuntu1 [14.0 kB] 284s Get:52 http://ftpmaster.internal/ubuntu noble/main armhf g++-arm-linux-gnueabihf armhf 4:13.2.0-7ubuntu1 [966 B] 284s Get:53 http://ftpmaster.internal/ubuntu noble/main armhf g++ armhf 4:13.2.0-7ubuntu1 [1090 B] 284s Get:54 http://ftpmaster.internal/ubuntu noble/main armhf build-essential armhf 12.10ubuntu1 [4928 B] 285s Fetched 94.3 MB in 3s (29.1 MB/s) 285s Selecting previously unselected package autotools-dev. 285s (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 ... 57465 files and directories currently installed.) 285s Preparing to unpack .../00-autotools-dev_20220109.1_all.deb ... 285s Unpacking autotools-dev (20220109.1) ... 285s Selecting previously unselected package m4. 285s Preparing to unpack .../01-m4_1.4.19-4_armhf.deb ... 285s Unpacking m4 (1.4.19-4) ... 285s Selecting previously unselected package autoconf. 285s Preparing to unpack .../02-autoconf_2.71-3_all.deb ... 285s Unpacking autoconf (2.71-3) ... 285s Selecting previously unselected package automake. 285s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 286s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 286s Selecting previously unselected package autopoint. 286s Preparing to unpack .../04-autopoint_0.21-14ubuntu1_all.deb ... 286s Unpacking autopoint (0.21-14ubuntu1) ... 286s Selecting previously unselected package libdebhelper-perl. 286s Preparing to unpack .../05-libdebhelper-perl_13.13ubuntu1_all.deb ... 286s Unpacking libdebhelper-perl (13.13ubuntu1) ... 286s Selecting previously unselected package libisl23:armhf. 286s Preparing to unpack .../06-libisl23_0.26-3_armhf.deb ... 286s Unpacking libisl23:armhf (0.26-3) ... 286s Selecting previously unselected package libmpc3:armhf. 286s Preparing to unpack .../07-libmpc3_1.3.1-1_armhf.deb ... 286s Unpacking libmpc3:armhf (1.3.1-1) ... 286s Selecting previously unselected package cpp-13-arm-linux-gnueabihf. 286s Preparing to unpack .../08-cpp-13-arm-linux-gnueabihf_13.2.0-13ubuntu1_armhf.deb ... 286s Unpacking cpp-13-arm-linux-gnueabihf (13.2.0-13ubuntu1) ... 286s Selecting previously unselected package cpp-13. 286s Preparing to unpack .../09-cpp-13_13.2.0-13ubuntu1_armhf.deb ... 286s Unpacking cpp-13 (13.2.0-13ubuntu1) ... 286s Selecting previously unselected package cpp-arm-linux-gnueabihf. 286s Preparing to unpack .../10-cpp-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 286s Unpacking cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 286s Selecting previously unselected package cpp. 286s Preparing to unpack .../11-cpp_4%3a13.2.0-7ubuntu1_armhf.deb ... 286s Unpacking cpp (4:13.2.0-7ubuntu1) ... 286s Selecting previously unselected package libcc1-0:armhf. 286s Preparing to unpack .../12-libcc1-0_14-20240201-3ubuntu1_armhf.deb ... 286s Unpacking libcc1-0:armhf (14-20240201-3ubuntu1) ... 286s Selecting previously unselected package libgomp1:armhf. 286s Preparing to unpack .../13-libgomp1_14-20240201-3ubuntu1_armhf.deb ... 286s Unpacking libgomp1:armhf (14-20240201-3ubuntu1) ... 286s Selecting previously unselected package libatomic1:armhf. 287s Preparing to unpack .../14-libatomic1_14-20240201-3ubuntu1_armhf.deb ... 287s Unpacking libatomic1:armhf (14-20240201-3ubuntu1) ... 287s Selecting previously unselected package libasan8:armhf. 287s Preparing to unpack .../15-libasan8_14-20240201-3ubuntu1_armhf.deb ... 287s Unpacking libasan8:armhf (14-20240201-3ubuntu1) ... 287s Selecting previously unselected package libubsan1:armhf. 287s Preparing to unpack .../16-libubsan1_14-20240201-3ubuntu1_armhf.deb ... 287s Unpacking libubsan1:armhf (14-20240201-3ubuntu1) ... 287s Selecting previously unselected package libgcc-13-dev:armhf. 287s Preparing to unpack .../17-libgcc-13-dev_13.2.0-13ubuntu1_armhf.deb ... 287s Unpacking libgcc-13-dev:armhf (13.2.0-13ubuntu1) ... 287s Selecting previously unselected package gcc-13-arm-linux-gnueabihf. 287s Preparing to unpack .../18-gcc-13-arm-linux-gnueabihf_13.2.0-13ubuntu1_armhf.deb ... 287s Unpacking gcc-13-arm-linux-gnueabihf (13.2.0-13ubuntu1) ... 288s Selecting previously unselected package gcc-13. 288s Preparing to unpack .../19-gcc-13_13.2.0-13ubuntu1_armhf.deb ... 288s Unpacking gcc-13 (13.2.0-13ubuntu1) ... 288s Selecting previously unselected package gcc-arm-linux-gnueabihf. 288s Preparing to unpack .../20-gcc-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 288s Unpacking gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 288s Selecting previously unselected package gcc. 288s Preparing to unpack .../21-gcc_4%3a13.2.0-7ubuntu1_armhf.deb ... 288s Unpacking gcc (4:13.2.0-7ubuntu1) ... 288s Selecting previously unselected package libc-dev-bin. 288s Preparing to unpack .../22-libc-dev-bin_2.38-3ubuntu1_armhf.deb ... 288s Unpacking libc-dev-bin (2.38-3ubuntu1) ... 288s Selecting previously unselected package linux-libc-dev:armhf. 288s Preparing to unpack .../23-linux-libc-dev_6.6.0-14.14_armhf.deb ... 288s Unpacking linux-libc-dev:armhf (6.6.0-14.14) ... 288s Selecting previously unselected package libcrypt-dev:armhf. 288s Preparing to unpack .../24-libcrypt-dev_1%3a4.4.36-4_armhf.deb ... 288s Unpacking libcrypt-dev:armhf (1:4.4.36-4) ... 288s Selecting previously unselected package libtirpc-dev:armhf. 288s Preparing to unpack .../25-libtirpc-dev_1.3.4+ds-1build1_armhf.deb ... 288s Unpacking libtirpc-dev:armhf (1.3.4+ds-1build1) ... 288s Selecting previously unselected package libnsl-dev:armhf. 288s Preparing to unpack .../26-libnsl-dev_1.3.0-3_armhf.deb ... 288s Unpacking libnsl-dev:armhf (1.3.0-3) ... 288s Selecting previously unselected package rpcsvc-proto. 288s Preparing to unpack .../27-rpcsvc-proto_1.4.2-0ubuntu6_armhf.deb ... 288s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 289s Selecting previously unselected package libc6-dev:armhf. 289s Preparing to unpack .../28-libc6-dev_2.38-3ubuntu1_armhf.deb ... 289s Unpacking libc6-dev:armhf (2.38-3ubuntu1) ... 289s Selecting previously unselected package libtool. 289s Preparing to unpack .../29-libtool_2.4.7-7_all.deb ... 289s Unpacking libtool (2.4.7-7) ... 289s Selecting previously unselected package dh-autoreconf. 289s Preparing to unpack .../30-dh-autoreconf_20_all.deb ... 289s Unpacking dh-autoreconf (20) ... 289s Selecting previously unselected package libarchive-zip-perl. 289s Preparing to unpack .../31-libarchive-zip-perl_1.68-1_all.deb ... 289s Unpacking libarchive-zip-perl (1.68-1) ... 289s Selecting previously unselected package libsub-override-perl. 289s Preparing to unpack .../32-libsub-override-perl_0.10-1_all.deb ... 289s Unpacking libsub-override-perl (0.10-1) ... 289s Selecting previously unselected package libfile-stripnondeterminism-perl. 289s Preparing to unpack .../33-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 289s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 289s Selecting previously unselected package dh-strip-nondeterminism. 289s Preparing to unpack .../34-dh-strip-nondeterminism_1.13.1-1_all.deb ... 289s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 289s Selecting previously unselected package libdw1:armhf. 289s Preparing to unpack .../35-libdw1_0.190-1_armhf.deb ... 289s Unpacking libdw1:armhf (0.190-1) ... 289s Selecting previously unselected package debugedit. 289s Preparing to unpack .../36-debugedit_1%3a5.0-5_armhf.deb ... 289s Unpacking debugedit (1:5.0-5) ... 289s Selecting previously unselected package dwz. 289s Preparing to unpack .../37-dwz_0.15-1_armhf.deb ... 289s Unpacking dwz (0.15-1) ... 289s Selecting previously unselected package gettext. 289s Preparing to unpack .../38-gettext_0.21-14ubuntu1_armhf.deb ... 289s Unpacking gettext (0.21-14ubuntu1) ... 290s Selecting previously unselected package intltool-debian. 290s Preparing to unpack .../39-intltool-debian_0.35.0+20060710.6_all.deb ... 290s Unpacking intltool-debian (0.35.0+20060710.6) ... 290s Selecting previously unselected package po-debconf. 290s Preparing to unpack .../40-po-debconf_1.0.21+nmu1_all.deb ... 290s Unpacking po-debconf (1.0.21+nmu1) ... 290s Selecting previously unselected package debhelper. 290s Preparing to unpack .../41-debhelper_13.13ubuntu1_all.deb ... 290s Unpacking debhelper (13.13ubuntu1) ... 290s Selecting previously unselected package dh-apport. 290s Preparing to unpack .../42-dh-apport_2.27.0-0ubuntu7_all.deb ... 290s Unpacking dh-apport (2.27.0-0ubuntu7) ... 290s Selecting previously unselected package dh-golang. 290s Preparing to unpack .../43-dh-golang_1.62_all.deb ... 290s Unpacking dh-golang (1.62) ... 290s Selecting previously unselected package golang-1.21-src. 290s Preparing to unpack .../44-golang-1.21-src_1.21.6-1_all.deb ... 290s Unpacking golang-1.21-src (1.21.6-1) ... 296s Selecting previously unselected package golang-1.21-go. 296s Preparing to unpack .../45-golang-1.21-go_1.21.6-1_armhf.deb ... 296s Unpacking golang-1.21-go (1.21.6-1) ... 297s Selecting previously unselected package golang-src. 297s Preparing to unpack .../46-golang-src_2%3a1.21~2_all.deb ... 297s Unpacking golang-src (2:1.21~2) ... 297s Selecting previously unselected package golang-go:armhf. 297s Preparing to unpack .../47-golang-go_2%3a1.21~2_armhf.deb ... 297s Unpacking golang-go:armhf (2:1.21~2) ... 297s Selecting previously unselected package libstdc++-13-dev:armhf. 297s Preparing to unpack .../48-libstdc++-13-dev_13.2.0-13ubuntu1_armhf.deb ... 297s Unpacking libstdc++-13-dev:armhf (13.2.0-13ubuntu1) ... 298s Selecting previously unselected package g++-13-arm-linux-gnueabihf. 298s Preparing to unpack .../49-g++-13-arm-linux-gnueabihf_13.2.0-13ubuntu1_armhf.deb ... 298s Unpacking g++-13-arm-linux-gnueabihf (13.2.0-13ubuntu1) ... 298s Selecting previously unselected package g++-13. 298s Preparing to unpack .../50-g++-13_13.2.0-13ubuntu1_armhf.deb ... 298s Unpacking g++-13 (13.2.0-13ubuntu1) ... 298s Selecting previously unselected package g++-arm-linux-gnueabihf. 298s Preparing to unpack .../51-g++-arm-linux-gnueabihf_4%3a13.2.0-7ubuntu1_armhf.deb ... 298s Unpacking g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 298s Selecting previously unselected package g++. 298s Preparing to unpack .../52-g++_4%3a13.2.0-7ubuntu1_armhf.deb ... 298s Unpacking g++ (4:13.2.0-7ubuntu1) ... 298s Selecting previously unselected package build-essential. 298s Preparing to unpack .../53-build-essential_12.10ubuntu1_armhf.deb ... 298s Unpacking build-essential (12.10ubuntu1) ... 298s Setting up libdw1:armhf (0.190-1) ... 298s Setting up libarchive-zip-perl (1.68-1) ... 298s Setting up libdebhelper-perl (13.13ubuntu1) ... 298s Setting up linux-libc-dev:armhf (6.6.0-14.14) ... 298s Setting up m4 (1.4.19-4) ... 298s Setting up libgomp1:armhf (14-20240201-3ubuntu1) ... 298s Setting up dh-apport (2.27.0-0ubuntu7) ... 298s Setting up autotools-dev (20220109.1) ... 298s Setting up libtirpc-dev:armhf (1.3.4+ds-1build1) ... 298s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 299s Setting up golang-1.21-src (1.21.6-1) ... 299s Setting up libmpc3:armhf (1.3.1-1) ... 299s Setting up libatomic1:armhf (14-20240201-3ubuntu1) ... 299s Setting up autopoint (0.21-14ubuntu1) ... 299s Setting up autoconf (2.71-3) ... 299s Setting up libubsan1:armhf (14-20240201-3ubuntu1) ... 299s Setting up dwz (0.15-1) ... 299s Setting up libnsl-dev:armhf (1.3.0-3) ... 299s Setting up libcrypt-dev:armhf (1:4.4.36-4) ... 299s Setting up libasan8:armhf (14-20240201-3ubuntu1) ... 299s Setting up debugedit (1:5.0-5) ... 299s Setting up libsub-override-perl (0.10-1) ... 299s Setting up libisl23:armhf (0.26-3) ... 299s Setting up libc-dev-bin (2.38-3ubuntu1) ... 299s Setting up golang-src (2:1.21~2) ... 299s Setting up libcc1-0:armhf (14-20240201-3ubuntu1) ... 299s Setting up automake (1:1.16.5-1.3ubuntu1) ... 299s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 299s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 299s Setting up gettext (0.21-14ubuntu1) ... 299s Setting up cpp-13-arm-linux-gnueabihf (13.2.0-13ubuntu1) ... 299s Setting up golang-1.21-go (1.21.6-1) ... 299s Setting up intltool-debian (0.35.0+20060710.6) ... 299s Setting up dh-strip-nondeterminism (1.13.1-1) ... 299s Setting up libgcc-13-dev:armhf (13.2.0-13ubuntu1) ... 299s Setting up libc6-dev:armhf (2.38-3ubuntu1) ... 299s Setting up cpp-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 299s Setting up libstdc++-13-dev:armhf (13.2.0-13ubuntu1) ... 299s Setting up cpp-13 (13.2.0-13ubuntu1) ... 299s Setting up golang-go:armhf (2:1.21~2) ... 299s Setting up po-debconf (1.0.21+nmu1) ... 299s Setting up gcc-13-arm-linux-gnueabihf (13.2.0-13ubuntu1) ... 299s Setting up gcc-13 (13.2.0-13ubuntu1) ... 299s Setting up cpp (4:13.2.0-7ubuntu1) ... 299s Setting up gcc-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 299s Setting up g++-13-arm-linux-gnueabihf (13.2.0-13ubuntu1) ... 299s Setting up g++-arm-linux-gnueabihf (4:13.2.0-7ubuntu1) ... 299s Setting up g++-13 (13.2.0-13ubuntu1) ... 299s Setting up libtool (2.4.7-7) ... 299s Setting up gcc (4:13.2.0-7ubuntu1) ... 299s Setting up dh-autoreconf (20) ... 299s Setting up g++ (4:13.2.0-7ubuntu1) ... 299s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 299s Setting up build-essential (12.10ubuntu1) ... 299s Setting up debhelper (13.13ubuntu1) ... 299s Setting up dh-golang (1.62) ... 299s Setting up autopkgtest-satdep (0) ... 299s Processing triggers for man-db (2.12.0-3) ... 300s Processing triggers for install-info (7.1-3) ... 300s Processing triggers for libc-bin (2.38-3ubuntu1) ... 312s (Reading database ... 75337 files and directories currently installed.) 312s Removing autopkgtest-satdep (0) ... 321s autopkgtest [23:39:09]: test command1: ./debian/tests/test 321s autopkgtest [23:39:09]: test command1: [----------------------- 363s === RUN TestRunSignal 363s === RUN TestRunSignal/Send_SIGINT_exits 363s === RUN TestRunSignal/Send_SIGTERM_exits 363s --- PASS: TestRunSignal (0.21s) 363s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 363s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 363s === RUN TestRun 363s === PAUSE TestRun 363s === CONT TestRun 363s === RUN TestRun/Run_and_exit_successfully 363s === PAUSE TestRun/Run_and_exit_successfully 363s === RUN TestRun/Run_and_return_error 363s === PAUSE TestRun/Run_and_return_error 363s === RUN TestRun/Run_and_return_usage_error 363s === PAUSE TestRun/Run_and_return_usage_error 363s === RUN TestRun/Run_and_usage_error_only_does_not_fail 363s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 363s === CONT TestRun/Run_and_exit_successfully 363s === CONT TestRun/Run_and_usage_error_only_does_not_fail 363s === CONT TestRun/Run_and_return_usage_error 363s === CONT TestRun/Run_and_return_error 363s ERROR context.Background Error requested 363s ERROR context.Background Error requested 363s --- PASS: TestRun (0.00s) 363s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 363s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 363s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 363s --- PASS: TestRun/Run_and_return_error (0.10s) 363s PASS 363s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.321s 363s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 363s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 379s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/logstreamer/test [no test files] 382s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 433s === RUN TestHelp 433s --- PASS: TestHelp (0.01s) 433s === RUN TestCompletion 433s --- PASS: TestCompletion (0.03s) 433s === RUN TestVersion 433s --- PASS: TestVersion (0.00s) 433s === RUN TestNoUsageError 433s --- PASS: TestNoUsageError (0.00s) 433s === RUN TestUsageError 433s === PAUSE TestUsageError 433s === RUN TestCanQuitWhenExecute 433s === PAUSE TestCanQuitWhenExecute 433s === RUN TestCanQuitTwice 433s === PAUSE TestCanQuitTwice 433s === RUN TestAppCanQuitWithoutExecute 433s === PAUSE TestAppCanQuitWithoutExecute 433s === RUN TestAppRunFailsOnComponentsCreationAndQuit 433s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 433s === RUN TestAppGetRootCmd 433s === PAUSE TestAppGetRootCmd 433s === RUN TestWithProMock 433s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 433s --- SKIP: TestWithProMock (0.00s) 433s === RUN TestWithWslPathMock 433s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 433s --- SKIP: TestWithWslPathMock (0.00s) 433s === RUN TestWithWslInfoMock 433s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 433s --- SKIP: TestWithWslInfoMock (0.00s) 433s === RUN TestWithCmdExeMock 433s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 433s --- SKIP: TestWithCmdExeMock (0.00s) 433s === CONT TestUsageError 433s --- PASS: TestUsageError (0.00s) 433s === CONT TestAppGetRootCmd 433s === CONT TestAppCanQuitWithoutExecute 433s service_test.go:138: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 433s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 433s === CONT TestAppRunFailsOnComponentsCreationAndQuit 433s === CONT TestCanQuitTwice 433s === CONT TestCanQuitWhenExecute 433s time="2024-02-18T23:39:51Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 433s time="2024-02-18T23:39:51Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:63 New() Building new daemon" 433s --- PASS: TestAppGetRootCmd (0.01s) 433s time="2024-02-18T23:39:51Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/testutils/mock_agent.go:82 MockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36989\"" 433s time="2024-02-18T23:39:51Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 433s time="2024-02-18T23:39:51Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:63 New() Building new daemon" 433s time="2024-02-18T23:39:51Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/testutils/mock_agent.go:82 MockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43329\"" 433s time="2024-02-18T23:40:23Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 433s time="2024-02-18T23:40:23Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 433s time="2024-02-18T23:40:28Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 433s time="2024-02-18T23:40:28Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 433s time="2024-02-18T23:40:40Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 433s time="2024-02-18T23:40:40Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 433s time="2024-02-18T23:40:40Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 433s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (48.95s) 433s time="2024-02-18T23:40:43Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/controlstream/session.go:23 newSession() Connecting to control stream at \"127.0.0.1:43329\"" 433s time="2024-02-18T23:40:43Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/controlstream/controlstream.go:69 (*ControlStream).Connect() Connected to Windows agent via the control stream" 433s time="2024-02-18T23:40:43Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/testutils/mock_agent.go:136 (*wslInstanceMockService).Connected() wslInstanceMockService: Received incoming connection" 433s time="2024-02-18T23:40:48Z" level=error msg="/tmp/autopkgtest.OiKSDG/build.IaB/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/TestCanQuitWhenExecute839701498/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestCanQuitWhenExecute839701498/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 433s time="2024-02-18T23:40:48Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 433s time="2024-02-18T23:40:48Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 433s time="2024-02-18T23:40:48Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 433s time="2024-02-18T23:40:48Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 433s --- PASS: TestCanQuitWhenExecute (56.88s) 433s time="2024-02-18T23:41:01Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/testutils/mock_agent.go:150 (*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\"" 433s time="2024-02-18T23:41:01Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/testutils/mock_agent.go:178 (*wslInstanceMockService).Connected() wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 37419" 433s time="2024-02-18T23:41:01Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/controlstream/controlstream.go:76 (*ControlStream).Connect() Completed handshake with Windows agent via the control stream" 433s time="2024-02-18T23:41:01Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:176 (*Daemon).serveOnce() Connected to control stream" 433s time="2024-02-18T23:41:01Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/wslinstanceservice/wslinstanceservice.go:41 (*Service).RegisterGRPCService() Registering GRPC WSL instance service" 433s time="2024-02-18T23:41:01Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:223 (*Daemon).serve() Starting to serve requests" 433s time="2024-02-18T23:41:01Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:233 (*Daemon).serve() Serving GRPC requests on localhost:37419" 433s time="2024-02-18T23:41:01Z" level=error msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:142 (*Daemon).Serve() serve error: WSL Pro Service stopped serving: grpc error: grpc: the server has been stopped" 433s time="2024-02-18T23:41:01Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 433s time="2024-02-18T23:41:01Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 433s time="2024-02-18T23:41:01Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 433s time="2024-02-18T23:41:01Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 433s time="2024-02-18T23:41:01Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 433s time="2024-02-18T23:41:01Z" level=info msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 433s time="2024-02-18T23:41:01Z" level=debug msg="/tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 433s --- PASS: TestCanQuitTwice (69.45s) 433s PASS 433s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 69.517s 470s === RUN TestNew 470s === PAUSE TestNew 470s === RUN TestConnect 470s === PAUSE TestConnect 470s === RUN TestSend 470s === PAUSE TestSend 470s === RUN TestReconnection 470s === PAUSE TestReconnection 470s === RUN TestWithProMock 470s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 470s --- SKIP: TestWithProMock (0.00s) 470s === RUN TestWithWslPathMock 470s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 470s --- SKIP: TestWithWslPathMock (0.00s) 470s === RUN TestWithWslInfoMock 470s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 470s --- SKIP: TestWithWslInfoMock (0.00s) 470s === RUN TestWithCmdExeMock 470s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 470s --- SKIP: TestWithCmdExeMock (0.00s) 470s === CONT TestNew 470s === RUN TestNew/Success 470s === PAUSE TestNew/Success 470s === RUN TestNew/Error_when_the_context_is_cancelled 470s === PAUSE TestNew/Error_when_the_context_is_cancelled 470s === RUN TestNew/Error_when_WslPath_returns_error 470s === PAUSE TestNew/Error_when_WslPath_returns_error 470s === CONT TestNew/Success 470s === CONT TestSend 470s === CONT TestNew/Error_when_the_context_is_cancelled 470s === CONT TestNew/Error_when_WslPath_returns_error 470s === CONT TestConnect 470s === RUN TestConnect/Success 470s === PAUSE TestConnect/Success 470s === RUN TestConnect/No_connection_because_port_file_does_not_exist 470s === PAUSE TestConnect/No_connection_because_port_file_does_not_exist 470s === RUN TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 470s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 470s === RUN TestConnect/No_connection_because_of_unreadable_port_file 470s === PAUSE TestConnect/No_connection_because_of_unreadable_port_file 470s === RUN TestConnect/No_connection_because_of_empty_port_file 470s === PAUSE TestConnect/No_connection_because_of_empty_port_file 470s === RUN TestConnect/No_connection_because_of_port_file_with_invalid_contents 470s === PAUSE TestConnect/No_connection_because_of_port_file_with_invalid_contents 470s === RUN TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 470s === PAUSE TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 470s === RUN TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 470s === PAUSE TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 470s === RUN TestConnect/Incomplete_handshake_because_Agent_never_receives 470s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_receives 470s === RUN TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 470s === PAUSE TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 470s === CONT TestConnect/Success 470s === CONT TestConnect/No_connection_because_of_empty_port_file 470s === CONT TestConnect/No_connection_because_of_unreadable_port_file 470s === CONT TestReconnection 470s === RUN TestReconnection/Success_connecting_after_failing_to_connect 470s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 470s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 470s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 470s === CONT TestReconnection/Success_connecting_after_failing_to_connect 470s time="2024-02-18T23:39:51Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:38541\"" 470s === CONT TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 470s time="2024-02-18T23:39:51Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43927\"" 470s time="2024-02-18T23:39:51Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41215\"" 470s time="2024-02-18T23:39:51Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:38673\"" 470s time="2024-02-18T23:39:51Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34097\"" 470s === CONT TestConnect/No_connection_because_port_file_does_not_exist 470s time="2024-02-18T23:40:18Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42655\"" 470s --- PASS: TestNew (0.00s) 470s --- PASS: TestNew/Error_when_the_context_is_cancelled (0.01s) 470s --- PASS: TestNew/Success (27.20s) 470s --- PASS: TestNew/Error_when_WslPath_returns_error (27.85s) 470s === CONT TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 470s time="2024-02-18T23:40:19Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43973\"" 470s === CONT TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 470s time="2024-02-18T23:40:34Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_unreadable_port_file2765158140/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 470s time="2024-02-18T23:40:34Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:35167\"" 470s time="2024-02-18T23:40:35Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33021\"" 470s time="2024-02-18T23:40:35Z" level=info msg="Connecting to control stream at \"127.0.0.1:43927\"" 470s time="2024-02-18T23:40:35Z" level=debug msg="Connected to Windows agent via the control stream" 470s time="2024-02-18T23:40:35Z" level=info msg="wslInstanceMockService: Received incoming connection" 470s time="2024-02-18T23:40:35Z" level=info msg="Connecting to control stream at \"127.0.0.1:34097\"" 470s time="2024-02-18T23:40:36Z" level=debug msg="Connected to Windows agent via the control stream" 470s time="2024-02-18T23:40:36Z" level=info msg="wslInstanceMockService: Received incoming connection" 470s time="2024-02-18T23:40:36Z" level=info msg="Connecting to control stream at \"127.0.0.1:38541\"" 470s time="2024-02-18T23:40:36Z" level=info msg="Connecting to control stream at \"127.0.0.1:\"" 470s time="2024-02-18T23:40:36Z" level=debug msg="Connected to Windows agent via the control stream" 470s time="2024-02-18T23:40:36Z" level=info msg="wslInstanceMockService: Received incoming connection" 470s === CONT TestConnect/Incomplete_handshake_because_Agent_never_receives 470s time="2024-02-18T23:40:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39399\"" 470s time="2024-02-18T23:40: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\"" 470s time="2024-02-18T23:40:52Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 34027" 470s time="2024-02-18T23:40:52Z" level=debug msg="Completed handshake with Windows agent via the control stream" 470s time="2024-02-18T23:40:52Z" level=info msg="Connecting to control stream at \"127.0.0.1:33021\"" 470s time="2024-02-18T23:40:52Z" level=debug msg="Connected to Windows agent via the control stream" 470s time="2024-02-18T23:40:52Z" level=info msg="wslInstanceMockService: Received incoming connection" 470s time="2024-02-18T23:40:53Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 470s time="2024-02-18T23:40:53Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 46685" 470s time="2024-02-18T23:40:53Z" level=debug msg="Completed handshake with Windows agent via the control stream" 470s time="2024-02-18T23:40:54Z" 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\"" 470s time="2024-02-18T23:40:54Z" level=info msg="connection with \"TEST_DISTRO\": mock error: dropping stream before sending port" 470s time="2024-02-18T23:40:54Z" level=info msg="wslInstanceMockService: dropped connection" 470s === CONT TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 470s time="2024-02-18T23:40:54Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectIncomplete_handshake_because_Agent_never_sends_a_port53928713/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 470s time="2024-02-18T23:40:54Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:37839\"" 470s time="2024-02-18T23:40:54Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectSuccess1007114834/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 470s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 470s time="2024-02-18T23:40:57Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 470s time="2024-02-18T23:40: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\"" 470s time="2024-02-18T23:40:57Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 470s time="2024-02-18T23:40:57Z" level=info msg="wslInstanceMockService: dropped connection" 470s --- PASS: TestSend (65.54s) 470s === CONT TestConnect/No_connection_because_of_port_file_with_invalid_contents 470s time="2024-02-18T23:40:57Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42551\"" 470s time="2024-02-18T23:41:07Z" 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\"" 470s time="2024-02-18T23:41:07Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 44517" 470s time="2024-02-18T23:41:07Z" level=debug msg="Completed handshake with Windows agent via the control stream" 470s time="2024-02-18T23:41:07Z" level=info msg="Connecting to control stream at \"127.0.0.1:36177\"" 470s time="2024-02-18T23:41:07Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_port_file_contains_the_wrong_port3419544608/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 470s time="2024-02-18T23:41:07Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_port_file_does_not_exist2739352138/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 470s time="2024-02-18T23:41:17Z" level=info msg="Connecting to control stream at \"127.0.0.1:35167\"" 470s time="2024-02-18T23:41:17Z" level=debug msg="Connected to Windows agent via the control stream" 470s time="2024-02-18T23:41:17Z" level=info msg="wslInstanceMockService: Received incoming connection" 470s time="2024-02-18T23:41:18Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:46685: connect: connection refused" 470s time="2024-02-18T23:41:19Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44671\"" 470s time="2024-02-18T23:41:20Z" level=info msg="Connecting to control stream at \"127.0.0.1:39399\"" 470s time="2024-02-18T23:41:20Z" level=debug msg="Connected to Windows agent via the control stream" 470s time="2024-02-18T23:41:20Z" level=info msg="wslInstanceMockService: Received incoming connection" 470s time="2024-02-18T23:41:20Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 470s time="2024-02-18T23:41:20Z" level=info msg="wslInstanceMockService: dropped connection" 470s time="2024-02-18T23:41:27Z" 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\"" 470s time="2024-02-18T23:41:27Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Sending bad port 0" 470s time="2024-02-18T23:41:27Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectIncomplete_handshake_because_Agent_sends_port_01925675309/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 470s time="2024-02-18T23:41:28Z" level=info msg="Connecting to control stream at \"127.0.0.1:44671\"" 470s time="2024-02-18T23:41:28Z" level=debug msg="Connected to Windows agent via the control stream" 470s time="2024-02-18T23:41:28Z" level=info msg="wslInstanceMockService: Received incoming connection" 470s time="2024-02-18T23:41:29Z" level=info msg="Connecting to control stream at \"127.0.0.1:This text is not a valid IP address\"" 470s time="2024-02-18T23:41:29Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_port_file_with_invalid_contents696833114/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 470s --- PASS: TestConnect (0.00s) 470s --- PASS: TestConnect/No_connection_because_of_unreadable_port_file (43.28s) 470s --- PASS: TestConnect/No_connection_because_of_empty_port_file (45.17s) 470s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port (62.41s) 470s --- PASS: TestConnect/Success (62.97s) 470s --- PASS: TestConnect/No_connection_because_of_port_file_contains_the_wrong_port (48.02s) 470s --- PASS: TestConnect/No_connection_because_port_file_does_not_exist (49.16s) 470s --- PASS: TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 (53.06s) 470s --- PASS: TestConnect/Error_because_WindowsForwardedLocalhost_returns_error (35.01s) 470s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_receives (52.29s) 470s --- PASS: TestConnect/No_connection_because_of_port_file_with_invalid_contents (32.67s) 470s time="2024-02-18T23:41:32Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:44517: connect: connection refused" 470s time="2024-02-18T23:41:32Z" 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\"" 470s time="2024-02-18T23:41:32Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 35977" 470s time="2024-02-18T23:41:32Z" level=debug msg="Completed handshake with Windows agent via the control stream" 470s time="2024-02-18T23:41:32Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45343\"" 470s time="2024-02-18T23:41:35Z" level=info msg="Connecting to control stream at \"127.0.0.1:45343\"" 470s time="2024-02-18T23:41:35Z" level=debug msg="Connected to Windows agent via the control stream" 470s time="2024-02-18T23:41:35Z" level=info msg="wslInstanceMockService: Received incoming connection" 470s time="2024-02-18T23:41:38Z" 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\"" 470s time="2024-02-18T23:41:38Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 38331" 470s time="2024-02-18T23:41:38Z" level=debug msg="Completed handshake with Windows agent via the control stream" 470s --- PASS: TestReconnection (0.00s) 470s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (75.72s) 470s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (43.78s) 470s PASS 470s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/controlstream 106.771s 474s === RUN TestNew 474s === PAUSE TestNew 474s === RUN TestServe 474s === PAUSE TestServe 474s === RUN TestServeAndQuit 474s === PAUSE TestServeAndQuit 474s === RUN TestReconnection 474s === PAUSE TestReconnection 474s === RUN TestWithProMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithProMock (0.00s) 474s === RUN TestWithWslPathMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithWslPathMock (0.00s) 474s === RUN TestWithWslInfoMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithWslInfoMock (0.00s) 474s === RUN TestWithCmdExeMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithCmdExeMock (0.00s) 474s === CONT TestNew 474s === RUN TestNew/Success 474s === PAUSE TestNew/Success 474s === RUN TestNew/Error_when_WslPath_returns_error 474s === PAUSE TestNew/Error_when_WslPath_returns_error 474s === CONT TestNew/Success 474s time="2024-02-18T23:39:52Z" level=debug msg="Building new daemon" 474s === CONT TestServeAndQuit 474s === RUN TestServeAndQuit/Success_with_double_quit 474s === PAUSE TestServeAndQuit/Success_with_double_quit 474s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 474s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 474s === RUN TestServeAndQuit/Success_with_graceful_quit 474s === PAUSE TestServeAndQuit/Success_with_graceful_quit 474s === RUN TestServeAndQuit/Success_with_forceful_quit 474s === PAUSE TestServeAndQuit/Success_with_forceful_quit 474s === CONT TestServeAndQuit/Success_with_double_quit 474s time="2024-02-18T23:39:52Z" level=debug msg="Building new daemon" 474s === CONT TestServe 474s === RUN TestServe/Success 474s === PAUSE TestServe/Success 474s === RUN TestServe/Success_with_systemd_notifier_returning_true 474s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 474s === RUN TestServe/No_connection_because_port_file_does_not_exist 474s === PAUSE TestServe/No_connection_because_port_file_does_not_exist 474s === RUN TestServe/No_connection_because_of_faulty_agent 474s === PAUSE TestServe/No_connection_because_of_faulty_agent 474s === RUN TestServe/Error_because_of_notifier_returning_error 474s === PAUSE TestServe/Error_because_of_notifier_returning_error 474s === RUN TestServe/Error_because_WindowsHostAddress_returns_error 474s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_error 474s === RUN TestServe/Error_because_of_context_cancelled 474s === PAUSE TestServe/Error_because_of_context_cancelled 474s === CONT TestServe/Success 474s === CONT TestServe/Error_because_of_notifier_returning_error 474s time="2024-02-18T23:39:52Z" level=debug msg="Building new daemon" 474s time="2024-02-18T23:39:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43853\"" 474s === CONT TestReconnection 474s === RUN TestReconnection/Success_connecting_after_failing_to_connect 474s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 474s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 474s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 474s === CONT TestReconnection/Success_connecting_after_failing_to_connect 474s time="2024-02-18T23:39:52Z" level=debug msg="Building new daemon" 474s === CONT TestServe/No_connection_because_port_file_does_not_exist 474s === CONT TestServeAndQuit/Success_with_forceful_quit 474s time="2024-02-18T23:39:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:38217\"" 474s === CONT TestNew/Error_when_WslPath_returns_error 474s time="2024-02-18T23:39:52Z" level=debug msg="Building new daemon" 474s time="2024-02-18T23:39:52Z" level=debug msg="Building new daemon" 474s time="2024-02-18T23:39:52Z" level=debug msg="Building new daemon" 474s time="2024-02-18T23:39:52Z" level=debug msg="Building new daemon" 474s time="2024-02-18T23:39:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:46843\"" 474s time="2024-02-18T23:39:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44631\"" 474s time="2024-02-18T23:39:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:35017\"" 474s time="2024-02-18T23:40:22Z" level=debug msg="Ready state sent to systemd" 474s === CONT TestServe/No_connection_because_of_faulty_agent 474s time="2024-02-18T23:40:22Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34891\"" 474s time="2024-02-18T23:40:22Z" level=debug msg="Building new daemon" 474s --- PASS: TestNew (0.00s) 474s --- PASS: TestNew/Success (30.31s) 474s --- PASS: TestNew/Error_when_WslPath_returns_error (30.25s) 474s === CONT TestServeAndQuit/Success_with_graceful_quit 474s time="2024-02-18T23:40:22Z" level=debug msg="Building new daemon" 474s time="2024-02-18T23:40:22Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:35431\"" 474s time="2024-02-18T23:40:22Z" level=debug msg="Ready state sent to systemd" 474s time="2024-02-18T23:40:22Z" level=warning msg="Could not change systemd status: couldn't update status to systemd: mock error" 474s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 474s time="2024-02-18T23:40:22Z" level=debug msg="Building new daemon" 474s time="2024-02-18T23:40:22Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34851\"" 474s time="2024-02-18T23:40:23Z" level=debug msg="Ready state sent to systemd" 474s time="2024-02-18T23:40:31Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:40:31Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:40:32Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:40:38Z" level=info msg="Connecting to control stream at \"127.0.0.1:46843\"" 474s time="2024-02-18T23:40:38Z" level=debug msg="Connected to Windows agent via the control stream" 474s time="2024-02-18T23:40:38Z" level=info msg="wslInstanceMockService: Received incoming connection" 474s time="2024-02-18T23:40:39Z" 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_exist3922896019/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist3922896019/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 474s time="2024-02-18T23:40:39Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeNo_connection_because_port_file_does_not_exist3922896019/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 474s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 474s time="2024-02-18T23:40:39Z" level=debug msg="Building new daemon" 474s time="2024-02-18T23:40:39Z" level=info msg="Connecting to control stream at \"127.0.0.1:43853\"" 474s time="2024-02-18T23:40:39Z" level=debug msg="Connected to Windows agent via the control stream" 474s time="2024-02-18T23:40:39Z" level=info msg="wslInstanceMockService: Received incoming connection" 474s time="2024-02-18T23:40:40Z" level=info msg="Connecting to control stream at \"127.0.0.1:44631\"" 474s time="2024-02-18T23:40:40Z" level=debug msg="Connected to Windows agent via the control stream" 474s time="2024-02-18T23:40:40Z" level=info msg="wslInstanceMockService: Received incoming connection" 474s time="2024-02-18T23:40:41Z" 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_connect3336387971/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect3336387971/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 474s time="2024-02-18T23:40:41Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 474s time="2024-02-18T23:40:41Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:38725\"" 474s time="2024-02-18T23:40:43Z" level=info msg="Retrying connection" 474s time="2024-02-18T23:40:43Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 474s time="2024-02-18T23:40: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\"" 474s time="2024-02-18T23:40:55Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 33265" 474s time="2024-02-18T23:40:55Z" level=debug msg="Completed handshake with Windows agent via the control stream" 474s time="2024-02-18T23:40:55Z" level=info msg="Connected to control stream" 474s time="2024-02-18T23:40:55Z" level=debug msg="Starting to serve requests" 474s time="2024-02-18T23:40:55Z" level=info msg="Serving GRPC requests on localhost:33265" 474s time="2024-02-18T23:40:55Z" level=debug msg="Updated systemd status to \"Serving\"" 474s time="2024-02-18T23:40: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\"" 474s time="2024-02-18T23:40:55Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 46541" 474s time="2024-02-18T23:40:55Z" level=debug msg="Completed handshake with Windows agent via the control stream" 474s time="2024-02-18T23:40:55Z" level=info msg="Connected to control stream" 474s time="2024-02-18T23:40:55Z" level=debug msg="Starting to serve requests" 474s time="2024-02-18T23:40:55Z" level=info msg="Serving GRPC requests on localhost:46541" 474s time="2024-02-18T23:40:55Z" level=debug msg="Updated systemd status to \"Serving\"" 474s time="2024-02-18T23:40: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\"" 474s time="2024-02-18T23:40:55Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 33405" 474s time="2024-02-18T23:40:55Z" level=debug msg="Completed handshake with Windows agent via the control stream" 474s time="2024-02-18T23:40:55Z" level=info msg="Connected to control stream" 474s time="2024-02-18T23:40:55Z" level=debug msg="Starting to serve requests" 474s time="2024-02-18T23:40:55Z" level=info msg="Serving GRPC requests on localhost:33405" 474s time="2024-02-18T23:40:55Z" level=error msg="serve error: WSL Pro Service stopped serving: grpc error: grpc: the server has been stopped" 474s time="2024-02-18T23:40:55Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeSuccess4160039718/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 474s === CONT TestServe/Success_with_systemd_notifier_returning_true 474s time="2024-02-18T23:40:55Z" level=debug msg="Building new daemon" 474s time="2024-02-18T23:40:55Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34623\"" 474s time="2024-02-18T23:40:56Z" level=debug msg="Ready state sent to systemd" 474s time="2024-02-18T23:40:56Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:40:56Z" level=debug msg="Updated systemd status to \"Stopped\"" 474s === CONT TestServe/Error_because_of_context_cancelled 474s time="2024-02-18T23:40:56Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_forceful_quit2297894187/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 474s time="2024-02-18T23:40:56Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34535\"" 474s time="2024-02-18T23:40:56Z" level=debug msg="Building new daemon" 474s time="2024-02-18T23:40:56Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:40:56Z" level=info msg="Waiting for active requests to close." 474s time="2024-02-18T23:40:56Z" level=debug msg="Updated systemd status to \"Stopped\"" 474s time="2024-02-18T23:40:56Z" level=debug msg="All connections have now ended." 474s time="2024-02-18T23:40:56Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:40:56Z" level=info msg="Waiting for active requests to close." 474s time="2024-02-18T23:40:56Z" level=debug msg="All connections have now ended." 474s time="2024-02-18T23:40:56Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_double_quit2851809659/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 474s === CONT TestServe/Error_because_WindowsHostAddress_returns_error 474s time="2024-02-18T23:40:56Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41945\"" 474s time="2024-02-18T23:40:56Z" level=debug msg="Building new daemon" 474s time="2024-02-18T23:40:56Z" level=debug msg="Updated systemd status to \"Stopped\"" 474s time="2024-02-18T23:40:56Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitError_due_to_quitting_before_serving3224752694/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 474s time="2024-02-18T23:40:58Z" level=info msg="Connecting to control stream at \"127.0.0.1:38725\"" 474s time="2024-02-18T23:40:58Z" level=debug msg="Connected to Windows agent via the control stream" 474s time="2024-02-18T23:40:58Z" level=info msg="wslInstanceMockService: Received incoming connection" 474s time="2024-02-18T23:41:07Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:41:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:46709\"" 474s time="2024-02-18T23:41:09Z" level=debug msg="Ready state sent to systemd" 474s time="2024-02-18T23:41:10Z" level=info msg="Connecting to control stream at \"127.0.0.1:35431\"" 474s time="2024-02-18T23:41:10Z" level=debug msg="Connected to Windows agent via the control stream" 474s time="2024-02-18T23:41:10Z" level=info msg="wslInstanceMockService: Received incoming connection" 474s time="2024-02-18T23:41:11Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 474s time="2024-02-18T23:41:11Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 42177" 474s time="2024-02-18T23:41:11Z" level=debug msg="Completed handshake with Windows agent via the control stream" 474s time="2024-02-18T23:41:11Z" level=info msg="Connected to control stream" 474s time="2024-02-18T23:41:11Z" level=debug msg="Starting to serve requests" 474s time="2024-02-18T23:41:11Z" level=info msg="Serving GRPC requests on localhost:42177" 474s time="2024-02-18T23:41:11Z" level=debug msg="Updated systemd status to \"Serving\"" 474s === NAME TestReconnection/Success_connecting_after_failing_to_connect 474s daemon_test.go:344: 474s Error Trace: /tmp/autopkgtest.OiKSDG/build.IaB/src/internal/daemon/daemon_test.go:344 474s Error: Condition never satisfied 474s Test: TestReconnection/Success_connecting_after_failing_to_connect 474s Messages: Service should eventually connect to the agent 474s time="2024-02-18T23:41:11Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:41:11Z" level=debug msg="Updated systemd status to \"Stopped\"" 474s time="2024-02-18T23:41:11Z" level=info msg="Connecting to control stream at \"127.0.0.1:34891\"" 474s time="2024-02-18T23:41:11Z" level=debug msg="Connected to Windows agent via the control stream" 474s time="2024-02-18T23:41:11Z" level=info msg="wslInstanceMockService: Received incoming connection" 474s time="2024-02-18T23:41:11Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 474s time="2024-02-18T23:41:11Z" level=info msg="wslInstanceMockService: dropped connection" 474s time="2024-02-18T23:41:20Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:33265: connect: connection refused" 474s time="2024-02-18T23:41:20Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:46541: connect: connection refused" 474s time="2024-02-18T23:41:20Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:33405: connect: connection refused" 474s time="2024-02-18T23:41:21Z" level=debug msg="Ready state sent to systemd" 474s time="2024-02-18T23:41:22Z" level=info msg="Connecting to control stream at \"127.0.0.1:46709\"" 474s time="2024-02-18T23:41:22Z" level=debug msg="Connected to Windows agent via the control stream" 474s time="2024-02-18T23:41:22Z" level=info msg="wslInstanceMockService: Received incoming connection" 474s time="2024-02-18T23:41:22Z" 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\"" 474s time="2024-02-18T23:41:22Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 36343" 474s time="2024-02-18T23:41:22Z" level=debug msg="Completed handshake with Windows agent via the control stream" 474s time="2024-02-18T23:41:22Z" level=info msg="Connected to control stream" 474s time="2024-02-18T23:41:22Z" level=debug msg="Starting to serve requests" 474s time="2024-02-18T23:41:22Z" level=info msg="Serving GRPC requests on localhost:36343" 474s time="2024-02-18T23:41:22Z" level=debug msg="Updated systemd status to \"Serving\"" 474s time="2024-02-18T23:41:23Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:41:23Z" level=info msg="Waiting for active requests to close." 474s time="2024-02-18T23:41:23Z" level=debug msg="Updated systemd status to \"Stopped\"" 474s time="2024-02-18T23:41:23Z" level=debug msg="All connections have now ended." 474s --- PASS: TestServeAndQuit (0.00s) 474s --- PASS: TestServeAndQuit/Success_with_forceful_quit (64.28s) 474s --- PASS: TestServeAndQuit/Success_with_double_quit (64.43s) 474s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (33.91s) 474s --- PASS: TestServeAndQuit/Success_with_graceful_quit (60.65s) 474s time="2024-02-18T23:41:23Z" level=error msg="serve error: could not connect to windows agent via the control stream: could not complete handshake: could not send system info: could not send system info: EOF" 474s time="2024-02-18T23:41:29Z" level=info msg="Connecting to control stream at \"127.0.0.1:34623\"" 474s time="2024-02-18T23:41:29Z" level=debug msg="Connected to Windows agent via the control stream" 474s time="2024-02-18T23:41:29Z" level=info msg="wslInstanceMockService: Received incoming connection" 474s time="2024-02-18T23:41:30Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeError_because_WindowsHostAddress_returns_error4124733070/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 474s time="2024-02-18T23:41: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\"" 474s time="2024-02-18T23:41:30Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 40055" 474s time="2024-02-18T23:41:30Z" level=debug msg="Completed handshake with Windows agent via the control stream" 474s time="2024-02-18T23:41:30Z" level=info msg="Connected to control stream" 474s time="2024-02-18T23:41:30Z" level=debug msg="Starting to serve requests" 474s time="2024-02-18T23:41:30Z" level=info msg="Serving GRPC requests on localhost:40055" 474s time="2024-02-18T23:41:30Z" level=debug msg="Updated systemd status to \"Serving\"" 474s time="2024-02-18T23:41:30Z" level=error msg="serve error: lost connection to Windows Agent" 474s time="2024-02-18T23:41:30Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 474s time="2024-02-18T23:41:30Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43657\"" 474s time="2024-02-18T23:41:31Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:41:31Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:41:32Z" level=info msg="Retrying connection" 474s time="2024-02-18T23:41:32Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 474s time="2024-02-18T23:41:33Z" 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\"" 474s time="2024-02-18T23:41:33Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 45845" 474s time="2024-02-18T23:41:33Z" level=debug msg="Completed handshake with Windows agent via the control stream" 474s time="2024-02-18T23:41:33Z" level=info msg="Connected to control stream" 474s time="2024-02-18T23:41:33Z" level=debug msg="Starting to serve requests" 474s time="2024-02-18T23:41:33Z" level=info msg="Serving GRPC requests on localhost:45845" 474s time="2024-02-18T23:41:33Z" level=debug msg="Updated systemd status to \"Serving\"" 474s time="2024-02-18T23:41:33Z" level=error msg="serve error: WSL Pro Service stopped serving: grpc error: grpc: the server has been stopped" 474s time="2024-02-18T23:41:33Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 474s time="2024-02-18T23:41:33Z" level=debug msg="Updated systemd status to \"Stopped\"" 474s --- PASS: TestServe (0.03s) 474s --- PASS: TestServe/Error_because_of_notifier_returning_error (30.50s) 474s --- PASS: TestServe/No_connection_because_port_file_does_not_exist (47.26s) 474s --- PASS: TestServe/Success (63.58s) 474s --- PASS: TestServe/Error_because_of_context_cancelled (25.48s) 474s --- PASS: TestServe/No_connection_because_of_faulty_agent (61.03s) 474s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_error (33.42s) 474s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (37.20s) 474s time="2024-02-18T23:41:35Z" level=info msg="Connecting to control stream at \"127.0.0.1:43657\"" 474s time="2024-02-18T23:41:35Z" level=debug msg="Connected to Windows agent via the control stream" 474s time="2024-02-18T23:41:35Z" level=info msg="wslInstanceMockService: Received incoming connection" 474s time="2024-02-18T23:41:36Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:42177: connect: connection refused" 474s time="2024-02-18T23:41:37Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 474s time="2024-02-18T23:41:37Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 34497" 474s time="2024-02-18T23:41:37Z" level=debug msg="Completed handshake with Windows agent via the control stream" 474s time="2024-02-18T23:41:37Z" level=info msg="Connected to control stream" 474s time="2024-02-18T23:41:37Z" level=debug msg="Starting to serve requests" 474s time="2024-02-18T23:41:37Z" level=info msg="Serving GRPC requests on localhost:34497" 474s time="2024-02-18T23:41:37Z" level=debug msg="Updated systemd status to \"Serving\"" 474s time="2024-02-18T23:41:42Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 474s time="2024-02-18T23:41:42Z" level=info msg="Stopping daemon requested." 474s time="2024-02-18T23:41:42Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 474s time="2024-02-18T23:41:42Z" level=info msg="wslInstanceMockService: dropped connection" 474s time="2024-02-18T23:41:42Z" level=error msg="serve error: lost connection to Windows Agent" 474s time="2024-02-18T23:41:42Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 474s time="2024-02-18T23:41:42Z" level=debug msg="Updated systemd status to \"Stopped\"" 474s --- FAIL: TestReconnection (0.00s) 474s --- FAIL: TestReconnection/Success_connecting_after_failing_to_connect (79.29s) 474s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (62.77s) 474s FAIL 474s FAIL github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 110.294s 474s === RUN TestActiveConnection 474s === PAUSE TestActiveConnection 474s === RUN TestTimeoutOnInactiveConnection 474s === PAUSE TestTimeoutOnInactiveConnection 474s === RUN TestCancelOnClientSide 474s === PAUSE TestCancelOnClientSide 474s === RUN TestClientInterceptorFailed 474s === PAUSE TestClientInterceptorFailed 474s === RUN TestRecvMessageError 474s === PAUSE TestRecvMessageError 474s === CONT TestActiveConnection 474s === CONT TestClientInterceptorFailed 474s --- PASS: TestClientInterceptorFailed (0.00s) 474s === CONT TestRecvMessageError 474s --- PASS: TestRecvMessageError (0.00s) 474s === CONT TestCancelOnClientSide 474s === CONT TestTimeoutOnInactiveConnection 474s --- PASS: TestCancelOnClientSide (0.00s) 474s --- PASS: TestTimeoutOnInactiveConnection (0.05s) 474s --- PASS: TestActiveConnection (0.15s) 474s PASS 474s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/contextidler 0.332s 474s === RUN TestFormat 474s === PAUSE TestFormat 474s === CONT TestFormat 474s === RUN TestFormat/GRPC_Deadline_errors_don't_print_status_nor_daemon_nor_message 474s === PAUSE TestFormat/GRPC_Deadline_errors_don't_print_status_nor_daemon_nor_message 474s === RUN TestFormat/GRPC_Unknown_errors_don't_print_status_and_daemon 474s === PAUSE TestFormat/GRPC_Unknown_errors_don't_print_status_and_daemon 474s === RUN TestFormat/GRPC_Random_errors_prints_status_and_message 474s === PAUSE TestFormat/GRPC_Random_errors_prints_status_and_message 474s === RUN TestFormat/Nil_returns_nil 474s === PAUSE TestFormat/Nil_returns_nil 474s === RUN TestFormat/Non_GRPC_errors_are_returned_as_is 474s === PAUSE TestFormat/Non_GRPC_errors_are_returned_as_is 474s === RUN TestFormat/GRPC_Unavailable_errors_prints_daemon_name 474s === PAUSE TestFormat/GRPC_Unavailable_errors_prints_daemon_name 474s === CONT TestFormat/GRPC_Deadline_errors_don't_print_status_nor_daemon_nor_message 474s === CONT TestFormat/GRPC_Unavailable_errors_prints_daemon_name 474s === CONT TestFormat/Non_GRPC_errors_are_returned_as_is 474s === CONT TestFormat/Nil_returns_nil 474s === CONT TestFormat/GRPC_Random_errors_prints_status_and_message 474s === CONT TestFormat/GRPC_Unknown_errors_don't_print_status_and_daemon 474s --- PASS: TestFormat (0.00s) 474s --- PASS: TestFormat/GRPC_Deadline_errors_don't_print_status_nor_daemon_nor_message (0.00s) 474s --- PASS: TestFormat/GRPC_Unavailable_errors_prints_daemon_name (0.00s) 474s --- PASS: TestFormat/Non_GRPC_errors_are_returned_as_is (0.00s) 474s --- PASS: TestFormat/Nil_returns_nil (0.00s) 474s --- PASS: TestFormat/GRPC_Random_errors_prints_status_and_message (0.00s) 474s --- PASS: TestFormat/GRPC_Unknown_errors_don't_print_status_and_daemon (0.00s) 474s PASS 474s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/grpcerror 0.266s 474s === RUN TestStreamServer 474s === PAUSE TestStreamServer 474s === RUN TestStreamClient 474s === PAUSE TestStreamClient 474s === CONT TestStreamServer 474s === CONT TestStreamClient 474s --- PASS: TestStreamClient (0.00s) 474s --- PASS: TestStreamServer (0.00s) 474s PASS 474s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/interceptorschain 0.114s 474s === RUN TestChildRecvMsgAndHandlerCalled 474s === PAUSE TestChildRecvMsgAndHandlerCalled 474s === CONT TestChildRecvMsgAndHandlerCalled 474s === RUN TestChildRecvMsgAndHandlerCalled/Handler_and_RecvMsg_are_called 474s === PAUSE TestChildRecvMsgAndHandlerCalled/Handler_and_RecvMsg_are_called 474s === RUN TestChildRecvMsgAndHandlerCalled/Info_being_nil_has_no_impact 474s === PAUSE TestChildRecvMsgAndHandlerCalled/Info_being_nil_has_no_impact 474s === RUN TestChildRecvMsgAndHandlerCalled/Error_when_handler_fails_out 474s === PAUSE TestChildRecvMsgAndHandlerCalled/Error_when_handler_fails_out 474s === RUN TestChildRecvMsgAndHandlerCalled/Error_when_RecvMsg_errors_out 474s === PAUSE TestChildRecvMsgAndHandlerCalled/Error_when_RecvMsg_errors_out 474s === CONT TestChildRecvMsgAndHandlerCalled/Handler_and_RecvMsg_are_called 474s === CONT TestChildRecvMsgAndHandlerCalled/Error_when_RecvMsg_errors_out 474s === CONT TestChildRecvMsgAndHandlerCalled/Error_when_handler_fails_out 474s time="2024-02-18T23:40:00Z" level=info msg="Error sent to client: Failing handler" 474s === CONT TestChildRecvMsgAndHandlerCalled/Info_being_nil_has_no_impact 474s --- PASS: TestChildRecvMsgAndHandlerCalled (0.00s) 474s --- PASS: TestChildRecvMsgAndHandlerCalled/Handler_and_RecvMsg_are_called (0.00s) 474s --- PASS: TestChildRecvMsgAndHandlerCalled/Error_when_RecvMsg_errors_out (0.00s) 474s --- PASS: TestChildRecvMsgAndHandlerCalled/Error_when_handler_fails_out (0.00s) 474s --- PASS: TestChildRecvMsgAndHandlerCalled/Info_being_nil_has_no_impact (0.00s) 474s PASS 474s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/logconnections 0.103s 474s === RUN TestRecvLogMsg 474s === PAUSE TestRecvLogMsg 474s === RUN TestAddStreamToForwardLocalLogs 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:519529]]" 474s time="2024-02-18T23:40:07Z" level=warning msg=something 474s --- PASS: TestAddStreamToForwardLocalLogs (0.00s) 474s === RUN TestAddStreamToForwardOtherStream 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:074814]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:506220]]" 474s --- PASS: TestAddStreamToForwardOtherStream (0.00s) 474s === RUN TestAddStreamToForwardAfterClientIsConnected 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:522703]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:610732]]" 474s --- PASS: TestAddStreamToForwardAfterClientIsConnected (0.00s) 474s === RUN TestAddStreamToForwardDisconnect 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:374460]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:941675]]" 474s --- PASS: TestAddStreamToForwardDisconnect (0.00s) 474s === RUN TestAddStreamToForwardTwoClients 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:867249]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:350328]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:381864]]" 474s --- PASS: TestAddStreamToForwardTwoClients (0.00s) 474s === RUN TestAddStreamToForwardWithListenerCaller 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:549343]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:536010]]" 474s --- PASS: TestAddStreamToForwardWithListenerCaller (0.00s) 474s === RUN TestAddStreamMultipleForwarders 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:825455]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:160504]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:455190]]" 474s --- PASS: TestAddStreamMultipleForwarders (0.00s) 474s === RUN TestAddStreamMultipleForwardersOneWithCaller 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:525922]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:099374]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:717915]]" 474s --- PASS: TestAddStreamMultipleForwardersOneWithCaller (0.00s) 474s === RUN TestAddStreamToForwardFailSend 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:116443]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:627381]]" 474s time="2024-02-18T23:40:07Z" level=warning msg="Couldn't send log to one or more listener: SendMsg failed" 474s --- PASS: TestAddStreamToForwardFailSend (0.00s) 474s === RUN TestRemoveAllStreams 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:910603]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:669056]]" 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:354663]]" 474s time="2024-02-18T23:40:07Z" level=warning msg="[[123456:354663]] else" 474s --- PASS: TestRemoveAllStreams (0.00s) 474s === RUN TestLogWarningOnly 474s === PAUSE TestLogWarningOnly 474s === RUN TestMultipleLogs 474s === PAUSE TestMultipleLogs 474s === RUN TestAllLogLevels 474s === PAUSE TestAllLogLevels 474s === RUN TestDebugSentToRemoteEvenIfLocalIsWarning 474s === PAUSE TestDebugSentToRemoteEvenIfLocalIsWarning 474s === RUN TestLogWarningWithLocalCaller 474s === PAUSE TestLogWarningWithLocalCaller 474s === RUN TestLogWarningWithRemoteCaller 474s === PAUSE TestLogWarningWithRemoteCaller 474s === RUN TestLogWithNoCaller 474s === PAUSE TestLogWithNoCaller 474s === RUN TestSetReportCaller 474s === RUN TestSetReportCaller/Report_caller 474s === RUN TestSetReportCaller/Disable_caller 474s --- PASS: TestSetReportCaller (0.00s) 474s --- PASS: TestSetReportCaller/Report_caller (0.00s) 474s --- PASS: TestSetReportCaller/Disable_caller (0.00s) 474s === RUN TestLogSendingFail 474s === PAUSE TestLogSendingFail 474s === RUN TestLogStreamsAreSeparated 474s === PAUSE TestLogStreamsAreSeparated 474s === RUN TestLogAddHook 474s time="2024-02-18T23:40:07Z" level=info 474s --- PASS: TestLogAddHook (0.02s) 474s === RUN TestStreamServerInterceptor 474s === PAUSE TestStreamServerInterceptor 474s === RUN TestStreamServerInterceptorSendLogsFails 474s === PAUSE TestStreamServerInterceptorSendLogsFails 474s === RUN TestStreamServerInterceptorLoggerInvalidMetadata 474s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata 474s === CONT TestRecvLogMsg 474s === CONT TestDebugSentToRemoteEvenIfLocalIsWarning 474s === CONT TestLogWarningWithRemoteCaller 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:387318]]" 474s --- PASS: TestLogWarningWithRemoteCaller (0.00s) 474s === CONT TestStreamServerInterceptorLoggerInvalidMetadata 474s === RUN TestStreamServerInterceptorLoggerInvalidMetadata/No_meta_sent 474s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata/No_meta_sent 474s === RUN TestStreamServerInterceptorLoggerInvalidMetadata/Missing_client_ID 474s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata/Missing_client_ID 474s === RUN TestStreamServerInterceptorLoggerInvalidMetadata/Missing_caller_key 474s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata/Missing_caller_key 474s === RUN TestStreamServerInterceptorLoggerInvalidMetadata/Caller_key_is_not_a_boolean 474s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata/Caller_key_is_not_a_boolean 474s === RUN TestStreamServerInterceptorLoggerInvalidMetadata/Multiple_log_metas 474s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata/Multiple_log_metas 474s === CONT TestLogWarningWithLocalCaller 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:714773]]" 474s --- PASS: TestLogWarningWithLocalCaller (0.00s) 474s === CONT TestLogStreamsAreSeparated 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:577778]]" 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:048209]]" 474s --- PASS: TestLogStreamsAreSeparated (0.00s) 474s === CONT TestLogSendingFail 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:378383]]" 474s --- PASS: TestLogSendingFail (0.00s) 474s === CONT TestLogWithNoCaller 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:295655]]" 474s --- PASS: TestLogWithNoCaller (0.00s) 474s === CONT TestStreamServerInterceptorLoggerInvalidMetadata/No_meta_sent 474s === CONT TestStreamServerInterceptorSendLogsFails 474s time="2024-02-18T23:40:07Z" level=warning msg="[[123456:880160]] Couldn't send initial connection log to client" 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:880160]]" 474s --- PASS: TestStreamServerInterceptorSendLogsFails (0.00s) 474s === CONT TestStreamServerInterceptorLoggerInvalidMetadata/Multiple_log_metas 474s === CONT TestStreamServerInterceptorLoggerInvalidMetadata/Caller_key_is_not_a_boolean 474s === CONT TestStreamServerInterceptorLoggerInvalidMetadata/Missing_caller_key 474s === CONT TestStreamServerInterceptorLoggerInvalidMetadata/Missing_client_ID 474s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata (0.00s) 474s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata/No_meta_sent (0.00s) 474s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata/Multiple_log_metas (0.00s) 474s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata/Caller_key_is_not_a_boolean (0.00s) 474s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata/Missing_caller_key (0.00s) 474s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata/Missing_client_ID (0.00s) 474s === CONT TestMultipleLogs 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:595873]]" 474s --- PASS: TestMultipleLogs (0.00s) 474s === CONT TestAllLogLevels 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:090418]]" 474s --- PASS: TestAllLogLevels (0.00s) 474s === CONT TestLogWarningOnly 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:343653]]" 474s --- PASS: TestLogWarningOnly (0.00s) 474s === CONT TestStreamServerInterceptor 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:733592]]" 474s --- PASS: TestStreamServerInterceptor (0.00s) 474s Failed to fire hook: hook fired 474s time="2024-02-18T23:40:07Z" level=info msg="New connection from client [[123456:284849]]" 474s --- PASS: TestDebugSentToRemoteEvenIfLocalIsWarning (0.00s) 474s === RUN TestRecvLogMsg/One_message,_no_log 474s === PAUSE TestRecvLogMsg/One_message,_no_log 474s === RUN TestRecvLogMsg/Two_logs_with_different_debug_level 474s === PAUSE TestRecvLogMsg/Two_logs_with_different_debug_level 474s === RUN TestRecvLogMsg/Log_with_caller 474s === PAUSE TestRecvLogMsg/Log_with_caller 474s === RUN TestRecvLogMsg/No_caller_when_not_requested 474s === PAUSE TestRecvLogMsg/No_caller_when_not_requested 474s === RUN TestRecvLogMsg/Invalid_object_passed_to_RecvMsg_is_gracefully_skipped 474s === PAUSE TestRecvLogMsg/Invalid_object_passed_to_RecvMsg_is_gracefully_skipped 474s === RUN TestRecvLogMsg/One_log_(and_one_closing_empty_message) 474s === PAUSE TestRecvLogMsg/One_log_(and_one_closing_empty_message) 474s === RUN TestRecvLogMsg/No_caller_on_any_logs 474s === PAUSE TestRecvLogMsg/No_caller_on_any_logs 474s === RUN TestRecvLogMsg/One_message_with_error,_no_log,_error_is_preserved 474s === PAUSE TestRecvLogMsg/One_message_with_error,_no_log,_error_is_preserved 474s === RUN TestRecvLogMsg/Logs_and_then_message_with_error,_error_is_preserved 474s === PAUSE TestRecvLogMsg/Logs_and_then_message_with_error,_error_is_preserved 474s === RUN TestRecvLogMsg/Unknown_log_level_triggers_a_client_error_(protocole_issue) 474s === PAUSE TestRecvLogMsg/Unknown_log_level_triggers_a_client_error_(protocole_issue) 474s === CONT TestRecvLogMsg/One_message,_no_log 474s === CONT TestRecvLogMsg/No_caller_when_not_requested 474s === CONT TestRecvLogMsg/Invalid_object_passed_to_RecvMsg_is_gracefully_skipped 474s === CONT TestRecvLogMsg/No_caller_on_any_logs 474s === CONT TestRecvLogMsg/One_log_(and_one_closing_empty_message) 474s === CONT TestRecvLogMsg/Log_with_caller 474s === CONT TestRecvLogMsg/Two_logs_with_different_debug_level 474s === CONT TestRecvLogMsg/Logs_and_then_message_with_error,_error_is_preserved 474s === CONT TestRecvLogMsg/Unknown_log_level_triggers_a_client_error_(protocole_issue) 474s === CONT TestRecvLogMsg/One_message_with_error,_no_log,_error_is_preserved 474s --- PASS: TestRecvLogMsg (0.01s) 474s --- PASS: TestRecvLogMsg/Invalid_object_passed_to_RecvMsg_is_gracefully_skipped (0.00s) 474s --- PASS: TestRecvLogMsg/No_caller_on_any_logs (0.00s) 474s --- PASS: TestRecvLogMsg/One_log_(and_one_closing_empty_message) (0.00s) 474s --- PASS: TestRecvLogMsg/Log_with_caller (0.00s) 474s --- PASS: TestRecvLogMsg/Two_logs_with_different_debug_level (0.00s) 474s --- PASS: TestRecvLogMsg/Logs_and_then_message_with_error,_error_is_preserved (0.00s) 474s --- PASS: TestRecvLogMsg/Unknown_log_level_triggers_a_client_error_(protocole_issue) (0.00s) 474s --- PASS: TestRecvLogMsg/One_message_with_error,_no_log,_error_is_preserved (0.00s) 474s --- PASS: TestRecvLogMsg/No_caller_when_not_requested (0.00s) 474s --- PASS: TestRecvLogMsg/One_message,_no_log (0.00s) 474s PASS 474s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/logstreamer 0.279s 474s === RUN TestInfo 474s === PAUSE TestInfo 474s === RUN TestUserProfileDir 474s === PAUSE TestUserProfileDir 474s === RUN TestProStatus 474s === PAUSE TestProStatus 474s === RUN TestProAttach 474s === PAUSE TestProAttach 474s === RUN TestProDetach 474s === PAUSE TestProDetach 474s === RUN TestLandscapeEnable 474s === PAUSE TestLandscapeEnable 474s === RUN TestWindowsHostAddress 474s === PAUSE TestWindowsHostAddress 474s === RUN TestLandscapeDisable 474s === PAUSE TestLandscapeDisable 474s === RUN TestWithProMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithProMock (0.00s) 474s === RUN TestWithLandscapeConfigMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithLandscapeConfigMock (0.00s) 474s === RUN TestWithWslPathMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithWslPathMock (0.00s) 474s === RUN TestWithWslInfoMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithWslInfoMock (0.00s) 474s === RUN TestWithCmdExeMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithCmdExeMock (0.00s) 474s === CONT TestInfo 474s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 474s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 474s === RUN TestInfo/Success_reading_from_WSL_DISTRO_NAME 474s === PAUSE TestInfo/Success_reading_from_WSL_DISTRO_NAME 474s === RUN TestInfo/Success_using_wslpath 474s === PAUSE TestInfo/Success_using_wslpath 474s === RUN TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 474s === PAUSE TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 474s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 474s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 474s === RUN TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 474s === PAUSE TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 474s === RUN TestInfo/Error_when_pro_status_command_fails 474s === PAUSE TestInfo/Error_when_pro_status_command_fails 474s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 474s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 474s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 474s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 474s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 474s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 474s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 474s === CONT TestInfo/Error_when_pro_status_command_fails 474s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 474s === CONT TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 474s === CONT TestLandscapeDisable 474s === RUN TestLandscapeDisable/Success 474s === PAUSE TestLandscapeDisable/Success 474s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 474s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 474s === CONT TestLandscapeDisable/Success 474s === CONT TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 474s === CONT TestWindowsHostAddress 474s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 474s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 474s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 474s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 474s === RUN TestWindowsHostAddress/Success_without_NAT 474s === PAUSE TestWindowsHostAddress/Success_without_NAT 474s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 474s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 474s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 474s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 474s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 474s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 474s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 474s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 474s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 474s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 474s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 474s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 474s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 474s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 474s === CONT TestLandscapeEnable 474s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 474s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 474s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 474s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 474s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 474s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 474s === RUN TestLandscapeEnable/Success 474s === PAUSE TestLandscapeEnable/Success 474s === RUN TestLandscapeEnable/Success_overriding_computer_title 474s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 474s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 474s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 474s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 474s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 474s === CONT TestProDetach 474s === RUN TestProDetach/success_on_unattached_distro 474s === PAUSE TestProDetach/success_on_unattached_distro 474s === RUN TestProDetach/success_on_attached_distro 474s === PAUSE TestProDetach/success_on_attached_distro 474s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 474s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 474s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 474s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 474s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 474s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 474s === CONT TestProAttach 474s === RUN TestProAttach/success 474s === PAUSE TestProAttach/success 474s === RUN TestProAttach/error_on_'pro_attach'_error 474s === PAUSE TestProAttach/error_on_'pro_attach'_error 474s === CONT TestProStatus 474s === RUN TestProStatus/error_on_'pro_attach'_error 474s === PAUSE TestProStatus/error_on_'pro_attach'_error 474s === RUN TestProStatus/success_on_unattached_distro 474s === PAUSE TestProStatus/success_on_unattached_distro 474s === RUN TestProStatus/success_on_attached_distro 474s === PAUSE TestProStatus/success_on_attached_distro 474s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 474s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 474s === CONT TestUserProfileDir 474s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 474s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 474s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 474s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 474s === RUN TestUserProfileDir/Error_on_wslpath_error 474s === PAUSE TestUserProfileDir/Error_on_wslpath_error 474s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 474s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 474s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 474s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 474s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 474s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 474s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 474s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 474s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 474s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 474s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 474s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 474s === RUN TestUserProfileDir/Error_on_cmd.exe_error 474s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 474s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 474s === CONT TestInfo/Success_using_wslpath 474s === CONT TestInfo/Success_reading_from_WSL_DISTRO_NAME 474s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 474s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 474s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 474s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 474s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 474s --- PASS: TestLandscapeDisable (0.00s) 474s --- PASS: TestLandscapeDisable/Success (18.31s) 474s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (17.19s) 474s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 474s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 474s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 474s === CONT TestWindowsHostAddress/Success_without_NAT 474s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 474s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 474s === CONT TestLandscapeEnable/Success_overriding_computer_title 474s time="2024-02-18T23:40:48Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden with TEST_DISTRO" 474s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 474s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 474s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 474s --- PASS: TestInfo (0.00s) 474s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (17.25s) 474s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (17.92s) 474s --- PASS: TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (18.45s) 474s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (18.53s) 474s --- PASS: TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (18.55s) 474s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (18.59s) 474s --- PASS: TestInfo/Error_when_pro_status_command_fails (18.94s) 474s --- PASS: TestInfo/Success_reading_from_WSL_DISTRO_NAME (16.13s) 474s --- PASS: TestInfo/Success_using_wslpath (31.67s) 474s === CONT TestLandscapeEnable/Success 474s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 474s === CONT TestProDetach/success_on_unattached_distro 474s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 474s --- PASS: TestWindowsHostAddress (0.00s) 474s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (17.42s) 474s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (18.32s) 474s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (18.44s) 474s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (18.96s) 474s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (19.31s) 474s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (12.98s) 474s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (15.90s) 474s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (16.42s) 474s --- PASS: TestWindowsHostAddress/Success_without_NAT (16.35s) 474s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (16.43s) 474s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 474s === NAME TestLandscapeEnable/Success_overriding_computer_title 474s system_test.go:413: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 474s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 474s === CONT TestProDetach/success_on_attached_distro 474s === NAME TestLandscapeEnable/Success 474s system_test.go:413: testdata/TestLandscapeEnable/golden/success 474s === CONT TestProAttach/success 474s === CONT TestProAttach/error_on_'pro_attach'_error 474s === CONT TestProStatus/error_on_'pro_attach'_error 474s === CONT TestProStatus/success_on_attached_distro 474s === NAME TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 474s system_test.go:413: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 474s --- PASS: TestLandscapeEnable (0.00s) 474s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.00s) 474s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.01s) 474s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (15.88s) 474s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (15.13s) 474s --- PASS: TestLandscapeEnable/Success (13.30s) 474s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (13.54s) 474s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (28.09s) 474s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 474s === CONT TestProStatus/success_on_unattached_distro 474s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 474s system_test.go:166: Removing default proc/mounts 474s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 474s === CONT TestUserProfileDir/Error_on_cmd.exe_error 474s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 474s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 474s --- PASS: TestProDetach (0.00s) 474s --- PASS: TestProDetach/success_on_unattached_distro (12.45s) 474s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (12.80s) 474s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (12.77s) 474s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (13.29s) 474s --- PASS: TestProDetach/success_on_attached_distro (12.07s) 474s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 474s --- PASS: TestProAttach (0.00s) 474s --- PASS: TestProAttach/success (10.86s) 474s --- PASS: TestProAttach/error_on_'pro_attach'_error (11.17s) 474s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 474s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 474s === CONT TestUserProfileDir/Error_on_wslpath_error 474s --- PASS: TestProStatus (0.00s) 474s --- PASS: TestProStatus/error_on_'pro_attach'_error (9.64s) 474s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (10.37s) 474s --- PASS: TestProStatus/success_on_attached_distro (10.82s) 474s --- PASS: TestProStatus/success_on_unattached_distro (10.74s) 474s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 474s system_test.go:166: Removing default proc/mounts 474s --- PASS: TestUserProfileDir (0.00s) 474s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 474s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 474s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.01s) 474s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (6.12s) 474s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (13.97s) 474s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (9.10s) 474s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (8.79s) 474s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (9.58s) 474s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (7.76s) 474s --- PASS: TestUserProfileDir/Error_on_wslpath_error (7.64s) 474s PASS 474s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 84.621s 474s === RUN TestApplyProToken 474s === PAUSE TestApplyProToken 474s === RUN TestApplyLandscapeConfig 474s === PAUSE TestApplyLandscapeConfig 474s === RUN TestWithProMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithProMock (0.00s) 474s === RUN TestWithLandscapeConfigMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithLandscapeConfigMock (0.00s) 474s === RUN TestWithWslPathMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithWslPathMock (0.00s) 474s === RUN TestWithWslInfoMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithWslInfoMock (0.00s) 474s === RUN TestWithCmdExeMock 474s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 474s --- SKIP: TestWithCmdExeMock (0.00s) 474s === CONT TestApplyProToken 474s === RUN TestApplyProToken/success_attaching_attached_machine 474s === PAUSE TestApplyProToken/success_attaching_attached_machine 474s === RUN TestApplyProToken/success_attaching_non-attached_machine 474s === PAUSE TestApplyProToken/success_attaching_non-attached_machine 474s === RUN TestApplyProToken/Error_getting_system_info 474s === PAUSE TestApplyProToken/Error_getting_system_info 474s === RUN TestApplyProToken/Error_calling_pro_status 474s === PAUSE TestApplyProToken/Error_calling_pro_status 474s === RUN TestApplyProToken/Error_cannot_send_info_to_stream 474s === PAUSE TestApplyProToken/Error_cannot_send_info_to_stream 474s === RUN TestApplyProToken/success_detaching_attached_machine 474s === PAUSE TestApplyProToken/success_detaching_attached_machine 474s === RUN TestApplyProToken/success_detaching_non-attached_machine 474s === PAUSE TestApplyProToken/success_detaching_non-attached_machine 474s === RUN TestApplyProToken/Error_calling_pro_attach 474s === PAUSE TestApplyProToken/Error_calling_pro_attach 474s === RUN TestApplyProToken/Error_detaching_pro 474s === PAUSE TestApplyProToken/Error_detaching_pro 474s === CONT TestApplyProToken/success_attaching_attached_machine 474s === CONT TestApplyProToken/Error_calling_pro_status 474s === CONT TestApplyProToken/success_detaching_attached_machine 474s time="2024-02-18T23:40:13Z" level=debug msg="Registering GRPC WSL instance service" 474s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:42841 474s === CONT TestApplyLandscapeConfig 474s === RUN TestApplyLandscapeConfig/Success_enabling 474s === PAUSE TestApplyLandscapeConfig/Success_enabling 474s === RUN TestApplyLandscapeConfig/Success_disabling 474s === PAUSE TestApplyLandscapeConfig/Success_disabling 474s === RUN TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 474s === PAUSE TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 474s === RUN TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 474s === PAUSE TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 474s === CONT TestApplyLandscapeConfig/Success_enabling 474s time="2024-02-18T23:40:13Z" level=debug msg="Registering GRPC WSL instance service" 474s === NAME TestApplyProToken/Error_calling_pro_status 474s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:46659 474s === CONT TestApplyProToken/Error_cannot_send_info_to_stream 474s time="2024-02-18T23:40:13Z" level=debug msg="Registering GRPC WSL instance service" 474s === NAME TestApplyLandscapeConfig/Success_enabling 474s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:35931 474s === CONT TestApplyProToken/Error_detaching_pro 474s === CONT TestApplyProToken/Error_getting_system_info 474s time="2024-02-18T23:40:13Z" level=debug msg="Registering GRPC WSL instance service" 474s === NAME TestApplyProToken/Error_detaching_pro 474s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:38761 474s === CONT TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 474s === NAME TestApplyProToken/Error_detaching_pro 474s wslinstanceservice_test.go:108: Client connected to WslInstanceService 474s === NAME TestApplyProToken/Error_calling_pro_status 474s wslinstanceservice_test.go:108: Client connected to WslInstanceService 474s === NAME TestApplyProToken/success_detaching_attached_machine 474s wslinstanceservice_test.go:108: Client connected to WslInstanceService 474s time="2024-02-18T23:40:13Z" level=debug msg="Registering GRPC WSL instance service" 474s === NAME TestApplyLandscapeConfig/Success_enabling 474s wslinstanceservice_test.go:167: Client connected to WslInstanceService 474s === NAME TestApplyProToken/success_attaching_attached_machine 474s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:36791 474s time="2024-02-18T23:40:13Z" level=debug msg="Registering GRPC WSL instance service" 474s === NAME TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 474s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:43017 474s === NAME TestApplyProToken/success_attaching_attached_machine 474s wslinstanceservice_test.go:108: Client connected to WslInstanceService 474s time="2024-02-18T23:40:13Z" level=info msg="ApplyProToken: Received empty token: detaching" 474s time="2024-02-18T23:40:13Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 474s time="2024-02-18T23:40:13Z" level=debug msg="Registering GRPC WSL instance service" 474s === NAME TestApplyProToken/Error_getting_system_info 474s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:46425 474s wslinstanceservice_test.go:108: Client connected to WslInstanceService 474s === NAME TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 474s wslinstanceservice_test.go:167: Client connected to WslInstanceService 474s time="2024-02-18T23:40:13Z" level=info msg="ApplyProToken: Received empty token: detaching" 474s time="2024-02-18T23:40:13Z" level=info msg="ApplyProToken: Received empty token: detaching" 474s time="2024-02-18T23:40:13Z" level=info msg="ApplyLandscapeConfig: Received config: registering" 474s time="2024-02-18T23:40:13Z" level=info msg="ApplyProToken: Received empty token: detaching" 474s time="2024-02-18T23:40:13Z" level=info msg="ApplyLandscapeConfig: Received empty config: disabling" 474s time="2024-02-18T23:40:13Z" level=debug msg="Registering GRPC WSL instance service" 474s === NAME TestApplyProToken/Error_cannot_send_info_to_stream 474s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:42457 474s wslinstanceservice_test.go:108: Client connected to WslInstanceService 474s time="2024-02-18T23:40:13Z" level=info msg="ApplyProToken: Received empty token: detaching" 474s === NAME TestApplyLandscapeConfig/Success_enabling 474s wslinstanceservice_test.go:192: testdata/TestApplyLandscapeConfig/golden/success_enabling 474s === CONT TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 474s time="2024-02-18T23:40:30Z" level=debug msg="Registering GRPC WSL instance service" 474s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:37291 474s wslinstanceservice_test.go:167: Client connected to WslInstanceService 474s time="2024-02-18T23:40:30Z" level=info msg="ApplyLandscapeConfig: Received config: registering" 474s === CONT TestApplyLandscapeConfig/Success_disabling 474s time="2024-02-18T23:40:30Z" level=debug msg="Registering GRPC WSL instance service" 474s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:42393 474s wslinstanceservice_test.go:167: Client connected to WslInstanceService 474s time="2024-02-18T23:40:30Z" level=info msg="ApplyLandscapeConfig: Received empty config: disabling" 474s time="2024-02-18T23:40:31Z" level=error msg="Error in ApplyProToken: detachPro: command returned error: mock_error: This error is produced by a mock instructed to fail on pro detach" 474s === CONT TestApplyProToken/Error_calling_pro_attach 474s time="2024-02-18T23:40:46Z" level=debug msg="Registering GRPC WSL instance service" 474s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:40679 474s wslinstanceservice_test.go:108: Client connected to WslInstanceService 474s time="2024-02-18T23:40:46Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 474s === CONT TestApplyProToken/success_detaching_non-attached_machine 474s time="2024-02-18T23:40:47Z" level=debug msg="Registering GRPC WSL instance service" 474s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:34321 474s wslinstanceservice_test.go:108: Client connected to WslInstanceService 474s time="2024-02-18T23:40:47Z" level=info msg="ApplyProToken: Received empty token: detaching" 474s === CONT TestApplyProToken/success_attaching_non-attached_machine 474s time="2024-02-18T23:40:48Z" level=debug msg="Registering GRPC WSL instance service" 474s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:38013 474s wslinstanceservice_test.go:108: Client connected to WslInstanceService 474s time="2024-02-18T23:40:48Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 474s --- PASS: TestApplyLandscapeConfig (0.00s) 474s --- PASS: TestApplyLandscapeConfig/Success_enabling (16.40s) 474s --- PASS: TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails (16.65s) 474s --- PASS: TestApplyLandscapeConfig/Success_disabling (16.47s) 474s --- PASS: TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails (18.71s) 474s time="2024-02-18T23:40:48Z" level=warning msg="Error in ApplyProToken: could not gather system info: could not obtain pro status: pro status command returned error: exit status 1\nStdout:" 474s time="2024-02-18T23:40:49Z" level=warning msg="Error in ApplyProToken: could not gather system info: could not read /etc/os-release file: open /tmp/TestApplyProTokenError_getting_system_info1131170807/001/etc/os-release: no such file or directory" 474s time="2024-02-18T23:40:50Z" level=warning msg="Error in ApplyProToken: could not send back system info: test error" 474s time="2024-02-18T23:41:15Z" level=error msg="Error in ApplyProToken: attachPro:: command returned error: exit status 1\nOutput:{\"message\": \"This error is produced by a mock instructed to fail on pro attach\", \"message_code\": \"mock_error\"}\n" 474s --- PASS: TestApplyProToken (0.00s) 474s --- PASS: TestApplyProToken/success_detaching_attached_machine (33.97s) 474s --- PASS: TestApplyProToken/Error_detaching_pro (34.97s) 474s --- PASS: TestApplyProToken/Error_calling_pro_status (35.24s) 474s --- PASS: TestApplyProToken/Error_getting_system_info (35.96s) 474s --- PASS: TestApplyProToken/Error_cannot_send_info_to_stream (36.48s) 474s --- PASS: TestApplyProToken/success_attaching_attached_machine (48.33s) 474s --- PASS: TestApplyProToken/success_detaching_non-attached_machine (28.12s) 474s --- PASS: TestApplyProToken/Error_calling_pro_attach (39.60s) 474s --- PASS: TestApplyProToken/success_attaching_non-attached_machine (39.00s) 474s PASS 474s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/wslinstanceservice 74.091s 474s FAIL 475s autopkgtest [23:41:43]: test command1: -----------------------] 478s autopkgtest [23:41:46]: test command1: - - - - - - - - - - results - - - - - - - - - - 478s command1 FAIL non-zero exit status 1 482s autopkgtest [23:41:50]: @@@@@@@@@@@@@@@@@@@@ summary 482s command1 FAIL non-zero exit status 1