0s autopkgtest [02:21:11]: starting date: 2024-02-29 0s autopkgtest [02:21:11]: git checkout: d9c0295 adt_testbed.py: supress warnings from apt using a shell pipeline 0s autopkgtest [02:21:11]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work._hhajjnm/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:ubuntu-advantage-tools,src:apt --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=ubuntu-advantage-tools/31.1build1 apt/2.7.12+nmu1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@lcy02-20.secgroup --name adt-noble-amd64-wsl-pro-service-20240229-022111-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 346s autopkgtest [02:26:57]: @@@@@@@@@@@@@@@@@@@@ test bed setup 346s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 346s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [10.4 kB] 346s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [704 kB] 346s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [109 kB] 346s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [9908 B] 346s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 Packages [199 kB] 346s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main i386 Packages [114 kB] 346s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 c-n-f Metadata [3508 B] 346s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted i386 Packages [8124 B] 346s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/restricted amd64 Packages [60.1 kB] 346s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/restricted amd64 c-n-f Metadata [116 B] 346s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/universe i386 Packages [276 kB] 346s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/universe amd64 Packages [752 kB] 346s Get:14 http://ftpmaster.internal/ubuntu noble-proposed/universe amd64 c-n-f Metadata [9396 B] 346s Get:15 http://ftpmaster.internal/ubuntu noble-proposed/multiverse i386 Packages [3052 B] 346s Get:16 http://ftpmaster.internal/ubuntu noble-proposed/multiverse amd64 Packages [7852 B] 346s Get:17 http://ftpmaster.internal/ubuntu noble-proposed/multiverse amd64 c-n-f Metadata [196 B] 347s Fetched 2384 kB in 0s (5815 kB/s) 348s Reading package lists... 352s Reading package lists... 352s Building dependency tree... 352s Reading state information... 353s Calculating upgrade... 353s The following packages will be REMOVED: 353s libapt-pkg6.0 353s The following NEW packages will be installed: 353s libapt-pkg6.0t64 353s The following packages will be upgraded: 353s apt apt-utils cloud-init dash fwupd gir1.2-glib-2.0 grub-common grub-pc 353s grub-pc-bin grub2-common ibverbs-providers libcbor0.10 libexpat1 libfwupd2 353s libglib2.0-0 libglib2.0-data libibverbs1 libpng16-16 python3-launchpadlib 353s python3-tz ssh-import-id ubuntu-advantage-tools ubuntu-pro-client 353s ubuntu-pro-client-l10n xfsprogs 353s 25 upgraded, 1 newly installed, 1 to remove and 0 not upgraded. 353s Need to get 15.7 MB of archives. 353s After this operation, 65.5 kB of additional disk space will be used. 353s Get:1 http://ftpmaster.internal/ubuntu noble/main amd64 dash amd64 0.5.12-6ubuntu4 [89.8 kB] 353s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 ubuntu-pro-client-l10n amd64 31.1build1 [19.4 kB] 353s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 ubuntu-pro-client amd64 31.1build1 [214 kB] 353s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 libapt-pkg6.0t64 amd64 2.7.12+nmu1 [985 kB] 353s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 apt amd64 2.7.12+nmu1 [1376 kB] 353s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 apt-utils amd64 2.7.12+nmu1 [217 kB] 353s Get:7 http://ftpmaster.internal/ubuntu noble/main amd64 gir1.2-glib-2.0 amd64 2.79.2-1~ubuntu1 [183 kB] 353s Get:8 http://ftpmaster.internal/ubuntu noble/main amd64 libglib2.0-0 amd64 2.79.2-1~ubuntu1 [1537 kB] 353s Get:9 http://ftpmaster.internal/ubuntu noble/main amd64 libexpat1 amd64 2.6.0-1 [86.0 kB] 353s Get:10 http://ftpmaster.internal/ubuntu noble/main amd64 libglib2.0-data all 2.79.2-1~ubuntu1 [45.1 kB] 353s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 ubuntu-advantage-tools all 31.1build1 [10.7 kB] 353s Get:12 http://ftpmaster.internal/ubuntu noble/main amd64 libcbor0.10 amd64 0.10.2-1.2ubuntu1 [25.8 kB] 353s Get:13 http://ftpmaster.internal/ubuntu noble/main amd64 libpng16-16 amd64 1.6.43-1 [186 kB] 353s Get:14 http://ftpmaster.internal/ubuntu noble/main amd64 libfwupd2 amd64 1.9.14-1 [135 kB] 353s Get:15 http://ftpmaster.internal/ubuntu noble/main amd64 fwupd amd64 1.9.14-1 [4515 kB] 353s Get:16 http://ftpmaster.internal/ubuntu noble/main amd64 grub-pc amd64 2.12-1ubuntu3 [137 kB] 353s Get:17 http://ftpmaster.internal/ubuntu noble/main amd64 grub2-common amd64 2.12-1ubuntu3 [668 kB] 353s Get:18 http://ftpmaster.internal/ubuntu noble/main amd64 grub-pc-bin amd64 2.12-1ubuntu3 [1097 kB] 353s Get:19 http://ftpmaster.internal/ubuntu noble/main amd64 grub-common amd64 2.12-1ubuntu3 [2111 kB] 353s Get:20 http://ftpmaster.internal/ubuntu noble/main amd64 libibverbs1 amd64 50.0-1 [67.6 kB] 353s Get:21 http://ftpmaster.internal/ubuntu noble/main amd64 ibverbs-providers amd64 50.0-1 [374 kB] 353s Get:22 http://ftpmaster.internal/ubuntu noble/main amd64 python3-launchpadlib all 1.11.0-6 [127 kB] 353s Get:23 http://ftpmaster.internal/ubuntu noble/main amd64 python3-tz all 2024.1-2 [31.4 kB] 353s Get:24 http://ftpmaster.internal/ubuntu noble/main amd64 xfsprogs amd64 6.6.0-1ubuntu1 [900 kB] 353s Get:25 http://ftpmaster.internal/ubuntu noble/main amd64 cloud-init all 24.1~7g54599148-0ubuntu1 [596 kB] 353s Get:26 http://ftpmaster.internal/ubuntu noble/main amd64 ssh-import-id all 5.11-0ubuntu2 [10.0 kB] 354s Preconfiguring packages ... 354s Fetched 15.7 MB in 0s (103 MB/s) 354s (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 ... 70435 files and directories currently installed.) 354s Preparing to unpack .../dash_0.5.12-6ubuntu4_amd64.deb ... 354s Unpacking dash (0.5.12-6ubuntu4) over (0.5.12-6ubuntu3) ... 354s Setting up dash (0.5.12-6ubuntu4) ... 354s (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 ... 70435 files and directories currently installed.) 354s Preparing to unpack .../ubuntu-pro-client-l10n_31.1build1_amd64.deb ... 354s Unpacking ubuntu-pro-client-l10n (31.1build1) over (31.1) ... 354s Preparing to unpack .../ubuntu-pro-client_31.1build1_amd64.deb ... 354s Unpacking ubuntu-pro-client (31.1build1) over (31.1) ... 354s dpkg: libapt-pkg6.0:amd64: dependency problems, but removing anyway as you requested: 354s python3-apt depends on libapt-pkg6.0 (>= 2.7.11). 354s apt-utils depends on libapt-pkg6.0 (>= 2.7.12). 354s apt depends on libapt-pkg6.0 (>= 2.7.12). 354s 354s (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 ... 70435 files and directories currently installed.) 354s Removing libapt-pkg6.0:amd64 (2.7.12) ... 354s Selecting previously unselected package libapt-pkg6.0t64:amd64. 355s (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 ... 70386 files and directories currently installed.) 355s Preparing to unpack .../libapt-pkg6.0t64_2.7.12+nmu1_amd64.deb ... 355s Unpacking libapt-pkg6.0t64:amd64 (2.7.12+nmu1) ... 355s Setting up libapt-pkg6.0t64:amd64 (2.7.12+nmu1) ... 355s (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 ... 70436 files and directories currently installed.) 355s Preparing to unpack .../apt_2.7.12+nmu1_amd64.deb ... 355s Unpacking apt (2.7.12+nmu1) over (2.7.12) ... 355s Setting up apt (2.7.12+nmu1) ... 356s (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 ... 70436 files and directories currently installed.) 356s Preparing to unpack .../00-apt-utils_2.7.12+nmu1_amd64.deb ... 356s Unpacking apt-utils (2.7.12+nmu1) over (2.7.12) ... 356s Preparing to unpack .../01-gir1.2-glib-2.0_2.79.2-1~ubuntu1_amd64.deb ... 356s Unpacking gir1.2-glib-2.0:amd64 (2.79.2-1~ubuntu1) over (2.79.1-1) ... 356s Preparing to unpack .../02-libglib2.0-0_2.79.2-1~ubuntu1_amd64.deb ... 356s Unpacking libglib2.0-0:amd64 (2.79.2-1~ubuntu1) over (2.79.1-1) ... 356s Preparing to unpack .../03-libexpat1_2.6.0-1_amd64.deb ... 356s Unpacking libexpat1:amd64 (2.6.0-1) over (2.5.0-2) ... 356s Preparing to unpack .../04-libglib2.0-data_2.79.2-1~ubuntu1_all.deb ... 356s Unpacking libglib2.0-data (2.79.2-1~ubuntu1) over (2.79.1-1) ... 356s Preparing to unpack .../05-ubuntu-advantage-tools_31.1build1_all.deb ... 356s Unpacking ubuntu-advantage-tools (31.1build1) over (31.1) ... 356s Preparing to unpack .../06-libcbor0.10_0.10.2-1.2ubuntu1_amd64.deb ... 356s Unpacking libcbor0.10:amd64 (0.10.2-1.2ubuntu1) over (0.10.2-1.1ubuntu1) ... 356s Preparing to unpack .../07-libpng16-16_1.6.43-1_amd64.deb ... 356s Unpacking libpng16-16:amd64 (1.6.43-1) over (1.6.42-1) ... 356s Preparing to unpack .../08-libfwupd2_1.9.14-1_amd64.deb ... 356s Unpacking libfwupd2:amd64 (1.9.14-1) over (1.9.13-1) ... 356s Preparing to unpack .../09-fwupd_1.9.14-1_amd64.deb ... 356s Unpacking fwupd (1.9.14-1) over (1.9.13-1) ... 356s Preparing to unpack .../10-grub-pc_2.12-1ubuntu3_amd64.deb ... 356s Unpacking grub-pc (2.12-1ubuntu3) over (2.12-1ubuntu2) ... 356s Preparing to unpack .../11-grub2-common_2.12-1ubuntu3_amd64.deb ... 356s Unpacking grub2-common (2.12-1ubuntu3) over (2.12-1ubuntu2) ... 356s Preparing to unpack .../12-grub-pc-bin_2.12-1ubuntu3_amd64.deb ... 356s Unpacking grub-pc-bin (2.12-1ubuntu3) over (2.12-1ubuntu2) ... 357s Preparing to unpack .../13-grub-common_2.12-1ubuntu3_amd64.deb ... 357s Unpacking grub-common (2.12-1ubuntu3) over (2.12-1ubuntu2) ... 357s Preparing to unpack .../14-libibverbs1_50.0-1_amd64.deb ... 357s Unpacking libibverbs1:amd64 (50.0-1) over (48.0-1.1) ... 357s Preparing to unpack .../15-ibverbs-providers_50.0-1_amd64.deb ... 357s Unpacking ibverbs-providers:amd64 (50.0-1) over (48.0-1.1) ... 357s Preparing to unpack .../16-python3-launchpadlib_1.11.0-6_all.deb ... 357s Unpacking python3-launchpadlib (1.11.0-6) over (1.11.0-2ubuntu2) ... 357s Preparing to unpack .../17-python3-tz_2024.1-2_all.deb ... 357s Unpacking python3-tz (2024.1-2) over (2023.3.post1-2) ... 357s Preparing to unpack .../18-xfsprogs_6.6.0-1ubuntu1_amd64.deb ... 357s Unpacking xfsprogs (6.6.0-1ubuntu1) over (6.5.0-1ubuntu2) ... 357s Preparing to unpack .../19-cloud-init_24.1~7g54599148-0ubuntu1_all.deb ... 357s Unpacking cloud-init (24.1~7g54599148-0ubuntu1) over (24.1~6ga4140119-0ubuntu1) ... 358s Preparing to unpack .../20-ssh-import-id_5.11-0ubuntu2_all.deb ... 358s Unpacking ssh-import-id (5.11-0ubuntu2) over (5.11-0ubuntu1) ... 358s Setting up libexpat1:amd64 (2.6.0-1) ... 358s Setting up libibverbs1:amd64 (50.0-1) ... 358s Setting up cloud-init (24.1~7g54599148-0ubuntu1) ... 358s Installing new version of config file /etc/cloud/cloud.cfg ... 359s Setting up apt-utils (2.7.12+nmu1) ... 359s Setting up ibverbs-providers:amd64 (50.0-1) ... 359s Setting up libcbor0.10:amd64 (0.10.2-1.2ubuntu1) ... 359s Setting up libglib2.0-0:amd64 (2.79.2-1~ubuntu1) ... 359s No schema files found: doing nothing. 359s Setting up ssh-import-id (5.11-0ubuntu2) ... 359s Setting up python3-launchpadlib (1.11.0-6) ... 359s Setting up libfwupd2:amd64 (1.9.14-1) ... 359s Setting up python3-tz (2024.1-2) ... 360s Setting up grub-common (2.12-1ubuntu3) ... 360s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 361s Setting up libglib2.0-data (2.79.2-1~ubuntu1) ... 361s Setting up libpng16-16:amd64 (1.6.43-1) ... 361s Setting up xfsprogs (6.6.0-1ubuntu1) ... 361s update-initramfs: deferring update (trigger activated) 361s Setting up gir1.2-glib-2.0:amd64 (2.79.2-1~ubuntu1) ... 361s Setting up ubuntu-pro-client (31.1build1) ... 362s Setting up fwupd (1.9.14-1) ... 363s fwupd-offline-update.service is a disabled or a static unit not running, not starting it. 363s fwupd-refresh.service is a disabled or a static unit not running, not starting it. 363s fwupd.service is a disabled or a static unit not running, not starting it. 363s Setting up ubuntu-pro-client-l10n (31.1build1) ... 363s Setting up grub2-common (2.12-1ubuntu3) ... 363s Setting up grub-pc-bin (2.12-1ubuntu3) ... 363s Setting up grub-pc (2.12-1ubuntu3) ... 363s Replacing config file /etc/default/grub with new version 364s grub-pc: Running grub-install ... 364s Installing for i386-pc platform. 364s Installation finished. No error reported. 364s grub-install success for /dev/vda 364s Sourcing file `/etc/default/grub' 364s Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg' 364s Sourcing file `/etc/default/grub.d/90-autopkgtest.cfg' 364s Generating grub configuration file ... 364s Found linux image: /boot/vmlinuz-6.6.0-14-generic 364s Found initrd image: /boot/initrd.img-6.6.0-14-generic 364s Warning: os-prober will not be executed to detect other bootable partitions. 364s Systems on them will not be added to the GRUB boot configuration. 364s Check GRUB_DISABLE_OS_PROBER documentation entry. 364s Adding boot menu entry for UEFI Firmware Settings ... 365s done 365s Setting up ubuntu-advantage-tools (31.1build1) ... 365s Processing triggers for libc-bin (2.39-0ubuntu2) ... 365s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 365s Processing triggers for man-db (2.12.0-3) ... 367s Processing triggers for dbus (1.14.10-3ubuntu1) ... 367s Processing triggers for debianutils (5.16) ... 367s Processing triggers for install-info (7.1-3) ... 367s Processing triggers for initramfs-tools (0.142ubuntu19) ... 367s update-initramfs: Generating /boot/initrd.img-6.6.0-14-generic 367s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 375s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 375s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 375s Reading package lists... 375s Building dependency tree... 375s Reading state information... 375s The following packages will be REMOVED: 375s libicu72* 376s 0 upgraded, 0 newly installed, 1 to remove and 0 not upgraded. 376s After this operation, 37.1 MB disk space will be freed. 376s (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 ... 70439 files and directories currently installed.) 376s Removing libicu72:amd64 (72.1-4ubuntu1) ... 376s Processing triggers for libc-bin (2.39-0ubuntu2) ... 376s sh: Attempting to set up Debian/Ubuntu apt sources automatically 376s sh: Distribution appears to be Ubuntu 376s Reading package lists... 377s Building dependency tree... 377s Reading state information... 377s eatmydata is already the newest version (131-1). 377s dbus is already the newest version (1.14.10-3ubuntu1). 377s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 377s Reading package lists... 377s Building dependency tree... 377s Reading state information... 378s rng-tools-debian is already the newest version (2.4). 378s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 378s Reading package lists... 378s Building dependency tree... 378s Reading state information... 378s haveged is already the newest version (1.9.14-1ubuntu1). 378s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 378s Reading package lists... 379s Building dependency tree... 379s Reading state information... 379s The following packages will be REMOVED: 379s cloud-init* python3-configobj* python3-debconf* 379s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 379s After this operation, 3248 kB disk space will be freed. 379s (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 ... 70423 files and directories currently installed.) 379s Removing cloud-init (24.1~7g54599148-0ubuntu1) ... 380s Removing python3-configobj (5.0.8-3) ... 380s Removing python3-debconf (1.5.86) ... 380s Processing triggers for man-db (2.12.0-3) ... 380s (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 ... 70034 files and directories currently installed.) 380s Purging configuration files for cloud-init (24.1~7g54599148-0ubuntu1) ... 381s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 381s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 385s Reading package lists... 385s Building dependency tree... 385s Reading state information... 385s linux-generic is already the newest version (6.6.0-14.14). 385s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 386s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 386s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 386s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 386s Hit:4 http://ftpmaster.internal/ubuntu noble-proposed InRelease 386s Hit:5 http://ftpmaster.internal/ubuntu noble-backports InRelease 390s Reading package lists... 390s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 390s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 390s Reading package lists... 390s Building dependency tree... 390s Reading state information... 391s Calculating upgrade... 391s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 391s Reading package lists... 391s Building dependency tree... 391s Reading state information... 392s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 392s autopkgtest [02:27:43]: rebooting testbed after setup commands that affected boot 408s autopkgtest [02:27:59]: testbed running kernel: Linux 6.6.0-14-generic #14-Ubuntu SMP PREEMPT_DYNAMIC Thu Nov 30 10:27:29 UTC 2023 408s autopkgtest [02:27:59]: testbed dpkg architecture: amd64 409s autopkgtest [02:28:00]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 409s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:1 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:2 and /etc/apt/sources.list.d/ubuntu.sources:1 409s W: Target Packages (main/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target Packages (main/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target Packages (main/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target CNF (main/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target CNF (main/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target Packages (universe/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target Packages (universe/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target Packages (universe/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target CNF (universe/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target CNF (universe/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target Packages (restricted/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target Packages (restricted/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target Packages (restricted/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target CNF (restricted/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target CNF (restricted/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target Packages (multiverse/binary-amd64/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target Packages (multiverse/binary-i386/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target Packages (multiverse/binary-all/Packages) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target CNF (multiverse/cnf/Commands-amd64) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 409s W: Target CNF (multiverse/cnf/Commands-all) is configured multiple times in /etc/apt/sources.list:3 and /etc/apt/sources.list.d/ubuntu.sources:2 410s Get:1 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.1 (dsc) [1420 B] 410s Get:2 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.1 (tar) [1763 kB] 410s gpgv: Signature made Mon Feb 5 09:51:14 2024 UTC 410s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 410s gpgv: Can't check signature: No public key 410s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.1.dsc: no acceptable signature found 410s autopkgtest [02:28:01]: testing package wsl-pro-service version 0.1.1 410s autopkgtest [02:28:01]: build not needed 410s autopkgtest [02:28:01]: test command1: preparing testbed 411s Reading package lists... 412s Building dependency tree... 412s Reading state information... 412s Correcting dependencies...Starting pkgProblemResolver with broken count: 0 412s Starting 2 pkgProblemResolver with broken count: 0 412s Done 412s Done 413s Starting pkgProblemResolver with broken count: 0 413s Starting 2 pkgProblemResolver with broken count: 0 413s Done 413s The following additional packages will be installed: 413s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 413s cpp-13-x86-64-linux-gnu cpp-x86-64-linux-gnu debhelper debugedit dh-apport 413s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 413s g++-13-x86-64-linux-gnu g++-x86-64-linux-gnu gcc gcc-13 413s gcc-13-x86-64-linux-gnu gcc-x86-64-linux-gnu gettext golang-1.22-go 413s golang-1.22-src golang-go golang-src intltool-debian libarchive-zip-perl 413s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 413s libdebhelper-perl libdw1 libfile-stripnondeterminism-perl libgcc-13-dev 413s libgomp1 libhwasan0 libisl23 libitm1 liblsan0 libmpc3 libnsl-dev 413s libquadmath0 libstdc++-13-dev libsub-override-perl libtirpc-dev libtool 413s libtsan2 libubsan1 linux-libc-dev m4 po-debconf rpcsvc-proto 413s Suggested packages: 413s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 413s cpp-13-doc dh-make g++-multilib g++-13-multilib gcc-13-doc gcc-multilib 413s manpages-dev flex bison gdb gcc-doc gcc-13-multilib gdb-x86-64-linux-gnu 413s gettext-doc libasprintf-dev libgettextpo-dev bzr | brz git mercurial 413s subversion glibc-doc libstdc++-13-doc libtool-doc gfortran 413s | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 413s Recommended packages: 413s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 413s libltdl-dev libmail-sendmail-perl 413s The following NEW packages will be installed: 413s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 413s cpp-13-x86-64-linux-gnu cpp-x86-64-linux-gnu debhelper debugedit dh-apport 413s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 413s g++-13-x86-64-linux-gnu g++-x86-64-linux-gnu gcc gcc-13 413s gcc-13-x86-64-linux-gnu gcc-x86-64-linux-gnu gettext golang-1.22-go 413s golang-1.22-src golang-go golang-src intltool-debian libarchive-zip-perl 413s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 413s libdebhelper-perl libdw1 libfile-stripnondeterminism-perl libgcc-13-dev 413s libgomp1 libhwasan0 libisl23 libitm1 liblsan0 libmpc3 libnsl-dev 413s libquadmath0 libstdc++-13-dev libsub-override-perl libtirpc-dev libtool 413s libtsan2 libubsan1 linux-libc-dev m4 po-debconf rpcsvc-proto 413s 0 upgraded, 59 newly installed, 0 to remove and 0 not upgraded. 413s 1 not fully installed or removed. 413s Need to get 116 MB of archives. 413s After this operation, 485 MB of additional disk space will be used. 413s Get:1 http://ftpmaster.internal/ubuntu noble/main amd64 autotools-dev all 20220109.1 [44.9 kB] 413s Get:2 http://ftpmaster.internal/ubuntu noble/main amd64 m4 amd64 1.4.19-4 [243 kB] 413s Get:3 http://ftpmaster.internal/ubuntu noble/main amd64 autoconf all 2.71-3 [339 kB] 413s Get:4 http://ftpmaster.internal/ubuntu noble/main amd64 automake all 1:1.16.5-1.3ubuntu1 [558 kB] 413s Get:5 http://ftpmaster.internal/ubuntu noble/main amd64 autopoint all 0.21-14ubuntu1 [422 kB] 413s Get:6 http://ftpmaster.internal/ubuntu noble/main amd64 libdebhelper-perl all 13.14.1ubuntu1 [89.5 kB] 413s Get:7 http://ftpmaster.internal/ubuntu noble/main amd64 libisl23 amd64 0.26-3 [741 kB] 413s Get:8 http://ftpmaster.internal/ubuntu noble/main amd64 libmpc3 amd64 1.3.1-1 [54.1 kB] 413s Get:9 http://ftpmaster.internal/ubuntu noble/main amd64 cpp-13-x86-64-linux-gnu amd64 13.2.0-13ubuntu1 [11.2 MB] 414s Get:10 http://ftpmaster.internal/ubuntu noble/main amd64 cpp-13 amd64 13.2.0-13ubuntu1 [1032 B] 414s Get:11 http://ftpmaster.internal/ubuntu noble/main amd64 cpp-x86-64-linux-gnu amd64 4:13.2.0-7ubuntu1 [5326 B] 414s Get:12 http://ftpmaster.internal/ubuntu noble/main amd64 cpp amd64 4:13.2.0-7ubuntu1 [22.4 kB] 414s Get:13 http://ftpmaster.internal/ubuntu noble/main amd64 libcc1-0 amd64 14-20240201-3ubuntu1 [47.5 kB] 414s Get:14 http://ftpmaster.internal/ubuntu noble/main amd64 libgomp1 amd64 14-20240201-3ubuntu1 [147 kB] 414s Get:15 http://ftpmaster.internal/ubuntu noble/main amd64 libitm1 amd64 14-20240201-3ubuntu1 [29.1 kB] 414s Get:16 http://ftpmaster.internal/ubuntu noble/main amd64 libatomic1 amd64 14-20240201-3ubuntu1 [10.5 kB] 414s Get:17 http://ftpmaster.internal/ubuntu noble/main amd64 libasan8 amd64 14-20240201-3ubuntu1 [3026 kB] 414s Get:18 http://ftpmaster.internal/ubuntu noble/main amd64 liblsan0 amd64 14-20240201-3ubuntu1 [1309 kB] 414s Get:19 http://ftpmaster.internal/ubuntu noble/main amd64 libtsan2 amd64 14-20240201-3ubuntu1 [2731 kB] 414s Get:20 http://ftpmaster.internal/ubuntu noble/main amd64 libubsan1 amd64 14-20240201-3ubuntu1 [1171 kB] 414s Get:21 http://ftpmaster.internal/ubuntu noble/main amd64 libhwasan0 amd64 14-20240201-3ubuntu1 [1629 kB] 414s Get:22 http://ftpmaster.internal/ubuntu noble/main amd64 libquadmath0 amd64 14-20240201-3ubuntu1 [155 kB] 414s Get:23 http://ftpmaster.internal/ubuntu noble/main amd64 libgcc-13-dev amd64 13.2.0-13ubuntu1 [2687 kB] 414s Get:24 http://ftpmaster.internal/ubuntu noble/main amd64 gcc-13-x86-64-linux-gnu amd64 13.2.0-13ubuntu1 [21.8 MB] 414s Get:25 http://ftpmaster.internal/ubuntu noble/main amd64 gcc-13 amd64 13.2.0-13ubuntu1 [473 kB] 414s Get:26 http://ftpmaster.internal/ubuntu noble/main amd64 gcc-x86-64-linux-gnu amd64 4:13.2.0-7ubuntu1 [1212 B] 414s Get:27 http://ftpmaster.internal/ubuntu noble/main amd64 gcc amd64 4:13.2.0-7ubuntu1 [5018 B] 414s Get:28 http://ftpmaster.internal/ubuntu noble/main amd64 libc-dev-bin amd64 2.39-0ubuntu2 [20.4 kB] 414s Get:29 http://ftpmaster.internal/ubuntu noble/main amd64 linux-libc-dev amd64 6.6.0-14.14 [1580 kB] 414s Get:30 http://ftpmaster.internal/ubuntu noble/main amd64 libcrypt-dev amd64 1:4.4.36-4 [128 kB] 414s Get:31 http://ftpmaster.internal/ubuntu noble/main amd64 libtirpc-dev amd64 1.3.4+ds-1build1 [222 kB] 414s Get:32 http://ftpmaster.internal/ubuntu noble/main amd64 libnsl-dev amd64 1.3.0-3 [71.2 kB] 414s Get:33 http://ftpmaster.internal/ubuntu noble/main amd64 rpcsvc-proto amd64 1.4.2-0ubuntu6 [68.5 kB] 414s Get:34 http://ftpmaster.internal/ubuntu noble/main amd64 libc6-dev amd64 2.39-0ubuntu2 [2126 kB] 414s Get:35 http://ftpmaster.internal/ubuntu noble/main amd64 libtool all 2.4.7-7 [166 kB] 414s Get:36 http://ftpmaster.internal/ubuntu noble/main amd64 dh-autoreconf all 20 [16.1 kB] 414s Get:37 http://ftpmaster.internal/ubuntu noble/main amd64 libarchive-zip-perl all 1.68-1 [90.2 kB] 414s Get:38 http://ftpmaster.internal/ubuntu noble/main amd64 libsub-override-perl all 0.10-1 [10.0 kB] 414s Get:39 http://ftpmaster.internal/ubuntu noble/main amd64 libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 414s Get:40 http://ftpmaster.internal/ubuntu noble/main amd64 dh-strip-nondeterminism all 1.13.1-1 [5362 B] 414s Get:41 http://ftpmaster.internal/ubuntu noble/main amd64 libdw1 amd64 0.190-1 [260 kB] 414s Get:42 http://ftpmaster.internal/ubuntu noble/main amd64 debugedit amd64 1:5.0-5 [46.1 kB] 414s Get:43 http://ftpmaster.internal/ubuntu noble/main amd64 dwz amd64 0.15-1 [112 kB] 414s Get:44 http://ftpmaster.internal/ubuntu noble/main amd64 gettext amd64 0.21-14ubuntu1 [864 kB] 414s Get:45 http://ftpmaster.internal/ubuntu noble/main amd64 intltool-debian all 0.35.0+20060710.6 [23.2 kB] 414s Get:46 http://ftpmaster.internal/ubuntu noble/main amd64 po-debconf all 1.0.21+nmu1 [233 kB] 414s Get:47 http://ftpmaster.internal/ubuntu noble/main amd64 debhelper all 13.14.1ubuntu1 [869 kB] 414s Get:48 http://ftpmaster.internal/ubuntu noble/universe amd64 dh-apport all 2.28.0-0ubuntu1 [16.9 kB] 414s Get:49 http://ftpmaster.internal/ubuntu noble/main amd64 dh-golang all 1.62 [25.2 kB] 414s Get:50 http://ftpmaster.internal/ubuntu noble/main amd64 golang-1.22-src all 1.22.0-2 [19.7 MB] 414s Get:51 http://ftpmaster.internal/ubuntu noble/main amd64 golang-1.22-go amd64 1.22.0-2 [25.9 MB] 414s Get:52 http://ftpmaster.internal/ubuntu noble/main amd64 golang-src all 2:1.22~2 [4992 B] 414s Get:53 http://ftpmaster.internal/ubuntu noble/main amd64 golang-go amd64 2:1.22~2 [43.9 kB] 414s Get:54 http://ftpmaster.internal/ubuntu noble/main amd64 libstdc++-13-dev amd64 13.2.0-13ubuntu1 [2337 kB] 414s Get:55 http://ftpmaster.internal/ubuntu noble/main amd64 g++-13-x86-64-linux-gnu amd64 13.2.0-13ubuntu1 [12.5 MB] 414s Get:56 http://ftpmaster.internal/ubuntu noble/main amd64 g++-13 amd64 13.2.0-13ubuntu1 [14.0 kB] 414s Get:57 http://ftpmaster.internal/ubuntu noble/main amd64 g++-x86-64-linux-gnu amd64 4:13.2.0-7ubuntu1 [964 B] 414s Get:58 http://ftpmaster.internal/ubuntu noble/main amd64 g++ amd64 4:13.2.0-7ubuntu1 [1100 B] 414s Get:59 http://ftpmaster.internal/ubuntu noble/main amd64 build-essential amd64 12.10ubuntu1 [4928 B] 415s Fetched 116 MB in 1s (125 MB/s) 415s Selecting previously unselected package autotools-dev. 415s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 69979 files and directories currently installed.) 415s Preparing to unpack .../00-autotools-dev_20220109.1_all.deb ... 415s Unpacking autotools-dev (20220109.1) ... 415s Selecting previously unselected package m4. 415s Preparing to unpack .../01-m4_1.4.19-4_amd64.deb ... 415s Unpacking m4 (1.4.19-4) ... 415s Selecting previously unselected package autoconf. 415s Preparing to unpack .../02-autoconf_2.71-3_all.deb ... 415s Unpacking autoconf (2.71-3) ... 415s Selecting previously unselected package automake. 415s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 415s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 415s Selecting previously unselected package autopoint. 415s Preparing to unpack .../04-autopoint_0.21-14ubuntu1_all.deb ... 415s Unpacking autopoint (0.21-14ubuntu1) ... 415s Selecting previously unselected package libdebhelper-perl. 415s Preparing to unpack .../05-libdebhelper-perl_13.14.1ubuntu1_all.deb ... 415s Unpacking libdebhelper-perl (13.14.1ubuntu1) ... 415s Selecting previously unselected package libisl23:amd64. 415s Preparing to unpack .../06-libisl23_0.26-3_amd64.deb ... 415s Unpacking libisl23:amd64 (0.26-3) ... 415s Selecting previously unselected package libmpc3:amd64. 415s Preparing to unpack .../07-libmpc3_1.3.1-1_amd64.deb ... 415s Unpacking libmpc3:amd64 (1.3.1-1) ... 415s Selecting previously unselected package cpp-13-x86-64-linux-gnu. 415s Preparing to unpack .../08-cpp-13-x86-64-linux-gnu_13.2.0-13ubuntu1_amd64.deb ... 415s Unpacking cpp-13-x86-64-linux-gnu (13.2.0-13ubuntu1) ... 415s Selecting previously unselected package cpp-13. 415s Preparing to unpack .../09-cpp-13_13.2.0-13ubuntu1_amd64.deb ... 415s Unpacking cpp-13 (13.2.0-13ubuntu1) ... 415s Selecting previously unselected package cpp-x86-64-linux-gnu. 415s Preparing to unpack .../10-cpp-x86-64-linux-gnu_4%3a13.2.0-7ubuntu1_amd64.deb ... 415s Unpacking cpp-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 415s Selecting previously unselected package cpp. 415s Preparing to unpack .../11-cpp_4%3a13.2.0-7ubuntu1_amd64.deb ... 415s Unpacking cpp (4:13.2.0-7ubuntu1) ... 415s Selecting previously unselected package libcc1-0:amd64. 416s Preparing to unpack .../12-libcc1-0_14-20240201-3ubuntu1_amd64.deb ... 416s Unpacking libcc1-0:amd64 (14-20240201-3ubuntu1) ... 416s Selecting previously unselected package libgomp1:amd64. 416s Preparing to unpack .../13-libgomp1_14-20240201-3ubuntu1_amd64.deb ... 416s Unpacking libgomp1:amd64 (14-20240201-3ubuntu1) ... 416s Selecting previously unselected package libitm1:amd64. 416s Preparing to unpack .../14-libitm1_14-20240201-3ubuntu1_amd64.deb ... 416s Unpacking libitm1:amd64 (14-20240201-3ubuntu1) ... 416s Selecting previously unselected package libatomic1:amd64. 416s Preparing to unpack .../15-libatomic1_14-20240201-3ubuntu1_amd64.deb ... 416s Unpacking libatomic1:amd64 (14-20240201-3ubuntu1) ... 416s Selecting previously unselected package libasan8:amd64. 416s Preparing to unpack .../16-libasan8_14-20240201-3ubuntu1_amd64.deb ... 416s Unpacking libasan8:amd64 (14-20240201-3ubuntu1) ... 416s Selecting previously unselected package liblsan0:amd64. 416s Preparing to unpack .../17-liblsan0_14-20240201-3ubuntu1_amd64.deb ... 416s Unpacking liblsan0:amd64 (14-20240201-3ubuntu1) ... 416s Selecting previously unselected package libtsan2:amd64. 416s Preparing to unpack .../18-libtsan2_14-20240201-3ubuntu1_amd64.deb ... 416s Unpacking libtsan2:amd64 (14-20240201-3ubuntu1) ... 416s Selecting previously unselected package libubsan1:amd64. 416s Preparing to unpack .../19-libubsan1_14-20240201-3ubuntu1_amd64.deb ... 416s Unpacking libubsan1:amd64 (14-20240201-3ubuntu1) ... 416s Selecting previously unselected package libhwasan0:amd64. 416s Preparing to unpack .../20-libhwasan0_14-20240201-3ubuntu1_amd64.deb ... 416s Unpacking libhwasan0:amd64 (14-20240201-3ubuntu1) ... 416s Selecting previously unselected package libquadmath0:amd64. 416s Preparing to unpack .../21-libquadmath0_14-20240201-3ubuntu1_amd64.deb ... 416s Unpacking libquadmath0:amd64 (14-20240201-3ubuntu1) ... 416s Selecting previously unselected package libgcc-13-dev:amd64. 416s Preparing to unpack .../22-libgcc-13-dev_13.2.0-13ubuntu1_amd64.deb ... 416s Unpacking libgcc-13-dev:amd64 (13.2.0-13ubuntu1) ... 416s Selecting previously unselected package gcc-13-x86-64-linux-gnu. 416s Preparing to unpack .../23-gcc-13-x86-64-linux-gnu_13.2.0-13ubuntu1_amd64.deb ... 416s Unpacking gcc-13-x86-64-linux-gnu (13.2.0-13ubuntu1) ... 416s Selecting previously unselected package gcc-13. 417s Preparing to unpack .../24-gcc-13_13.2.0-13ubuntu1_amd64.deb ... 417s Unpacking gcc-13 (13.2.0-13ubuntu1) ... 417s Selecting previously unselected package gcc-x86-64-linux-gnu. 417s Preparing to unpack .../25-gcc-x86-64-linux-gnu_4%3a13.2.0-7ubuntu1_amd64.deb ... 417s Unpacking gcc-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 417s Selecting previously unselected package gcc. 417s Preparing to unpack .../26-gcc_4%3a13.2.0-7ubuntu1_amd64.deb ... 417s Unpacking gcc (4:13.2.0-7ubuntu1) ... 417s Selecting previously unselected package libc-dev-bin. 417s Preparing to unpack .../27-libc-dev-bin_2.39-0ubuntu2_amd64.deb ... 417s Unpacking libc-dev-bin (2.39-0ubuntu2) ... 417s Selecting previously unselected package linux-libc-dev:amd64. 417s Preparing to unpack .../28-linux-libc-dev_6.6.0-14.14_amd64.deb ... 417s Unpacking linux-libc-dev:amd64 (6.6.0-14.14) ... 417s Selecting previously unselected package libcrypt-dev:amd64. 417s Preparing to unpack .../29-libcrypt-dev_1%3a4.4.36-4_amd64.deb ... 417s Unpacking libcrypt-dev:amd64 (1:4.4.36-4) ... 417s Selecting previously unselected package libtirpc-dev:amd64. 417s Preparing to unpack .../30-libtirpc-dev_1.3.4+ds-1build1_amd64.deb ... 417s Unpacking libtirpc-dev:amd64 (1.3.4+ds-1build1) ... 417s Selecting previously unselected package libnsl-dev:amd64. 417s Preparing to unpack .../31-libnsl-dev_1.3.0-3_amd64.deb ... 417s Unpacking libnsl-dev:amd64 (1.3.0-3) ... 417s Selecting previously unselected package rpcsvc-proto. 417s Preparing to unpack .../32-rpcsvc-proto_1.4.2-0ubuntu6_amd64.deb ... 417s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 417s Selecting previously unselected package libc6-dev:amd64. 417s Preparing to unpack .../33-libc6-dev_2.39-0ubuntu2_amd64.deb ... 417s Unpacking libc6-dev:amd64 (2.39-0ubuntu2) ... 417s Selecting previously unselected package libtool. 417s Preparing to unpack .../34-libtool_2.4.7-7_all.deb ... 417s Unpacking libtool (2.4.7-7) ... 417s Selecting previously unselected package dh-autoreconf. 417s Preparing to unpack .../35-dh-autoreconf_20_all.deb ... 417s Unpacking dh-autoreconf (20) ... 417s Selecting previously unselected package libarchive-zip-perl. 417s Preparing to unpack .../36-libarchive-zip-perl_1.68-1_all.deb ... 417s Unpacking libarchive-zip-perl (1.68-1) ... 417s Selecting previously unselected package libsub-override-perl. 417s Preparing to unpack .../37-libsub-override-perl_0.10-1_all.deb ... 417s Unpacking libsub-override-perl (0.10-1) ... 417s Selecting previously unselected package libfile-stripnondeterminism-perl. 417s Preparing to unpack .../38-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 417s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 417s Selecting previously unselected package dh-strip-nondeterminism. 417s Preparing to unpack .../39-dh-strip-nondeterminism_1.13.1-1_all.deb ... 417s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 417s Selecting previously unselected package libdw1:amd64. 417s Preparing to unpack .../40-libdw1_0.190-1_amd64.deb ... 417s Unpacking libdw1:amd64 (0.190-1) ... 417s Selecting previously unselected package debugedit. 417s Preparing to unpack .../41-debugedit_1%3a5.0-5_amd64.deb ... 417s Unpacking debugedit (1:5.0-5) ... 417s Selecting previously unselected package dwz. 417s Preparing to unpack .../42-dwz_0.15-1_amd64.deb ... 417s Unpacking dwz (0.15-1) ... 417s Selecting previously unselected package gettext. 418s Preparing to unpack .../43-gettext_0.21-14ubuntu1_amd64.deb ... 418s Unpacking gettext (0.21-14ubuntu1) ... 418s Selecting previously unselected package intltool-debian. 418s Preparing to unpack .../44-intltool-debian_0.35.0+20060710.6_all.deb ... 418s Unpacking intltool-debian (0.35.0+20060710.6) ... 418s Selecting previously unselected package po-debconf. 418s Preparing to unpack .../45-po-debconf_1.0.21+nmu1_all.deb ... 418s Unpacking po-debconf (1.0.21+nmu1) ... 418s Selecting previously unselected package debhelper. 418s Preparing to unpack .../46-debhelper_13.14.1ubuntu1_all.deb ... 418s Unpacking debhelper (13.14.1ubuntu1) ... 418s Selecting previously unselected package dh-apport. 418s Preparing to unpack .../47-dh-apport_2.28.0-0ubuntu1_all.deb ... 418s Unpacking dh-apport (2.28.0-0ubuntu1) ... 418s Selecting previously unselected package dh-golang. 418s Preparing to unpack .../48-dh-golang_1.62_all.deb ... 418s Unpacking dh-golang (1.62) ... 418s Selecting previously unselected package golang-1.22-src. 418s Preparing to unpack .../49-golang-1.22-src_1.22.0-2_all.deb ... 418s Unpacking golang-1.22-src (1.22.0-2) ... 420s Selecting previously unselected package golang-1.22-go. 420s Preparing to unpack .../50-golang-1.22-go_1.22.0-2_amd64.deb ... 420s Unpacking golang-1.22-go (1.22.0-2) ... 420s Selecting previously unselected package golang-src. 420s Preparing to unpack .../51-golang-src_2%3a1.22~2_all.deb ... 420s Unpacking golang-src (2:1.22~2) ... 421s Selecting previously unselected package golang-go:amd64. 421s Preparing to unpack .../52-golang-go_2%3a1.22~2_amd64.deb ... 421s Unpacking golang-go:amd64 (2:1.22~2) ... 421s Selecting previously unselected package libstdc++-13-dev:amd64. 421s Preparing to unpack .../53-libstdc++-13-dev_13.2.0-13ubuntu1_amd64.deb ... 421s Unpacking libstdc++-13-dev:amd64 (13.2.0-13ubuntu1) ... 421s Selecting previously unselected package g++-13-x86-64-linux-gnu. 421s Preparing to unpack .../54-g++-13-x86-64-linux-gnu_13.2.0-13ubuntu1_amd64.deb ... 421s Unpacking g++-13-x86-64-linux-gnu (13.2.0-13ubuntu1) ... 421s Selecting previously unselected package g++-13. 421s Preparing to unpack .../55-g++-13_13.2.0-13ubuntu1_amd64.deb ... 421s Unpacking g++-13 (13.2.0-13ubuntu1) ... 421s Selecting previously unselected package g++-x86-64-linux-gnu. 421s Preparing to unpack .../56-g++-x86-64-linux-gnu_4%3a13.2.0-7ubuntu1_amd64.deb ... 421s Unpacking g++-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 421s Selecting previously unselected package g++. 421s Preparing to unpack .../57-g++_4%3a13.2.0-7ubuntu1_amd64.deb ... 421s Unpacking g++ (4:13.2.0-7ubuntu1) ... 421s Selecting previously unselected package build-essential. 421s Preparing to unpack .../58-build-essential_12.10ubuntu1_amd64.deb ... 421s Unpacking build-essential (12.10ubuntu1) ... 421s Setting up libdw1:amd64 (0.190-1) ... 421s Setting up libarchive-zip-perl (1.68-1) ... 421s Setting up libdebhelper-perl (13.14.1ubuntu1) ... 421s Setting up linux-libc-dev:amd64 (6.6.0-14.14) ... 421s Setting up m4 (1.4.19-4) ... 421s Setting up libgomp1:amd64 (14-20240201-3ubuntu1) ... 421s Setting up dh-apport (2.28.0-0ubuntu1) ... 421s Setting up autotools-dev (20220109.1) ... 421s Setting up libtirpc-dev:amd64 (1.3.4+ds-1build1) ... 421s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 421s Setting up golang-1.22-src (1.22.0-2) ... 421s Setting up libquadmath0:amd64 (14-20240201-3ubuntu1) ... 421s Setting up libmpc3:amd64 (1.3.1-1) ... 421s Setting up libatomic1:amd64 (14-20240201-3ubuntu1) ... 421s Setting up autopoint (0.21-14ubuntu1) ... 421s Setting up autoconf (2.71-3) ... 421s Setting up libubsan1:amd64 (14-20240201-3ubuntu1) ... 421s Setting up dwz (0.15-1) ... 421s Setting up libnsl-dev:amd64 (1.3.0-3) ... 421s Setting up libhwasan0:amd64 (14-20240201-3ubuntu1) ... 421s Setting up libcrypt-dev:amd64 (1:4.4.36-4) ... 421s Setting up libasan8:amd64 (14-20240201-3ubuntu1) ... 421s Setting up debugedit (1:5.0-5) ... 421s Setting up libsub-override-perl (0.10-1) ... 421s Setting up libtsan2:amd64 (14-20240201-3ubuntu1) ... 421s Setting up libisl23:amd64 (0.26-3) ... 421s Setting up libc-dev-bin (2.39-0ubuntu2) ... 421s Setting up golang-src (2:1.22~2) ... 421s Setting up libcc1-0:amd64 (14-20240201-3ubuntu1) ... 421s Setting up liblsan0:amd64 (14-20240201-3ubuntu1) ... 421s Setting up libitm1:amd64 (14-20240201-3ubuntu1) ... 421s Setting up automake (1:1.16.5-1.3ubuntu1) ... 421s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 421s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 421s Setting up gettext (0.21-14ubuntu1) ... 421s Setting up cpp-13-x86-64-linux-gnu (13.2.0-13ubuntu1) ... 421s Setting up golang-1.22-go (1.22.0-2) ... 421s Setting up intltool-debian (0.35.0+20060710.6) ... 421s Setting up dh-strip-nondeterminism (1.13.1-1) ... 421s Setting up libgcc-13-dev:amd64 (13.2.0-13ubuntu1) ... 421s Setting up libc6-dev:amd64 (2.39-0ubuntu2) ... 421s Setting up libstdc++-13-dev:amd64 (13.2.0-13ubuntu1) ... 421s Setting up cpp-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 421s Setting up cpp-13 (13.2.0-13ubuntu1) ... 421s Setting up golang-go:amd64 (2:1.22~2) ... 421s Setting up gcc-13-x86-64-linux-gnu (13.2.0-13ubuntu1) ... 421s Setting up po-debconf (1.0.21+nmu1) ... 421s Setting up gcc-13 (13.2.0-13ubuntu1) ... 421s Setting up cpp (4:13.2.0-7ubuntu1) ... 421s Setting up g++-13-x86-64-linux-gnu (13.2.0-13ubuntu1) ... 421s Setting up gcc-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 421s Setting up libtool (2.4.7-7) ... 421s Setting up gcc (4:13.2.0-7ubuntu1) ... 421s Setting up dh-autoreconf (20) ... 421s Setting up g++-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 421s Setting up g++-13 (13.2.0-13ubuntu1) ... 421s Setting up debhelper (13.14.1ubuntu1) ... 421s Setting up g++ (4:13.2.0-7ubuntu1) ... 421s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 421s Setting up build-essential (12.10ubuntu1) ... 421s Setting up dh-golang (1.62) ... 421s Setting up autopkgtest-satdep (0) ... 421s Processing triggers for man-db (2.12.0-3) ... 422s Processing triggers for install-info (7.1-3) ... 423s Processing triggers for libc-bin (2.39-0ubuntu2) ... 425s (Reading database ... 88380 files and directories currently installed.) 425s Removing autopkgtest-satdep (0) ... 425s autopkgtest [02:28:16]: test command1: ./debian/tests/test 425s autopkgtest [02:28:16]: test command1: [----------------------- 458s === RUN TestRunSignal 458s === RUN TestRunSignal/Send_SIGINT_exits 458s === RUN TestRunSignal/Send_SIGTERM_exits 458s --- PASS: TestRunSignal (0.20s) 458s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 458s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 458s === RUN TestRun 458s === PAUSE TestRun 458s === CONT TestRun 458s === RUN TestRun/Run_and_exit_successfully 458s === PAUSE TestRun/Run_and_exit_successfully 458s === RUN TestRun/Run_and_return_error 458s === PAUSE TestRun/Run_and_return_error 458s === RUN TestRun/Run_and_return_usage_error 458s === PAUSE TestRun/Run_and_return_usage_error 458s === RUN TestRun/Run_and_usage_error_only_does_not_fail 458s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 458s === CONT TestRun/Run_and_exit_successfully 458s === CONT TestRun/Run_and_return_usage_error 458s ERROR context.Background Error requested 458s === CONT TestRun/Run_and_usage_error_only_does_not_fail 458s === CONT TestRun/Run_and_return_error 458s ERROR context.Background Error requested 458s --- PASS: TestRun (0.00s) 458s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 458s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 458s --- PASS: TestRun/Run_and_return_error (0.10s) 458s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 458s PASS 458s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.411s 458s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 458s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 469s === RUN TestHelp 469s --- PASS: TestHelp (0.00s) 469s === RUN TestCompletion 469s --- PASS: TestCompletion (0.00s) 469s === RUN TestVersion 469s --- PASS: TestVersion (0.00s) 469s === RUN TestNoUsageError 469s --- PASS: TestNoUsageError (0.00s) 469s === RUN TestUsageError 469s === PAUSE TestUsageError 469s === RUN TestCanQuitWhenExecute 469s === PAUSE TestCanQuitWhenExecute 469s === RUN TestCanQuitTwice 469s === PAUSE TestCanQuitTwice 469s === RUN TestAppCanQuitWithoutExecute 469s === PAUSE TestAppCanQuitWithoutExecute 469s === RUN TestAppRunFailsOnComponentsCreationAndQuit 469s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 469s === RUN TestAppGetRootCmd 469s === PAUSE TestAppGetRootCmd 469s === RUN TestWithProMock 469s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 469s --- SKIP: TestWithProMock (0.00s) 469s === RUN TestWithWslPathMock 469s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 469s --- SKIP: TestWithWslPathMock (0.00s) 469s === RUN TestWithWslInfoMock 469s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 469s --- SKIP: TestWithWslInfoMock (0.00s) 469s === RUN TestWithCmdExeMock 469s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 469s --- SKIP: TestWithCmdExeMock (0.00s) 469s === CONT TestUsageError 469s --- PASS: TestUsageError (0.00s) 469s === CONT TestAppGetRootCmd 469s === CONT TestAppCanQuitWithoutExecute 469s service_test.go:138: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 469s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 469s === CONT TestAppRunFailsOnComponentsCreationAndQuit 469s --- PASS: TestAppGetRootCmd (0.00s) 469s === CONT TestCanQuitTwice 469s time="2024-02-29T02:28:49Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 469s time="2024-02-29T02:28:49Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:63 New() Building new daemon" 469s time="2024-02-29T02:28:49Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/testutils/mock_agent.go:82 MockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42551\"" 469s time="2024-02-29T02:28:53Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 469s time="2024-02-29T02:28:53Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 469s time="2024-02-29T02:28:55Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/controlstream/session.go:23 newSession() Connecting to control stream at \"127.0.0.1:42551\"" 469s time="2024-02-29T02:28:55Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/controlstream/controlstream.go:69 (*ControlStream).Connect() Connected to Windows agent via the control stream" 469s time="2024-02-29T02:28:55Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/testutils/mock_agent.go:136 (*wslInstanceMockService).Connected() wslInstanceMockService: Received incoming connection" 469s time="2024-02-29T02:28:55Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 469s time="2024-02-29T02:28:55Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 469s time="2024-02-29T02:28:55Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 469s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (6.22s) 469s === CONT TestCanQuitWhenExecute 469s time="2024-02-29T02:28:55Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 469s time="2024-02-29T02:28:55Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:63 New() Building new daemon" 469s time="2024-02-29T02:28:55Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/testutils/mock_agent.go:82 MockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44905\"" 469s time="2024-02-29T02:28:58Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/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\"" 469s time="2024-02-29T02:28:58Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/testutils/mock_agent.go:178 (*wslInstanceMockService).Connected() wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 42403" 469s time="2024-02-29T02:28:58Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/controlstream/controlstream.go:76 (*ControlStream).Connect() Completed handshake with Windows agent via the control stream" 469s time="2024-02-29T02:28:58Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:176 (*Daemon).serveOnce() Connected to control stream" 469s time="2024-02-29T02:28:58Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/wslinstanceservice/wslinstanceservice.go:41 (*Service).RegisterGRPCService() Registering GRPC WSL instance service" 469s time="2024-02-29T02:28:58Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:223 (*Daemon).serve() Starting to serve requests" 469s time="2024-02-29T02:28:58Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:233 (*Daemon).serve() Serving GRPC requests on localhost:42403" 469s time="2024-02-29T02:28:58Z" level=error msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:142 (*Daemon).Serve() serve error: WSL Pro Service stopped serving: grpc error: grpc: the server has been stopped" 469s time="2024-02-29T02:28:58Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 469s time="2024-02-29T02:28:58Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 469s time="2024-02-29T02:28:58Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 469s time="2024-02-29T02:28:58Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 469s time="2024-02-29T02:28:58Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 469s time="2024-02-29T02:28:58Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 469s time="2024-02-29T02:28:58Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 469s --- PASS: TestCanQuitTwice (8.37s) 469s time="2024-02-29T02:29:00Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 469s time="2024-02-29T02:29:00Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 469s time="2024-02-29T02:29:00Z" level=error msg="/tmp/autopkgtest.HYnWyr/build.36S/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/TestCanQuitWhenExecute2512277652/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestCanQuitWhenExecute2512277652/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 469s time="2024-02-29T02:29:00Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 469s time="2024-02-29T02:29:00Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 469s time="2024-02-29T02:29:00Z" level=info msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 469s time="2024-02-29T02:29:00Z" level=debug msg="/tmp/autopkgtest.HYnWyr/build.36S/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 469s --- PASS: TestCanQuitWhenExecute (4.43s) 469s PASS 469s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 10.665s 506s === RUN TestNew 506s === PAUSE TestNew 506s === RUN TestConnect 506s === PAUSE TestConnect 506s === RUN TestSend 506s === PAUSE TestSend 506s === RUN TestReconnection 506s === PAUSE TestReconnection 506s === RUN TestWithProMock 506s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 506s --- SKIP: TestWithProMock (0.00s) 506s === RUN TestWithWslPathMock 506s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 506s --- SKIP: TestWithWslPathMock (0.00s) 506s === RUN TestWithWslInfoMock 506s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 506s --- SKIP: TestWithWslInfoMock (0.00s) 506s === RUN TestWithCmdExeMock 506s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 506s --- SKIP: TestWithCmdExeMock (0.00s) 506s === CONT TestNew 506s === CONT TestSend 506s === RUN TestNew/Success 506s === PAUSE TestNew/Success 506s === RUN TestNew/Error_when_the_context_is_cancelled 506s === PAUSE TestNew/Error_when_the_context_is_cancelled 506s === RUN TestNew/Error_when_WslPath_returns_error 506s === PAUSE TestNew/Error_when_WslPath_returns_error 506s === CONT TestConnect 506s === RUN TestConnect/No_connection_because_of_unreadable_port_file 506s === PAUSE TestConnect/No_connection_because_of_unreadable_port_file 506s === RUN TestConnect/No_connection_because_of_empty_port_file 506s === PAUSE TestConnect/No_connection_because_of_empty_port_file 506s === RUN TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 506s === PAUSE TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 506s === RUN TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 506s === PAUSE TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 506s === RUN TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 506s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 506s === RUN TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 506s === PAUSE TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 506s === RUN TestConnect/No_connection_because_port_file_does_not_exist 506s === PAUSE TestConnect/No_connection_because_port_file_does_not_exist 506s === RUN TestConnect/No_connection_because_of_port_file_with_invalid_contents 506s === PAUSE TestConnect/No_connection_because_of_port_file_with_invalid_contents 506s === RUN TestConnect/Incomplete_handshake_because_Agent_never_receives 506s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_receives 506s === RUN TestConnect/Success 506s === PAUSE TestConnect/Success 506s === CONT TestReconnection 506s === RUN TestReconnection/Success_connecting_after_failing_to_connect 506s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 506s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 506s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 506s === CONT TestNew/Success 506s time="2024-02-29T02:28:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:46391\"" 506s === CONT TestConnect/No_connection_because_of_unreadable_port_file 506s time="2024-02-29T02:28:54Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:40517\"" 506s time="2024-02-29T02:28:56Z" level=info msg="Connecting to control stream at \"127.0.0.1:46391\"" 506s time="2024-02-29T02:28:56Z" level=debug msg="Connected to Windows agent via the control stream" 506s time="2024-02-29T02:28:56Z" level=info msg="wslInstanceMockService: Received incoming connection" 506s time="2024-02-29T02:28:58Z" 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\"" 506s time="2024-02-29T02:28:58Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 40847" 506s time="2024-02-29T02:28:58Z" level=debug msg="Completed handshake with Windows agent via the control stream" 506s === CONT TestNew/Error_when_WslPath_returns_error 506s === CONT TestNew/Error_when_the_context_is_cancelled 506s --- PASS: TestNew (0.00s) 506s --- PASS: TestNew/Success (3.91s) 506s --- PASS: TestNew/Error_when_WslPath_returns_error (1.89s) 506s --- PASS: TestNew/Error_when_the_context_is_cancelled (0.00s) 506s === CONT TestReconnection/Success_connecting_after_failing_to_connect 506s time="2024-02-29T02:29:03Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 506s time="2024-02-29T02:29:03Z" 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\"" 506s --- PASS: TestSend (13.62s) 506s === CONT TestConnect/Success 506s time="2024-02-29T02:29:04Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 506s time="2024-02-29T02:29:04Z" level=info msg="wslInstanceMockService: dropped connection" 506s time="2024-02-29T02:29:04Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39799\"" 506s time="2024-02-29T02:29:05Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39029\"" 506s time="2024-02-29T02:29:07Z" level=info msg="Connecting to control stream at \"127.0.0.1:39029\"" 506s time="2024-02-29T02:29:07Z" level=debug msg="Connected to Windows agent via the control stream" 506s time="2024-02-29T02:29:07Z" level=info msg="wslInstanceMockService: Received incoming connection" 506s time="2024-02-29T02:29:09Z" 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\"" 506s time="2024-02-29T02:29:09Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 35177" 506s time="2024-02-29T02:29:09Z" level=debug msg="Completed handshake with Windows agent via the control stream" 506s === CONT TestConnect/Incomplete_handshake_because_Agent_never_receives 506s time="2024-02-29T02:29:09Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36501\"" 506s time="2024-02-29T02:29:09Z" level=info msg="Connecting to control stream at \"127.0.0.1:39799\"" 506s time="2024-02-29T02:29:09Z" level=debug msg="Connected to Windows agent via the control stream" 506s time="2024-02-29T02:29:09Z" level=info msg="wslInstanceMockService: Received incoming connection" 506s time="2024-02-29T02:29: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\"" 506s time="2024-02-29T02:29:11Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 40767" 506s time="2024-02-29T02:29:11Z" level=debug msg="Completed handshake with Windows agent via the control stream" 506s === CONT TestConnect/No_connection_because_of_port_file_with_invalid_contents 506s time="2024-02-29T02:29:12Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectSuccess1466961262/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 506s time="2024-02-29T02:29:12Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33407\"" 506s time="2024-02-29T02:29:15Z" level=info msg="Connecting to control stream at \"127.0.0.1:36501\"" 506s time="2024-02-29T02:29:15Z" level=debug msg="Connected to Windows agent via the control stream" 506s time="2024-02-29T02:29:15Z" level=info msg="wslInstanceMockService: Received incoming connection" 506s time="2024-02-29T02:29:15Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 506s time="2024-02-29T02:29:15Z" level=info msg="wslInstanceMockService: dropped connection" 506s === CONT TestConnect/No_connection_because_port_file_does_not_exist 506s time="2024-02-29T02:29:17Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41675\"" 506s time="2024-02-29T02:29:18Z" level=info msg="Connecting to control stream at \"127.0.0.1:This text is not a valid IP address\"" 506s === CONT TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 506s time="2024-02-29T02:29:18Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_port_file_with_invalid_contents427458559/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 506s time="2024-02-29T02:29:18Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41081\"" 506s time="2024-02-29T02:29:22Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_port_file_does_not_exist2417505941/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 506s === CONT TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 506s time="2024-02-29T02:29:22Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43007\"" 506s time="2024-02-29T02:29:23Z" level=info msg="Connecting to control stream at \"127.0.0.1:41081\"" 506s time="2024-02-29T02:29:23Z" level=debug msg="Connected to Windows agent via the control stream" 506s time="2024-02-29T02:29:23Z" level=info msg="wslInstanceMockService: Received incoming connection" 506s time="2024-02-29T02:29:25Z" 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\"" 506s time="2024-02-29T02:29:25Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Sending bad port 0" 506s === CONT TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 506s time="2024-02-29T02:29:25Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectIncomplete_handshake_because_Agent_sends_port_02865645947/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 506s time="2024-02-29T02:29:25Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44041\"" 506s time="2024-02-29T02:29:26Z" level=info msg="Connecting to control stream at \"127.0.0.1:43007\"" 506s time="2024-02-29T02:29:26Z" level=debug msg="Connected to Windows agent via the control stream" 506s time="2024-02-29T02:29:26Z" level=info msg="wslInstanceMockService: Received incoming connection" 506s time="2024-02-29T02:29:28Z" 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\"" 506s time="2024-02-29T02:29:28Z" level=info msg="connection with \"TEST_DISTRO\": mock error: dropping stream before sending port" 506s time="2024-02-29T02:29:28Z" level=info msg="wslInstanceMockService: dropped connection" 506s === CONT TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 506s time="2024-02-29T02:29:28Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43755\"" 506s === CONT TestConnect/No_connection_because_of_empty_port_file 506s time="2024-02-29T02:29:29Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectError_because_WindowsForwardedLocalhost_returns_error891933171/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 506s time="2024-02-29T02:29:29Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:46841\"" 506s time="2024-02-29T02:29:32Z" level=info msg="Connecting to control stream at \"127.0.0.1:35885\"" 506s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 506s time="2024-02-29T02:29:32Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_port_file_contains_the_wrong_port3032847159/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 506s time="2024-02-29T02:29:33Z" level=info msg="Connecting to control stream at \"127.0.0.1:\"" 506s --- PASS: TestConnect (0.00s) 506s --- PASS: TestConnect/No_connection_because_of_unreadable_port_file (4.43s) 506s --- PASS: TestConnect/Success (8.07s) 506s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_receives (7.97s) 506s --- PASS: TestConnect/No_connection_because_of_port_file_with_invalid_contents (5.66s) 506s --- PASS: TestConnect/No_connection_because_port_file_does_not_exist (5.30s) 506s --- PASS: TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 (7.23s) 506s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port (5.44s) 506s --- PASS: TestConnect/Error_because_WindowsForwardedLocalhost_returns_error (4.21s) 506s --- PASS: TestConnect/No_connection_because_of_port_file_contains_the_wrong_port (4.25s) 506s --- PASS: TestConnect/No_connection_because_of_empty_port_file (4.25s) 506s time="2024-02-29T02:29:34Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:35177: connect: connection refused" 506s time="2024-02-29T02:29:34Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:35283\"" 506s time="2024-02-29T02:29:35Z" level=info msg="Connecting to control stream at \"127.0.0.1:35283\"" 506s time="2024-02-29T02:29:35Z" level=debug msg="Connected to Windows agent via the control stream" 506s time="2024-02-29T02:29:35Z" level=info msg="wslInstanceMockService: Received incoming connection" 506s time="2024-02-29T02:29:36Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 506s time="2024-02-29T02:29:36Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 40821" 506s time="2024-02-29T02:29:36Z" level=debug msg="Completed handshake with Windows agent via the control stream" 506s time="2024-02-29T02:29:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:40407\"" 506s time="2024-02-29T02:29:36Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:40767: connect: connection refused" 506s time="2024-02-29T02:29:37Z" level=info msg="Connecting to control stream at \"127.0.0.1:40407\"" 506s time="2024-02-29T02:29:37Z" level=debug msg="Connected to Windows agent via the control stream" 506s time="2024-02-29T02:29:37Z" level=info msg="wslInstanceMockService: Received incoming connection" 506s time="2024-02-29T02:29: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\"" 506s time="2024-02-29T02:29:37Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 42073" 506s time="2024-02-29T02:29:37Z" level=debug msg="Completed handshake with Windows agent via the control stream" 506s --- PASS: TestReconnection (0.00s) 506s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (8.42s) 506s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (5.25s) 506s PASS 506s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/controlstream 46.883s 509s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/logstreamer/test [no test files] 539s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 631s === RUN TestNew 631s === PAUSE TestNew 631s === RUN TestServe 631s === PAUSE TestServe 631s === RUN TestServeAndQuit 631s === PAUSE TestServeAndQuit 631s === RUN TestReconnection 631s === PAUSE TestReconnection 631s === RUN TestWithProMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithProMock (0.00s) 631s === RUN TestWithWslPathMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithWslPathMock (0.00s) 631s === RUN TestWithWslInfoMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithWslInfoMock (0.00s) 631s === RUN TestWithCmdExeMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithCmdExeMock (0.00s) 631s === CONT TestNew 631s === RUN TestNew/Success 631s === PAUSE TestNew/Success 631s === RUN TestNew/Error_when_WslPath_returns_error 631s === PAUSE TestNew/Error_when_WslPath_returns_error 631s === CONT TestNew/Success 631s === CONT TestServeAndQuit 631s === RUN TestServeAndQuit/Success_with_graceful_quit 631s === PAUSE TestServeAndQuit/Success_with_graceful_quit 631s === RUN TestServeAndQuit/Success_with_forceful_quit 631s === PAUSE TestServeAndQuit/Success_with_forceful_quit 631s === RUN TestServeAndQuit/Success_with_double_quit 631s === PAUSE TestServeAndQuit/Success_with_double_quit 631s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 631s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 631s === CONT TestServeAndQuit/Success_with_graceful_quit 631s time="2024-02-29T02:29:01Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:01Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:01Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:37145\"" 631s time="2024-02-29T02:29:04Z" level=debug msg="Ready state sent to systemd" 631s === CONT TestServe 631s === RUN TestServe/Success 631s === PAUSE TestServe/Success 631s === RUN TestServe/Success_with_systemd_notifier_returning_true 631s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 631s === RUN TestServe/No_connection_because_port_file_does_not_exist 631s === PAUSE TestServe/No_connection_because_port_file_does_not_exist 631s === RUN TestServe/No_connection_because_of_faulty_agent 631s === PAUSE TestServe/No_connection_because_of_faulty_agent 631s === RUN TestServe/Error_because_of_notifier_returning_error 631s === PAUSE TestServe/Error_because_of_notifier_returning_error 631s === RUN TestServe/Error_because_WindowsHostAddress_returns_error 631s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_error 631s === RUN TestServe/Error_because_of_context_cancelled 631s === PAUSE TestServe/Error_because_of_context_cancelled 631s === CONT TestNew/Error_when_WslPath_returns_error 631s time="2024-02-29T02:29:04Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:06Z" level=info msg="Connecting to control stream at \"127.0.0.1:37145\"" 631s time="2024-02-29T02:29:06Z" level=debug msg="Connected to Windows agent via the control stream" 631s time="2024-02-29T02:29:06Z" level=info msg="wslInstanceMockService: Received incoming connection" 631s --- PASS: TestNew (0.00s) 631s --- PASS: TestNew/Success (3.13s) 631s --- PASS: TestNew/Error_when_WslPath_returns_error (3.75s) 631s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 631s time="2024-02-29T02:29:08Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34667\"" 631s time="2024-02-29T02:29:08Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:08Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 631s time="2024-02-29T02:29:08Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 32993" 631s time="2024-02-29T02:29:08Z" level=debug msg="Completed handshake with Windows agent via the control stream" 631s time="2024-02-29T02:29:08Z" level=info msg="Connected to control stream" 631s time="2024-02-29T02:29:08Z" level=debug msg="Starting to serve requests" 631s time="2024-02-29T02:29:08Z" level=info msg="Serving GRPC requests on localhost:32993" 631s time="2024-02-29T02:29:08Z" level=debug msg="Updated systemd status to \"Serving\"" 631s time="2024-02-29T02:29:08Z" level=info msg="Stopping daemon requested." 631s time="2024-02-29T02:29:08Z" level=info msg="Waiting for active requests to close." 631s time="2024-02-29T02:29:08Z" level=debug msg="Updated systemd status to \"Stopped\"" 631s time="2024-02-29T02:29:08Z" level=debug msg="All connections have now ended." 631s time="2024-02-29T02:29:08Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_graceful_quit1858678466/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 631s === CONT TestReconnection 631s === RUN TestReconnection/Success_connecting_after_failing_to_connect 631s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 631s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 631s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 631s === CONT TestServeAndQuit/Success_with_double_quit 631s time="2024-02-29T02:29:08Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:08Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39477\"" 631s time="2024-02-29T02:29:11Z" level=debug msg="Updated systemd status to \"Stopped\"" 631s === CONT TestServeAndQuit/Success_with_forceful_quit 631s time="2024-02-29T02:29:11Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42625\"" 631s time="2024-02-29T02:29:11Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:12Z" level=debug msg="Ready state sent to systemd" 631s time="2024-02-29T02:29:14Z" level=info msg="Connecting to control stream at \"127.0.0.1:39477\"" 631s time="2024-02-29T02:29:14Z" level=debug msg="Connected to Windows agent via the control stream" 631s time="2024-02-29T02:29:14Z" level=info msg="wslInstanceMockService: Received incoming connection" 631s time="2024-02-29T02:29:14Z" level=debug msg="Ready state sent to systemd" 631s time="2024-02-29T02:29:16Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 631s time="2024-02-29T02:29:16Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 41501" 631s time="2024-02-29T02:29:16Z" level=debug msg="Completed handshake with Windows agent via the control stream" 631s time="2024-02-29T02:29:16Z" level=info msg="Connected to control stream" 631s time="2024-02-29T02:29:16Z" level=debug msg="Starting to serve requests" 631s time="2024-02-29T02:29:16Z" level=info msg="Serving GRPC requests on localhost:41501" 631s time="2024-02-29T02:29:16Z" level=debug msg="Updated systemd status to \"Serving\"" 631s time="2024-02-29T02:29:16Z" level=info msg="Stopping daemon requested." 631s time="2024-02-29T02:29:16Z" level=info msg="Waiting for active requests to close." 631s time="2024-02-29T02:29:16Z" level=debug msg="Updated systemd status to \"Stopped\"" 631s time="2024-02-29T02:29:16Z" level=debug msg="All connections have now ended." 631s time="2024-02-29T02:29:16Z" level=info msg="Stopping daemon requested." 631s time="2024-02-29T02:29:16Z" level=info msg="Waiting for active requests to close." 631s time="2024-02-29T02:29:16Z" level=debug msg="All connections have now ended." 631s time="2024-02-29T02:29:16Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_double_quit3612412781/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 631s === CONT TestServe/Success 631s time="2024-02-29T02:29:16Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:40321\"" 631s time="2024-02-29T02:29:16Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:16Z" level=info msg="Connecting to control stream at \"127.0.0.1:42625\"" 631s time="2024-02-29T02:29:16Z" level=debug msg="Connected to Windows agent via the control stream" 631s time="2024-02-29T02:29:16Z" level=info msg="wslInstanceMockService: Received incoming connection" 631s time="2024-02-29T02:29:18Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 631s time="2024-02-29T02:29:18Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 42331" 631s time="2024-02-29T02:29:18Z" level=debug msg="Completed handshake with Windows agent via the control stream" 631s time="2024-02-29T02:29:18Z" level=info msg="Connected to control stream" 631s time="2024-02-29T02:29:18Z" level=debug msg="Starting to serve requests" 631s time="2024-02-29T02:29:18Z" level=info msg="Serving GRPC requests on localhost:42331" 631s time="2024-02-29T02:29:18Z" level=debug msg="Updated systemd status to \"Serving\"" 631s time="2024-02-29T02:29:18Z" level=info msg="Stopping daemon requested." 631s time="2024-02-29T02:29:18Z" level=debug msg="Updated systemd status to \"Stopped\"" 631s --- PASS: TestServeAndQuit (0.00s) 631s --- PASS: TestServeAndQuit/Success_with_graceful_quit (7.11s) 631s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (3.57s) 631s --- PASS: TestServeAndQuit/Success_with_double_quit (8.18s) 631s --- PASS: TestServeAndQuit/Success_with_forceful_quit (7.15s) 631s === CONT TestServe/Error_because_of_notifier_returning_error 631s time="2024-02-29T02:29:18Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:18Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:32853\"" 631s time="2024-02-29T02:29:22Z" level=info msg="Connecting to control stream at \"127.0.0.1:40321\"" 631s time="2024-02-29T02:29:22Z" level=debug msg="Connected to Windows agent via the control stream" 631s time="2024-02-29T02:29:22Z" level=info msg="wslInstanceMockService: Received incoming connection" 631s time="2024-02-29T02:29:22Z" level=warning msg="Could not change systemd status: couldn't update status to systemd: mock error" 631s === CONT TestServe/Error_because_of_context_cancelled 631s time="2024-02-29T02:29:22Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:22Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeError_because_of_notifier_returning_error1667106068/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 631s time="2024-02-29T02:29:22Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34101\"" 631s time="2024-02-29T02:29:23Z" 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\"" 631s time="2024-02-29T02:29:23Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 43329" 631s time="2024-02-29T02:29:23Z" level=debug msg="Completed handshake with Windows agent via the control stream" 631s time="2024-02-29T02:29:23Z" level=info msg="Connected to control stream" 631s time="2024-02-29T02:29:23Z" level=debug msg="Starting to serve requests" 631s time="2024-02-29T02:29:23Z" level=info msg="Serving GRPC requests on localhost:43329" 631s === CONT TestServe/Error_because_WindowsHostAddress_returns_error 631s time="2024-02-29T02:29:25Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:25Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeError_because_of_context_cancelled3176119660/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 631s time="2024-02-29T02:29:25Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39429\"" 631s time="2024-02-29T02:29:28Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 631s === CONT TestServe/No_connection_because_port_file_does_not_exist 631s time="2024-02-29T02:29:29Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:40353\"" 631s time="2024-02-29T02:29:29Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:30Z" level=info msg="Stopping daemon requested." 631s time="2024-02-29T02:29:32Z" level=info msg="Stopping daemon requested." 631s time="2024-02-29T02:29:33Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:32993: connect: connection refused" 631s time="2024-02-29T02:29:34Z" 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_exist2567914463/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist2567914463/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 631s time="2024-02-29T02:29:36Z" level=info msg="Retrying connection" 631s time="2024-02-29T02:29:37Z" level=error msg="serve error: could not connect to windows agent via the control stream: could not get address: could not read agent port file \"/tmp/TestServeNo_connection_because_port_file_does_not_exist2567914463/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist2567914463/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 631s time="2024-02-29T02:29:38Z" level=info msg="Stopping daemon requested." 631s time="2024-02-29T02:29:41Z" level=info msg="Retrying connection" 631s time="2024-02-29T02:29:41Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:41501: connect: connection refused" 631s time="2024-02-29T02:29:42Z" 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_exist2567914463/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist2567914463/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 631s time="2024-02-29T02:29:42Z" level=info msg="Stopping daemon requested." 631s time="2024-02-29T02:29:42Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeNo_connection_because_port_file_does_not_exist2567914463/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 631s === CONT TestServe/No_connection_because_of_faulty_agent 631s time="2024-02-29T02:29:42Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33135\"" 631s time="2024-02-29T02:29:42Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:43Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:42331: connect: connection refused" 631s time="2024-02-29T02:29:47Z" level=info msg="Connecting to control stream at \"127.0.0.1:33135\"" 631s time="2024-02-29T02:29:47Z" level=debug msg="Connected to Windows agent via the control stream" 631s time="2024-02-29T02:29:47Z" level=info msg="wslInstanceMockService: Received incoming connection" 631s time="2024-02-29T02:29:47Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 631s time="2024-02-29T02:29:47Z" level=info msg="wslInstanceMockService: dropped connection" 631s time="2024-02-29T02:29:48Z" 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" 631s time="2024-02-29T02:29:50Z" level=info msg="Retrying connection" 631s time="2024-02-29T02:29:51Z" level=info msg="Connecting to control stream at \"127.0.0.1:33135\"" 631s time="2024-02-29T02:29:51Z" level=debug msg="Connected to Windows agent via the control stream" 631s time="2024-02-29T02:29:51Z" level=info msg="wslInstanceMockService: Received incoming connection" 631s time="2024-02-29T02:29:51Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 631s time="2024-02-29T02:29:51Z" level=info msg="wslInstanceMockService: dropped connection" 631s time="2024-02-29T02:29:53Z" 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" 631s time="2024-02-29T02:29:55Z" level=info msg="Stopping daemon requested." 631s === CONT TestServe/Success_with_systemd_notifier_returning_true 631s time="2024-02-29T02:29:55Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:29:55Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeNo_connection_because_of_faulty_agent2632173292/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 631s time="2024-02-29T02:29:55Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33553\"" 631s time="2024-02-29T02:29:58Z" level=debug msg="Ready state sent to systemd" 631s time="2024-02-29T02:30:00Z" level=info msg="Connecting to control stream at \"127.0.0.1:33553\"" 631s time="2024-02-29T02:30:00Z" level=debug msg="Connected to Windows agent via the control stream" 631s time="2024-02-29T02:30:00Z" level=info msg="wslInstanceMockService: Received incoming connection" 631s time="2024-02-29T02:30:01Z" 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\"" 631s time="2024-02-29T02:30:01Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 40143" 631s time="2024-02-29T02:30:01Z" level=debug msg="Completed handshake with Windows agent via the control stream" 631s time="2024-02-29T02:30:01Z" level=info msg="Connected to control stream" 631s time="2024-02-29T02:30:01Z" level=debug msg="Starting to serve requests" 631s time="2024-02-29T02:30:01Z" level=info msg="Serving GRPC requests on localhost:40143" 631s time="2024-02-29T02:30:01Z" level=debug msg="Updated systemd status to \"Serving\"" 631s time="2024-02-29T02:30:06Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 631s time="2024-02-29T02:30:08Z" level=info msg="Stopping daemon requested." 631s time="2024-02-29T02:31:28Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 631s time="2024-02-29T02:31:28Z" level=info msg="wslInstanceMockService: dropped connection" 631s === CONT TestReconnection/Success_connecting_after_failing_to_connect 631s time="2024-02-29T02:31:28Z" level=debug msg="Updated systemd status to \"Stopped\"" 631s time="2024-02-29T02:31:28Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 631s time="2024-02-29T02:31:28Z" level=info msg="wslInstanceMockService: dropped connection" 631s time="2024-02-29T02:31:28Z" level=debug msg="Building new daemon" 631s --- PASS: TestServe (0.00s) 631s --- PASS: TestServe/Error_because_of_notifier_returning_error (3.43s) 631s --- PASS: TestServe/Error_because_of_context_cancelled (3.22s) 631s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_error (4.56s) 631s --- PASS: TestServe/No_connection_because_port_file_does_not_exist (13.02s) 631s --- PASS: TestServe/No_connection_because_of_faulty_agent (12.95s) 631s --- PASS: TestServe/Success (132.51s) 631s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (93.04s) 631s time="2024-02-29T02:31:28Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeSuccess_with_systemd_notifier_returning_true895631111/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 631s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 631s time="2024-02-29T02:31:28Z" level=debug msg="Building new daemon" 631s time="2024-02-29T02:31:30Z" level=debug msg="Ready state sent to systemd" 631s time="2024-02-29T02:31:30Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39323\"" 631s time="2024-02-29T02:31:31Z" level=debug msg="Ready state sent to systemd" 631s time="2024-02-29T02:31:31Z" level=info msg="Connecting to control stream at \"127.0.0.1:39323\"" 631s time="2024-02-29T02:31:31Z" level=debug msg="Connected to Windows agent via the control stream" 631s time="2024-02-29T02:31:31Z" level=info msg="wslInstanceMockService: Received incoming connection" 631s time="2024-02-29T02:31:32Z" 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_connect3201890823/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect3201890823/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 631s time="2024-02-29T02:31:32Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 631s time="2024-02-29T02:31:32Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:37029\"" 631s time="2024-02-29T02:31: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\"" 631s time="2024-02-29T02:31:32Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 35651" 631s time="2024-02-29T02:31:32Z" level=debug msg="Completed handshake with Windows agent via the control stream" 631s time="2024-02-29T02:31:32Z" level=info msg="Connected to control stream" 631s time="2024-02-29T02:31:32Z" level=debug msg="Starting to serve requests" 631s time="2024-02-29T02:31:32Z" level=info msg="Serving GRPC requests on localhost:35651" 631s time="2024-02-29T02:31:32Z" level=debug msg="Updated systemd status to \"Serving\"" 631s time="2024-02-29T02:31:32Z" level=error msg="serve error: lost connection to Windows Agent" 631s time="2024-02-29T02:31:32Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 631s time="2024-02-29T02:31:33Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34013\"" 631s time="2024-02-29T02:31:34Z" level=info msg="Retrying connection" 631s time="2024-02-29T02:31:34Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 631s time="2024-02-29T02:31:34Z" level=info msg="Connecting to control stream at \"127.0.0.1:37029\"" 631s time="2024-02-29T02:31:34Z" level=debug msg="Connected to Windows agent via the control stream" 631s time="2024-02-29T02:31:34Z" level=info msg="wslInstanceMockService: Received incoming connection" 631s time="2024-02-29T02:31:34Z" level=info msg="Retrying connection" 631s time="2024-02-29T02:31:34Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 631s time="2024-02-29T02:31:35Z" 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\"" 631s time="2024-02-29T02:31:35Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 34625" 631s time="2024-02-29T02:31:35Z" level=debug msg="Completed handshake with Windows agent via the control stream" 631s time="2024-02-29T02:31:35Z" level=info msg="Connected to control stream" 631s time="2024-02-29T02:31:35Z" level=debug msg="Starting to serve requests" 631s time="2024-02-29T02:31:35Z" level=info msg="Serving GRPC requests on localhost:34625" 631s time="2024-02-29T02:31:35Z" level=debug msg="Updated systemd status to \"Serving\"" 631s time="2024-02-29T02:31:35Z" level=info msg="Connecting to control stream at \"127.0.0.1:34013\"" 631s time="2024-02-29T02:31:35Z" level=debug msg="Connected to Windows agent via the control stream" 631s time="2024-02-29T02:31:35Z" level=info msg="wslInstanceMockService: Received incoming connection" 631s time="2024-02-29T02:31:36Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 631s time="2024-02-29T02:31:36Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 33223" 631s time="2024-02-29T02:31:36Z" level=debug msg="Completed handshake with Windows agent via the control stream" 631s time="2024-02-29T02:31:36Z" level=info msg="Connected to control stream" 631s time="2024-02-29T02:31:36Z" level=debug msg="Starting to serve requests" 631s time="2024-02-29T02:31:36Z" level=info msg="Serving GRPC requests on localhost:33223" 631s time="2024-02-29T02:31:36Z" level=debug msg="Updated systemd status to \"Serving\"" 631s time="2024-02-29T02:31:40Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 631s time="2024-02-29T02:31:41Z" level=info msg="Stopping daemon requested." 631s time="2024-02-29T02:31:41Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 631s time="2024-02-29T02:31:41Z" level=info msg="wslInstanceMockService: dropped connection" 631s time="2024-02-29T02:31:41Z" level=error msg="serve error: lost connection to Windows Agent" 631s time="2024-02-29T02:31:41Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 631s time="2024-02-29T02:31:41Z" level=debug msg="Updated systemd status to \"Stopped\"" 631s time="2024-02-29T02:31:41Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 631s time="2024-02-29T02:31:42Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 631s time="2024-02-29T02:31:42Z" level=info msg="wslInstanceMockService: dropped connection" 631s time="2024-02-29T02:31:42Z" level=info msg="Stopping daemon requested." 631s time="2024-02-29T02:31:42Z" level=error msg="serve error: lost connection to Windows Agent" 631s time="2024-02-29T02:31:42Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 631s time="2024-02-29T02:31:42Z" level=debug msg="Updated systemd status to \"Stopped\"" 631s --- PASS: TestReconnection (0.00s) 631s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (12.16s) 631s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (13.05s) 631s PASS 631s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 160.856s 631s === RUN TestActiveConnection 631s === PAUSE TestActiveConnection 631s === RUN TestTimeoutOnInactiveConnection 631s === PAUSE TestTimeoutOnInactiveConnection 631s === RUN TestCancelOnClientSide 631s === PAUSE TestCancelOnClientSide 631s === RUN TestClientInterceptorFailed 631s === PAUSE TestClientInterceptorFailed 631s === RUN TestRecvMessageError 631s === PAUSE TestRecvMessageError 631s === CONT TestActiveConnection 631s === CONT TestClientInterceptorFailed 631s --- PASS: TestClientInterceptorFailed (0.00s) 631s === CONT TestCancelOnClientSide 631s --- PASS: TestCancelOnClientSide (0.00s) 631s === CONT TestTimeoutOnInactiveConnection 631s --- PASS: TestTimeoutOnInactiveConnection (0.05s) 631s === CONT TestRecvMessageError 631s --- PASS: TestRecvMessageError (0.00s) 631s --- PASS: TestActiveConnection (0.15s) 631s PASS 631s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/contextidler 0.157s 631s === RUN TestFormat 631s === PAUSE TestFormat 631s === CONT TestFormat 631s === RUN TestFormat/GRPC_Unknown_errors_don't_print_status_and_daemon 631s === PAUSE TestFormat/GRPC_Unknown_errors_don't_print_status_and_daemon 631s === RUN TestFormat/GRPC_Random_errors_prints_status_and_message 631s === PAUSE TestFormat/GRPC_Random_errors_prints_status_and_message 631s === RUN TestFormat/Nil_returns_nil 631s === PAUSE TestFormat/Nil_returns_nil 631s === RUN TestFormat/Non_GRPC_errors_are_returned_as_is 631s === PAUSE TestFormat/Non_GRPC_errors_are_returned_as_is 631s === RUN TestFormat/GRPC_Unavailable_errors_prints_daemon_name 631s === PAUSE TestFormat/GRPC_Unavailable_errors_prints_daemon_name 631s === RUN TestFormat/GRPC_Deadline_errors_don't_print_status_nor_daemon_nor_message 631s === PAUSE TestFormat/GRPC_Deadline_errors_don't_print_status_nor_daemon_nor_message 631s === CONT TestFormat/GRPC_Unknown_errors_don't_print_status_and_daemon 631s === CONT TestFormat/Non_GRPC_errors_are_returned_as_is 631s === CONT TestFormat/GRPC_Random_errors_prints_status_and_message 631s === CONT TestFormat/Nil_returns_nil 631s === CONT TestFormat/GRPC_Deadline_errors_don't_print_status_nor_daemon_nor_message 631s === CONT TestFormat/GRPC_Unavailable_errors_prints_daemon_name 631s --- PASS: TestFormat (0.00s) 631s --- PASS: TestFormat/GRPC_Unknown_errors_don't_print_status_and_daemon (0.00s) 631s --- PASS: TestFormat/Non_GRPC_errors_are_returned_as_is (0.00s) 631s --- PASS: TestFormat/GRPC_Random_errors_prints_status_and_message (0.00s) 631s --- PASS: TestFormat/Nil_returns_nil (0.00s) 631s --- PASS: TestFormat/GRPC_Deadline_errors_don't_print_status_nor_daemon_nor_message (0.00s) 631s --- PASS: TestFormat/GRPC_Unavailable_errors_prints_daemon_name (0.00s) 631s PASS 631s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/grpcerror 0.004s 631s === RUN TestStreamServer 631s === PAUSE TestStreamServer 631s === RUN TestStreamClient 631s === PAUSE TestStreamClient 631s === CONT TestStreamServer 631s === CONT TestStreamClient 631s --- PASS: TestStreamServer (0.00s) 631s --- PASS: TestStreamClient (0.00s) 631s PASS 631s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/interceptorschain 0.005s 631s === RUN TestChildRecvMsgAndHandlerCalled 631s === PAUSE TestChildRecvMsgAndHandlerCalled 631s === CONT TestChildRecvMsgAndHandlerCalled 631s === RUN TestChildRecvMsgAndHandlerCalled/Handler_and_RecvMsg_are_called 631s === PAUSE TestChildRecvMsgAndHandlerCalled/Handler_and_RecvMsg_are_called 631s === RUN TestChildRecvMsgAndHandlerCalled/Info_being_nil_has_no_impact 631s === PAUSE TestChildRecvMsgAndHandlerCalled/Info_being_nil_has_no_impact 631s === RUN TestChildRecvMsgAndHandlerCalled/Error_when_handler_fails_out 631s === PAUSE TestChildRecvMsgAndHandlerCalled/Error_when_handler_fails_out 631s === RUN TestChildRecvMsgAndHandlerCalled/Error_when_RecvMsg_errors_out 631s === PAUSE TestChildRecvMsgAndHandlerCalled/Error_when_RecvMsg_errors_out 631s === CONT TestChildRecvMsgAndHandlerCalled/Handler_and_RecvMsg_are_called 631s === CONT TestChildRecvMsgAndHandlerCalled/Error_when_RecvMsg_errors_out 631s === CONT TestChildRecvMsgAndHandlerCalled/Error_when_handler_fails_out 631s time="2024-02-29T02:29:39Z" level=info msg="Error sent to client: Failing handler" 631s === CONT TestChildRecvMsgAndHandlerCalled/Info_being_nil_has_no_impact 631s --- PASS: TestChildRecvMsgAndHandlerCalled (0.00s) 631s --- PASS: TestChildRecvMsgAndHandlerCalled/Handler_and_RecvMsg_are_called (0.00s) 631s --- PASS: TestChildRecvMsgAndHandlerCalled/Error_when_RecvMsg_errors_out (0.00s) 631s --- PASS: TestChildRecvMsgAndHandlerCalled/Error_when_handler_fails_out (0.00s) 631s --- PASS: TestChildRecvMsgAndHandlerCalled/Info_being_nil_has_no_impact (0.00s) 631s PASS 631s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/logconnections 0.005s 631s === RUN TestRecvLogMsg 631s === PAUSE TestRecvLogMsg 631s === RUN TestAddStreamToForwardLocalLogs 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:310017]]" 631s time="2024-02-29T02:29:40Z" level=warning msg=something 631s --- PASS: TestAddStreamToForwardLocalLogs (0.00s) 631s === RUN TestAddStreamToForwardOtherStream 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:930126]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:163003]]" 631s --- PASS: TestAddStreamToForwardOtherStream (0.00s) 631s === RUN TestAddStreamToForwardAfterClientIsConnected 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:686324]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:631463]]" 631s --- PASS: TestAddStreamToForwardAfterClientIsConnected (0.00s) 631s === RUN TestAddStreamToForwardDisconnect 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:939354]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:645544]]" 631s --- PASS: TestAddStreamToForwardDisconnect (0.00s) 631s === RUN TestAddStreamToForwardTwoClients 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:623571]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:342211]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:964093]]" 631s --- PASS: TestAddStreamToForwardTwoClients (0.00s) 631s === RUN TestAddStreamToForwardWithListenerCaller 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:975752]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:874497]]" 631s --- PASS: TestAddStreamToForwardWithListenerCaller (0.00s) 631s === RUN TestAddStreamMultipleForwarders 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:657479]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:060733]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:352943]]" 631s --- PASS: TestAddStreamMultipleForwarders (0.00s) 631s === RUN TestAddStreamMultipleForwardersOneWithCaller 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:772764]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:910403]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:974974]]" 631s --- PASS: TestAddStreamMultipleForwardersOneWithCaller (0.00s) 631s === RUN TestAddStreamToForwardFailSend 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:936199]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:036266]]" 631s time="2024-02-29T02:29:40Z" level=warning msg="Couldn't send log to one or more listener: SendMsg failed" 631s --- PASS: TestAddStreamToForwardFailSend (0.00s) 631s === RUN TestRemoveAllStreams 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:666699]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:978270]]" 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:238756]]" 631s time="2024-02-29T02:29:40Z" level=warning msg="[[123456:238756]] else" 631s --- PASS: TestRemoveAllStreams (0.00s) 631s === RUN TestLogWarningOnly 631s === PAUSE TestLogWarningOnly 631s === RUN TestMultipleLogs 631s === PAUSE TestMultipleLogs 631s === RUN TestAllLogLevels 631s === PAUSE TestAllLogLevels 631s === RUN TestDebugSentToRemoteEvenIfLocalIsWarning 631s === PAUSE TestDebugSentToRemoteEvenIfLocalIsWarning 631s === RUN TestLogWarningWithLocalCaller 631s === PAUSE TestLogWarningWithLocalCaller 631s === RUN TestLogWarningWithRemoteCaller 631s === PAUSE TestLogWarningWithRemoteCaller 631s === RUN TestLogWithNoCaller 631s === PAUSE TestLogWithNoCaller 631s === RUN TestSetReportCaller 631s === RUN TestSetReportCaller/Report_caller 631s === RUN TestSetReportCaller/Disable_caller 631s --- PASS: TestSetReportCaller (0.00s) 631s --- PASS: TestSetReportCaller/Report_caller (0.00s) 631s --- PASS: TestSetReportCaller/Disable_caller (0.00s) 631s === RUN TestLogSendingFail 631s === PAUSE TestLogSendingFail 631s === RUN TestLogStreamsAreSeparated 631s === PAUSE TestLogStreamsAreSeparated 631s === RUN TestLogAddHook 631s time="2024-02-29T02:29:40Z" level=info 631s --- PASS: TestLogAddHook (0.00s) 631s === RUN TestStreamServerInterceptor 631s === PAUSE TestStreamServerInterceptor 631s === RUN TestStreamServerInterceptorSendLogsFails 631s === PAUSE TestStreamServerInterceptorSendLogsFails 631s === RUN TestStreamServerInterceptorLoggerInvalidMetadata 631s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata 631s === CONT TestRecvLogMsg 631s === RUN TestRecvLogMsg/No_caller_when_not_requested 631s === PAUSE TestRecvLogMsg/No_caller_when_not_requested 631s === RUN TestRecvLogMsg/One_message_with_error,_no_log,_error_is_preserved 631s === PAUSE TestRecvLogMsg/One_message_with_error,_no_log,_error_is_preserved 631s === RUN TestRecvLogMsg/Logs_and_then_message_with_error,_error_is_preserved 631s === CONT TestLogWithNoCaller 631s === PAUSE TestRecvLogMsg/Logs_and_then_message_with_error,_error_is_preserved 631s === RUN TestRecvLogMsg/Unknown_log_level_triggers_a_client_error_(protocole_issue) 631s === PAUSE TestRecvLogMsg/Unknown_log_level_triggers_a_client_error_(protocole_issue) 631s === RUN TestRecvLogMsg/One_message,_no_log 631s === PAUSE TestRecvLogMsg/One_message,_no_log 631s === RUN TestRecvLogMsg/Two_logs_with_different_debug_level 631s === PAUSE TestRecvLogMsg/Two_logs_with_different_debug_level 631s === RUN TestRecvLogMsg/Log_with_caller 631s === PAUSE TestRecvLogMsg/Log_with_caller 631s === RUN TestRecvLogMsg/One_log_(and_one_closing_empty_message) 631s Failed to fire hook: hook fired 631s === PAUSE TestRecvLogMsg/One_log_(and_one_closing_empty_message) 631s === RUN TestRecvLogMsg/No_caller_on_any_logs 631s === PAUSE TestRecvLogMsg/No_caller_on_any_logs 631s === RUN TestRecvLogMsg/Invalid_object_passed_to_RecvMsg_is_gracefully_skipped 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:084209]]" 631s === PAUSE TestRecvLogMsg/Invalid_object_passed_to_RecvMsg_is_gracefully_skipped 631s === CONT TestLogWarningWithRemoteCaller 631s --- PASS: TestLogWithNoCaller (0.00s) 631s === CONT TestLogWarningWithLocalCaller 631s Failed to fire hook: hook fired 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:190835]]" 631s Failed to fire hook: hook fired 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:597826]]" 631s --- PASS: TestLogWarningWithLocalCaller (0.00s) 631s === CONT TestDebugSentToRemoteEvenIfLocalIsWarning 631s --- PASS: TestLogWarningWithRemoteCaller (0.00s) 631s === CONT TestAllLogLevels 631s Failed to fire hook: hook fired 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:406074]]" 631s --- PASS: TestDebugSentToRemoteEvenIfLocalIsWarning (0.00s) 631s Failed to fire hook: hook fired 631s === CONT TestMultipleLogs 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:885360]]" 631s Failed to fire hook: hook fired 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:209352]]" 631s --- PASS: TestMultipleLogs (0.00s) 631s === CONT TestLogWarningOnly 631s Failed to fire hook: hook fired 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:073772]]" 631s --- PASS: TestLogWarningOnly (0.00s) 631s === CONT TestStreamServerInterceptorLoggerInvalidMetadata 631s === RUN TestStreamServerInterceptorLoggerInvalidMetadata/Missing_client_ID 631s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata/Missing_client_ID 631s === RUN TestStreamServerInterceptorLoggerInvalidMetadata/Missing_caller_key 631s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata/Missing_caller_key 631s === RUN TestStreamServerInterceptorLoggerInvalidMetadata/Caller_key_is_not_a_boolean 631s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata/Caller_key_is_not_a_boolean 631s === RUN TestStreamServerInterceptorLoggerInvalidMetadata/Multiple_log_metas 631s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata/Multiple_log_metas 631s === RUN TestStreamServerInterceptorLoggerInvalidMetadata/No_meta_sent 631s === PAUSE TestStreamServerInterceptorLoggerInvalidMetadata/No_meta_sent 631s === CONT TestStreamServerInterceptor 631s Failed to fire hook: hook fired 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:025585]]" 631s === CONT TestLogStreamsAreSeparated 631s Failed to fire hook: hook fired 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:970707]]" 631s Failed to fire hook: hook fired 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:966486]]" 631s === CONT TestLogSendingFail 631s Failed to fire hook: hook fired 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:196267]]" 631s === CONT TestRecvLogMsg/No_caller_when_not_requested 631s === CONT TestRecvLogMsg/Invalid_object_passed_to_RecvMsg_is_gracefully_skipped 631s === CONT TestRecvLogMsg/No_caller_on_any_logs 631s === CONT TestRecvLogMsg/One_log_(and_one_closing_empty_message) 631s === CONT TestRecvLogMsg/Log_with_caller 631s === CONT TestRecvLogMsg/Two_logs_with_different_debug_level 631s === CONT TestRecvLogMsg/One_message,_no_log 631s === CONT TestRecvLogMsg/Unknown_log_level_triggers_a_client_error_(protocole_issue) 631s === CONT TestRecvLogMsg/Logs_and_then_message_with_error,_error_is_preserved 631s === CONT TestRecvLogMsg/One_message_with_error,_no_log,_error_is_preserved 631s === CONT TestStreamServerInterceptorLoggerInvalidMetadata/Missing_client_ID 631s === CONT TestStreamServerInterceptorSendLogsFails 631s time="2024-02-29T02:29:40Z" level=warning msg="[[123456:516873]] Couldn't send initial connection log to client" 631s Failed to fire hook: hook fired 631s time="2024-02-29T02:29:40Z" level=info msg="New connection from client [[123456:516873]]" 631s === CONT TestStreamServerInterceptorLoggerInvalidMetadata/No_meta_sent 631s === CONT TestStreamServerInterceptorLoggerInvalidMetadata/Multiple_log_metas 631s === CONT TestStreamServerInterceptorLoggerInvalidMetadata/Caller_key_is_not_a_boolean 631s === CONT TestStreamServerInterceptorLoggerInvalidMetadata/Missing_caller_key 631s --- PASS: TestAllLogLevels (0.00s) 631s --- PASS: TestStreamServerInterceptor (0.00s) 631s --- PASS: TestLogStreamsAreSeparated (0.00s) 631s --- PASS: TestLogSendingFail (0.00s) 631s --- PASS: TestRecvLogMsg (0.00s) 631s --- PASS: TestRecvLogMsg/No_caller_when_not_requested (0.00s) 631s --- PASS: TestRecvLogMsg/Invalid_object_passed_to_RecvMsg_is_gracefully_skipped (0.00s) 631s --- PASS: TestRecvLogMsg/No_caller_on_any_logs (0.00s) 631s --- PASS: TestRecvLogMsg/One_log_(and_one_closing_empty_message) (0.00s) 631s --- PASS: TestRecvLogMsg/Log_with_caller (0.00s) 631s --- PASS: TestRecvLogMsg/Two_logs_with_different_debug_level (0.00s) 631s --- PASS: TestRecvLogMsg/One_message,_no_log (0.00s) 631s --- PASS: TestRecvLogMsg/Unknown_log_level_triggers_a_client_error_(protocole_issue) (0.00s) 631s --- PASS: TestRecvLogMsg/Logs_and_then_message_with_error,_error_is_preserved (0.00s) 631s --- PASS: TestRecvLogMsg/One_message_with_error,_no_log,_error_is_preserved (0.00s) 631s --- PASS: TestStreamServerInterceptorSendLogsFails (0.00s) 631s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata (0.00s) 631s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata/Missing_client_ID (0.00s) 631s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata/No_meta_sent (0.00s) 631s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata/Multiple_log_metas (0.00s) 631s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata/Caller_key_is_not_a_boolean (0.00s) 631s --- PASS: TestStreamServerInterceptorLoggerInvalidMetadata/Missing_caller_key (0.00s) 631s PASS 631s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/grpc/logstreamer 0.009s 631s === RUN TestInfo 631s === PAUSE TestInfo 631s === RUN TestUserProfileDir 631s === PAUSE TestUserProfileDir 631s === RUN TestProStatus 631s === PAUSE TestProStatus 631s === RUN TestProAttach 631s === PAUSE TestProAttach 631s === RUN TestProDetach 631s === PAUSE TestProDetach 631s === RUN TestLandscapeEnable 631s === PAUSE TestLandscapeEnable 631s === RUN TestWindowsHostAddress 631s === PAUSE TestWindowsHostAddress 631s === RUN TestLandscapeDisable 631s === PAUSE TestLandscapeDisable 631s === RUN TestWithProMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithProMock (0.00s) 631s === RUN TestWithLandscapeConfigMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithLandscapeConfigMock (0.00s) 631s === RUN TestWithWslPathMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithWslPathMock (0.00s) 631s === RUN TestWithWslInfoMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithWslInfoMock (0.00s) 631s === RUN TestWithCmdExeMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithCmdExeMock (0.00s) 631s === CONT TestInfo 631s === RUN TestInfo/Success_reading_from_WSL_DISTRO_NAME 631s === PAUSE TestInfo/Success_reading_from_WSL_DISTRO_NAME 631s === RUN TestInfo/Error_when_pro_status_command_fails 631s === PAUSE TestInfo/Error_when_pro_status_command_fails 631s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 631s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 631s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 631s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 631s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 631s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 631s === RUN TestInfo/Success_using_wslpath 631s === PAUSE TestInfo/Success_using_wslpath 631s === RUN TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 631s === PAUSE TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 631s === RUN TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 631s === PAUSE TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 631s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 631s === CONT TestProDetach 631s === RUN TestProDetach/success_on_unattached_distro 631s === PAUSE TestProDetach/success_on_unattached_distro 631s === RUN TestProDetach/success_on_attached_distro 631s === PAUSE TestProDetach/success_on_attached_distro 631s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 631s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 631s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 631s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 631s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 631s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 631s === CONT TestLandscapeDisable 631s === RUN TestLandscapeDisable/Success 631s === PAUSE TestLandscapeDisable/Success 631s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 631s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 631s === CONT TestWindowsHostAddress 631s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 631s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 631s === RUN TestWindowsHostAddress/Success_without_NAT 631s === PAUSE TestWindowsHostAddress/Success_without_NAT 631s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 631s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 631s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 631s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 631s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 631s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 631s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 631s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 631s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 631s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 631s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 631s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 631s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 631s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 631s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 631s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 631s === CONT TestLandscapeEnable 631s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 631s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 631s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 631s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 631s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 631s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 631s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 631s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 631s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 631s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 631s === RUN TestLandscapeEnable/Success 631s === PAUSE TestLandscapeEnable/Success 631s === RUN TestLandscapeEnable/Success_overriding_computer_title 631s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 631s === CONT TestProStatus 631s === RUN TestProStatus/success_on_unattached_distro 631s === PAUSE TestProStatus/success_on_unattached_distro 631s === RUN TestProStatus/success_on_attached_distro 631s === PAUSE TestProStatus/success_on_attached_distro 631s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 631s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 631s === RUN TestProStatus/error_on_'pro_attach'_error 631s === PAUSE TestProStatus/error_on_'pro_attach'_error 631s === CONT TestProAttach 631s === RUN TestProAttach/success 631s === PAUSE TestProAttach/success 631s === RUN TestProAttach/error_on_'pro_attach'_error 631s === PAUSE TestProAttach/error_on_'pro_attach'_error 631s === CONT TestUserProfileDir 631s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 631s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 631s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 631s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 631s === RUN TestUserProfileDir/Error_on_cmd.exe_error 631s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 631s === RUN TestUserProfileDir/Error_on_wslpath_error 631s === PAUSE TestUserProfileDir/Error_on_wslpath_error 631s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 631s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 631s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 631s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 631s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 631s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 631s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 631s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 631s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 631s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 631s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 631s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 631s === CONT TestProDetach/success_on_unattached_distro 631s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 631s === CONT TestLandscapeDisable/Success 631s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 631s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 631s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 631s === CONT TestProDetach/success_on_attached_distro 631s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 631s --- PASS: TestProDetach (0.00s) 631s --- PASS: TestProDetach/success_on_unattached_distro (1.17s) 631s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (1.29s) 631s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (1.56s) 631s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (1.44s) 631s --- PASS: TestProDetach/success_on_attached_distro (1.19s) 631s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 631s --- PASS: TestLandscapeDisable (0.00s) 631s --- PASS: TestLandscapeDisable/Success (1.17s) 631s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (1.34s) 631s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 631s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 631s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 631s === NAME TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 631s system_test.go:413: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 631s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 631s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 631s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 631s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 631s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 631s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 631s === CONT TestWindowsHostAddress/Success_without_NAT 631s === CONT TestProStatus/success_on_unattached_distro 631s --- PASS: TestWindowsHostAddress (0.00s) 631s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (1.45s) 631s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (1.34s) 631s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (1.05s) 631s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (0.92s) 631s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (0.94s) 631s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (1.29s) 631s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (1.36s) 631s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (1.33s) 631s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (1.48s) 631s --- PASS: TestWindowsHostAddress/Success_without_NAT (1.12s) 631s === CONT TestLandscapeEnable/Success_overriding_computer_title 631s time="2024-02-29T02:29:54Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden with TEST_DISTRO" 631s === CONT TestLandscapeEnable/Success 631s === NAME TestLandscapeEnable/Success_overriding_computer_title 631s system_test.go:413: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 631s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 631s === NAME TestLandscapeEnable/Success 631s system_test.go:413: testdata/TestLandscapeEnable/golden/success 631s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 631s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 631s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 631s === CONT TestProAttach/success 631s === CONT TestProStatus/error_on_'pro_attach'_error 631s --- PASS: TestLandscapeEnable (0.00s) 631s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (2.46s) 631s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (0.95s) 631s --- PASS: TestLandscapeEnable/Success (0.94s) 631s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (1.00s) 631s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.00s) 631s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.00s) 631s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (0.99s) 631s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 631s === CONT TestProStatus/success_on_attached_distro 631s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 631s --- PASS: TestProStatus (0.00s) 631s --- PASS: TestProStatus/success_on_unattached_distro (1.00s) 631s --- PASS: TestProStatus/error_on_'pro_attach'_error (1.33s) 631s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (1.18s) 631s --- PASS: TestProStatus/success_on_attached_distro (1.54s) 631s === CONT TestProAttach/error_on_'pro_attach'_error 631s --- PASS: TestProAttach (0.00s) 631s --- PASS: TestProAttach/success (1.32s) 631s --- PASS: TestProAttach/error_on_'pro_attach'_error (1.18s) 631s === CONT TestInfo/Success_reading_from_WSL_DISTRO_NAME 631s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 631s === CONT TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 631s === CONT TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 631s === CONT TestInfo/Success_using_wslpath 631s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 631s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 631s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 631s === CONT TestInfo/Error_when_pro_status_command_fails 631s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 631s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 631s --- PASS: TestInfo (0.00s) 631s --- PASS: TestInfo/Success_reading_from_WSL_DISTRO_NAME (1.59s) 631s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (1.08s) 631s --- PASS: TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (0.88s) 631s --- PASS: TestInfo/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (1.00s) 631s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (0.84s) 631s --- PASS: TestInfo/Success_using_wslpath (1.83s) 631s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (1.00s) 631s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (0.95s) 631s --- PASS: TestInfo/Error_when_pro_status_command_fails (0.94s) 631s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 631s system_test.go:166: Removing default proc/mounts 631s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 631s system_test.go:166: Removing default proc/mounts 631s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 631s === CONT TestUserProfileDir/Error_on_cmd.exe_error 631s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 631s === CONT TestUserProfileDir/Error_on_wslpath_error 631s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 631s --- PASS: TestUserProfileDir (0.00s) 631s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (2.85s) 631s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (1.81s) 631s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 631s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 631s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (1.82s) 631s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (1.80s) 631s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (0.97s) 631s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (1.88s) 631s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 631s --- PASS: TestUserProfileDir/Error_on_wslpath_error (1.85s) 631s PASS 631s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 29.228s 631s === RUN TestApplyProToken 631s === PAUSE TestApplyProToken 631s === RUN TestApplyLandscapeConfig 631s === PAUSE TestApplyLandscapeConfig 631s === RUN TestWithProMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithProMock (0.00s) 631s === RUN TestWithLandscapeConfigMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithLandscapeConfigMock (0.00s) 631s === RUN TestWithWslPathMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithWslPathMock (0.00s) 631s === RUN TestWithWslInfoMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithWslInfoMock (0.00s) 631s === RUN TestWithCmdExeMock 631s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 631s --- SKIP: TestWithCmdExeMock (0.00s) 631s === CONT TestApplyProToken 631s === RUN TestApplyProToken/success_attaching_non-attached_machine 631s === PAUSE TestApplyProToken/success_attaching_non-attached_machine 631s === RUN TestApplyProToken/Error_calling_pro_attach 631s === PAUSE TestApplyProToken/Error_calling_pro_attach 631s === CONT TestApplyLandscapeConfig 631s === RUN TestApplyLandscapeConfig/Success_enabling 631s === PAUSE TestApplyLandscapeConfig/Success_enabling 631s === RUN TestApplyLandscapeConfig/Success_disabling 631s === PAUSE TestApplyLandscapeConfig/Success_disabling 631s === RUN TestApplyProToken/Error_calling_pro_status 631s === RUN TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 631s === PAUSE TestApplyProToken/Error_calling_pro_status 631s === PAUSE TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 631s === RUN TestApplyProToken/success_attaching_attached_machine 631s === RUN TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 631s === PAUSE TestApplyProToken/success_attaching_attached_machine 631s === PAUSE TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 631s === RUN TestApplyProToken/success_detaching_attached_machine 631s === PAUSE TestApplyProToken/success_detaching_attached_machine 631s === RUN TestApplyProToken/success_detaching_non-attached_machine 631s === PAUSE TestApplyProToken/success_detaching_non-attached_machine 631s === RUN TestApplyProToken/Error_detaching_pro 631s === CONT TestApplyLandscapeConfig/Success_enabling 631s === PAUSE TestApplyProToken/Error_detaching_pro 631s === RUN TestApplyProToken/Error_getting_system_info 631s === PAUSE TestApplyProToken/Error_getting_system_info 631s === RUN TestApplyProToken/Error_cannot_send_info_to_stream 631s === PAUSE TestApplyProToken/Error_cannot_send_info_to_stream 631s === CONT TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 631s time="2024-02-29T02:30:11Z" level=debug msg="Registering GRPC WSL instance service" 631s time="2024-02-29T02:30:11Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:44203 631s === NAME TestApplyLandscapeConfig/Success_enabling 631s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:39577 631s === NAME TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 631s wslinstanceservice_test.go:167: Client connected to WslInstanceService 631s === NAME TestApplyLandscapeConfig/Success_enabling 631s wslinstanceservice_test.go:167: Client connected to WslInstanceService 631s time="2024-02-29T02:30:11Z" level=info msg="ApplyLandscapeConfig: Received empty config: disabling" 631s time="2024-02-29T02:30:11Z" level=info msg="ApplyLandscapeConfig: Received config: registering" 631s === CONT TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 631s time="2024-02-29T02:30:12Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:35779 631s wslinstanceservice_test.go:167: Client connected to WslInstanceService 631s time="2024-02-29T02:30:12Z" level=info msg="ApplyLandscapeConfig: Received config: registering" 631s === NAME TestApplyLandscapeConfig/Success_enabling 631s wslinstanceservice_test.go:192: testdata/TestApplyLandscapeConfig/golden/success_enabling 631s === CONT TestApplyLandscapeConfig/Success_disabling 631s time="2024-02-29T02:30:12Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:39617 631s wslinstanceservice_test.go:167: Client connected to WslInstanceService 631s time="2024-02-29T02:30:12Z" level=info msg="ApplyLandscapeConfig: Received empty config: disabling" 631s === CONT TestApplyProToken/success_attaching_non-attached_machine 631s time="2024-02-29T02:30:13Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:37859 631s wslinstanceservice_test.go:108: Client connected to WslInstanceService 631s time="2024-02-29T02:30:13Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 631s --- PASS: TestApplyLandscapeConfig (0.00s) 631s --- PASS: TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails (0.96s) 631s --- PASS: TestApplyLandscapeConfig/Success_enabling (0.98s) 631s --- PASS: TestApplyLandscapeConfig/Success_disabling (1.02s) 631s --- PASS: TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails (1.07s) 631s === CONT TestApplyProToken/Error_cannot_send_info_to_stream 631s time="2024-02-29T02:30:13Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:39549 631s wslinstanceservice_test.go:108: Client connected to WslInstanceService 631s time="2024-02-29T02:30:13Z" level=info msg="ApplyProToken: Received empty token: detaching" 631s time="2024-02-29T02:30:15Z" level=warning msg="Error in ApplyProToken: could not send back system info: test error" 631s === CONT TestApplyProToken/Error_getting_system_info 631s time="2024-02-29T02:30:15Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:35955 631s wslinstanceservice_test.go:108: Client connected to WslInstanceService 631s time="2024-02-29T02:30:15Z" level=info msg="ApplyProToken: Received empty token: detaching" 631s === CONT TestApplyProToken/Error_detaching_pro 631s time="2024-02-29T02:30:16Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:34203 631s wslinstanceservice_test.go:108: Client connected to WslInstanceService 631s time="2024-02-29T02:30:16Z" level=info msg="ApplyProToken: Received empty token: detaching" 631s time="2024-02-29T02:30:17Z" 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" 631s time="2024-02-29T02:30:17Z" level=warning msg="Error in ApplyProToken: could not gather system info: could not read /etc/os-release file: open /tmp/TestApplyProTokenError_getting_system_info3760908195/001/etc/os-release: no such file or directory" 631s === CONT TestApplyProToken/success_detaching_non-attached_machine 631s time="2024-02-29T02:30:17Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:42811 631s wslinstanceservice_test.go:108: Client connected to WslInstanceService 631s time="2024-02-29T02:30:17Z" level=info msg="ApplyProToken: Received empty token: detaching" 631s === CONT TestApplyProToken/success_detaching_attached_machine 631s time="2024-02-29T02:30:18Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:40657 631s wslinstanceservice_test.go:108: Client connected to WslInstanceService 631s time="2024-02-29T02:30:18Z" level=info msg="ApplyProToken: Received empty token: detaching" 631s === CONT TestApplyProToken/success_attaching_attached_machine 631s time="2024-02-29T02:30:19Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:39467 631s wslinstanceservice_test.go:108: Client connected to WslInstanceService 631s time="2024-02-29T02:30:19Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 631s === CONT TestApplyProToken/Error_calling_pro_status 631s time="2024-02-29T02:30:20Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:40515 631s wslinstanceservice_test.go:108: Client connected to WslInstanceService 631s time="2024-02-29T02:30:20Z" level=info msg="ApplyProToken: Received empty token: detaching" 631s === CONT TestApplyProToken/Error_calling_pro_attach 631s time="2024-02-29T02:30:22Z" level=debug msg="Registering GRPC WSL instance service" 631s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:41629 631s wslinstanceservice_test.go:108: Client connected to WslInstanceService 631s time="2024-02-29T02:30:22Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 631s time="2024-02-29T02:30:22Z" 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:" 631s time="2024-02-29T02:30:23Z" 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" 631s --- PASS: TestApplyProToken (0.00s) 631s --- PASS: TestApplyProToken/Error_cannot_send_info_to_stream (1.91s) 631s --- PASS: TestApplyProToken/success_attaching_non-attached_machine (2.91s) 631s --- PASS: TestApplyProToken/Error_getting_system_info (2.00s) 631s --- PASS: TestApplyProToken/Error_detaching_pro (1.90s) 631s --- PASS: TestApplyProToken/success_detaching_non-attached_machine (1.84s) 631s --- PASS: TestApplyProToken/success_detaching_attached_machine (1.89s) 631s --- PASS: TestApplyProToken/success_attaching_attached_machine (2.83s) 631s --- PASS: TestApplyProToken/Error_calling_pro_status (1.94s) 631s --- PASS: TestApplyProToken/Error_calling_pro_attach (1.96s) 631s PASS 631s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/wslinstanceservice 12.578s 631s autopkgtest [02:31:42]: test command1: -----------------------] 631s autopkgtest [02:31:42]: test command1: - - - - - - - - - - results - - - - - - - - - - 631s command1 PASS 631s autopkgtest [02:31:42]: @@@@@@@@@@@@@@@@@@@@ summary 631s command1 PASS 662s Creating nova instance adt-noble-amd64-wsl-pro-service-20240229-022111-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-amd64-server-20240226.img (UUID fe3c0c7b-5419-4067-86f2-cd2ab88ea397)...