0s autopkgtest [23:09:04]: starting date: 2024-03-09 0s autopkgtest [23:09:04]: git checkout: d9c0295 adt_testbed.py: supress warnings from apt using a shell pipeline 0s autopkgtest [23:09:04]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.pc8xm5sv/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:glibc --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.39-0ubuntu5 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@lcy02-4.secgroup --name adt-noble-amd64-wsl-pro-service-20240309-230904-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 488s autopkgtest [23:17:12]: @@@@@@@@@@@@@@@@@@@@ test bed setup 488s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 488s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [37.3 kB] 488s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [429 kB] 488s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [2627 kB] 488s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [3976 B] 488s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 Packages [586 kB] 488s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main i386 Packages [372 kB] 488s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 c-n-f Metadata [3508 B] 488s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted amd64 Packages [20.4 kB] 488s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/restricted i386 Packages [6228 B] 488s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/restricted amd64 c-n-f Metadata [116 B] 488s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/universe i386 Packages [880 kB] 488s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/universe amd64 Packages [3020 kB] 488s Get:14 http://ftpmaster.internal/ubuntu noble-proposed/universe amd64 c-n-f Metadata [9396 B] 488s Get:15 http://ftpmaster.internal/ubuntu noble-proposed/multiverse amd64 Packages [50.1 kB] 488s Get:16 http://ftpmaster.internal/ubuntu noble-proposed/multiverse i386 Packages [13.0 kB] 488s Get:17 http://ftpmaster.internal/ubuntu noble-proposed/multiverse amd64 c-n-f Metadata [196 B] 491s Fetched 8175 kB in 1s (7515 kB/s) 491s Reading package lists... 496s Reading package lists... 496s Building dependency tree... 496s Reading state information... 496s Calculating upgrade... 497s The following packages will be upgraded: 497s libc-bin libc6 locales python3-attr 497s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 497s Need to get 8224 kB of archives. 497s After this operation, 2048 B of additional disk space will be used. 497s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 libc6 amd64 2.39-0ubuntu5 [3262 kB] 497s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 libc-bin amd64 2.39-0ubuntu5 [682 kB] 497s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 locales all 2.39-0ubuntu5 [4231 kB] 497s Get:4 http://ftpmaster.internal/ubuntu noble/main amd64 python3-attr all 23.2.0-2 [48.6 kB] 497s Preconfiguring packages ... 497s Fetched 8224 kB in 0s (92.5 MB/s) 497s (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 ... 71805 files and directories currently installed.) 497s Preparing to unpack .../libc6_2.39-0ubuntu5_amd64.deb ... 498s Unpacking libc6:amd64 (2.39-0ubuntu5) over (2.39-0ubuntu2) ... 498s Setting up libc6:amd64 (2.39-0ubuntu5) ... 498s (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 ... 71805 files and directories currently installed.) 498s Preparing to unpack .../libc-bin_2.39-0ubuntu5_amd64.deb ... 498s Unpacking libc-bin (2.39-0ubuntu5) over (2.39-0ubuntu2) ... 498s Setting up libc-bin (2.39-0ubuntu5) ... 498s (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 ... 71805 files and directories currently installed.) 498s Preparing to unpack .../locales_2.39-0ubuntu5_all.deb ... 498s Unpacking locales (2.39-0ubuntu5) over (2.39-0ubuntu2) ... 499s Preparing to unpack .../python3-attr_23.2.0-2_all.deb ... 499s Unpacking python3-attr (23.2.0-2) over (23.2.0-1) ... 499s Setting up python3-attr (23.2.0-2) ... 499s Setting up locales (2.39-0ubuntu5) ... 500s Generating locales (this might take a while)... 501s en_US.UTF-8... done 501s Generation complete. 501s Processing triggers for man-db (2.12.0-3) ... 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s 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 502s Reading package lists... 503s Building dependency tree... 503s Reading state information... 503s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 504s sh: Attempting to set up Debian/Ubuntu apt sources automatically 504s sh: Distribution appears to be Ubuntu 508s Reading package lists... 508s Building dependency tree... 508s Reading state information... 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s Weatmydata is already the newest version (131-1). 508s dbus is already the newest version (1.14.10-4ubuntu1). 508s dbus set to manually installed. 508s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 508s : 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s 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 508s Reading package lists... 509s Building dependency tree... 509s Reading state information... 509s rng-tools-debian is already the newest version (2.4). 509s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 509s Reading package lists... 510s Building dependency tree... 510s Reading state information... 510s haveged is already the newest version (1.9.14-1ubuntu1). 510s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 510s Reading package lists... 510s Building dependency tree... 510s Reading state information... 511s The following packages will be REMOVED: 511s cloud-init* python3-configobj* python3-debconf* 511s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 511s After this operation, 3248 kB disk space will be freed. 511s (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 ... 71805 files and directories currently installed.) 511s Removing cloud-init (24.1-0ubuntu1) ... 511s Removing python3-configobj (5.0.8-3) ... 512s Removing python3-debconf (1.5.86) ... 512s Processing triggers for man-db (2.12.0-3) ... 512s (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 ... 71416 files and directories currently installed.) 512s Purging configuration files for cloud-init (24.1-0ubuntu1) ... 513s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 513s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 513s Reading package lists... 513s Building dependency tree... 513s Reading state information... 514s linux-generic is already the newest version (6.8.0-11.11+1). 514s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 514s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 514s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 514s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 514s Hit:4 http://ftpmaster.internal/ubuntu noble-proposed InRelease 514s Hit:5 http://ftpmaster.internal/ubuntu noble-backports InRelease 518s Reading package lists... 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s 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 518s Reading package lists... 518s Building dependency tree... 518s Reading state information... 519s Calculating upgrade... 519s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 519s Reading package lists... 519s Building dependency tree... 519s Reading state information... 520s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 520s autopkgtest [23:17:44]: rebooting testbed after setup commands that affected boot 681s autopkgtest [23:20:25]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 00:29:05 UTC 2024 681s autopkgtest [23:20:25]: testbed dpkg architecture: amd64 682s autopkgtest [23:20:26]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 682s 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 683s Get:1 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.2 (dsc) [1420 B] 683s Get:2 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.2 (tar) [1746 kB] 683s gpgv: Signature made Thu Feb 29 15:15:37 2024 UTC 683s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 683s gpgv: Can't check signature: No public key 683s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.2.dsc: no acceptable signature found 683s autopkgtest [23:20:27]: testing package wsl-pro-service version 0.1.2 683s autopkgtest [23:20:27]: build not needed 684s autopkgtest [23:20:28]: test command1: preparing testbed 685s Reading package lists... 685s Building dependency tree... 685s Reading state information... 686s Correcting dependencies...Starting pkgProblemResolver with broken count: 0 686s Starting 2 pkgProblemResolver with broken count: 0 686s Done 686s Done 686s Starting pkgProblemResolver with broken count: 0 687s Starting 2 pkgProblemResolver with broken count: 0 687s Done 687s The following additional packages will be installed: 687s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 687s cpp-13-x86-64-linux-gnu cpp-x86-64-linux-gnu debhelper debugedit dh-apport 687s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 687s g++-13-x86-64-linux-gnu g++-x86-64-linux-gnu gcc gcc-13 687s gcc-13-x86-64-linux-gnu gcc-x86-64-linux-gnu gettext golang-1.22-go 687s golang-1.22-src golang-go golang-src intltool-debian libarchive-zip-perl 687s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 687s libdebhelper-perl libdw1 libfile-stripnondeterminism-perl libgcc-13-dev 687s libgomp1 libhwasan0 libisl23 libitm1 liblsan0 libmpc3 libquadmath0 687s libstdc++-13-dev libsub-override-perl libtool libtsan2 libubsan1 687s linux-libc-dev m4 po-debconf rpcsvc-proto 687s Suggested packages: 687s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 687s cpp-13-doc dh-make g++-multilib g++-13-multilib gcc-13-doc gcc-multilib 687s manpages-dev flex bison gdb gcc-doc gcc-13-multilib gdb-x86-64-linux-gnu 687s gettext-doc libasprintf-dev libgettextpo-dev bzr | brz git mercurial 687s subversion glibc-doc libstdc++-13-doc libtool-doc gfortran 687s | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 687s Recommended packages: 687s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 687s libltdl-dev libmail-sendmail-perl 687s The following NEW packages will be installed: 687s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 687s cpp-13-x86-64-linux-gnu cpp-x86-64-linux-gnu debhelper debugedit dh-apport 687s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 687s g++-13-x86-64-linux-gnu g++-x86-64-linux-gnu gcc gcc-13 687s gcc-13-x86-64-linux-gnu gcc-x86-64-linux-gnu gettext golang-1.22-go 687s golang-1.22-src golang-go golang-src intltool-debian libarchive-zip-perl 687s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 687s libdebhelper-perl libdw1 libfile-stripnondeterminism-perl libgcc-13-dev 687s libgomp1 libhwasan0 libisl23 libitm1 liblsan0 libmpc3 libquadmath0 687s libstdc++-13-dev libsub-override-perl libtool libtsan2 libubsan1 687s linux-libc-dev m4 po-debconf rpcsvc-proto 687s 0 upgraded, 57 newly installed, 0 to remove and 0 not upgraded. 687s 1 not fully installed or removed. 687s Need to get 116 MB of archives. 687s After this operation, 484 MB of additional disk space will be used. 687s Get:1 http://ftpmaster.internal/ubuntu noble/main amd64 autotools-dev all 20220109.1 [44.9 kB] 687s Get:2 http://ftpmaster.internal/ubuntu noble/main amd64 m4 amd64 1.4.19-4 [243 kB] 687s Get:3 http://ftpmaster.internal/ubuntu noble/main amd64 autoconf all 2.71-3 [339 kB] 687s Get:4 http://ftpmaster.internal/ubuntu noble/main amd64 automake all 1:1.16.5-1.3ubuntu1 [558 kB] 687s Get:5 http://ftpmaster.internal/ubuntu noble/main amd64 autopoint all 0.21-14ubuntu1 [422 kB] 687s Get:6 http://ftpmaster.internal/ubuntu noble/main amd64 libdebhelper-perl all 13.14.1ubuntu1 [89.5 kB] 687s Get:7 http://ftpmaster.internal/ubuntu noble/main amd64 libisl23 amd64 0.26-3 [741 kB] 687s Get:8 http://ftpmaster.internal/ubuntu noble/main amd64 libmpc3 amd64 1.3.1-1 [54.1 kB] 687s Get:9 http://ftpmaster.internal/ubuntu noble/main amd64 cpp-13-x86-64-linux-gnu amd64 13.2.0-17ubuntu2 [11.2 MB] 687s Get:10 http://ftpmaster.internal/ubuntu noble/main amd64 cpp-13 amd64 13.2.0-17ubuntu2 [1030 B] 687s Get:11 http://ftpmaster.internal/ubuntu noble/main amd64 cpp-x86-64-linux-gnu amd64 4:13.2.0-7ubuntu1 [5326 B] 687s Get:12 http://ftpmaster.internal/ubuntu noble/main amd64 cpp amd64 4:13.2.0-7ubuntu1 [22.4 kB] 687s Get:13 http://ftpmaster.internal/ubuntu noble/main amd64 libcc1-0 amd64 14-20240303-1ubuntu1 [47.7 kB] 687s Get:14 http://ftpmaster.internal/ubuntu noble/main amd64 libgomp1 amd64 14-20240303-1ubuntu1 [147 kB] 687s Get:15 http://ftpmaster.internal/ubuntu noble/main amd64 libitm1 amd64 14-20240303-1ubuntu1 [29.1 kB] 687s Get:16 http://ftpmaster.internal/ubuntu noble/main amd64 libatomic1 amd64 14-20240303-1ubuntu1 [10.4 kB] 687s Get:17 http://ftpmaster.internal/ubuntu noble/main amd64 libasan8 amd64 14-20240303-1ubuntu1 [3026 kB] 687s Get:18 http://ftpmaster.internal/ubuntu noble/main amd64 liblsan0 amd64 14-20240303-1ubuntu1 [1310 kB] 687s Get:19 http://ftpmaster.internal/ubuntu noble/main amd64 libtsan2 amd64 14-20240303-1ubuntu1 [2732 kB] 687s Get:20 http://ftpmaster.internal/ubuntu noble/main amd64 libubsan1 amd64 14-20240303-1ubuntu1 [1172 kB] 687s Get:21 http://ftpmaster.internal/ubuntu noble/main amd64 libhwasan0 amd64 14-20240303-1ubuntu1 [1629 kB] 687s Get:22 http://ftpmaster.internal/ubuntu noble/main amd64 libquadmath0 amd64 14-20240303-1ubuntu1 [155 kB] 687s Get:23 http://ftpmaster.internal/ubuntu noble/main amd64 libgcc-13-dev amd64 13.2.0-17ubuntu2 [2687 kB] 687s Get:24 http://ftpmaster.internal/ubuntu noble/main amd64 gcc-13-x86-64-linux-gnu amd64 13.2.0-17ubuntu2 [21.9 MB] 687s Get:25 http://ftpmaster.internal/ubuntu noble/main amd64 gcc-13 amd64 13.2.0-17ubuntu2 [477 kB] 687s Get:26 http://ftpmaster.internal/ubuntu noble/main amd64 gcc-x86-64-linux-gnu amd64 4:13.2.0-7ubuntu1 [1212 B] 687s Get:27 http://ftpmaster.internal/ubuntu noble/main amd64 gcc amd64 4:13.2.0-7ubuntu1 [5018 B] 687s Get:28 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 libc-dev-bin amd64 2.39-0ubuntu5 [20.4 kB] 687s Get:29 http://ftpmaster.internal/ubuntu noble/main amd64 linux-libc-dev amd64 6.8.0-11.11 [1595 kB] 687s Get:30 http://ftpmaster.internal/ubuntu noble/main amd64 libcrypt-dev amd64 1:4.4.36-4 [128 kB] 687s Get:31 http://ftpmaster.internal/ubuntu noble/main amd64 rpcsvc-proto amd64 1.4.2-0ubuntu6 [68.5 kB] 687s Get:32 http://ftpmaster.internal/ubuntu noble-proposed/main amd64 libc6-dev amd64 2.39-0ubuntu5 [2125 kB] 687s Get:33 http://ftpmaster.internal/ubuntu noble/main amd64 libtool all 2.4.7-7 [166 kB] 687s Get:34 http://ftpmaster.internal/ubuntu noble/main amd64 dh-autoreconf all 20 [16.1 kB] 687s Get:35 http://ftpmaster.internal/ubuntu noble/main amd64 libarchive-zip-perl all 1.68-1 [90.2 kB] 687s Get:36 http://ftpmaster.internal/ubuntu noble/main amd64 libsub-override-perl all 0.10-1 [10.0 kB] 687s Get:37 http://ftpmaster.internal/ubuntu noble/main amd64 libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 687s Get:38 http://ftpmaster.internal/ubuntu noble/main amd64 dh-strip-nondeterminism all 1.13.1-1 [5362 B] 687s Get:39 http://ftpmaster.internal/ubuntu noble/main amd64 libdw1 amd64 0.190-1 [260 kB] 687s Get:40 http://ftpmaster.internal/ubuntu noble/main amd64 debugedit amd64 1:5.0-5 [46.1 kB] 687s Get:41 http://ftpmaster.internal/ubuntu noble/main amd64 dwz amd64 0.15-1 [112 kB] 687s Get:42 http://ftpmaster.internal/ubuntu noble/main amd64 gettext amd64 0.21-14ubuntu1 [864 kB] 687s Get:43 http://ftpmaster.internal/ubuntu noble/main amd64 intltool-debian all 0.35.0+20060710.6 [23.2 kB] 687s Get:44 http://ftpmaster.internal/ubuntu noble/main amd64 po-debconf all 1.0.21+nmu1 [233 kB] 687s Get:45 http://ftpmaster.internal/ubuntu noble/main amd64 debhelper all 13.14.1ubuntu1 [869 kB] 687s Get:46 http://ftpmaster.internal/ubuntu noble/universe amd64 dh-apport all 2.28.0-0ubuntu1 [16.9 kB] 687s Get:47 http://ftpmaster.internal/ubuntu noble/main amd64 dh-golang all 1.62 [25.2 kB] 687s Get:48 http://ftpmaster.internal/ubuntu noble/main amd64 golang-1.22-src all 1.22.1-1 [19.7 MB] 688s Get:49 http://ftpmaster.internal/ubuntu noble/main amd64 golang-1.22-go amd64 1.22.1-1 [25.9 MB] 688s Get:50 http://ftpmaster.internal/ubuntu noble/main amd64 golang-src all 2:1.22~2 [4992 B] 688s Get:51 http://ftpmaster.internal/ubuntu noble/main amd64 golang-go amd64 2:1.22~2 [43.9 kB] 688s Get:52 http://ftpmaster.internal/ubuntu noble/main amd64 libstdc++-13-dev amd64 13.2.0-17ubuntu2 [2340 kB] 688s Get:53 http://ftpmaster.internal/ubuntu noble/main amd64 g++-13-x86-64-linux-gnu amd64 13.2.0-17ubuntu2 [12.5 MB] 688s Get:54 http://ftpmaster.internal/ubuntu noble/main amd64 g++-13 amd64 13.2.0-17ubuntu2 [14.5 kB] 688s Get:55 http://ftpmaster.internal/ubuntu noble/main amd64 g++-x86-64-linux-gnu amd64 4:13.2.0-7ubuntu1 [964 B] 688s Get:56 http://ftpmaster.internal/ubuntu noble/main amd64 g++ amd64 4:13.2.0-7ubuntu1 [1100 B] 688s Get:57 http://ftpmaster.internal/ubuntu noble/main amd64 build-essential amd64 12.10ubuntu1 [4928 B] 689s Fetched 116 MB in 1s (114 MB/s) 689s Selecting previously unselected package autotools-dev. 689s (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 ... 71361 files and directories currently installed.) 689s Preparing to unpack .../00-autotools-dev_20220109.1_all.deb ... 689s Unpacking autotools-dev (20220109.1) ... 689s Selecting previously unselected package m4. 689s Preparing to unpack .../01-m4_1.4.19-4_amd64.deb ... 689s Unpacking m4 (1.4.19-4) ... 689s Selecting previously unselected package autoconf. 689s Preparing to unpack .../02-autoconf_2.71-3_all.deb ... 689s Unpacking autoconf (2.71-3) ... 689s Selecting previously unselected package automake. 689s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 689s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 689s Selecting previously unselected package autopoint. 689s Preparing to unpack .../04-autopoint_0.21-14ubuntu1_all.deb ... 689s Unpacking autopoint (0.21-14ubuntu1) ... 689s Selecting previously unselected package libdebhelper-perl. 689s Preparing to unpack .../05-libdebhelper-perl_13.14.1ubuntu1_all.deb ... 689s Unpacking libdebhelper-perl (13.14.1ubuntu1) ... 689s Selecting previously unselected package libisl23:amd64. 689s Preparing to unpack .../06-libisl23_0.26-3_amd64.deb ... 689s Unpacking libisl23:amd64 (0.26-3) ... 689s Selecting previously unselected package libmpc3:amd64. 689s Preparing to unpack .../07-libmpc3_1.3.1-1_amd64.deb ... 689s Unpacking libmpc3:amd64 (1.3.1-1) ... 689s Selecting previously unselected package cpp-13-x86-64-linux-gnu. 689s Preparing to unpack .../08-cpp-13-x86-64-linux-gnu_13.2.0-17ubuntu2_amd64.deb ... 689s Unpacking cpp-13-x86-64-linux-gnu (13.2.0-17ubuntu2) ... 689s Selecting previously unselected package cpp-13. 689s Preparing to unpack .../09-cpp-13_13.2.0-17ubuntu2_amd64.deb ... 689s Unpacking cpp-13 (13.2.0-17ubuntu2) ... 689s Selecting previously unselected package cpp-x86-64-linux-gnu. 689s Preparing to unpack .../10-cpp-x86-64-linux-gnu_4%3a13.2.0-7ubuntu1_amd64.deb ... 689s Unpacking cpp-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 689s Selecting previously unselected package cpp. 689s Preparing to unpack .../11-cpp_4%3a13.2.0-7ubuntu1_amd64.deb ... 689s Unpacking cpp (4:13.2.0-7ubuntu1) ... 689s Selecting previously unselected package libcc1-0:amd64. 689s Preparing to unpack .../12-libcc1-0_14-20240303-1ubuntu1_amd64.deb ... 689s Unpacking libcc1-0:amd64 (14-20240303-1ubuntu1) ... 689s Selecting previously unselected package libgomp1:amd64. 689s Preparing to unpack .../13-libgomp1_14-20240303-1ubuntu1_amd64.deb ... 689s Unpacking libgomp1:amd64 (14-20240303-1ubuntu1) ... 689s Selecting previously unselected package libitm1:amd64. 689s Preparing to unpack .../14-libitm1_14-20240303-1ubuntu1_amd64.deb ... 689s Unpacking libitm1:amd64 (14-20240303-1ubuntu1) ... 689s Selecting previously unselected package libatomic1:amd64. 689s Preparing to unpack .../15-libatomic1_14-20240303-1ubuntu1_amd64.deb ... 689s Unpacking libatomic1:amd64 (14-20240303-1ubuntu1) ... 689s Selecting previously unselected package libasan8:amd64. 689s Preparing to unpack .../16-libasan8_14-20240303-1ubuntu1_amd64.deb ... 689s Unpacking libasan8:amd64 (14-20240303-1ubuntu1) ... 689s Selecting previously unselected package liblsan0:amd64. 689s Preparing to unpack .../17-liblsan0_14-20240303-1ubuntu1_amd64.deb ... 689s Unpacking liblsan0:amd64 (14-20240303-1ubuntu1) ... 689s Selecting previously unselected package libtsan2:amd64. 689s Preparing to unpack .../18-libtsan2_14-20240303-1ubuntu1_amd64.deb ... 689s Unpacking libtsan2:amd64 (14-20240303-1ubuntu1) ... 689s Selecting previously unselected package libubsan1:amd64. 689s Preparing to unpack .../19-libubsan1_14-20240303-1ubuntu1_amd64.deb ... 689s Unpacking libubsan1:amd64 (14-20240303-1ubuntu1) ... 690s Selecting previously unselected package libhwasan0:amd64. 690s Preparing to unpack .../20-libhwasan0_14-20240303-1ubuntu1_amd64.deb ... 690s Unpacking libhwasan0:amd64 (14-20240303-1ubuntu1) ... 690s Selecting previously unselected package libquadmath0:amd64. 690s Preparing to unpack .../21-libquadmath0_14-20240303-1ubuntu1_amd64.deb ... 690s Unpacking libquadmath0:amd64 (14-20240303-1ubuntu1) ... 690s Selecting previously unselected package libgcc-13-dev:amd64. 690s Preparing to unpack .../22-libgcc-13-dev_13.2.0-17ubuntu2_amd64.deb ... 690s Unpacking libgcc-13-dev:amd64 (13.2.0-17ubuntu2) ... 690s Selecting previously unselected package gcc-13-x86-64-linux-gnu. 690s Preparing to unpack .../23-gcc-13-x86-64-linux-gnu_13.2.0-17ubuntu2_amd64.deb ... 690s Unpacking gcc-13-x86-64-linux-gnu (13.2.0-17ubuntu2) ... 690s Selecting previously unselected package gcc-13. 690s Preparing to unpack .../24-gcc-13_13.2.0-17ubuntu2_amd64.deb ... 690s Unpacking gcc-13 (13.2.0-17ubuntu2) ... 690s Selecting previously unselected package gcc-x86-64-linux-gnu. 690s Preparing to unpack .../25-gcc-x86-64-linux-gnu_4%3a13.2.0-7ubuntu1_amd64.deb ... 690s Unpacking gcc-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 690s Selecting previously unselected package gcc. 690s Preparing to unpack .../26-gcc_4%3a13.2.0-7ubuntu1_amd64.deb ... 690s Unpacking gcc (4:13.2.0-7ubuntu1) ... 690s Selecting previously unselected package libc-dev-bin. 690s Preparing to unpack .../27-libc-dev-bin_2.39-0ubuntu5_amd64.deb ... 690s Unpacking libc-dev-bin (2.39-0ubuntu5) ... 690s Selecting previously unselected package linux-libc-dev:amd64. 690s Preparing to unpack .../28-linux-libc-dev_6.8.0-11.11_amd64.deb ... 690s Unpacking linux-libc-dev:amd64 (6.8.0-11.11) ... 690s Selecting previously unselected package libcrypt-dev:amd64. 690s Preparing to unpack .../29-libcrypt-dev_1%3a4.4.36-4_amd64.deb ... 690s Unpacking libcrypt-dev:amd64 (1:4.4.36-4) ... 690s Selecting previously unselected package rpcsvc-proto. 690s Preparing to unpack .../30-rpcsvc-proto_1.4.2-0ubuntu6_amd64.deb ... 690s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 690s Selecting previously unselected package libc6-dev:amd64. 690s Preparing to unpack .../31-libc6-dev_2.39-0ubuntu5_amd64.deb ... 690s Unpacking libc6-dev:amd64 (2.39-0ubuntu5) ... 691s Selecting previously unselected package libtool. 691s Preparing to unpack .../32-libtool_2.4.7-7_all.deb ... 691s Unpacking libtool (2.4.7-7) ... 691s Selecting previously unselected package dh-autoreconf. 691s Preparing to unpack .../33-dh-autoreconf_20_all.deb ... 691s Unpacking dh-autoreconf (20) ... 691s Selecting previously unselected package libarchive-zip-perl. 691s Preparing to unpack .../34-libarchive-zip-perl_1.68-1_all.deb ... 691s Unpacking libarchive-zip-perl (1.68-1) ... 691s Selecting previously unselected package libsub-override-perl. 691s Preparing to unpack .../35-libsub-override-perl_0.10-1_all.deb ... 691s Unpacking libsub-override-perl (0.10-1) ... 691s Selecting previously unselected package libfile-stripnondeterminism-perl. 691s Preparing to unpack .../36-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 691s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 691s Selecting previously unselected package dh-strip-nondeterminism. 691s Preparing to unpack .../37-dh-strip-nondeterminism_1.13.1-1_all.deb ... 691s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 691s Selecting previously unselected package libdw1:amd64. 691s Preparing to unpack .../38-libdw1_0.190-1_amd64.deb ... 691s Unpacking libdw1:amd64 (0.190-1) ... 691s Selecting previously unselected package debugedit. 691s Preparing to unpack .../39-debugedit_1%3a5.0-5_amd64.deb ... 691s Unpacking debugedit (1:5.0-5) ... 691s Selecting previously unselected package dwz. 691s Preparing to unpack .../40-dwz_0.15-1_amd64.deb ... 691s Unpacking dwz (0.15-1) ... 691s Selecting previously unselected package gettext. 691s Preparing to unpack .../41-gettext_0.21-14ubuntu1_amd64.deb ... 691s Unpacking gettext (0.21-14ubuntu1) ... 691s Selecting previously unselected package intltool-debian. 691s Preparing to unpack .../42-intltool-debian_0.35.0+20060710.6_all.deb ... 691s Unpacking intltool-debian (0.35.0+20060710.6) ... 691s Selecting previously unselected package po-debconf. 691s Preparing to unpack .../43-po-debconf_1.0.21+nmu1_all.deb ... 691s Unpacking po-debconf (1.0.21+nmu1) ... 691s Selecting previously unselected package debhelper. 691s Preparing to unpack .../44-debhelper_13.14.1ubuntu1_all.deb ... 691s Unpacking debhelper (13.14.1ubuntu1) ... 691s Selecting previously unselected package dh-apport. 691s Preparing to unpack .../45-dh-apport_2.28.0-0ubuntu1_all.deb ... 691s Unpacking dh-apport (2.28.0-0ubuntu1) ... 691s Selecting previously unselected package dh-golang. 691s Preparing to unpack .../46-dh-golang_1.62_all.deb ... 691s Unpacking dh-golang (1.62) ... 691s Selecting previously unselected package golang-1.22-src. 691s Preparing to unpack .../47-golang-1.22-src_1.22.1-1_all.deb ... 691s Unpacking golang-1.22-src (1.22.1-1) ... 693s Selecting previously unselected package golang-1.22-go. 693s Preparing to unpack .../48-golang-1.22-go_1.22.1-1_amd64.deb ... 693s Unpacking golang-1.22-go (1.22.1-1) ... 694s Selecting previously unselected package golang-src. 694s Preparing to unpack .../49-golang-src_2%3a1.22~2_all.deb ... 694s Unpacking golang-src (2:1.22~2) ... 694s Selecting previously unselected package golang-go:amd64. 694s Preparing to unpack .../50-golang-go_2%3a1.22~2_amd64.deb ... 694s Unpacking golang-go:amd64 (2:1.22~2) ... 694s Selecting previously unselected package libstdc++-13-dev:amd64. 694s Preparing to unpack .../51-libstdc++-13-dev_13.2.0-17ubuntu2_amd64.deb ... 694s Unpacking libstdc++-13-dev:amd64 (13.2.0-17ubuntu2) ... 694s Selecting previously unselected package g++-13-x86-64-linux-gnu. 694s Preparing to unpack .../52-g++-13-x86-64-linux-gnu_13.2.0-17ubuntu2_amd64.deb ... 694s Unpacking g++-13-x86-64-linux-gnu (13.2.0-17ubuntu2) ... 694s Selecting previously unselected package g++-13. 694s Preparing to unpack .../53-g++-13_13.2.0-17ubuntu2_amd64.deb ... 694s Unpacking g++-13 (13.2.0-17ubuntu2) ... 694s Selecting previously unselected package g++-x86-64-linux-gnu. 694s Preparing to unpack .../54-g++-x86-64-linux-gnu_4%3a13.2.0-7ubuntu1_amd64.deb ... 694s Unpacking g++-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 694s Selecting previously unselected package g++. 694s Preparing to unpack .../55-g++_4%3a13.2.0-7ubuntu1_amd64.deb ... 694s Unpacking g++ (4:13.2.0-7ubuntu1) ... 694s Selecting previously unselected package build-essential. 694s Preparing to unpack .../56-build-essential_12.10ubuntu1_amd64.deb ... 694s Unpacking build-essential (12.10ubuntu1) ... 694s Setting up libdw1:amd64 (0.190-1) ... 694s Setting up libarchive-zip-perl (1.68-1) ... 694s Setting up libdebhelper-perl (13.14.1ubuntu1) ... 694s Setting up linux-libc-dev:amd64 (6.8.0-11.11) ... 694s Setting up m4 (1.4.19-4) ... 694s Setting up libgomp1:amd64 (14-20240303-1ubuntu1) ... 694s Setting up dh-apport (2.28.0-0ubuntu1) ... 694s Setting up autotools-dev (20220109.1) ... 694s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 694s Setting up golang-1.22-src (1.22.1-1) ... 694s Setting up libquadmath0:amd64 (14-20240303-1ubuntu1) ... 694s Setting up libmpc3:amd64 (1.3.1-1) ... 694s Setting up libatomic1:amd64 (14-20240303-1ubuntu1) ... 694s Setting up autopoint (0.21-14ubuntu1) ... 694s Setting up autoconf (2.71-3) ... 694s Setting up libubsan1:amd64 (14-20240303-1ubuntu1) ... 694s Setting up dwz (0.15-1) ... 694s Setting up libhwasan0:amd64 (14-20240303-1ubuntu1) ... 694s Setting up libcrypt-dev:amd64 (1:4.4.36-4) ... 694s Setting up libasan8:amd64 (14-20240303-1ubuntu1) ... 694s Setting up debugedit (1:5.0-5) ... 694s Setting up libsub-override-perl (0.10-1) ... 694s Setting up libtsan2:amd64 (14-20240303-1ubuntu1) ... 694s Setting up libisl23:amd64 (0.26-3) ... 694s Setting up libc-dev-bin (2.39-0ubuntu5) ... 694s Setting up golang-src (2:1.22~2) ... 694s Setting up libcc1-0:amd64 (14-20240303-1ubuntu1) ... 694s Setting up liblsan0:amd64 (14-20240303-1ubuntu1) ... 694s Setting up libitm1:amd64 (14-20240303-1ubuntu1) ... 694s Setting up automake (1:1.16.5-1.3ubuntu1) ... 694s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 694s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 694s Setting up gettext (0.21-14ubuntu1) ... 694s Setting up cpp-13-x86-64-linux-gnu (13.2.0-17ubuntu2) ... 694s Setting up golang-1.22-go (1.22.1-1) ... 694s Setting up intltool-debian (0.35.0+20060710.6) ... 694s Setting up dh-strip-nondeterminism (1.13.1-1) ... 694s Setting up libgcc-13-dev:amd64 (13.2.0-17ubuntu2) ... 694s Setting up libc6-dev:amd64 (2.39-0ubuntu5) ... 694s Setting up libstdc++-13-dev:amd64 (13.2.0-17ubuntu2) ... 694s Setting up cpp-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 694s Setting up cpp-13 (13.2.0-17ubuntu2) ... 694s Setting up golang-go:amd64 (2:1.22~2) ... 694s Setting up gcc-13-x86-64-linux-gnu (13.2.0-17ubuntu2) ... 694s Setting up po-debconf (1.0.21+nmu1) ... 694s Setting up gcc-13 (13.2.0-17ubuntu2) ... 694s Setting up cpp (4:13.2.0-7ubuntu1) ... 695s Setting up g++-13-x86-64-linux-gnu (13.2.0-17ubuntu2) ... 695s Setting up gcc-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 695s Setting up libtool (2.4.7-7) ... 695s Setting up gcc (4:13.2.0-7ubuntu1) ... 695s Setting up dh-autoreconf (20) ... 695s Setting up g++-x86-64-linux-gnu (4:13.2.0-7ubuntu1) ... 695s Setting up g++-13 (13.2.0-17ubuntu2) ... 695s Setting up debhelper (13.14.1ubuntu1) ... 695s Setting up g++ (4:13.2.0-7ubuntu1) ... 695s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 695s Setting up build-essential (12.10ubuntu1) ... 695s Setting up dh-golang (1.62) ... 695s Setting up autopkgtest-satdep (0) ... 695s Processing triggers for man-db (2.12.0-3) ... 696s Processing triggers for install-info (7.1-3) ... 696s Processing triggers for libc-bin (2.39-0ubuntu5) ... 699s (Reading database ... 89672 files and directories currently installed.) 699s Removing autopkgtest-satdep (0) ... 699s autopkgtest [23:20:43]: test command1: ./debian/tests/test 699s autopkgtest [23:20:43]: test command1: [----------------------- 734s === RUN TestRunSignal 734s === RUN TestRunSignal/Send_SIGINT_exits 734s INFO Starting WSL Pro Service version Dev 734s === RUN TestRunSignal/Send_SIGTERM_exits 734s INFO Starting WSL Pro Service version Dev 734s --- PASS: TestRunSignal (0.20s) 734s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 734s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 734s === RUN TestRun 734s === PAUSE TestRun 734s === CONT TestRun 734s === RUN TestRun/Run_and_usage_error_only_does_not_fail 734s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 734s === RUN TestRun/Run_and_exit_successfully 734s === PAUSE TestRun/Run_and_exit_successfully 734s === RUN TestRun/Run_and_return_error 734s === PAUSE TestRun/Run_and_return_error 734s === RUN TestRun/Run_and_return_usage_error 734s === PAUSE TestRun/Run_and_return_usage_error 734s === CONT TestRun/Run_and_usage_error_only_does_not_fail 734s === CONT TestRun/Run_and_return_error 734s INFO Starting WSL Pro Service version Dev 734s INFO Starting WSL Pro Service version Dev 734s ERROR context.Background Error requested 734s === CONT TestRun/Run_and_exit_successfully 734s INFO Starting WSL Pro Service version Dev 734s === CONT TestRun/Run_and_return_usage_error 734s INFO Starting WSL Pro Service version Dev 734s ERROR context.Background Error requested 734s --- PASS: TestRun (0.00s) 734s --- PASS: TestRun/Run_and_return_error (0.10s) 734s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 734s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 734s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 734s PASS 734s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 0.413s 734s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 734s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 745s === RUN TestHelp 745s --- PASS: TestHelp (0.00s) 745s === RUN TestCompletion 745s --- PASS: TestCompletion (0.00s) 745s === RUN TestVersion 745s --- PASS: TestVersion (0.00s) 745s === RUN TestNoUsageError 745s --- PASS: TestNoUsageError (0.00s) 745s === RUN TestUsageError 745s === PAUSE TestUsageError 745s === RUN TestCanQuitWhenExecute 745s === PAUSE TestCanQuitWhenExecute 745s === RUN TestCanQuitTwice 745s === PAUSE TestCanQuitTwice 745s === RUN TestAppCanQuitWithoutExecute 745s === PAUSE TestAppCanQuitWithoutExecute 745s === RUN TestAppRunFailsOnComponentsCreationAndQuit 745s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 745s === RUN TestAppGetRootCmd 745s === PAUSE TestAppGetRootCmd 745s === RUN TestWithProMock 745s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 745s --- SKIP: TestWithProMock (0.00s) 745s === RUN TestWithWslPathMock 745s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 745s --- SKIP: TestWithWslPathMock (0.00s) 745s === RUN TestWithWslInfoMock 745s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 745s --- SKIP: TestWithWslInfoMock (0.00s) 745s === RUN TestWithCmdExeMock 745s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 745s --- SKIP: TestWithCmdExeMock (0.00s) 745s === CONT TestUsageError 745s === CONT TestAppCanQuitWithoutExecute 745s service_test.go:137: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 745s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 745s === CONT TestAppGetRootCmd 745s --- PASS: TestAppGetRootCmd (0.00s) 745s === CONT TestAppRunFailsOnComponentsCreationAndQuit 745s --- PASS: TestUsageError (0.00s) 745s === CONT TestCanQuitTwice 745s time="2024-03-09T23:21:18Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 745s time="2024-03-09T23:21:18Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:63 New() Building new daemon" 745s time="2024-03-09T23:21:18Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/testutils/mock_agent.go:80 MockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44505\"" 745s time="2024-03-09T23:21:22Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 745s time="2024-03-09T23:21:22Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 745s time="2024-03-09T23:21:24Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/controlstream/session.go:25 newSession() Connecting to control stream at \"127.0.0.1:44505\"" 745s time="2024-03-09T23:21:24Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/controlstream/controlstream.go:75 (*ControlStream).Connect() Control stream: starting handshake" 745s time="2024-03-09T23:21:24Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/testutils/mock_agent.go:134 (*wslInstanceMockService).Connected() wslInstanceMockService: Received incoming connection" 745s time="2024-03-09T23:21:24Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 745s time="2024-03-09T23:21:24Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 745s time="2024-03-09T23:21:24Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 745s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (6.44s) 745s === CONT TestCanQuitWhenExecute 745s time="2024-03-09T23:21:24Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/testutils/mock_agent.go:80 MockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33219\"" 745s time="2024-03-09T23:21:24Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 745s time="2024-03-09T23:21:24Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:63 New() Building new daemon" 745s time="2024-03-09T23:21:26Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/testutils/mock_agent.go:148 (*wslInstanceMockService).Connected() wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 745s time="2024-03-09T23:21:26Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/testutils/mock_agent.go:176 (*wslInstanceMockService).Connected() wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 33243" 745s time="2024-03-09T23:21:26Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/controlstream/controlstream.go:82 (*ControlStream).Connect() Control stream: completed handshake" 745s time="2024-03-09T23:21:26Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:176 (*Daemon).serveOnce() Connected to control stream" 745s time="2024-03-09T23:21:26Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/wslinstanceservice/wslinstanceservice.go:44 (*Service).RegisterGRPCService() Registering gRPC WSL instance service" 745s time="2024-03-09T23:21:26Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:223 (*Daemon).serve() Starting to serve gRPC requests" 745s time="2024-03-09T23:21:26Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:233 (*Daemon).serve() Serving gRPC requests on localhost:33243" 745s time="2024-03-09T23:21:26Z" level=error msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:142 (*Daemon).Serve() Serve error: WSL Pro Service stopped serving: grpc error: grpc: the server has been stopped" 745s time="2024-03-09T23:21:26Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 745s time="2024-03-09T23:21:26Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 745s time="2024-03-09T23:21:26Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 745s time="2024-03-09T23:21:26Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 745s time="2024-03-09T23:21:26Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 745s time="2024-03-09T23:21:26Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 745s time="2024-03-09T23:21:26Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 745s --- PASS: TestCanQuitTwice (8.48s) 745s time="2024-03-09T23:21:29Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 745s time="2024-03-09T23:21:29Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 745s time="2024-03-09T23:21:29Z" level=error msg="/tmp/autopkgtest.4xsj8q/build.HzQ/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/TestCanQuitWhenExecute3231187992/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestCanQuitWhenExecute3231187992/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 745s time="2024-03-09T23:21:29Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 745s time="2024-03-09T23:21:29Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 745s time="2024-03-09T23:21:29Z" level=info msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 745s time="2024-03-09T23:21:29Z" level=debug msg="/tmp/autopkgtest.4xsj8q/build.HzQ/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 745s --- PASS: TestCanQuitWhenExecute (4.43s) 745s PASS 745s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 10.887s 780s === RUN TestNew 780s === PAUSE TestNew 780s === RUN TestConnect 780s === PAUSE TestConnect 780s === RUN TestSend 780s === PAUSE TestSend 780s === RUN TestReconnection 780s === PAUSE TestReconnection 780s === RUN TestWithProMock 780s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 780s --- SKIP: TestWithProMock (0.00s) 780s === RUN TestWithWslPathMock 780s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 780s --- SKIP: TestWithWslPathMock (0.00s) 780s === RUN TestWithWslInfoMock 780s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 780s --- SKIP: TestWithWslInfoMock (0.00s) 780s === RUN TestWithCmdExeMock 780s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 780s --- SKIP: TestWithCmdExeMock (0.00s) 780s === CONT TestNew 780s === RUN TestNew/Success 780s === PAUSE TestNew/Success 780s === RUN TestNew/Error_when_the_context_is_cancelled 780s === PAUSE TestNew/Error_when_the_context_is_cancelled 780s === RUN TestNew/Error_when_WslPath_returns_error 780s === PAUSE TestNew/Error_when_WslPath_returns_error 780s === CONT TestNew/Success 780s === CONT TestSend 780s time="2024-03-09T23:21:19Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41179\"" 780s === CONT TestConnect 780s === RUN TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 780s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 780s === RUN TestConnect/No_connection_because_of_empty_port_file 780s === PAUSE TestConnect/No_connection_because_of_empty_port_file 780s === RUN TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 780s === PAUSE TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 780s === RUN TestConnect/No_connection_because_of_unreadable_port_file 780s === PAUSE TestConnect/No_connection_because_of_unreadable_port_file 780s === RUN TestConnect/No_connection_because_of_port_file_with_invalid_contents 780s === PAUSE TestConnect/No_connection_because_of_port_file_with_invalid_contents 780s === RUN TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 780s === PAUSE TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 780s === RUN TestConnect/Incomplete_handshake_because_Agent_never_receives 780s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_receives 780s === RUN TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 780s === PAUSE TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 780s === RUN TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 780s === PAUSE TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 780s === RUN TestConnect/Success 780s === PAUSE TestConnect/Success 780s === RUN TestConnect/No_connection_because_port_file_does_not_exist 780s === PAUSE TestConnect/No_connection_because_port_file_does_not_exist 780s === CONT TestNew/Error_when_WslPath_returns_error 780s time="2024-03-09T23:21:25Z" level=info msg="Connecting to control stream at \"127.0.0.1:41179\"" 780s time="2024-03-09T23:21:25Z" level=debug msg="Control stream: starting handshake" 780s time="2024-03-09T23:21:25Z" level=info msg="wslInstanceMockService: Received incoming connection" 780s === CONT TestNew/Error_when_the_context_is_cancelled 780s --- PASS: TestNew (0.00s) 780s --- PASS: TestNew/Success (3.48s) 780s --- PASS: TestNew/Error_when_WslPath_returns_error (3.73s) 780s --- PASS: TestNew/Error_when_the_context_is_cancelled (0.00s) 780s === CONT TestReconnection 780s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 780s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 780s === RUN TestReconnection/Success_connecting_after_failing_to_connect 780s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 780s === CONT TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 780s time="2024-03-09T23:21:26Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41655\"" 780s time="2024-03-09T23:21:27Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 780s time="2024-03-09T23:21:27Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 34247" 780s time="2024-03-09T23:21:27Z" level=debug msg="Control stream: completed handshake" 780s time="2024-03-09T23:21:29Z" level=info msg="Connecting to control stream at \"127.0.0.1:41655\"" 780s time="2024-03-09T23:21:29Z" level=debug msg="Control stream: starting handshake" 780s time="2024-03-09T23:21:29Z" level=info msg="wslInstanceMockService: Received incoming connection" 780s time="2024-03-09T23:21:30Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 780s time="2024-03-09T23:21:30Z" level=info msg="connection with \"TEST_DISTRO\": mock error: dropping stream before sending port" 780s time="2024-03-09T23:21:30Z" level=info msg="wslInstanceMockService: dropped connection" 780s === CONT TestConnect/Incomplete_handshake_because_Agent_never_receives 780s time="2024-03-09T23:21:30Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42619\"" 780s time="2024-03-09T23:21:32Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 780s time="2024-03-09T23:21: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\"" 780s --- PASS: TestSend (13.50s) 780s === CONT TestConnect/No_connection_because_port_file_does_not_exist 780s time="2024-03-09T23:21:33Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 780s time="2024-03-09T23:21:33Z" level=info msg="wslInstanceMockService: dropped connection" 780s time="2024-03-09T23:21:33Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestSend2047381022/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 780s time="2024-03-09T23:21:33Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33511\"" 780s time="2024-03-09T23:21:35Z" level=info msg="Connecting to control stream at \"127.0.0.1:42619\"" 780s time="2024-03-09T23:21:35Z" level=debug msg="Control stream: starting handshake" 780s time="2024-03-09T23:21:35Z" level=info msg="wslInstanceMockService: Received incoming connection" 780s time="2024-03-09T23:21:35Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 780s time="2024-03-09T23:21:35Z" level=info msg="wslInstanceMockService: dropped connection" 780s === CONT TestConnect/Success 780s time="2024-03-09T23:21:37Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectIncomplete_handshake_because_Agent_never_receives3454725190/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 780s time="2024-03-09T23:21:37Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43143\"" 780s time="2024-03-09T23:21:38Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_port_file_does_not_exist1723750665/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 780s === CONT TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 780s time="2024-03-09T23:21:38Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43443\"" 780s time="2024-03-09T23:21:42Z" level=info msg="Connecting to control stream at \"127.0.0.1:43143\"" 780s time="2024-03-09T23:21:42Z" level=debug msg="Control stream: starting handshake" 780s time="2024-03-09T23:21:42Z" level=info msg="wslInstanceMockService: Received incoming connection" 780s time="2024-03-09T23:21:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 780s time="2024-03-09T23:21:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 46705" 780s time="2024-03-09T23:21:44Z" level=debug msg="Control stream: completed handshake" 780s time="2024-03-09T23:21:45Z" level=warning msg="Controlstream: assigning arbitrary connection ID because of error: could not obtain WSL distro name: could not get distro root path: exit status 1. Stdout: " 780s time="2024-03-09T23:21:45Z" level=info msg="Connecting to control stream at \"127.0.0.1:43443\"" 780s time="2024-03-09T23:21:45Z" level=debug msg="Control stream: starting handshake" 780s time="2024-03-09T23:21:45Z" level=info msg="wslInstanceMockService: Received incoming connection" 780s === CONT TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 780s time="2024-03-09T23:21:45Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectSuccess569744199/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 780s time="2024-03-09T23:21:45Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:38427\"" 780s time="2024-03-09T23:21:46Z" level=warning msg="wslInstanceMockService: dropped connection: new connection: did not receive info from WSL distro: rpc error: code = Canceled desc = context canceled" 780s === CONT TestConnect/No_connection_because_of_unreadable_port_file 780s time="2024-03-09T23:21:46Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34029\"" 780s time="2024-03-09T23:21:48Z" level=info msg="Connecting to control stream at \"127.0.0.1:38427\"" 780s time="2024-03-09T23:21:48Z" level=debug msg="Control stream: starting handshake" 780s time="2024-03-09T23:21:48Z" level=info msg="wslInstanceMockService: Received incoming connection" 780s === CONT TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 780s time="2024-03-09T23:21:49Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:37079\"" 780s time="2024-03-09T23:21:50Z" 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\"" 780s time="2024-03-09T23:21:50Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Sending bad port 0" 780s === CONT TestConnect/No_connection_because_of_port_file_with_invalid_contents 780s time="2024-03-09T23:21:50Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45529\"" 780s === CONT TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 780s time="2024-03-09T23:21:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34507\"" 780s === CONT TestConnect/No_connection_because_of_empty_port_file 780s time="2024-03-09T23:21:52Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36795\"" 780s time="2024-03-09T23:21:56Z" level=info msg="Connecting to control stream at \"127.0.0.1:42573\"" 780s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 780s --- PASS: TestConnect (0.00s) 780s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port (3.91s) 780s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_receives (6.67s) 780s --- PASS: TestConnect/No_connection_because_port_file_does_not_exist (5.36s) 780s --- PASS: TestConnect/Success (8.13s) 780s --- PASS: TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name (8.06s) 780s --- PASS: TestConnect/No_connection_because_of_unreadable_port_file (3.11s) 780s --- PASS: TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 (4.63s) 780s --- PASS: TestConnect/Error_because_WindowsForwardedLocalhost_returns_error (3.10s) 780s --- PASS: TestConnect/No_connection_because_of_port_file_with_invalid_contents (2.92s) 780s --- PASS: TestConnect/No_connection_because_of_port_file_contains_the_wrong_port (3.37s) 780s --- PASS: TestConnect/No_connection_because_of_empty_port_file (3.33s) 780s time="2024-03-09T23:21:56Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_empty_port_file1294604437/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 780s === CONT TestReconnection/Success_connecting_after_failing_to_connect 780s time="2024-03-09T23:21:58Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33687\"" 780s time="2024-03-09T23:22:00Z" level=info msg="Connecting to control stream at \"127.0.0.1:33687\"" 780s time="2024-03-09T23:22:00Z" level=debug msg="Control stream: starting handshake" 780s time="2024-03-09T23:22:00Z" level=info msg="wslInstanceMockService: Received incoming connection" 780s time="2024-03-09T23:22:00Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36391\"" 780s time="2024-03-09T23:22: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\"" 780s time="2024-03-09T23:22:01Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 34373" 780s time="2024-03-09T23:22:01Z" level=debug msg="Control stream: completed handshake" 780s time="2024-03-09T23:22:01Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:38461\"" 780s time="2024-03-09T23:22:01Z" level=info msg="Connecting to control stream at \"127.0.0.1:36391\"" 780s time="2024-03-09T23:22:01Z" level=debug msg="Control stream: starting handshake" 780s time="2024-03-09T23:22:01Z" level=info msg="wslInstanceMockService: Received incoming connection" 780s time="2024-03-09T23:22:02Z" level=info msg="Connecting to control stream at \"127.0.0.1:38461\"" 780s time="2024-03-09T23:22:02Z" level=debug msg="Control stream: starting handshake" 780s time="2024-03-09T23:22:02Z" level=info msg="wslInstanceMockService: Received incoming connection" 780s time="2024-03-09T23:22: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\"" 780s time="2024-03-09T23:22:03Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 38539" 780s time="2024-03-09T23:22:03Z" level=debug msg="Control stream: completed handshake" 780s time="2024-03-09T23:22:04Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 780s time="2024-03-09T23:22:04Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 40907" 780s time="2024-03-09T23:22:04Z" level=debug msg="Control stream: completed handshake" 780s --- PASS: TestReconnection (0.00s) 780s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (7.23s) 780s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (8.38s) 780s PASS 780s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/controlstream 44.905s 813s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 832s === RUN TestNew 832s === PAUSE TestNew 832s === RUN TestServe 832s === PAUSE TestServe 832s === RUN TestServeAndQuit 832s === PAUSE TestServeAndQuit 832s === RUN TestReconnection 832s === PAUSE TestReconnection 832s === RUN TestWithProMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithProMock (0.00s) 832s === RUN TestWithWslPathMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithWslPathMock (0.00s) 832s === RUN TestWithWslInfoMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithWslInfoMock (0.00s) 832s === RUN TestWithCmdExeMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithCmdExeMock (0.00s) 832s === CONT TestNew 832s === RUN TestNew/Success 832s === PAUSE TestNew/Success 832s === RUN TestNew/Error_when_WslPath_returns_error 832s === PAUSE TestNew/Error_when_WslPath_returns_error 832s === CONT TestNew/Success 832s time="2024-03-09T23:21:30Z" level=debug msg="Building new daemon" 832s === CONT TestServe 832s === RUN TestServe/No_connection_because_of_faulty_agent 832s === PAUSE TestServe/No_connection_because_of_faulty_agent 832s === RUN TestServe/Error_because_of_notifier_returning_error 832s === PAUSE TestServe/Error_because_of_notifier_returning_error 832s === RUN TestServe/Error_because_WindowsHostAddress_returns_error 832s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_error 832s === RUN TestServe/Error_because_of_context_cancelled 832s === PAUSE TestServe/Error_because_of_context_cancelled 832s === RUN TestServe/Success 832s === PAUSE TestServe/Success 832s === RUN TestServe/Success_with_systemd_notifier_returning_true 832s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 832s === RUN TestServe/No_connection_because_port_file_does_not_exist 832s === PAUSE TestServe/No_connection_because_port_file_does_not_exist 832s === CONT TestServe/No_connection_because_of_faulty_agent 832s time="2024-03-09T23:21:30Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:21:30Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42439\"" 832s === CONT TestNew/Error_when_WslPath_returns_error 832s time="2024-03-09T23:21:33Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:21:35Z" level=info msg="Connecting to control stream at \"127.0.0.1:42439\"" 832s time="2024-03-09T23:21:35Z" level=debug msg="Control stream: starting handshake" 832s time="2024-03-09T23:21:35Z" level=info msg="wslInstanceMockService: Received incoming connection" 832s time="2024-03-09T23:21:35Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 832s time="2024-03-09T23:21:35Z" level=info msg="wslInstanceMockService: dropped connection" 832s time="2024-03-09T23:21:36Z" level=error msg="Serve error: could not connect to Windows Agent via the control stream: could not complete handshake: could not send system info: EOF" 832s === CONT TestServe/Success_with_systemd_notifier_returning_true 832s --- PASS: TestNew (0.00s) 832s --- PASS: TestNew/Success (3.00s) 832s --- PASS: TestNew/Error_when_WslPath_returns_error (3.90s) 832s time="2024-03-09T23:21:37Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44467\"" 832s time="2024-03-09T23:21:37Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:21:38Z" level=info msg="Retrying connection to control stream" 832s time="2024-03-09T23:21:40Z" level=debug msg="Ready state sent to systemd" 832s time="2024-03-09T23:21:40Z" level=info msg="Connecting to control stream at \"127.0.0.1:42439\"" 832s time="2024-03-09T23:21:40Z" level=debug msg="Control stream: starting handshake" 832s time="2024-03-09T23:21:40Z" level=info msg="wslInstanceMockService: Received incoming connection" 832s time="2024-03-09T23:21:40Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 832s time="2024-03-09T23:21:40Z" level=info msg="wslInstanceMockService: dropped connection" 832s time="2024-03-09T23:21:42Z" level=info msg="Connecting to control stream at \"127.0.0.1:44467\"" 832s time="2024-03-09T23:21:42Z" level=debug msg="Control stream: starting handshake" 832s time="2024-03-09T23:21:42Z" level=info msg="wslInstanceMockService: Received incoming connection" 832s time="2024-03-09T23:21:42Z" level=error msg="Serve error: could not connect to Windows Agent via the control stream: could not complete handshake: could not send system info: EOF" 832s time="2024-03-09T23:21:43Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:21:43Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeNo_connection_because_of_faulty_agent1515538673/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 832s === CONT TestReconnection 832s === RUN TestReconnection/Success_connecting_after_failing_to_connect 832s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 832s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 832s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 832s === CONT TestServeAndQuit 832s === RUN TestServeAndQuit/Success_with_graceful_quit 832s === PAUSE TestServeAndQuit/Success_with_graceful_quit 832s === RUN TestServeAndQuit/Success_with_forceful_quit 832s === PAUSE TestServeAndQuit/Success_with_forceful_quit 832s === RUN TestServeAndQuit/Success_with_double_quit 832s === PAUSE TestServeAndQuit/Success_with_double_quit 832s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 832s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 832s === CONT TestServe/No_connection_because_port_file_does_not_exist 832s time="2024-03-09T23:21:43Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:21:43Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:32879\"" 832s time="2024-03-09T23:21:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 832s time="2024-03-09T23:21:44Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 44371" 832s time="2024-03-09T23:21:44Z" level=debug msg="Control stream: completed handshake" 832s time="2024-03-09T23:21:44Z" level=info msg="Connected to control stream" 832s time="2024-03-09T23:21:44Z" level=debug msg="Starting to serve gRPC requests" 832s time="2024-03-09T23:21:44Z" level=info msg="Serving gRPC requests on localhost:44371" 832s time="2024-03-09T23:21:44Z" level=debug msg="Updated systemd status to \"Serving\"" 832s time="2024-03-09T23:21:47Z" 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_exist3626249623/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist3626249623/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 832s time="2024-03-09T23:21:49Z" level=info msg="Retrying connection to control stream" 832s time="2024-03-09T23:21:49Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 832s time="2024-03-09T23:21:50Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:21:50Z" 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_exist3626249623/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist3626249623/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 832s time="2024-03-09T23:21:54Z" level=info msg="Retrying connection to control stream" 832s time="2024-03-09T23:21:55Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:21:55Z" 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_exist3626249623/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist3626249623/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 832s === CONT TestServe/Error_because_of_context_cancelled 832s time="2024-03-09T23:21:55Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:21:55Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeNo_connection_because_port_file_does_not_exist3626249623/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 832s time="2024-03-09T23:21:55Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33931\"" 832s === CONT TestServe/Success 832s time="2024-03-09T23:21:58Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:21:58Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeError_because_of_context_cancelled3233823327/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 832s time="2024-03-09T23:21:58Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39675\"" 832s time="2024-03-09T23:22:02Z" level=info msg="Connecting to control stream at \"127.0.0.1:39675\"" 832s time="2024-03-09T23:22:02Z" level=debug msg="Control stream: starting handshake" 832s time="2024-03-09T23:22:02Z" level=info msg="wslInstanceMockService: Received incoming connection" 832s time="2024-03-09T23:22:02Z" level=debug msg="Updated systemd status to \"Stopped\"" 832s time="2024-03-09T23:22:02Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 832s time="2024-03-09T23:22:02Z" level=info msg="wslInstanceMockService: dropped connection" 832s === CONT TestServe/Error_because_WindowsHostAddress_returns_error 832s time="2024-03-09T23:22:02Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:22:02Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeSuccess_with_systemd_notifier_returning_true3103633103/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 832s time="2024-03-09T23:22:02Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:37317\"" 832s time="2024-03-09T23:22:04Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 832s time="2024-03-09T23:22:04Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 42015" 832s time="2024-03-09T23:22:04Z" level=debug msg="Control stream: completed handshake" 832s time="2024-03-09T23:22:04Z" level=info msg="Connected to control stream" 832s time="2024-03-09T23:22:04Z" level=debug msg="Starting to serve gRPC requests" 832s time="2024-03-09T23:22:04Z" level=info msg="Serving gRPC requests on localhost:42015" 832s === CONT TestServe/Error_because_of_notifier_returning_error 832s time="2024-03-09T23:22:06Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:22:06Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36419\"" 832s time="2024-03-09T23:22:09Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 832s time="2024-03-09T23:22:09Z" level=warning msg="Could not change systemd status: couldn't update status to systemd: mock error" 832s === CONT TestReconnection/Success_connecting_after_failing_to_connect 832s time="2024-03-09T23:22:09Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:22:11Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:22:12Z" level=debug msg="Ready state sent to systemd" 832s time="2024-03-09T23:22:13Z" 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_connect400969892/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect400969892/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 832s time="2024-03-09T23:22:13Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 832s time="2024-03-09T23:22:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44669\"" 832s time="2024-03-09T23:22:15Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:22:15Z" level=info msg="Retrying connection to control stream" 832s time="2024-03-09T23:22:15Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 832s time="2024-03-09T23:22:16Z" level=info msg="Connecting to control stream at \"127.0.0.1:44669\"" 832s time="2024-03-09T23:22:16Z" level=debug msg="Control stream: starting handshake" 832s time="2024-03-09T23:22:16Z" level=info msg="wslInstanceMockService: Received incoming connection" 832s time="2024-03-09T23:22: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\"" 832s time="2024-03-09T23:22:18Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 43779" 832s time="2024-03-09T23:22:18Z" level=debug msg="Control stream: completed handshake" 832s time="2024-03-09T23:22:18Z" level=info msg="Connected to control stream" 832s time="2024-03-09T23:22:18Z" level=debug msg="Starting to serve gRPC requests" 832s time="2024-03-09T23:22:18Z" level=info msg="Serving gRPC requests on localhost:43779" 832s time="2024-03-09T23:22:18Z" level=debug msg="Updated systemd status to \"Serving\"" 832s time="2024-03-09T23:22:19Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:22:23Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 832s time="2024-03-09T23:22:23Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 832s time="2024-03-09T23:22:23Z" level=info msg="wslInstanceMockService: dropped connection" 832s time="2024-03-09T23:22:23Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:22:23Z" level=error msg="Serve error: lost connection to Windows Agent" 832s time="2024-03-09T23:22:23Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 832s time="2024-03-09T23:22:23Z" level=debug msg="Updated systemd status to \"Stopped\"" 832s === CONT TestServeAndQuit/Success_with_graceful_quit 832s time="2024-03-09T23:22:23Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:22:23Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43755\"" 832s time="2024-03-09T23:22:26Z" level=debug msg="Ready state sent to systemd" 832s time="2024-03-09T23:22:27Z" level=info msg="Connecting to control stream at \"127.0.0.1:43755\"" 832s time="2024-03-09T23:22:27Z" level=debug msg="Control stream: starting handshake" 832s time="2024-03-09T23:22:27Z" level=info msg="wslInstanceMockService: Received incoming connection" 832s time="2024-03-09T23:22:29Z" 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\"" 832s time="2024-03-09T23:22:29Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 43243" 832s time="2024-03-09T23:22:29Z" level=debug msg="Control stream: completed handshake" 832s time="2024-03-09T23:22:29Z" level=info msg="Connected to control stream" 832s time="2024-03-09T23:22:29Z" level=debug msg="Starting to serve gRPC requests" 832s time="2024-03-09T23:22:29Z" level=info msg="Serving gRPC requests on localhost:43243" 832s time="2024-03-09T23:22:29Z" level=debug msg="Updated systemd status to \"Serving\"" 832s time="2024-03-09T23:22:29Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:22:29Z" level=info msg="Waiting for active requests to close." 832s time="2024-03-09T23:22:29Z" level=debug msg="Updated systemd status to \"Stopped\"" 832s time="2024-03-09T23:22:29Z" level=debug msg="All connections have now ended." 832s time="2024-03-09T23:22:29Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_graceful_quit2570700066/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 832s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 832s time="2024-03-09T23:22:29Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:22:32Z" level=debug msg="Ready state sent to systemd" 832s time="2024-03-09T23:22:32Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43157\"" 832s time="2024-03-09T23:22:33Z" level=info msg="Connecting to control stream at \"127.0.0.1:43157\"" 832s time="2024-03-09T23:22:33Z" level=debug msg="Control stream: starting handshake" 832s time="2024-03-09T23:22:33Z" level=info msg="wslInstanceMockService: Received incoming connection" 832s time="2024-03-09T23:22: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\"" 832s time="2024-03-09T23:22:35Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 36155" 832s time="2024-03-09T23:22:35Z" level=debug msg="Control stream: completed handshake" 832s time="2024-03-09T23:22:35Z" level=info msg="Connected to control stream" 832s time="2024-03-09T23:22:35Z" level=debug msg="Starting to serve gRPC requests" 832s time="2024-03-09T23:22:35Z" level=info msg="Serving gRPC requests on localhost:36155" 832s time="2024-03-09T23:22:35Z" level=debug msg="Updated systemd status to \"Serving\"" 832s time="2024-03-09T23:22:35Z" level=error msg="Serve error: lost connection to Windows Agent" 832s time="2024-03-09T23:22:35Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 832s time="2024-03-09T23:22:35Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45769\"" 832s time="2024-03-09T23:22:37Z" level=info msg="Retrying connection to control stream" 832s time="2024-03-09T23:22:37Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 832s time="2024-03-09T23:22:38Z" level=info msg="Connecting to control stream at \"127.0.0.1:45769\"" 832s time="2024-03-09T23:22:38Z" level=debug msg="Control stream: starting handshake" 832s time="2024-03-09T23:22:38Z" level=info msg="wslInstanceMockService: Received incoming connection" 832s time="2024-03-09T23:22:40Z" 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\"" 832s time="2024-03-09T23:22:40Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 44611" 832s time="2024-03-09T23:22:40Z" level=debug msg="Control stream: completed handshake" 832s time="2024-03-09T23:22:40Z" level=info msg="Connected to control stream" 832s time="2024-03-09T23:22:40Z" level=debug msg="Starting to serve gRPC requests" 832s time="2024-03-09T23:22:40Z" level=info msg="Serving gRPC requests on localhost:44611" 832s time="2024-03-09T23:22:40Z" level=debug msg="Updated systemd status to \"Serving\"" 832s time="2024-03-09T23:22:45Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 832s time="2024-03-09T23:22:45Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 832s time="2024-03-09T23:22:45Z" level=info msg="wslInstanceMockService: dropped connection" 832s time="2024-03-09T23:22:45Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:22:45Z" level=error msg="Serve error: lost connection to Windows Agent" 832s time="2024-03-09T23:22:45Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 832s time="2024-03-09T23:22:45Z" level=debug msg="Updated systemd status to \"Stopped\"" 832s --- PASS: TestReconnection (0.00s) 832s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (14.17s) 832s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (15.97s) 832s === CONT TestServeAndQuit/Success_with_double_quit 832s time="2024-03-09T23:22:45Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:22:45Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45989\"" 832s time="2024-03-09T23:22:48Z" level=debug msg="Ready state sent to systemd" 832s time="2024-03-09T23:22:49Z" level=info msg="Connecting to control stream at \"127.0.0.1:45989\"" 832s time="2024-03-09T23:22:49Z" level=debug msg="Control stream: starting handshake" 832s time="2024-03-09T23:22:49Z" level=info msg="wslInstanceMockService: Received incoming connection" 832s time="2024-03-09T23:22:50Z" 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\"" 832s time="2024-03-09T23:22:50Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 44665" 832s time="2024-03-09T23:22:50Z" level=debug msg="Control stream: completed handshake" 832s time="2024-03-09T23:22:50Z" level=info msg="Connected to control stream" 832s time="2024-03-09T23:22:50Z" level=debug msg="Starting to serve gRPC requests" 832s time="2024-03-09T23:22:50Z" level=info msg="Serving gRPC requests on localhost:44665" 832s time="2024-03-09T23:22:50Z" level=debug msg="Updated systemd status to \"Serving\"" 832s time="2024-03-09T23:22:51Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:22:51Z" level=info msg="Waiting for active requests to close." 832s time="2024-03-09T23:22:51Z" level=debug msg="Updated systemd status to \"Stopped\"" 832s time="2024-03-09T23:22:51Z" level=debug msg="All connections have now ended." 832s time="2024-03-09T23:22:51Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:22:51Z" level=info msg="Waiting for active requests to close." 832s time="2024-03-09T23:22:51Z" level=debug msg="All connections have now ended." 832s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 832s time="2024-03-09T23:22:51Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_double_quit1765773595/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 832s time="2024-03-09T23:22:51Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44153\"" 832s time="2024-03-09T23:22:51Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:22:51Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 832s time="2024-03-09T23:22:51Z" level=info msg="wslInstanceMockService: dropped connection" 832s --- PASS: TestServe (0.00s) 832s --- PASS: TestServe/No_connection_because_of_faulty_agent (12.91s) 832s --- PASS: TestServe/No_connection_because_port_file_does_not_exist (12.90s) 832s --- PASS: TestServe/Error_because_of_context_cancelled (2.85s) 832s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (25.83s) 832s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_error (3.78s) 832s --- PASS: TestServe/Error_because_of_notifier_returning_error (2.89s) 832s --- PASS: TestServe/Success (52.41s) 832s time="2024-03-09T23:22:51Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeSuccess494084545/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 832s === CONT TestServeAndQuit/Success_with_forceful_quit 832s time="2024-03-09T23:22:51Z" level=debug msg="Building new daemon" 832s time="2024-03-09T23:22:51Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41289\"" 832s time="2024-03-09T23:22:54Z" level=debug msg="Updated systemd status to \"Stopped\"" 832s time="2024-03-09T23:22:54Z" level=debug msg="Ready state sent to systemd" 832s time="2024-03-09T23:22:54Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:43243: connect: connection refused" 832s time="2024-03-09T23:22:54Z" level=info msg="Connecting to control stream at \"127.0.0.1:41289\"" 832s time="2024-03-09T23:22:54Z" level=debug msg="Control stream: starting handshake" 832s time="2024-03-09T23:22:54Z" level=info msg="wslInstanceMockService: Received incoming connection" 832s time="2024-03-09T23:22:55Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 832s time="2024-03-09T23:22:55Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 41145" 832s time="2024-03-09T23:22:55Z" level=debug msg="Control stream: completed handshake" 832s time="2024-03-09T23:22:55Z" level=info msg="Connected to control stream" 832s time="2024-03-09T23:22:55Z" level=debug msg="Starting to serve gRPC requests" 832s time="2024-03-09T23:22:55Z" level=info msg="Serving gRPC requests on localhost:41145" 832s time="2024-03-09T23:22:55Z" level=debug msg="Updated systemd status to \"Serving\"" 832s time="2024-03-09T23:22:56Z" level=info msg="Stopping daemon requested." 832s time="2024-03-09T23:22:56Z" level=debug msg="Updated systemd status to \"Stopped\"" 832s --- PASS: TestServeAndQuit (0.00s) 832s --- PASS: TestServeAndQuit/Success_with_graceful_quit (5.89s) 832s --- PASS: TestServeAndQuit/Success_with_double_quit (5.62s) 832s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (2.85s) 832s --- PASS: TestServeAndQuit/Success_with_forceful_quit (4.96s) 832s PASS 832s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 86.043s 832s === RUN TestInfo 832s === PAUSE TestInfo 832s === RUN TestWslDistroName 832s === PAUSE TestWslDistroName 832s === RUN TestUserProfileDir 832s === PAUSE TestUserProfileDir 832s === RUN TestProStatus 832s === PAUSE TestProStatus 832s === RUN TestProAttach 832s === PAUSE TestProAttach 832s === RUN TestProDetach 832s === PAUSE TestProDetach 832s === RUN TestLandscapeEnable 832s === PAUSE TestLandscapeEnable 832s === RUN TestWindowsHostAddress 832s === PAUSE TestWindowsHostAddress 832s === RUN TestLandscapeDisable 832s === PAUSE TestLandscapeDisable 832s === RUN TestWithProMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithProMock (0.00s) 832s === RUN TestWithLandscapeConfigMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithLandscapeConfigMock (0.00s) 832s === RUN TestWithWslPathMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithWslPathMock (0.00s) 832s === RUN TestWithWslInfoMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithWslInfoMock (0.00s) 832s === RUN TestWithCmdExeMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithCmdExeMock (0.00s) 832s === CONT TestInfo 832s === RUN TestInfo/Success 832s === PAUSE TestInfo/Success 832s === RUN TestInfo/Error_when_WslDistroName_fails 832s === PAUSE TestInfo/Error_when_WslDistroName_fails 832s === RUN TestInfo/Error_when_pro_status_command_fails 832s === PAUSE TestInfo/Error_when_pro_status_command_fails 832s === CONT TestProDetach 832s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 832s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 832s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 832s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 832s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 832s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 832s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 832s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 832s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 832s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 832s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 832s === CONT TestLandscapeDisable 832s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 832s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 832s === RUN TestLandscapeDisable/Success 832s === PAUSE TestLandscapeDisable/Success 832s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 832s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 832s === CONT TestWindowsHostAddress 832s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 832s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 832s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 832s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 832s === RUN TestWindowsHostAddress/Success_without_NAT 832s === PAUSE TestWindowsHostAddress/Success_without_NAT 832s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 832s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 832s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 832s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 832s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 832s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 832s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 832s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 832s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 832s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 832s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 832s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 832s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 832s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 832s === CONT TestLandscapeEnable 832s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 832s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 832s === RUN TestLandscapeEnable/Success 832s === PAUSE TestLandscapeEnable/Success 832s === RUN TestLandscapeEnable/Success_overriding_computer_title 832s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 832s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 832s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 832s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 832s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 832s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 832s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 832s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 832s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 832s === CONT TestProStatus 832s === RUN TestProStatus/error_on_'pro_attach'_error 832s === PAUSE TestProStatus/error_on_'pro_attach'_error 832s === RUN TestProStatus/success_on_unattached_distro 832s === PAUSE TestProStatus/success_on_unattached_distro 832s === RUN TestProStatus/success_on_attached_distro 832s === PAUSE TestProStatus/success_on_attached_distro 832s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 832s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 832s === CONT TestProAttach 832s === RUN TestProAttach/success 832s === PAUSE TestProAttach/success 832s === RUN TestProAttach/error_on_'pro_attach'_error 832s === PAUSE TestProAttach/error_on_'pro_attach'_error 832s === CONT TestUserProfileDir 832s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 832s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 832s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 832s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 832s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 832s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 832s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 832s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 832s === RUN TestUserProfileDir/Error_on_wslpath_error 832s === PAUSE TestUserProfileDir/Error_on_wslpath_error 832s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 832s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 832s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 832s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 832s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 832s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 832s === RUN TestUserProfileDir/Error_on_cmd.exe_error 832s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 832s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 832s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 832s === CONT TestWslDistroName 832s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 832s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 832s === RUN TestWslDistroName/Success_using_wslpath 832s === PAUSE TestWslDistroName/Success_using_wslpath 832s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 832s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 832s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 832s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 832s === CONT TestInfo/Success 832s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 832s === RUN TestProDetach/success_on_unattached_distro 832s === PAUSE TestProDetach/success_on_unattached_distro 832s === RUN TestProDetach/success_on_attached_distro 832s === PAUSE TestProDetach/success_on_attached_distro 832s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 832s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 832s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 832s === CONT TestInfo/Error_when_pro_status_command_fails 832s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 832s === CONT TestInfo/Error_when_WslDistroName_fails 832s === CONT TestLandscapeDisable/Success 832s --- PASS: TestInfo (0.00s) 832s --- PASS: TestInfo/Success (1.47s) 832s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (1.54s) 832s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (1.32s) 832s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (1.32s) 832s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (1.26s) 832s --- PASS: TestInfo/Error_when_pro_status_command_fails (1.39s) 832s --- PASS: TestInfo/Error_when_WslDistroName_fails (1.23s) 832s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 832s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 832s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 832s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 832s --- PASS: TestLandscapeDisable (0.00s) 832s --- PASS: TestLandscapeDisable/Success (1.48s) 832s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (1.41s) 832s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 832s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 832s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 832s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 832s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 832s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 832s === CONT TestWindowsHostAddress/Success_without_NAT 832s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 832s === CONT TestProStatus/error_on_'pro_attach'_error 832s --- PASS: TestWindowsHostAddress (0.00s) 832s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (1.28s) 832s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (1.23s) 832s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (0.91s) 832s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (0.88s) 832s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (0.92s) 832s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (1.14s) 832s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (1.28s) 832s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (1.61s) 832s --- PASS: TestWindowsHostAddress/Success_without_NAT (1.52s) 832s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (1.12s) 832s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 832s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 832s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 832s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 832s === CONT TestLandscapeEnable/Success_overriding_computer_title 832s time="2024-03-09T23:22:19Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden with TEST_DISTRO" 832s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 832s === CONT TestLandscapeEnable/Success 832s === NAME TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 832s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 832s === CONT TestProAttach/success 832s === NAME TestLandscapeEnable/Success 832s system_test.go:456: testdata/TestLandscapeEnable/golden/success 832s --- PASS: TestLandscapeEnable (0.00s) 832s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (1.42s) 832s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.00s) 832s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.00s) 832s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (0.92s) 832s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (0.84s) 832s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (1.94s) 832s --- PASS: TestLandscapeEnable/Success (0.96s) 832s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 832s === CONT TestProStatus/success_on_attached_distro 832s === CONT TestProStatus/success_on_unattached_distro 832s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 832s --- PASS: TestProStatus (0.00s) 832s --- PASS: TestProStatus/error_on_'pro_attach'_error (0.92s) 832s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (1.00s) 832s --- PASS: TestProStatus/success_on_attached_distro (0.97s) 832s --- PASS: TestProStatus/success_on_unattached_distro (0.94s) 832s === CONT TestProAttach/error_on_'pro_attach'_error 832s --- PASS: TestProAttach (0.00s) 832s --- PASS: TestProAttach/success (0.90s) 832s --- PASS: TestProAttach/error_on_'pro_attach'_error (1.34s) 832s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 832s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 832s === CONT TestUserProfileDir/Error_on_cmd.exe_error 832s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 832s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 832s system_test.go:209: Removing default proc/mounts 832s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 832s === CONT TestUserProfileDir/Error_on_wslpath_error 832s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 832s system_test.go:209: Removing default proc/mounts 832s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 832s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 832s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 832s === CONT TestProDetach/success_on_attached_distro 832s --- PASS: TestUserProfileDir (0.00s) 832s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (2.34s) 832s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (1.36s) 832s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 832s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 832s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (2.71s) 832s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (2.75s) 832s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 832s --- PASS: TestUserProfileDir/Error_on_wslpath_error (2.81s) 832s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (2.67s) 832s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (2.67s) 832s === CONT TestProDetach/success_on_unattached_distro 832s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 832s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 832s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 832s --- PASS: TestProDetach (0.00s) 832s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (1.35s) 832s --- PASS: TestProDetach/success_on_attached_distro (1.36s) 832s --- PASS: TestProDetach/success_on_unattached_distro (1.36s) 832s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (1.27s) 832s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (1.09s) 832s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 832s === CONT TestWslDistroName/Success_using_wslpath 832s --- PASS: TestWslDistroName (0.00s) 832s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.00s) 832s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (0.91s) 832s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (0.95s) 832s --- PASS: TestWslDistroName/Success_using_wslpath (0.76s) 832s PASS 832s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 31.564s 832s === RUN TestApplyProToken 832s === PAUSE TestApplyProToken 832s === RUN TestApplyLandscapeConfig 832s === PAUSE TestApplyLandscapeConfig 832s === RUN TestWithProMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithProMock (0.00s) 832s === RUN TestWithLandscapeConfigMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithLandscapeConfigMock (0.00s) 832s === RUN TestWithWslPathMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithWslPathMock (0.00s) 832s === RUN TestWithWslInfoMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithWslInfoMock (0.00s) 832s === RUN TestWithCmdExeMock 832s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 832s --- SKIP: TestWithCmdExeMock (0.00s) 832s === CONT TestApplyProToken 832s === CONT TestApplyLandscapeConfig 832s === RUN TestApplyLandscapeConfig/Success_enabling 832s === PAUSE TestApplyLandscapeConfig/Success_enabling 832s === RUN TestApplyLandscapeConfig/Success_disabling 832s === PAUSE TestApplyLandscapeConfig/Success_disabling 832s === RUN TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 832s === PAUSE TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 832s === RUN TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 832s === PAUSE TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 832s === CONT TestApplyLandscapeConfig/Success_enabling 832s time="2024-03-09T23:22:37Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:35133 832s wslinstanceservice_test.go:167: Client connected to WslInstanceService 832s time="2024-03-09T23:22:37Z" level=info msg="ApplyLandscapeConfig: received config: registering" 832s === RUN TestApplyProToken/Error_cannot_send_info_to_stream 832s === PAUSE TestApplyProToken/Error_cannot_send_info_to_stream 832s === RUN TestApplyProToken/success_attaching_attached_machine 832s === PAUSE TestApplyProToken/success_attaching_attached_machine 832s === RUN TestApplyProToken/success_attaching_non-attached_machine 832s === PAUSE TestApplyProToken/success_attaching_non-attached_machine 832s === RUN TestApplyProToken/Error_calling_pro_status 832s === PAUSE TestApplyProToken/Error_calling_pro_status 832s === RUN TestApplyProToken/Error_getting_system_info 832s === PAUSE TestApplyProToken/Error_getting_system_info 832s === RUN TestApplyProToken/success_detaching_attached_machine 832s === PAUSE TestApplyProToken/success_detaching_attached_machine 832s === RUN TestApplyProToken/success_detaching_non-attached_machine 832s === PAUSE TestApplyProToken/success_detaching_non-attached_machine 832s === RUN TestApplyProToken/Error_calling_pro_attach 832s === PAUSE TestApplyProToken/Error_calling_pro_attach 832s === RUN TestApplyProToken/Error_detaching_pro 832s === PAUSE TestApplyProToken/Error_detaching_pro 832s === CONT TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 832s time="2024-03-09T23:22:37Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:45077 832s wslinstanceservice_test.go:167: Client connected to WslInstanceService 832s time="2024-03-09T23:22:37Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 832s === NAME TestApplyLandscapeConfig/Success_enabling 832s wslinstanceservice_test.go:192: testdata/TestApplyLandscapeConfig/golden/success_enabling 832s === CONT TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 832s time="2024-03-09T23:22:39Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:36925 832s wslinstanceservice_test.go:167: Client connected to WslInstanceService 832s time="2024-03-09T23:22:39Z" level=info msg="ApplyLandscapeConfig: received config: registering" 832s === CONT TestApplyLandscapeConfig/Success_disabling 832s time="2024-03-09T23:22:39Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:35891 832s wslinstanceservice_test.go:167: Client connected to WslInstanceService 832s time="2024-03-09T23:22:39Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 832s === CONT TestApplyProToken/Error_cannot_send_info_to_stream 832s time="2024-03-09T23:22:40Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:43941 832s wslinstanceservice_test.go:108: Client connected to WslInstanceService 832s time="2024-03-09T23:22:40Z" level=info msg="ApplyProToken: Received empty token: detaching" 832s --- PASS: TestApplyLandscapeConfig (0.00s) 832s --- PASS: TestApplyLandscapeConfig/Success_enabling (1.34s) 832s --- PASS: TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails (1.40s) 832s --- PASS: TestApplyLandscapeConfig/Success_disabling (1.19s) 832s --- PASS: TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails (1.28s) 832s === CONT TestApplyProToken/Error_detaching_pro 832s time="2024-03-09T23:22:40Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:32973 832s wslinstanceservice_test.go:108: Client connected to WslInstanceService 832s time="2024-03-09T23:22:40Z" level=info msg="ApplyProToken: Received empty token: detaching" 832s time="2024-03-09T23:22:42Z" level=warning msg="ApplyProToken: could not send update via control stream: could not send system info: test error" 832s === CONT TestApplyProToken/Error_calling_pro_attach 832s time="2024-03-09T23:22:42Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:45305 832s wslinstanceservice_test.go:108: Client connected to WslInstanceService 832s time="2024-03-09T23:22:42Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 832s === CONT TestApplyProToken/success_detaching_non-attached_machine 832s time="2024-03-09T23:22:42Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:40869 832s wslinstanceservice_test.go:108: Client connected to WslInstanceService 832s time="2024-03-09T23:22:42Z" level=info msg="ApplyProToken: Received empty token: detaching" 832s === CONT TestApplyProToken/success_detaching_attached_machine 832s time="2024-03-09T23:22:44Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:39845 832s wslinstanceservice_test.go:108: Client connected to WslInstanceService 832s time="2024-03-09T23:22:44Z" level=info msg="ApplyProToken: Received empty token: detaching" 832s === CONT TestApplyProToken/Error_getting_system_info 832s time="2024-03-09T23:22:45Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:44911 832s wslinstanceservice_test.go:108: Client connected to WslInstanceService 832s time="2024-03-09T23:22:45Z" level=info msg="ApplyProToken: Received empty token: detaching" 832s === CONT TestApplyProToken/Error_calling_pro_status 832s time="2024-03-09T23:22:46Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:36777 832s wslinstanceservice_test.go:108: Client connected to WslInstanceService 832s time="2024-03-09T23:22:46Z" level=info msg="ApplyProToken: Received empty token: detaching" 832s time="2024-03-09T23:22:48Z" level=warning msg="ApplyProToken: could not send update via control stream: could not gather system info: could not read /etc/os-release: open /tmp/TestApplyProTokenError_getting_system_info1415961107/001/etc/os-release: no such file or directory" 832s === CONT TestApplyProToken/success_attaching_non-attached_machine 832s time="2024-03-09T23:22:48Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:46025 832s wslinstanceservice_test.go:108: Client connected to WslInstanceService 832s time="2024-03-09T23:22:48Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 832s time="2024-03-09T23:22:49Z" level=warning msg="ApplyProToken: could not send update via control stream: could not gather system info: could not obtain pro status: pro status: command returned error: exit status 1\nStdout:" 832s === CONT TestApplyProToken/success_attaching_attached_machine 832s time="2024-03-09T23:22:49Z" level=debug msg="Registering gRPC WSL instance service" 832s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:38817 832s wslinstanceservice_test.go:108: Client connected to WslInstanceService 832s time="2024-03-09T23:22:49Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 832s --- PASS: TestApplyProToken (0.00s) 832s --- PASS: TestApplyProToken/Error_cannot_send_info_to_stream (1.89s) 832s --- PASS: TestApplyProToken/Error_detaching_pro (1.89s) 832s --- PASS: TestApplyProToken/success_detaching_non-attached_machine (1.93s) 832s --- PASS: TestApplyProToken/Error_calling_pro_attach (2.87s) 832s --- PASS: TestApplyProToken/success_detaching_attached_machine (2.21s) 832s --- PASS: TestApplyProToken/Error_getting_system_info (2.89s) 832s --- PASS: TestApplyProToken/Error_calling_pro_status (2.95s) 832s --- PASS: TestApplyProToken/success_attaching_non-attached_machine (4.66s) 832s --- PASS: TestApplyProToken/success_attaching_attached_machine (4.30s) 832s PASS 832s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/wslinstanceservice 15.915s 832s autopkgtest [23:22:56]: test command1: -----------------------] 832s autopkgtest [23:22:56]: test command1: - - - - - - - - - - results - - - - - - - - - - 832s command1 PASS 832s autopkgtest [23:22:56]: @@@@@@@@@@@@@@@@@@@@ summary 832s command1 PASS 842s Creating nova instance adt-noble-amd64-wsl-pro-service-20240309-230904-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-amd64-server-20240309.img (UUID cf9084ac-d9f1-45d9-9e58-3f793071bee0)...