0s autopkgtest [02:20:36]: starting date and time: 2024-03-27 02:20:36+0000 0s autopkgtest [02:20:36]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [02:20:36]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.cacynd32/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:ubuntu-advantage-tools,src:apt --apt-upgrade wsl-pro-service --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=ubuntu-advantage-tools/31.2.2 apt/2.7.14' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-arm64-15.secgroup --name adt-noble-arm64-wsl-pro-service-20240327-022036-juju-7f2275-prod-proposed-migration-environment-3-60811f93-cc72-4bce-98a7-b22cd2fb3854 --image adt/ubuntu-noble-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 377s Creating nova instance adt-noble-arm64-wsl-pro-service-20240327-022036-juju-7f2275-prod-proposed-migration-environment-3-60811f93-cc72-4bce-98a7-b22cd2fb3854 from image adt/ubuntu-noble-arm64-server-20240327.img (UUID 4cac5f13-6ada-4e25-827f-1de2aa2ec4b4)... 377s nova boot failed (attempt #1): 377s DEBUG (extension:189) found extension EntryPoint.parse('v1password = swiftclient.authv1:PasswordLoader') 377s DEBUG (extension:189) found extension EntryPoint.parse('noauth = cinderclient.contrib.noauth:CinderNoAuthLoader') 377s DEBUG (extension:189) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken') 377s DEBUG (extension:189) found extension EntryPoint.parse('none = keystoneauth1.loading._plugins.noauth:NoAuth') 377s DEBUG (extension:189) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password') 377s DEBUG (extension:189) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token') 377s DEBUG (extension:189) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password') 377s DEBUG (extension:189) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3adfspassword = keystoneauth1.extras._saml2._loading:ADFSPassword') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3applicationcredential = keystoneauth1.loading._plugins.identity.v3:ApplicationCredential') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3fedkerb = keystoneauth1.extras.kerberos._loading:MappedKerberos') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3kerberos = keystoneauth1.extras.kerberos._loading:Kerberos') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3multifactor = keystoneauth1.loading._plugins.identity.v3:MultiFactor') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3oauth1 = keystoneauth1.extras.oauth1._loading:V3OAuth1') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcaccesstoken = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAccessToken') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcclientcredentials = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectClientCredentials') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3samlpassword = keystoneauth1.extras._saml2._loading:Saml2Password') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3tokenlessauth = keystoneauth1.loading._plugins.identity.v3:TokenlessAuth') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3totp = keystoneauth1.loading._plugins.identity.v3:TOTP') 377s DEBUG (session:517) REQ: curl -g -i -X GET https://keystone.ps6.canonical.com:5000/v3 -H "Accept: application/json" -H "User-Agent: nova keystoneauth1/4.0.0 python-requests/2.22.0 CPython/3.8.10" 377s DEBUG (connectionpool:962) Starting new HTTPS connection (1): keystone.ps6.canonical.com:5000 377s DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "GET /v3 HTTP/1.1" 200 267 377s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 267 Content-Type: application/json Date: Wed, 27 Mar 2024 02:21:10 GMT Keep-Alive: timeout=75, max=1000 Server: Apache/2.4.52 (Ubuntu) Vary: X-Auth-Token x-openstack-request-id: req-6aa856c8-47fc-4a96-bb69-b762867d7674 377s DEBUG (session:580) RESP BODY: {"version": {"id": "v3.14", "status": "stable", "updated": "2020-04-07T00:00:00Z", "links": [{"rel": "self", "href": "https://keystone.ps6.canonical.com:5000/v3/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.identity-v3+json"}]}} 377s DEBUG (session:946) GET call to https://keystone.ps6.canonical.com:5000/v3 used request id req-6aa856c8-47fc-4a96-bb69-b762867d7674 377s DEBUG (base:182) Making authentication request to https://keystone.ps6.canonical.com:5000/v3/auth/tokens 377s DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "POST /v3/auth/tokens HTTP/1.1" 201 12445 377s DEBUG (base:187) {"token": {"methods": ["password"], "user": {"domain": {"id": "default", "name": "Default"}, "id": "369f50a481564a2c9433776a4ebd224d", "name": "prod-proposed-migration-arm64", "password_expires_at": null}, "audit_ids": ["2qM00-pAQRm5llDxrKKj8w"], "expires_at": "2024-03-28T02:21:10.000000Z", "issued_at": "2024-03-27T02:21:10.000000Z", "project": {"domain": {"id": "default", "name": "Default"}, "id": "bd8b3df3f03d43a0a9303b7c0434fdab", "name": "prod-proposed-migration-arm64_project"}, "is_domain": false, "roles": [{"id": "86bf3043974746c2bf52c2a9f362f986", "name": "reader"}, {"id": "15cf997650e345b7b21c02b5cdb7d84a", "name": "member"}], "is_admin_project": false, "catalog": [{"endpoints": [{"id": "213cdf38198c4581bd5b7d6d0836923b", "interface": "internal", "region_id": "prodstack6", "url": "https://radosgw-internal.ps6.canonical.com:443/", "region": "prodstack6"}, {"id": "67061eee26944a1a8009f9d07be19738", "interface": "public", "region_id": "prodstack6", "url": "https://radosgw.ps6.canonical.com:443/", "region": "prodstack6"}, {"id": "fc88c52c749a47d08a24a2c7efbde01b", "interface": "admin", "region_id": "prodstack6", "url": "https://radosgw-admin.ps6.canonical.com:443/", "region": "prodstack6"}], "id": "06e4714942634054bf9aa11715312b4e", "type": "s3", "name": "s3"}, {"endpoints": [{"id": "8aa44c94b63746bd9b37f1008ad55fa8", "interface": "admin", "region_id": "prodstack6", "url": "https://aodh-admin.ps6.canonical.com:8042", "region": "prodstack6"}, {"id": "92cf08a80a0242ebbca8ec893cf332b1", "interface": "public", "region_id": "prodstack6", "url": "https://aodh.ps6.canonical.com:8042", "region": "prodstack6"}, {"id": "d8139c6e11ae4edcb78981c12bd53212", "interface": "internal", "region_id": "prodstack6", "url": "https://aodh-internal.ps6.canonical.com:8042", "region": "prodstack6"}], "id": "2fb939b0b969435c8f0c5fa4edb4de94", "type": "alarming", "name": "aodh"}, {"endpoints": [{"id": "a49fcc80e658411885f88cdd8432a571", "interface": "internal", "region_id": "prodstack6", "url": "https://heat-internal.ps6.canonical.com:8000/v1", "region": "prodstack6"}, {"id": "b23866fddba8415885b06c064c34b94b", "interface": "public", "region_id": "prodstack6", "url": "https://heat.ps6.canonical.com:8000/v1", "region": "prodstack6"}, {"id": "f84ae55197f94b8392a678f35c82ca26", "interface": "admin", "region_id": "prodstack6", "url": "https://heat-admin.ps6.canonical.com:8000/v1", "region": "prodstack6"}], "id": "3d4b59f7dab644b2b527e8e96b697545", "type": "cloudformation", "name": "heat-cfn"}, {"endpoints": [{"id": "2e0143334c6241169f45f1c11970c6bc", "interface": "public", "region_id": "prodstack6", "url": "https://heat.ps6.canonical.com:8004/v1/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}, {"id": "5e19c32243c04a3faf1e5f9ab900c260", "interface": "internal", "region_id": "prodstack6", "url": "https://heat-internal.ps6.canonical.com:8004/v1/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}, {"id": "b3fc5c88ec8543a691fec75726b86967", "interface": "admin", "region_id": "prodstack6", "url": "https://heat-admin.ps6.canonical.com:8004/v1/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}], "id": "43b926205044476b8093b18d8ce0a9e4", "type": "orchestration", "name": "heat"}, {"endpoints": [{"id": "a47569b96e5b4146a312e4673504557c", "interface": "public", "region_id": "prodstack6", "url": "https://glance.ps6.canonical.com:9292", "region": "prodstack6"}, {"id": "bba35c4ab04f4114a2a34e65568537bd", "interface": "internal", "region_id": "prodstack6", "url": "https://glance-internal.ps6.canonical.com:9292", "region": "prodstack6"}, {"id": "ef769f1117c74a62a279131486c89628", "interface": "admin", "region_id": "prodstack6", "url": "https://glance-admin.ps6.canonical.com:9292", "region": "prodstack6"}], "id": "49de724549524bd78e9cd46a4477226f", "type": "image", "name": "glance"}, {"endpoints": [{"id": "a1a41e9a1e2d42e5b6cd5380cd1e21bf", "interface": "admin", "region_id": "prodstack6", "url": "https://cinder-admin.ps6.canonical.com:8776/v3/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}, {"id": "d2309a99724b4111827482c68a832c0c", "interface": "public", "region_id": "prodstack6", "url": "https://cinder.ps6.canonical.com:8776/v3/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}, {"id": "f8a02e3949ff42afa772eb5e74dbef9f", "interface": "internal", "region_id": "prodstack6", "url": "https://cinder-internal.ps6.canonical.com:8776/v3/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}], "id": "5bf6578a631349e3af4bf36d7adb18e1", "type": "volumev3", "name": "cinderv3"}, {"endpoints": [{"id": "01d84128f36444e9b01549b86bd69116", "interface": "admin", "region_id": "prodstack6", "url": "https://barbican-admin.ps6.canonical.com:9312", "region": "prodstack6"}, {"id": "0ce4a8777e0b40a88f7fc0f77e34a605", "interface": "public", "region_id": "prodstack6", "url": "https://barbican.ps6.canonical.com:9311", "region": "prodstack6"}, {"id": "f507ba49e4a7435cb330234217c92449", "interface": "internal", "region_id": "prodstack6", "url": "https://barbican-internal.ps6.canonical.com:9311", "region": "prodstack6"}], "id": "62cb1c3d9f6c45979c13516a6b3ff114", "type": "key-manager", "name": "barbican"}, {"endpoints": [{"id": "3cb6121bd8f14da8aa2fba7be823a2b4", "interface": "internal", "region_id": "prodstack6", "url": "https://neutron-internal.ps6.canonical.com:9696", "region": "prodstack6"}, {"id": "46ba11d133fe4e8f9e8f9097ba737682", "interface": "admin", "region_id": "prodstack6", "url": "https://neutron-admin.ps6.canonical.com:9696", "region": "prodstack6"}, {"id": "d872eb6aa23d40009165aa3dcdce3948", "interface": "public", "region_id": "prodstack6", "url": "https://neutron.ps6.canonical.com:9696", "region": "prodstack6"}], "id": "759cb6fdfbc146dd8b9d1ba444c4b9db", "type": "network", "name": "neutron"}, {"endpoints": [{"id": "4349d565f72446da9699269f85df5e8b", "interface": "internal", "region_id": "prodstack6", "url": "https://manila-internal.ps6.canonical.com:8786/v2/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}, {"id": "a483dcc58f5e415e9ec739c94474e609", "interface": "public", "region_id": "prodstack6", "url": "https://manila.ps6.canonical.com:8786/v2/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}, {"id": "c1f14236723e42878562d8dd63d5a6a0", "interface": "admin", "region_id": "prodstack6", "url": "https://manila-admin.ps6.canonical.com:8786/v2/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}], "id": "93fc4008035946deb1f433c2dd905a5d", "type": "sharev2", "name": "manilav2"}, {"endpoints": [{"id": "0fa148793c7249c8b187b6b4ff425731", "interface": "internal", "region_id": "prodstack6", "url": "https://radosgw-internal.ps6.canonical.com:443/swift/v1/AUTH_e4b04ca58d734ed0aa29e306adad4f79/simplestreams/data", "region": "prodstack6"}, {"id": "555ade266a6b4bcc81b6e5abf607aec5", "interface": "public", "region_id": "prodstack6", "url": "https://radosgw.ps6.canonical.com:443/swift/v1/AUTH_e4b04ca58d734ed0aa29e306adad4f79/simplestreams/data", "region": "prodstack6"}, {"id": "7dad1a7b1552465686424fc014463173", "interface": "admin", "region_id": "prodstack6", "url": "https://radosgw-admin.ps6.canonical.com:443/swift/simplestreams/data", "region": "prodstack6"}], "id": "977dd359af1a4fcc91a9daf4dc33b0f3", "type": "product-streams", "name": "image-stream"}, {"endpoints": [{"id": "12d30eabcdec4831bd15273d55b1cbab", "interface": "internal", "region_id": "prodstack6", "url": "https://manila-internal.ps6.canonical.com:8786/v1/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}, {"id": "29d35b2b483346479f1ff1a13604d50e", "interface": "admin", "region_id": "prodstack6", "url": "https://manila-admin.ps6.canonical.com:8786/v1/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}, {"id": "4aa1422b8f5945d8aed9ba6a8d670e5c", "interface": "public", "region_id": "prodstack6", "url": "https://manila.ps6.canonical.com:8786/v1/bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}], "id": "ac5e4f28fa794cde99f1d7ffc1b664e5", "type": "share", "name": "manila"}, {"endpoints": [{"id": "c7db93d54a684d8caeae1d9e18a59118", "interface": "admin", "region_id": "prodstack6", "url": "https://nova-admin.ps6.canonical.com:8774/v2.1", "region": "prodstack6"}, {"id": "dd7a796f401b477f9f1b5bbc05747d8d", "interface": "public", "region_id": "prodstack6", "url": "https://nova.ps6.canonical.com:8774/v2.1", "region": "prodstack6"}, {"id": "e7ecb038d830462ea67b0b463af5e013", "interface": "internal", "region_id": "prodstack6", "url": "https://nova-internal.ps6.canonical.com:8774/v2.1", "region": "prodstack6"}], "id": "b4519af08e174d4a8333a2d14ac3ba3b", "type": "compute", "name": "nova"}, {"endpoints": [{"id": "02ef5b6154934ffd97d8b5b8e4d70d2a", "interface": "internal", "region_id": "prodstack6", "url": "https://radosgw-internal.ps6.canonical.com:443/swift/v1/AUTH_bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}, {"id": "2741abe526e94842a4fa801423ba1ef0", "interface": "public", "region_id": "prodstack6", "url": "https://radosgw.ps6.canonical.com:443/swift/v1/AUTH_bd8b3df3f03d43a0a9303b7c0434fdab", "region": "prodstack6"}, {"id": "3742f9406c604516933d6fd8c29e0880", "interface": "admin", "region_id": "prodstack6", "url": "https://radosgw-admin.ps6.canonical.com:443/swift", "region": "prodstack6"}], "id": "c7f07a7b3b3c408c96200dfd1d1a0f6f", "type": "object-store", "name": "swift"}, {"endpoints": [{"id": "06d4ed6c160a43f2b7fa7f80ad1dc709", "interface": "internal", "region_id": "prodstack6", "url": "https://designate-internal.ps6.canonical.com:9001", "region": "prodstack6"}, {"id": "1e240624a50c4ca0aaa6ef63cbc94f1b", "interface": "admin", "region_id": "prodstack6", "url": "https://designate-admin.ps6.canonical.com:9001", "region": "prodstack6"}, {"id": "f94d7d17997a43fd9c63b34c43301c6e", "interface": "public", "region_id": "prodstack6", "url": "https://designate.ps6.canonical.com:9001", "region": "prodstack6"}], "id": "cf82013d63844fedbe96bcacb6bd1fef", "type": "dns", "name": "designate"}, {"endpoints": [{"id": "4a688340be464710bfa60bf3f88f41c4", "interface": "admin", "region_id": "prodstack6", "url": "https://gnocchi-admin.ps6.canonical.com:8041", "region": "prodstack6"}, {"id": "a5a95506c96d4716adb4efc3ac9051be", "interface": "public", "region_id": "prodstack6", "url": "https://gnocchi.ps6.canonical.com:8041", "region": "prodstack6"}, {"id": "eebc17a086834f8fa8bb6b35c8061a8c", "interface": "internal", "region_id": "prodstack6", "url": "https://gnocchi-internal.ps6.canonical.com:8041", "region": "prodstack6"}], "id": "d10589e854a446a5b852593f33f170a7", "type": "metric", "name": "gnocchi"}, {"endpoints": [{"id": "08f66df28d0f47f3bec12f29e06d85ac", "interface": "admin", "region_id": "prodstack6", "url": "https://keystone-admin.ps6.canonical.com:35357/v3", "region": "prodstack6"}, {"id": "46d270b45ceb42db902f4d241afa6a70", "interface": "public", "region_id": "prodstack6", "url": "https://keystone.ps6.canonical.com:5000/v3", "region": "prodstack6"}, {"id": "a4addee11d9349cfa46ec6660dbad947", "interface": "internal", "region_id": "prodstack6", "url": "https://keystone-internal.ps6.canonical.com:5000/v3", "region": "prodstack6"}], "id": "deb38a775a9b4e87a8de9e3eed779882", "type": "identity", "name": "keystone"}, {"endpoints": [{"id": "18b4af5f40664babb94e884c6a53ccf3", "interface": "admin", "region_id": "prodstack6", "url": "https://octavia-admin.ps6.canonical.com:9876", "region": "prodstack6"}, {"id": "2b6f7ba7f9944ee1aebd6dccc5223b2d", "interface": "internal", "region_id": "prodstack6", "url": "https://octavia-internal.ps6.canonical.com:9876", "region": "prodstack6"}, {"id": "5e3012e9c79e4890be4d964b6208f5c6", "interface": "public", "region_id": "prodstack6", "url": "https://octavia.ps6.canonical.com:9876", "region": "prodstack6"}], "id": "e00337be20d54948b66a1c3109b885c6", "type": "load-balancer", "name": "octavia"}, {"endpoints": [{"id": "2d2942b35f3041389ad810f679d02f22", "interface": "public", "region_id": "prodstack6", "url": "https://placement.ps6.canonical.com:8778", "region": "prodstack6"}, {"id": "2e3beaac7ac14284b72e2592820ad725", "interface": "admin", "region_id": "prodstack6", "url": "https://placement-admin.ps6.canonical.com:8778", "region": "prodstack6"}, {"id": "91060f6df9ae444f81808adf40540d94", "interface": "internal", "region_id": "prodstack6", "url": "https://placement-internal.ps6.canonical.com:8778", "region": "prodstack6"}], "id": "edc6e37f154f4c0ab3dd4d52b82bf873", "type": "placement", "name": "placement"}]}} 377s REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1 -H "Accept: application/json" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}80d2655573d66d7b7ad21385e9beaa32849453052e91ad99a906171ccfe5d86d" 377s DEBUG (session:517) REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1 -H "Accept: application/json" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}80d2655573d66d7b7ad21385e9beaa32849453052e91ad99a906171ccfe5d86d" 377s DEBUG (connectionpool:962) Starting new HTTPS connection (1): nova.ps6.canonical.com:8774 377s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1 HTTP/1.1" 302 0 377s RESP: [302] Connection: Keep-Alive Content-Length: 0 Content-Type: text/plain; charset=utf8 Date: Wed, 27 Mar 2024 02:21:11 GMT Keep-Alive: timeout=75, max=1000 Location: https://nova.ps6.canonical.com:8774/v2.1/ Server: Apache/2.4.52 (Ubuntu) x-compute-request-id: req-1c9cbe8a-a41a-4d02-8eb6-2e5ff4b4225f x-openstack-request-id: req-1c9cbe8a-a41a-4d02-8eb6-2e5ff4b4225f 377s DEBUG (session:548) RESP: [302] Connection: Keep-Alive Content-Length: 0 Content-Type: text/plain; charset=utf8 Date: Wed, 27 Mar 2024 02:21:11 GMT Keep-Alive: timeout=75, max=1000 Location: https://nova.ps6.canonical.com:8774/v2.1/ Server: Apache/2.4.52 (Ubuntu) x-compute-request-id: req-1c9cbe8a-a41a-4d02-8eb6-2e5ff4b4225f x-openstack-request-id: req-1c9cbe8a-a41a-4d02-8eb6-2e5ff4b4225f 377s RESP BODY: Omitted, Content-Type is set to text/plain; charset=utf8. Only application/json responses have their bodies logged. 377s DEBUG (session:580) RESP BODY: Omitted, Content-Type is set to text/plain; charset=utf8. Only application/json responses have their bodies logged. 377s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/ HTTP/1.1" 200 397 377s RESP: [200] Connection: Keep-Alive Content-Length: 397 Content-Type: application/json Date: Wed, 27 Mar 2024 02:21:11 GMT Keep-Alive: timeout=75, max=999 OpenStack-API-Version: compute 2.1 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.1 x-compute-request-id: req-1f85900b-7814-4291-bb37-9e4ffccabb65 x-openstack-request-id: req-1f85900b-7814-4291-bb37-9e4ffccabb65 377s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 397 Content-Type: application/json Date: Wed, 27 Mar 2024 02:21:11 GMT Keep-Alive: timeout=75, max=999 OpenStack-API-Version: compute 2.1 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.1 x-compute-request-id: req-1f85900b-7814-4291-bb37-9e4ffccabb65 x-openstack-request-id: req-1f85900b-7814-4291-bb37-9e4ffccabb65 377s RESP BODY: {"version": {"id": "v2.1", "status": "CURRENT", "version": "2.90", "min_version": "2.1", "updated": "2013-07-23T11:33:21Z", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/"}, {"rel": "describedby", "type": "text/html", "href": "http://docs.openstack.org/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.compute+json;version=2.1"}]}} 377s DEBUG (session:580) RESP BODY: {"version": {"id": "v2.1", "status": "CURRENT", "version": "2.90", "min_version": "2.1", "updated": "2013-07-23T11:33:21Z", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/"}, {"rel": "describedby", "type": "text/html", "href": "http://docs.openstack.org/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.compute+json;version=2.1"}]}} 377s GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/ used request id req-1f85900b-7814-4291-bb37-9e4ffccabb65 377s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/ used request id req-1f85900b-7814-4291-bb37-9e4ffccabb65 377s DEBUG (extension:189) found extension EntryPoint.parse('v1password = swiftclient.authv1:PasswordLoader') 377s DEBUG (extension:189) found extension EntryPoint.parse('noauth = cinderclient.contrib.noauth:CinderNoAuthLoader') 377s DEBUG (extension:189) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken') 377s DEBUG (extension:189) found extension EntryPoint.parse('none = keystoneauth1.loading._plugins.noauth:NoAuth') 377s DEBUG (extension:189) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password') 377s DEBUG (extension:189) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token') 377s DEBUG (extension:189) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password') 377s DEBUG (extension:189) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3adfspassword = keystoneauth1.extras._saml2._loading:ADFSPassword') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3applicationcredential = keystoneauth1.loading._plugins.identity.v3:ApplicationCredential') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3fedkerb = keystoneauth1.extras.kerberos._loading:MappedKerberos') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3kerberos = keystoneauth1.extras.kerberos._loading:Kerberos') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3multifactor = keystoneauth1.loading._plugins.identity.v3:MultiFactor') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3oauth1 = keystoneauth1.extras.oauth1._loading:V3OAuth1') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcaccesstoken = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAccessToken') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcclientcredentials = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectClientCredentials') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3samlpassword = keystoneauth1.extras._saml2._loading:Saml2Password') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3tokenlessauth = keystoneauth1.loading._plugins.identity.v3:TokenlessAuth') 377s DEBUG (extension:189) found extension EntryPoint.parse('v3totp = keystoneauth1.loading._plugins.identity.v3:TOTP') 377s DEBUG (session:517) REQ: curl -g -i -X GET https://glance.ps6.canonical.com:9292/v2/images/4cac5f13-6ada-4e25-827f-1de2aa2ec4b4 -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}80d2655573d66d7b7ad21385e9beaa32849453052e91ad99a906171ccfe5d86d" -H "X-OpenStack-Nova-API-Version: 2.87" 377s DEBUG (connectionpool:962) Starting new HTTPS connection (1): glance.ps6.canonical.com:9292 377s DEBUG (connectionpool:429) https://glance.ps6.canonical.com:9292 "GET /v2/images/4cac5f13-6ada-4e25-827f-1de2aa2ec4b4 HTTP/1.1" 200 1935 377s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 1935 Content-Type: application/json Date: Wed, 27 Mar 2024 02:21:11 GMT Keep-Alive: timeout=75, max=1000 Server: Apache/2.4.52 (Ubuntu) X-Openstack-Request-Id: req-4cd7f68f-6d31-4b78-af83-c0a7c477d4bc 377s DEBUG (session:580) RESP BODY: {"base_image_ref": "09c3944a-cd0d-425d-b22f-cde7ec51d155", "boot_roles": "reader,member", "hw_cdrom_bus": "scsi", "hw_disk_bus": "virtio", "hw_machine_type": "virt", "hw_video_model": "virtio", "hw_vif_model": "virtio", "image_location": "snapshot", "image_state": "available", "image_type": "snapshot", "instance_uuid": "162f5254-1fb7-44a6-bb8f-8aeb30205416", "owner_id": "bd8b3df3f03d43a0a9303b7c0434fdab", "owner_project_name": "prod-proposed-migration-arm64_project", "owner_specified.openstack.md5": "087a26f2222dab8516c553374a9d1b4d", "owner_specified.openstack.object": "images/adt/ubuntu-noble-arm64-server-20240201.img", "owner_specified.openstack.sha256": "095b278df09de76f01843de514daf3f8eb0a0cc67fee4b3491161913153619db", "owner_user_name": "prod-proposed-migration-arm64", "user_id": "369f50a481564a2c9433776a4ebd224d", "name": "adt/ubuntu-noble-arm64-server-20240327.img", "disk_format": "qcow2", "container_format": "bare", "visibility": "private", "size": 2313224192, "virtual_size": 21474836480, "status": "active", "checksum": "247c13b4d4f9ef4f638a40eb5329e29c", "protected": false, "min_ram": 0, "min_disk": 20, "owner": "bd8b3df3f03d43a0a9303b7c0434fdab", "os_hidden": false, "os_hash_algo": "sha512", "os_hash_value": "d329ffaaee8ea38360f95e14bc1f477a73b1797b521d91dd141501761b52e736748f78d9d659be1a0416afe77db08f25e9991a7dbdef21a85bd06f97ce276e7a", "id": "4cac5f13-6ada-4e25-827f-1de2aa2ec4b4", "created_at": "2024-03-27T01:00:27Z", "updated_at": "2024-03-27T01:00:54Z", "locations": [{"url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/4cac5f13-6ada-4e25-827f-1de2aa2ec4b4/snap", "metadata": {"store": "ceph"}}], "direct_url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/4cac5f13-6ada-4e25-827f-1de2aa2ec4b4/snap", "tags": [], "self": "/v2/images/4cac5f13-6ada-4e25-827f-1de2aa2ec4b4", "file": "/v2/images/4cac5f13-6ada-4e25-827f-1de2aa2ec4b4/file", "schema": "/v2/schemas/image", "stores": "ceph"} 377s DEBUG (session:936) GET call to image for https://glance.ps6.canonical.com:9292/v2/images/4cac5f13-6ada-4e25-827f-1de2aa2ec4b4 used request id req-4cd7f68f-6d31-4b78-af83-c0a7c477d4bc 377s DEBUG (session:517) REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1/flavors/autopkgtest -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}80d2655573d66d7b7ad21385e9beaa32849453052e91ad99a906171ccfe5d86d" -H "X-OpenStack-Nova-API-Version: 2.87" 377s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors/autopkgtest HTTP/1.1" 404 84 377s DEBUG (session:548) RESP: [404] Connection: Keep-Alive Content-Length: 84 Content-Type: application/json; charset=UTF-8 Date: Wed, 27 Mar 2024 02:21:11 GMT Keep-Alive: timeout=75, max=998 OpenStack-API-Version: compute 2.87 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.87 x-compute-request-id: req-db9cefe3-f495-4cfc-bb1e-bafe98d25e77 x-openstack-request-id: req-db9cefe3-f495-4cfc-bb1e-bafe98d25e77 377s DEBUG (session:580) RESP BODY: {"itemNotFound": {"code": 404, "message": "Flavor autopkgtest could not be found."}} 377s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors/autopkgtest used request id req-db9cefe3-f495-4cfc-bb1e-bafe98d25e77 377s DEBUG (session:517) REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1/flavors?is_public=None -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}80d2655573d66d7b7ad21385e9beaa32849453052e91ad99a906171ccfe5d86d" -H "X-OpenStack-Nova-API-Version: 2.87" 377s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors?is_public=None HTTP/1.1" 200 1739 377s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 1739 Content-Type: application/json Date: Wed, 27 Mar 2024 02:21:11 GMT Keep-Alive: timeout=75, max=997 OpenStack-API-Version: compute 2.87 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.87 x-compute-request-id: req-ed5d33b5-bf3f-4ff9-9b78-cfcb9e10206b x-openstack-request-id: req-ed5d33b5-bf3f-4ff9-9b78-cfcb9e10206b 377s DEBUG (session:580) RESP BODY: {"flavors": [{"id": "062428d9-35a1-461f-a33a-38436ed516b4", "name": "autopkgtest-cpu8-ram16-disk160-arm64", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/062428d9-35a1-461f-a33a-38436ed516b4"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/062428d9-35a1-461f-a33a-38436ed516b4"}], "description": null}, {"id": "8d27c5f0-65c7-4018-b3f7-50463875ab09", "name": "autopkgtest", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/8d27c5f0-65c7-4018-b3f7-50463875ab09"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/8d27c5f0-65c7-4018-b3f7-50463875ab09"}], "description": null}, {"id": "8ebe34fb-1390-4cc7-bbe5-0d38c80f2fb4", "name": "autopkgtest-cpu2-ram4-disk20-arm64", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/8ebe34fb-1390-4cc7-bbe5-0d38c80f2fb4"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/8ebe34fb-1390-4cc7-bbe5-0d38c80f2fb4"}], "description": null}, {"id": "a8256f9e-53ee-4584-ad7f-60e088825b83", "name": "autopkgtest-big", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/a8256f9e-53ee-4584-ad7f-60e088825b83"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/a8256f9e-53ee-4584-ad7f-60e088825b83"}], "description": null}, {"id": "cfc564ac-9f6e-4477-91c4-a89ccb4b744e", "name": "autopkgtest-cpu4-ram8-disk100-arm64", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/cfc564ac-9f6e-4477-91c4-a89ccb4b744e"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/cfc564ac-9f6e-4477-91c4-a89ccb4b744e"}], "description": null}]} 377s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors?is_public=None used request id req-ed5d33b5-bf3f-4ff9-9b78-cfcb9e10206b 377s DEBUG (session:517) REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1/flavors/8d27c5f0-65c7-4018-b3f7-50463875ab09 -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}80d2655573d66d7b7ad21385e9beaa32849453052e91ad99a906171ccfe5d86d" -H "X-OpenStack-Nova-API-Version: 2.87" 377s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors/8d27c5f0-65c7-4018-b3f7-50463875ab09 HTTP/1.1" 200 613 377s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 613 Content-Type: application/json Date: Wed, 27 Mar 2024 02:21:11 GMT Keep-Alive: timeout=75, max=996 OpenStack-API-Version: compute 2.87 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.87 x-compute-request-id: req-afd22da9-5dd6-4039-b0c7-4c1371717c53 x-openstack-request-id: req-afd22da9-5dd6-4039-b0c7-4c1371717c53 377s DEBUG (session:580) RESP BODY: {"flavor": {"id": "8d27c5f0-65c7-4018-b3f7-50463875ab09", "name": "autopkgtest", "ram": 4096, "disk": 20, "swap": 0, "OS-FLV-EXT-DATA:ephemeral": 0, "OS-FLV-DISABLED:disabled": false, "vcpus": 2, "os-flavor-access:is_public": false, "rxtx_factor": 1.0, "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/8d27c5f0-65c7-4018-b3f7-50463875ab09"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/8d27c5f0-65c7-4018-b3f7-50463875ab09"}], "description": null, "extra_specs": {"aggregate_instance_extra_specs:commit": "builder-arm64", "hw_rng:allowed": "True"}}} 377s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors/8d27c5f0-65c7-4018-b3f7-50463875ab09 used request id req-afd22da9-5dd6-4039-b0c7-4c1371717c53 377s DEBUG (session:517) REQ: curl -g -i -X POST https://nova.ps6.canonical.com:8774/v2.1/servers -H "Accept: application/json" -H "Content-Type: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}80d2655573d66d7b7ad21385e9beaa32849453052e91ad99a906171ccfe5d86d" -H "X-OpenStack-Nova-API-Version: 2.87" -d '{"server": {"name": "adt-noble-arm64-wsl-pro-service-20240327-022036-juju-7f2275-prod-proposed-migration-environment-3-60811f93-cc72-4bce-98a7-b22cd2fb3854", "imageRef": "4cac5f13-6ada-4e25-827f-1de2aa2ec4b4", "flavorRef": "8d27c5f0-65c7-4018-b3f7-50463875ab09", "user_data": "I2Nsb3VkLWNvbmZpZwpob3N0bmFtZTogYXV0b3BrZ3Rlc3QKbWFuYWdlX2V0Y19ob3N0czogdHJ1ZQphcHRfdXBkYXRlOiB0cnVlCmFwdF91cGdyYWRlOiBmYWxzZQphcHRfbWlycm9yOiBodHRwOi8vZnRwbWFzdGVyLmludGVybmFsL3VidW50dS8KCnJ1bmNtZDoKIC0gZWNobyAnQWNxdWlyZTo6TGFuZ3VhZ2VzICJub25lIjsnID4gL2V0Yy9hcHQvYXB0LmNvbmYuZC85MG5vbGFuZ3VhZ2VzCiAtIGVjaG8gJ2ZvcmNlLXVuc2FmZS1pbycgPiAvZXRjL2Rwa2cvZHBrZy5jZmcuZC9hdXRvcGtndGVzdAogLSBwcmludGYgJ1xuVEVSTT1saW51eFxuJ2h0dHBfcHJveHk9aHR0cDovL3NxdWlkLmludGVybmFsOjMxMjgnXG4naHR0cHNfcHJveHk9aHR0cDovL3NxdWlkLmludGVybmFsOjMxMjgnXG4nbm9fcHJveHk9MTI3LjAuMC4xLDEyNy4wLjEuMSxsb2dpbi51YnVudHUuY29tLGxvY2FsaG9zdCxsb2NhbGRvbWFpbixub3ZhbG9jYWwsaW50ZXJuYWwsYXJjaGl2ZS51YnVudHUuY29tLHBvcnRzLnVidW50dS5jb20sc2VjdXJpdHkudWJ1bnR1LmNvbSxkZGVicy51YnVudHUuY29tLGNoYW5nZWxvZ3MudWJ1bnR1LmNvbSxsYXVuY2hwYWRsaWJyYXJpYW4ubmV0LGxhdW5jaHBhZGNvbnRlbnQubmV0LGxhdW5jaHBhZC5uZXQsMTAuMjQuMC4wLzI0LGtleXN0b25lLnBzNS5jYW5vbmljYWwuY29tLG9iamVjdHN0b3JhZ2UucHJvZHN0YWNrNS5jYW5vbmljYWwuY29tJ1xuJyA+PiAvZXRjL2Vudmlyb25tZW50CiAtIHNlZCAtaSAtciAnL14xMjcuMC4xLjEvIHMvYXV0b3BrZ3Rlc3QtW14gXStcLi9hdXRvcGtndGVzdFwuLycgL2V0Yy9ob3N0cwo=", "key_name": "testbed-juju-7f2275-prod-proposed-migration-environment-3", "min_count": 1, "max_count": 1, "security_groups": [{"name": "autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-arm64-15.secgroup"}], "networks": [{"uuid": "6ea4839c-9760-4b54-ab7d-70a6312667d1"}]}}' 377s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "POST /v2.1/servers HTTP/1.1" 403 115 377s DEBUG (session:548) RESP: [403] Connection: Keep-Alive Content-Length: 115 Content-Type: application/json; charset=UTF-8 Date: Wed, 27 Mar 2024 02:21:11 GMT Keep-Alive: timeout=75, max=995 OpenStack-API-Version: compute 2.87 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.87 x-compute-request-id: req-9454f2a5-06e7-46dd-9d67-b3af2f0823ab x-openstack-request-id: req-9454f2a5-06e7-46dd-9d67-b3af2f0823ab 377s DEBUG (session:580) RESP BODY: {"forbidden": {"code": 403, "message": "Quota exceeded for cores: Requested 2, but already used 140 of 140 cores"}} 377s DEBUG (session:936) POST call to compute for https://nova.ps6.canonical.com:8774/v2.1/servers used request id req-9454f2a5-06e7-46dd-9d67-b3af2f0823ab 377s DEBUG (shell:822) Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-9454f2a5-06e7-46dd-9d67-b3af2f0823ab) 377s Traceback (most recent call last): 377s File "/usr/lib/python3/dist-packages/novaclient/shell.py", line 820, in main 377s OpenStackComputeShell().main(argv) 377s File "/usr/lib/python3/dist-packages/novaclient/shell.py", line 742, in main 377s args.func(self.cs, args) 377s File "/usr/lib/python3/dist-packages/novaclient/v2/shell.py", line 971, in do_boot 377s server = cs.servers.create(*boot_args, **boot_kwargs) 377s File "/usr/lib/python3/dist-packages/novaclient/v2/servers.py", line 1477, in create 377s return self._boot(response_key, *boot_args, **boot_kwargs) 377s File "/usr/lib/python3/dist-packages/novaclient/v2/servers.py", line 836, in _boot 377s return self._create('/servers', body, response_key, 377s File "/usr/lib/python3/dist-packages/novaclient/base.py", line 363, in _create 377s resp, body = self.api.client.post(url, body=body) 377s File "/usr/lib/python3/dist-packages/keystoneauth1/adapter.py", line 392, in post 377s return self.request(url, 'POST', **kwargs) 377s File "/usr/lib/python3/dist-packages/novaclient/client.py", line 78, in request 377s raise exceptions.from_response(resp, body, url, method) 377s novaclient.exceptions.Forbidden: Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-9454f2a5-06e7-46dd-9d67-b3af2f0823ab) 377s ERROR (Forbidden): Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-9454f2a5-06e7-46dd-9d67-b3af2f0823ab) 380s autopkgtest [02:26:56]: testbed dpkg architecture: arm64 380s autopkgtest [02:26:56]: testbed apt version: 2.7.12 380s autopkgtest [02:26:56]: @@@@@@@@@@@@@@@@@@@@ test bed setup 380s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 381s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [497 kB] 381s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [8504 B] 381s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3984 kB] 381s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [55.4 kB] 381s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [717 kB] 381s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 381s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [43.0 kB] 381s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 381s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [4303 kB] 381s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 381s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [71.0 kB] 381s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 383s Fetched 9808 kB in 2s (4755 kB/s) 384s Reading package lists... 387s Reading package lists... 387s Building dependency tree... 387s Reading state information... 387s Calculating upgrade... 388s The following packages will be REMOVED: 388s libapt-pkg6.0 libdb5.3 libgnutls30 libhogweed6 libnettle8 388s The following NEW packages will be installed: 388s libapt-pkg6.0t64 libdb5.3t64 libgnutls30t64 libhogweed6t64 libnettle8t64 388s The following packages will be upgraded: 388s apt apt-utils ubuntu-pro-client ubuntu-pro-client-l10n 388s 4 upgraded, 5 newly installed, 5 to remove and 0 not upgraded. 388s Need to get 4875 kB of archives. 388s After this operation, 671 kB of additional disk space will be used. 388s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libnettle8t64 arm64 3.9.1-2.2 [192 kB] 388s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libhogweed6t64 arm64 3.9.1-2.2 [199 kB] 388s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libgnutls30t64 arm64 3.8.3-1.1ubuntu2 [1042 kB] 388s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 ubuntu-pro-client-l10n arm64 31.2.2 [19.4 kB] 388s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 ubuntu-pro-client arm64 31.2.2 [214 kB] 388s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libdb5.3t64 arm64 5.3.28+dfsg2-6 [732 kB] 388s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 libapt-pkg6.0t64 arm64 2.7.14 [934 kB] 388s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 apt arm64 2.7.14 [1337 kB] 388s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 apt-utils arm64 2.7.14 [205 kB] 389s Fetched 4875 kB in 1s (6856 kB/s) 389s dpkg: libnettle8:arm64: dependency problems, but removing anyway as you requested: 389s librtmp1:arm64 depends on libnettle8. 389s libhogweed6:arm64 depends on libnettle8. 389s libgnutls30:arm64 depends on libnettle8 (>= 3.9~). 389s libcurl3-gnutls:arm64 depends on libnettle8. 389s libarchive13:arm64 depends on libnettle8. 389s 389s (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 ... 74816 files and directories currently installed.) 389s Removing libnettle8:arm64 (3.9.1-2) ... 389s Selecting previously unselected package libnettle8t64:arm64. 389s (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 ... 74809 files and directories currently installed.) 389s Preparing to unpack .../libnettle8t64_3.9.1-2.2_arm64.deb ... 389s Unpacking libnettle8t64:arm64 (3.9.1-2.2) ... 389s Setting up libnettle8t64:arm64 (3.9.1-2.2) ... 389s dpkg: libhogweed6:arm64: dependency problems, but removing anyway as you requested: 389s librtmp1:arm64 depends on libhogweed6. 389s libjcat1:arm64 depends on libhogweed6. 389s libgnutls30:arm64 depends on libhogweed6 (>= 3.6). 389s 389s (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 ... 74817 files and directories currently installed.) 389s Removing libhogweed6:arm64 (3.9.1-2) ... 389s Selecting previously unselected package libhogweed6t64:arm64. 390s (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 ... 74812 files and directories currently installed.) 390s Preparing to unpack .../libhogweed6t64_3.9.1-2.2_arm64.deb ... 390s Unpacking libhogweed6t64:arm64 (3.9.1-2.2) ... 390s Setting up libhogweed6t64:arm64 (3.9.1-2.2) ... 390s dpkg: libgnutls30:arm64: dependency problems, but removing anyway as you requested: 390s u-boot-tools depends on libgnutls30 (>= 3.7.3). 390s librtmp1:arm64 depends on libgnutls30 (>= 3.7.2). 390s libldap2:arm64 depends on libgnutls30 (>= 3.8.2). 390s libjcat1:arm64 depends on libgnutls30 (>= 3.7.3). 390s libcurl3-gnutls:arm64 depends on libgnutls30 (>= 3.8.2). 390s fwupd depends on libgnutls30 (>= 3.7.3). 390s dirmngr depends on libgnutls30 (>= 3.8.1). 390s apt depends on libgnutls30 (>= 3.8.1). 390s 390s (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 ... 74818 files and directories currently installed.) 390s Removing libgnutls30:arm64 (3.8.3-1ubuntu1) ... 390s Selecting previously unselected package libgnutls30t64:arm64. 390s (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 ... 74809 files and directories currently installed.) 390s Preparing to unpack .../libgnutls30t64_3.8.3-1.1ubuntu2_arm64.deb ... 390s Unpacking libgnutls30t64:arm64 (3.8.3-1.1ubuntu2) ... 390s Setting up libgnutls30t64:arm64 (3.8.3-1.1ubuntu2) ... 390s (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 ... 74837 files and directories currently installed.) 390s Preparing to unpack .../ubuntu-pro-client-l10n_31.2.2_arm64.deb ... 390s Unpacking ubuntu-pro-client-l10n (31.2.2) over (31.1) ... 390s Preparing to unpack .../ubuntu-pro-client_31.2.2_arm64.deb ... 390s Unpacking ubuntu-pro-client (31.2.2) over (31.1) ... 391s dpkg: libdb5.3:arm64: dependency problems, but removing anyway as you requested: 391s libsasl2-modules-db:arm64 depends on libdb5.3. 391s libpython3.12-stdlib:arm64 depends on libdb5.3. 391s libperl5.38:arm64 depends on libdb5.3. 391s libpam-modules:arm64 depends on libdb5.3. 391s iproute2 depends on libdb5.3. 391s apt-utils depends on libdb5.3. 391s 391s (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 ... 74837 files and directories currently installed.) 391s Removing libdb5.3:arm64 (5.3.28+dfsg2-4) ... 391s Selecting previously unselected package libdb5.3t64:arm64. 391s (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 ... 74831 files and directories currently installed.) 391s Preparing to unpack .../libdb5.3t64_5.3.28+dfsg2-6_arm64.deb ... 391s Unpacking libdb5.3t64:arm64 (5.3.28+dfsg2-6) ... 391s Setting up libdb5.3t64:arm64 (5.3.28+dfsg2-6) ... 391s dpkg: libapt-pkg6.0:arm64: dependency problems, but removing anyway as you requested: 391s python3-apt depends on libapt-pkg6.0 (>= 2.7.11). 391s apt-utils depends on libapt-pkg6.0 (>= 2.7.12). 391s apt depends on libapt-pkg6.0 (>= 2.7.12). 391s 391s (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 ... 74837 files and directories currently installed.) 391s Removing libapt-pkg6.0:arm64 (2.7.12) ... 391s Selecting previously unselected package libapt-pkg6.0t64:arm64. 391s (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 ... 74788 files and directories currently installed.) 391s Preparing to unpack .../libapt-pkg6.0t64_2.7.14_arm64.deb ... 391s Unpacking libapt-pkg6.0t64:arm64 (2.7.14) ... 391s Setting up libapt-pkg6.0t64:arm64 (2.7.14) ... 391s (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 ... 74838 files and directories currently installed.) 391s Preparing to unpack .../archives/apt_2.7.14_arm64.deb ... 391s Unpacking apt (2.7.14) over (2.7.12) ... 391s Setting up apt (2.7.14) ... 392s (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 ... 74838 files and directories currently installed.) 392s Preparing to unpack .../apt-utils_2.7.14_arm64.deb ... 392s Unpacking apt-utils (2.7.14) over (2.7.12) ... 392s Setting up apt-utils (2.7.14) ... 392s Setting up ubuntu-pro-client (31.2.2) ... 394s Setting up ubuntu-pro-client-l10n (31.2.2) ... 394s Processing triggers for man-db (2.12.0-3) ... 396s Processing triggers for libc-bin (2.39-0ubuntu6) ... 398s Reading package lists... 398s Building dependency tree... 398s Reading state information... 400s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 400s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 401s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 401s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 401s Hit:4 http://ftpmaster.internal/ubuntu noble-proposed InRelease 403s Reading package lists... 403s Reading package lists... 403s Building dependency tree... 403s Reading state information... 404s Calculating upgrade... 404s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 405s Reading package lists... 405s Building dependency tree... 405s Reading state information... 406s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 406s autopkgtest [02:27:22]: rebooting testbed after setup commands that affected boot 433s autopkgtest [02:27:49]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 02:53:31 UTC 2024 436s autopkgtest [02:27:52]: @@@@@@@@@@@@@@@@@@@@ apt-source wsl-pro-service 439s Get:1 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.2 (dsc) [1420 B] 439s Get:2 http://ftpmaster.internal/ubuntu noble/universe wsl-pro-service 0.1.2 (tar) [1746 kB] 439s gpgv: Signature made Thu Feb 29 15:15:37 2024 UTC 439s gpgv: using DSA key DD00EF8F4D23C30D78EC990998B24A9CE4AC208E 439s gpgv: Can't check signature: No public key 439s dpkg-source: warning: cannot verify inline signature for ./wsl-pro-service_0.1.2.dsc: no acceptable signature found 439s autopkgtest [02:27:55]: testing package wsl-pro-service version 0.1.2 439s autopkgtest [02:27:55]: build not needed 441s autopkgtest [02:27:57]: test command1: preparing testbed 442s Reading package lists... 442s Building dependency tree... 442s Reading state information... 443s Starting pkgProblemResolver with broken count: 0 443s Starting 2 pkgProblemResolver with broken count: 0 443s Done 443s The following additional packages will be installed: 443s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 443s cpp-13-aarch64-linux-gnu cpp-aarch64-linux-gnu debhelper debugedit dh-apport 443s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 443s g++-13-aarch64-linux-gnu g++-aarch64-linux-gnu gcc gcc-13 443s gcc-13-aarch64-linux-gnu gcc-aarch64-linux-gnu gettext golang-1.22-go 443s golang-1.22-src golang-go golang-src intltool-debian libarchive-zip-perl 443s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 443s libdebhelper-perl libdw1 libfile-stripnondeterminism-perl libgcc-13-dev 443s libgomp1 libhwasan0 libisl23 libitm1 liblsan0 libmpc3 libstdc++-13-dev 443s libsub-override-perl libtool libtsan2 libubsan1 linux-libc-dev m4 po-debconf 443s rpcsvc-proto 443s Suggested packages: 443s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 443s cpp-13-doc dh-make gcc-13-doc gcc-multilib manpages-dev flex bison gdb 443s gcc-doc gdb-aarch64-linux-gnu gettext-doc libasprintf-dev libgettextpo-dev 443s bzr | brz git mercurial subversion glibc-doc libstdc++-13-doc libtool-doc 443s gfortran | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 443s Recommended packages: 443s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 443s libltdl-dev libmail-sendmail-perl 443s The following NEW packages will be installed: 443s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 443s cpp cpp-13 cpp-13-aarch64-linux-gnu cpp-aarch64-linux-gnu debhelper 443s debugedit dh-apport dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ 443s g++-13 g++-13-aarch64-linux-gnu g++-aarch64-linux-gnu gcc gcc-13 443s gcc-13-aarch64-linux-gnu gcc-aarch64-linux-gnu gettext golang-1.22-go 443s golang-1.22-src golang-go golang-src intltool-debian libarchive-zip-perl 443s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 443s libdebhelper-perl libdw1 libfile-stripnondeterminism-perl libgcc-13-dev 443s libgomp1 libhwasan0 libisl23 libitm1 liblsan0 libmpc3 libstdc++-13-dev 443s libsub-override-perl libtool libtsan2 libubsan1 linux-libc-dev m4 po-debconf 443s rpcsvc-proto 443s 0 upgraded, 57 newly installed, 0 to remove and 0 not upgraded. 443s Need to get 109 MB/109 MB of archives. 443s After this operation, 459 MB of additional disk space will be used. 443s Get:1 /tmp/autopkgtest.teJeqP/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [760 B] 443s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 m4 arm64 1.4.19-4 [240 kB] 444s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 autoconf all 2.71-3 [339 kB] 444s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 autotools-dev all 20220109.1 [44.9 kB] 444s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 automake all 1:1.16.5-1.3ubuntu1 [558 kB] 444s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 autopoint all 0.21-14ubuntu1 [422 kB] 444s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 libc-dev-bin arm64 2.39-0ubuntu6 [19.7 kB] 444s Get:8 http://ftpmaster.internal/ubuntu noble/main arm64 linux-libc-dev arm64 6.8.0-11.11 [1569 kB] 444s Get:9 http://ftpmaster.internal/ubuntu noble/main arm64 libcrypt-dev arm64 1:4.4.36-4 [136 kB] 444s Get:10 http://ftpmaster.internal/ubuntu noble/main arm64 rpcsvc-proto arm64 1.4.2-0ubuntu6 [65.4 kB] 444s Get:11 http://ftpmaster.internal/ubuntu noble/main arm64 libc6-dev arm64 2.39-0ubuntu6 [1596 kB] 444s Get:12 http://ftpmaster.internal/ubuntu noble/main arm64 libisl23 arm64 0.26-3 [713 kB] 444s Get:13 http://ftpmaster.internal/ubuntu noble/main arm64 libmpc3 arm64 1.3.1-1 [55.3 kB] 444s Get:14 http://ftpmaster.internal/ubuntu noble/main arm64 cpp-13-aarch64-linux-gnu arm64 13.2.0-17ubuntu2 [10.3 MB] 444s Get:15 http://ftpmaster.internal/ubuntu noble/main arm64 cpp-13 arm64 13.2.0-17ubuntu2 [1028 B] 444s Get:16 http://ftpmaster.internal/ubuntu noble/main arm64 cpp-aarch64-linux-gnu arm64 4:13.2.0-7ubuntu1 [5316 B] 444s Get:17 http://ftpmaster.internal/ubuntu noble/main arm64 cpp arm64 4:13.2.0-7ubuntu1 [22.4 kB] 444s Get:18 http://ftpmaster.internal/ubuntu noble/main arm64 libcc1-0 arm64 14-20240303-1ubuntu1 [44.7 kB] 444s Get:19 http://ftpmaster.internal/ubuntu noble/main arm64 libgomp1 arm64 14-20240303-1ubuntu1 [144 kB] 444s Get:20 http://ftpmaster.internal/ubuntu noble/main arm64 libitm1 arm64 14-20240303-1ubuntu1 [27.7 kB] 444s Get:21 http://ftpmaster.internal/ubuntu noble/main arm64 libatomic1 arm64 14-20240303-1ubuntu1 [11.4 kB] 444s Get:22 http://ftpmaster.internal/ubuntu noble/main arm64 libasan8 arm64 14-20240303-1ubuntu1 [2919 kB] 444s Get:23 http://ftpmaster.internal/ubuntu noble/main arm64 liblsan0 arm64 14-20240303-1ubuntu1 [1282 kB] 444s Get:24 http://ftpmaster.internal/ubuntu noble/main arm64 libtsan2 arm64 14-20240303-1ubuntu1 [2687 kB] 445s Get:25 http://ftpmaster.internal/ubuntu noble/main arm64 libubsan1 arm64 14-20240303-1ubuntu1 [1151 kB] 445s Get:26 http://ftpmaster.internal/ubuntu noble/main arm64 libhwasan0 arm64 14-20240303-1ubuntu1 [1597 kB] 445s Get:27 http://ftpmaster.internal/ubuntu noble/main arm64 libgcc-13-dev arm64 13.2.0-17ubuntu2 [2464 kB] 445s Get:28 http://ftpmaster.internal/ubuntu noble/main arm64 gcc-13-aarch64-linux-gnu arm64 13.2.0-17ubuntu2 [20.1 MB] 445s Get:29 http://ftpmaster.internal/ubuntu noble/main arm64 gcc-13 arm64 13.2.0-17ubuntu2 [467 kB] 445s Get:30 http://ftpmaster.internal/ubuntu noble/main arm64 gcc-aarch64-linux-gnu arm64 4:13.2.0-7ubuntu1 [1198 B] 445s Get:31 http://ftpmaster.internal/ubuntu noble/main arm64 gcc arm64 4:13.2.0-7ubuntu1 [5018 B] 445s Get:32 http://ftpmaster.internal/ubuntu noble/main arm64 libstdc++-13-dev arm64 13.2.0-17ubuntu2 [2322 kB] 445s Get:33 http://ftpmaster.internal/ubuntu noble/main arm64 g++-13-aarch64-linux-gnu arm64 13.2.0-17ubuntu2 [11.7 MB] 445s Get:34 http://ftpmaster.internal/ubuntu noble/main arm64 g++-13 arm64 13.2.0-17ubuntu2 [14.4 kB] 445s Get:35 http://ftpmaster.internal/ubuntu noble/main arm64 g++-aarch64-linux-gnu arm64 4:13.2.0-7ubuntu1 [962 B] 445s Get:36 http://ftpmaster.internal/ubuntu noble/main arm64 g++ arm64 4:13.2.0-7ubuntu1 [1082 B] 445s Get:37 http://ftpmaster.internal/ubuntu noble/main arm64 build-essential arm64 12.10ubuntu1 [4932 B] 445s Get:38 http://ftpmaster.internal/ubuntu noble/main arm64 libdebhelper-perl all 13.14.1ubuntu1 [89.5 kB] 445s Get:39 http://ftpmaster.internal/ubuntu noble/main arm64 libtool all 2.4.7-7 [166 kB] 445s Get:40 http://ftpmaster.internal/ubuntu noble/main arm64 dh-autoreconf all 20 [16.1 kB] 445s Get:41 http://ftpmaster.internal/ubuntu noble/main arm64 libarchive-zip-perl all 1.68-1 [90.2 kB] 445s Get:42 http://ftpmaster.internal/ubuntu noble/main arm64 libsub-override-perl all 0.10-1 [10.0 kB] 446s Get:43 http://ftpmaster.internal/ubuntu noble/main arm64 libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 446s Get:44 http://ftpmaster.internal/ubuntu noble/main arm64 dh-strip-nondeterminism all 1.13.1-1 [5362 B] 446s Get:45 http://ftpmaster.internal/ubuntu noble/main arm64 libdw1 arm64 0.190-1 [254 kB] 446s Get:46 http://ftpmaster.internal/ubuntu noble/main arm64 debugedit arm64 1:5.0-5 [45.9 kB] 446s Get:47 http://ftpmaster.internal/ubuntu noble/main arm64 dwz arm64 0.15-1 [110 kB] 446s Get:48 http://ftpmaster.internal/ubuntu noble/main arm64 gettext arm64 0.21-14ubuntu1 [846 kB] 446s Get:49 http://ftpmaster.internal/ubuntu noble/main arm64 intltool-debian all 0.35.0+20060710.6 [23.2 kB] 446s Get:50 http://ftpmaster.internal/ubuntu noble/main arm64 po-debconf all 1.0.21+nmu1 [233 kB] 446s Get:51 http://ftpmaster.internal/ubuntu noble/main arm64 debhelper all 13.14.1ubuntu1 [869 kB] 446s Get:52 http://ftpmaster.internal/ubuntu noble/universe arm64 dh-apport all 2.28.0-0ubuntu1 [16.9 kB] 446s Get:53 http://ftpmaster.internal/ubuntu noble/main arm64 golang-1.22-src all 1.22.1-1 [19.7 MB] 446s Get:54 http://ftpmaster.internal/ubuntu noble/main arm64 golang-1.22-go arm64 1.22.1-1 [23.6 MB] 447s Get:55 http://ftpmaster.internal/ubuntu noble/main arm64 golang-src all 2:1.22~2 [4992 B] 447s Get:56 http://ftpmaster.internal/ubuntu noble/main arm64 golang-go arm64 2:1.22~2 [43.9 kB] 447s Get:57 http://ftpmaster.internal/ubuntu noble/main arm64 dh-golang all 1.62 [25.2 kB] 448s Fetched 109 MB in 3s (31.6 MB/s) 448s Selecting previously unselected package m4. 448s (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 ... 74838 files and directories currently installed.) 448s Preparing to unpack .../00-m4_1.4.19-4_arm64.deb ... 448s Unpacking m4 (1.4.19-4) ... 448s Selecting previously unselected package autoconf. 448s Preparing to unpack .../01-autoconf_2.71-3_all.deb ... 448s Unpacking autoconf (2.71-3) ... 448s Selecting previously unselected package autotools-dev. 448s Preparing to unpack .../02-autotools-dev_20220109.1_all.deb ... 448s Unpacking autotools-dev (20220109.1) ... 448s Selecting previously unselected package automake. 448s Preparing to unpack .../03-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 448s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 448s Selecting previously unselected package autopoint. 448s Preparing to unpack .../04-autopoint_0.21-14ubuntu1_all.deb ... 448s Unpacking autopoint (0.21-14ubuntu1) ... 448s Selecting previously unselected package libc-dev-bin. 448s Preparing to unpack .../05-libc-dev-bin_2.39-0ubuntu6_arm64.deb ... 448s Unpacking libc-dev-bin (2.39-0ubuntu6) ... 448s Selecting previously unselected package linux-libc-dev:arm64. 448s Preparing to unpack .../06-linux-libc-dev_6.8.0-11.11_arm64.deb ... 448s Unpacking linux-libc-dev:arm64 (6.8.0-11.11) ... 448s Selecting previously unselected package libcrypt-dev:arm64. 448s Preparing to unpack .../07-libcrypt-dev_1%3a4.4.36-4_arm64.deb ... 448s Unpacking libcrypt-dev:arm64 (1:4.4.36-4) ... 448s Selecting previously unselected package rpcsvc-proto. 448s Preparing to unpack .../08-rpcsvc-proto_1.4.2-0ubuntu6_arm64.deb ... 448s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 448s Selecting previously unselected package libc6-dev:arm64. 448s Preparing to unpack .../09-libc6-dev_2.39-0ubuntu6_arm64.deb ... 448s Unpacking libc6-dev:arm64 (2.39-0ubuntu6) ... 448s Selecting previously unselected package libisl23:arm64. 448s Preparing to unpack .../10-libisl23_0.26-3_arm64.deb ... 448s Unpacking libisl23:arm64 (0.26-3) ... 448s Selecting previously unselected package libmpc3:arm64. 448s Preparing to unpack .../11-libmpc3_1.3.1-1_arm64.deb ... 448s Unpacking libmpc3:arm64 (1.3.1-1) ... 448s Selecting previously unselected package cpp-13-aarch64-linux-gnu. 448s Preparing to unpack .../12-cpp-13-aarch64-linux-gnu_13.2.0-17ubuntu2_arm64.deb ... 448s Unpacking cpp-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 448s Selecting previously unselected package cpp-13. 448s Preparing to unpack .../13-cpp-13_13.2.0-17ubuntu2_arm64.deb ... 448s Unpacking cpp-13 (13.2.0-17ubuntu2) ... 449s Selecting previously unselected package cpp-aarch64-linux-gnu. 449s Preparing to unpack .../14-cpp-aarch64-linux-gnu_4%3a13.2.0-7ubuntu1_arm64.deb ... 449s Unpacking cpp-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 449s Selecting previously unselected package cpp. 449s Preparing to unpack .../15-cpp_4%3a13.2.0-7ubuntu1_arm64.deb ... 449s Unpacking cpp (4:13.2.0-7ubuntu1) ... 449s Selecting previously unselected package libcc1-0:arm64. 449s Preparing to unpack .../16-libcc1-0_14-20240303-1ubuntu1_arm64.deb ... 449s Unpacking libcc1-0:arm64 (14-20240303-1ubuntu1) ... 449s Selecting previously unselected package libgomp1:arm64. 449s Preparing to unpack .../17-libgomp1_14-20240303-1ubuntu1_arm64.deb ... 449s Unpacking libgomp1:arm64 (14-20240303-1ubuntu1) ... 449s Selecting previously unselected package libitm1:arm64. 449s Preparing to unpack .../18-libitm1_14-20240303-1ubuntu1_arm64.deb ... 449s Unpacking libitm1:arm64 (14-20240303-1ubuntu1) ... 449s Selecting previously unselected package libatomic1:arm64. 449s Preparing to unpack .../19-libatomic1_14-20240303-1ubuntu1_arm64.deb ... 449s Unpacking libatomic1:arm64 (14-20240303-1ubuntu1) ... 449s Selecting previously unselected package libasan8:arm64. 449s Preparing to unpack .../20-libasan8_14-20240303-1ubuntu1_arm64.deb ... 449s Unpacking libasan8:arm64 (14-20240303-1ubuntu1) ... 449s Selecting previously unselected package liblsan0:arm64. 449s Preparing to unpack .../21-liblsan0_14-20240303-1ubuntu1_arm64.deb ... 449s Unpacking liblsan0:arm64 (14-20240303-1ubuntu1) ... 449s Selecting previously unselected package libtsan2:arm64. 449s Preparing to unpack .../22-libtsan2_14-20240303-1ubuntu1_arm64.deb ... 449s Unpacking libtsan2:arm64 (14-20240303-1ubuntu1) ... 449s Selecting previously unselected package libubsan1:arm64. 449s Preparing to unpack .../23-libubsan1_14-20240303-1ubuntu1_arm64.deb ... 449s Unpacking libubsan1:arm64 (14-20240303-1ubuntu1) ... 449s Selecting previously unselected package libhwasan0:arm64. 449s Preparing to unpack .../24-libhwasan0_14-20240303-1ubuntu1_arm64.deb ... 449s Unpacking libhwasan0:arm64 (14-20240303-1ubuntu1) ... 449s Selecting previously unselected package libgcc-13-dev:arm64. 449s Preparing to unpack .../25-libgcc-13-dev_13.2.0-17ubuntu2_arm64.deb ... 449s Unpacking libgcc-13-dev:arm64 (13.2.0-17ubuntu2) ... 449s Selecting previously unselected package gcc-13-aarch64-linux-gnu. 449s Preparing to unpack .../26-gcc-13-aarch64-linux-gnu_13.2.0-17ubuntu2_arm64.deb ... 449s Unpacking gcc-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 450s Selecting previously unselected package gcc-13. 450s Preparing to unpack .../27-gcc-13_13.2.0-17ubuntu2_arm64.deb ... 450s Unpacking gcc-13 (13.2.0-17ubuntu2) ... 450s Selecting previously unselected package gcc-aarch64-linux-gnu. 450s Preparing to unpack .../28-gcc-aarch64-linux-gnu_4%3a13.2.0-7ubuntu1_arm64.deb ... 450s Unpacking gcc-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 450s Selecting previously unselected package gcc. 450s Preparing to unpack .../29-gcc_4%3a13.2.0-7ubuntu1_arm64.deb ... 450s Unpacking gcc (4:13.2.0-7ubuntu1) ... 450s Selecting previously unselected package libstdc++-13-dev:arm64. 450s Preparing to unpack .../30-libstdc++-13-dev_13.2.0-17ubuntu2_arm64.deb ... 450s Unpacking libstdc++-13-dev:arm64 (13.2.0-17ubuntu2) ... 450s Selecting previously unselected package g++-13-aarch64-linux-gnu. 450s Preparing to unpack .../31-g++-13-aarch64-linux-gnu_13.2.0-17ubuntu2_arm64.deb ... 450s Unpacking g++-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 450s Selecting previously unselected package g++-13. 450s Preparing to unpack .../32-g++-13_13.2.0-17ubuntu2_arm64.deb ... 450s Unpacking g++-13 (13.2.0-17ubuntu2) ... 450s Selecting previously unselected package g++-aarch64-linux-gnu. 450s Preparing to unpack .../33-g++-aarch64-linux-gnu_4%3a13.2.0-7ubuntu1_arm64.deb ... 450s Unpacking g++-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 450s Selecting previously unselected package g++. 450s Preparing to unpack .../34-g++_4%3a13.2.0-7ubuntu1_arm64.deb ... 450s Unpacking g++ (4:13.2.0-7ubuntu1) ... 450s Selecting previously unselected package build-essential. 450s Preparing to unpack .../35-build-essential_12.10ubuntu1_arm64.deb ... 450s Unpacking build-essential (12.10ubuntu1) ... 450s Selecting previously unselected package libdebhelper-perl. 450s Preparing to unpack .../36-libdebhelper-perl_13.14.1ubuntu1_all.deb ... 450s Unpacking libdebhelper-perl (13.14.1ubuntu1) ... 450s Selecting previously unselected package libtool. 450s Preparing to unpack .../37-libtool_2.4.7-7_all.deb ... 450s Unpacking libtool (2.4.7-7) ... 450s Selecting previously unselected package dh-autoreconf. 450s Preparing to unpack .../38-dh-autoreconf_20_all.deb ... 450s Unpacking dh-autoreconf (20) ... 450s Selecting previously unselected package libarchive-zip-perl. 450s Preparing to unpack .../39-libarchive-zip-perl_1.68-1_all.deb ... 450s Unpacking libarchive-zip-perl (1.68-1) ... 450s Selecting previously unselected package libsub-override-perl. 450s Preparing to unpack .../40-libsub-override-perl_0.10-1_all.deb ... 450s Unpacking libsub-override-perl (0.10-1) ... 450s Selecting previously unselected package libfile-stripnondeterminism-perl. 450s Preparing to unpack .../41-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 450s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 450s Selecting previously unselected package dh-strip-nondeterminism. 450s Preparing to unpack .../42-dh-strip-nondeterminism_1.13.1-1_all.deb ... 450s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 450s Selecting previously unselected package libdw1:arm64. 450s Preparing to unpack .../43-libdw1_0.190-1_arm64.deb ... 450s Unpacking libdw1:arm64 (0.190-1) ... 450s Selecting previously unselected package debugedit. 450s Preparing to unpack .../44-debugedit_1%3a5.0-5_arm64.deb ... 450s Unpacking debugedit (1:5.0-5) ... 450s Selecting previously unselected package dwz. 450s Preparing to unpack .../45-dwz_0.15-1_arm64.deb ... 450s Unpacking dwz (0.15-1) ... 450s Selecting previously unselected package gettext. 450s Preparing to unpack .../46-gettext_0.21-14ubuntu1_arm64.deb ... 450s Unpacking gettext (0.21-14ubuntu1) ... 450s Selecting previously unselected package intltool-debian. 450s Preparing to unpack .../47-intltool-debian_0.35.0+20060710.6_all.deb ... 450s Unpacking intltool-debian (0.35.0+20060710.6) ... 450s Selecting previously unselected package po-debconf. 450s Preparing to unpack .../48-po-debconf_1.0.21+nmu1_all.deb ... 450s Unpacking po-debconf (1.0.21+nmu1) ... 450s Selecting previously unselected package debhelper. 450s Preparing to unpack .../49-debhelper_13.14.1ubuntu1_all.deb ... 450s Unpacking debhelper (13.14.1ubuntu1) ... 451s Selecting previously unselected package dh-apport. 451s Preparing to unpack .../50-dh-apport_2.28.0-0ubuntu1_all.deb ... 451s Unpacking dh-apport (2.28.0-0ubuntu1) ... 451s Selecting previously unselected package golang-1.22-src. 451s Preparing to unpack .../51-golang-1.22-src_1.22.1-1_all.deb ... 451s Unpacking golang-1.22-src (1.22.1-1) ... 452s Selecting previously unselected package golang-1.22-go. 452s Preparing to unpack .../52-golang-1.22-go_1.22.1-1_arm64.deb ... 452s Unpacking golang-1.22-go (1.22.1-1) ... 452s Selecting previously unselected package golang-src. 453s Preparing to unpack .../53-golang-src_2%3a1.22~2_all.deb ... 453s Unpacking golang-src (2:1.22~2) ... 453s Selecting previously unselected package golang-go:arm64. 453s Preparing to unpack .../54-golang-go_2%3a1.22~2_arm64.deb ... 453s Unpacking golang-go:arm64 (2:1.22~2) ... 453s Selecting previously unselected package dh-golang. 453s Preparing to unpack .../55-dh-golang_1.62_all.deb ... 453s Unpacking dh-golang (1.62) ... 453s Selecting previously unselected package autopkgtest-satdep. 453s Preparing to unpack .../56-1-autopkgtest-satdep.deb ... 453s Unpacking autopkgtest-satdep (0) ... 453s Setting up libdw1:arm64 (0.190-1) ... 453s Setting up libarchive-zip-perl (1.68-1) ... 453s Setting up libdebhelper-perl (13.14.1ubuntu1) ... 453s Setting up linux-libc-dev:arm64 (6.8.0-11.11) ... 453s Setting up m4 (1.4.19-4) ... 453s Setting up libgomp1:arm64 (14-20240303-1ubuntu1) ... 453s Setting up dh-apport (2.28.0-0ubuntu1) ... 453s Setting up autotools-dev (20220109.1) ... 453s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 453s Setting up golang-1.22-src (1.22.1-1) ... 453s Setting up libmpc3:arm64 (1.3.1-1) ... 453s Setting up libatomic1:arm64 (14-20240303-1ubuntu1) ... 453s Setting up autopoint (0.21-14ubuntu1) ... 453s Setting up autoconf (2.71-3) ... 453s Setting up libubsan1:arm64 (14-20240303-1ubuntu1) ... 453s Setting up dwz (0.15-1) ... 453s Setting up libhwasan0:arm64 (14-20240303-1ubuntu1) ... 453s Setting up libcrypt-dev:arm64 (1:4.4.36-4) ... 453s Setting up libasan8:arm64 (14-20240303-1ubuntu1) ... 453s Setting up debugedit (1:5.0-5) ... 453s Setting up libsub-override-perl (0.10-1) ... 453s Setting up libtsan2:arm64 (14-20240303-1ubuntu1) ... 453s Setting up libisl23:arm64 (0.26-3) ... 453s Setting up libc-dev-bin (2.39-0ubuntu6) ... 453s Setting up golang-src (2:1.22~2) ... 453s Setting up libcc1-0:arm64 (14-20240303-1ubuntu1) ... 453s Setting up liblsan0:arm64 (14-20240303-1ubuntu1) ... 453s Setting up libitm1:arm64 (14-20240303-1ubuntu1) ... 453s Setting up automake (1:1.16.5-1.3ubuntu1) ... 453s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 453s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 453s Setting up gettext (0.21-14ubuntu1) ... 453s Setting up cpp-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 453s Setting up golang-1.22-go (1.22.1-1) ... 453s Setting up intltool-debian (0.35.0+20060710.6) ... 453s Setting up cpp-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 453s Setting up dh-strip-nondeterminism (1.13.1-1) ... 453s Setting up libgcc-13-dev:arm64 (13.2.0-17ubuntu2) ... 453s Setting up libc6-dev:arm64 (2.39-0ubuntu6) ... 453s Setting up libstdc++-13-dev:arm64 (13.2.0-17ubuntu2) ... 453s Setting up cpp-13 (13.2.0-17ubuntu2) ... 453s Setting up golang-go:arm64 (2:1.22~2) ... 453s Setting up po-debconf (1.0.21+nmu1) ... 453s Setting up gcc-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 453s Setting up gcc-13 (13.2.0-17ubuntu2) ... 453s Setting up cpp (4:13.2.0-7ubuntu1) ... 453s Setting up g++-13-aarch64-linux-gnu (13.2.0-17ubuntu2) ... 453s Setting up gcc-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 453s Setting up g++-13 (13.2.0-17ubuntu2) ... 453s Setting up libtool (2.4.7-7) ... 453s Setting up gcc (4:13.2.0-7ubuntu1) ... 453s Setting up dh-autoreconf (20) ... 453s Setting up g++-aarch64-linux-gnu (4:13.2.0-7ubuntu1) ... 453s Setting up debhelper (13.14.1ubuntu1) ... 453s Setting up g++ (4:13.2.0-7ubuntu1) ... 453s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 453s Setting up build-essential (12.10ubuntu1) ... 453s Setting up dh-golang (1.62) ... 453s Setting up autopkgtest-satdep (0) ... 453s Processing triggers for man-db (2.12.0-3) ... 454s Processing triggers for install-info (7.1-3) ... 454s Processing triggers for libc-bin (2.39-0ubuntu6) ... 457s (Reading database ... 93001 files and directories currently installed.) 457s Removing autopkgtest-satdep (0) ... 458s autopkgtest [02:28:14]: test command1: ./debian/tests/test 458s autopkgtest [02:28:14]: test command1: [----------------------- 492s === RUN TestRunSignal 492s === RUN TestRunSignal/Send_SIGINT_exits 492s INFO Starting WSL Pro Service version Dev 492s === RUN TestRunSignal/Send_SIGTERM_exits 492s INFO Starting WSL Pro Service version Dev 492s --- PASS: TestRunSignal (0.20s) 492s --- PASS: TestRunSignal/Send_SIGINT_exits (0.10s) 492s --- PASS: TestRunSignal/Send_SIGTERM_exits (0.10s) 492s === RUN TestRun 492s === PAUSE TestRun 492s === CONT TestRun 492s === RUN TestRun/Run_and_exit_successfully 492s === PAUSE TestRun/Run_and_exit_successfully 492s === RUN TestRun/Run_and_return_error 492s === PAUSE TestRun/Run_and_return_error 492s === RUN TestRun/Run_and_return_usage_error 492s === PAUSE TestRun/Run_and_return_usage_error 492s === RUN TestRun/Run_and_usage_error_only_does_not_fail 492s === PAUSE TestRun/Run_and_usage_error_only_does_not_fail 492s === CONT TestRun/Run_and_exit_successfully 492s INFO Starting WSL Pro Service version Dev 492s === CONT TestRun/Run_and_usage_error_only_does_not_fail 492s INFO Starting WSL Pro Service version Dev 492s === CONT TestRun/Run_and_return_usage_error 492s INFO Starting WSL Pro Service version Dev 492s === CONT TestRun/Run_and_return_error 492s INFO Starting WSL Pro Service version Dev 492s ERROR context.Background Error requested 492s ERROR context.Background Error requested 492s --- PASS: TestRun (0.00s) 492s --- PASS: TestRun/Run_and_usage_error_only_does_not_fail (0.10s) 492s --- PASS: TestRun/Run_and_exit_successfully (0.10s) 492s --- PASS: TestRun/Run_and_return_error (0.10s) 492s --- PASS: TestRun/Run_and_return_usage_error (0.10s) 492s PASS 492s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service 1.158s 492s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/generate/doc [no test files] 492s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/consts [no test files] 505s === RUN TestHelp 505s --- PASS: TestHelp (0.01s) 505s === RUN TestCompletion 505s --- PASS: TestCompletion (0.00s) 505s === RUN TestVersion 505s --- PASS: TestVersion (0.00s) 505s === RUN TestNoUsageError 505s --- PASS: TestNoUsageError (0.00s) 505s === RUN TestUsageError 505s === PAUSE TestUsageError 505s === RUN TestCanQuitWhenExecute 505s === PAUSE TestCanQuitWhenExecute 505s === RUN TestCanQuitTwice 505s === PAUSE TestCanQuitTwice 505s === RUN TestAppCanQuitWithoutExecute 505s === PAUSE TestAppCanQuitWithoutExecute 505s === RUN TestAppRunFailsOnComponentsCreationAndQuit 505s === PAUSE TestAppRunFailsOnComponentsCreationAndQuit 505s === RUN TestAppGetRootCmd 505s === PAUSE TestAppGetRootCmd 505s === RUN TestWithProMock 505s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 505s --- SKIP: TestWithProMock (0.00s) 505s === RUN TestWithWslPathMock 505s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 505s --- SKIP: TestWithWslPathMock (0.00s) 505s === RUN TestWithWslInfoMock 505s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 505s --- SKIP: TestWithWslInfoMock (0.00s) 505s === RUN TestWithCmdExeMock 505s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 505s --- SKIP: TestWithCmdExeMock (0.00s) 505s === CONT TestUsageError 505s === CONT TestAppCanQuitWithoutExecute 505s service_test.go:137: This test is skipped because it is flaky. There is no way to guarantee Quit has been called before run. 505s --- SKIP: TestAppCanQuitWithoutExecute (0.00s) 505s === CONT TestAppGetRootCmd 505s --- PASS: TestUsageError (0.00s) 505s === CONT TestCanQuitTwice 505s --- PASS: TestAppGetRootCmd (0.00s) 505s === CONT TestAppRunFailsOnComponentsCreationAndQuit 505s time="2024-03-27T02:28:48Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 505s time="2024-03-27T02:28:48Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:63 New() Building new daemon" 505s time="2024-03-27T02:28:48Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/testutils/mock_agent.go:80 MockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:40711\"" 505s time="2024-03-27T02:28:54Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 505s time="2024-03-27T02:28:54Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 505s time="2024-03-27T02:28:56Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 505s time="2024-03-27T02:28:56Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 505s time="2024-03-27T02:28:56Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 505s --- PASS: TestAppRunFailsOnComponentsCreationAndQuit (8.18s) 505s === CONT TestCanQuitWhenExecute 505s time="2024-03-27T02:28:56Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/cmd/wsl-pro-service/service/service.go:67 New.func1() Debug mode is enabled" 505s time="2024-03-27T02:28:56Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:63 New() Building new daemon" 505s time="2024-03-27T02:28:56Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/testutils/mock_agent.go:80 MockWindowsAgent.func1() MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:38219\"" 505s time="2024-03-27T02:28:56Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/controlstream/session.go:25 newSession() Connecting to control stream at \"127.0.0.1:40711\"" 505s time="2024-03-27T02:28:56Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/controlstream/controlstream.go:75 (*ControlStream).Connect() Control stream: starting handshake" 505s time="2024-03-27T02:28:56Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/testutils/mock_agent.go:134 (*wslInstanceMockService).Connected() wslInstanceMockService: Received incoming connection" 505s time="2024-03-27T02:28:58Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/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\"" 505s time="2024-03-27T02:28:58Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/testutils/mock_agent.go:176 (*wslInstanceMockService).Connected() wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 38915" 505s time="2024-03-27T02:28:58Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/controlstream/controlstream.go:82 (*ControlStream).Connect() Control stream: completed handshake" 505s time="2024-03-27T02:28:58Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:176 (*Daemon).serveOnce() Connected to control stream" 505s time="2024-03-27T02:28:58Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/wslinstanceservice/wslinstanceservice.go:44 (*Service).RegisterGRPCService() Registering gRPC WSL instance service" 505s time="2024-03-27T02:28:58Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:223 (*Daemon).serve() Starting to serve gRPC requests" 505s time="2024-03-27T02:28:58Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:233 (*Daemon).serve() Serving gRPC requests on localhost:38915" 505s time="2024-03-27T02:28:58Z" level=error msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:142 (*Daemon).Serve() Serve error: WSL Pro Service stopped serving: grpc error: grpc: the server has been stopped" 505s time="2024-03-27T02:28:58Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 505s time="2024-03-27T02:28:58Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 505s time="2024-03-27T02:28:58Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 505s time="2024-03-27T02:28:58Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 505s time="2024-03-27T02:28:58Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 505s time="2024-03-27T02:28:58Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 505s time="2024-03-27T02:28:58Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 505s --- PASS: TestCanQuitTwice (10.24s) 505s time="2024-03-27T02:28:58Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/testutils/mock_agent.go:89 MockWindowsAgent.func1() MockWindowsAgent: Remove address file returned an error: remove /tmp/TestCanQuitTwice219859900/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 505s time="2024-03-27T02:29:00Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/controlstream/session.go:25 newSession() Connecting to control stream at \"127.0.0.1:38219\"" 505s time="2024-03-27T02:29:00Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/controlstream/controlstream.go:75 (*ControlStream).Connect() Control stream: starting handshake" 505s time="2024-03-27T02:29:00Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/testutils/mock_agent.go:134 (*wslInstanceMockService).Connected() wslInstanceMockService: Received incoming connection" 505s time="2024-03-27T02:29:00Z" level=warning msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/testutils/mock_agent.go:126 (*wslInstanceMockService).Connected.func1() wslInstanceMockService: dropped connection: new connection: did not receive info from WSL distro: rpc error: code = Canceled desc = context canceled" 505s time="2024-03-27T02:29:00Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 505s time="2024-03-27T02:29:00Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 505s time="2024-03-27T02:29:01Z" level=error msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:142 (*Daemon).Serve() Serve error: could not connect to Windows Agent via the control stream: could not complete handshake: could not send system info: EOF" 505s time="2024-03-27T02:29:01Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 505s time="2024-03-27T02:29:01Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:256 (*Daemon).Quit() Stopping daemon requested." 505s time="2024-03-27T02:29:01Z" level=info msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:265 (*Daemon).Quit() Waiting for active requests to close." 505s time="2024-03-27T02:29:01Z" level=debug msg="/tmp/autopkgtest.teJeqP/build.VNf/src/internal/daemon/daemon.go:267 (*Daemon).Quit() All connections have now ended." 505s --- PASS: TestCanQuitWhenExecute (5.34s) 505s PASS 505s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/cmd/wsl-pro-service/service 13.755s 549s === RUN TestNew 549s === PAUSE TestNew 549s === RUN TestConnect 549s === PAUSE TestConnect 549s === RUN TestSend 549s === PAUSE TestSend 549s === RUN TestReconnection 549s === PAUSE TestReconnection 549s === RUN TestWithProMock 549s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 549s --- SKIP: TestWithProMock (0.00s) 549s === RUN TestWithWslPathMock 549s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 549s --- SKIP: TestWithWslPathMock (0.00s) 549s === RUN TestWithWslInfoMock 549s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 549s --- SKIP: TestWithWslInfoMock (0.00s) 549s === RUN TestWithCmdExeMock 549s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 549s --- SKIP: TestWithCmdExeMock (0.00s) 549s === CONT TestNew 549s === RUN TestNew/Success 549s === PAUSE TestNew/Success 549s === CONT TestSend 549s === RUN TestNew/Error_when_the_context_is_cancelled 549s === PAUSE TestNew/Error_when_the_context_is_cancelled 549s === RUN TestNew/Error_when_WslPath_returns_error 549s === PAUSE TestNew/Error_when_WslPath_returns_error 549s === CONT TestConnect 549s === RUN TestConnect/No_connection_because_of_port_file_with_invalid_contents 549s === PAUSE TestConnect/No_connection_because_of_port_file_with_invalid_contents 549s === RUN TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 549s === PAUSE TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 549s === RUN TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 549s === PAUSE TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 549s === RUN TestConnect/No_connection_because_of_empty_port_file 549s === PAUSE TestConnect/No_connection_because_of_empty_port_file 549s === RUN TestConnect/No_connection_because_port_file_does_not_exist 549s === PAUSE TestConnect/No_connection_because_port_file_does_not_exist 549s === RUN TestConnect/No_connection_because_of_unreadable_port_file 549s === PAUSE TestConnect/No_connection_because_of_unreadable_port_file 549s === RUN TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 549s === PAUSE TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 549s === RUN TestConnect/Incomplete_handshake_because_Agent_never_receives 549s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_receives 549s === RUN TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 549s === PAUSE TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 549s === RUN TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 549s === PAUSE TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 549s === RUN TestConnect/Success 549s === PAUSE TestConnect/Success 549s === CONT TestReconnection 549s === RUN TestReconnection/Success_connecting_after_failing_to_connect 549s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 549s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 549s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 549s === CONT TestNew/Success 549s time="2024-03-27T02:28:49Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36561\"" 549s === CONT TestConnect/No_connection_because_of_port_file_with_invalid_contents 549s time="2024-03-27T02:28:53Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39181\"" 549s time="2024-03-27T02:28:55Z" level=info msg="Connecting to control stream at \"127.0.0.1:36561\"" 549s time="2024-03-27T02:28:55Z" level=debug msg="Control stream: starting handshake" 549s time="2024-03-27T02:28:55Z" level=info msg="wslInstanceMockService: Received incoming connection" 549s time="2024-03-27T02:28:57Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 549s time="2024-03-27T02:28:57Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 36025" 549s time="2024-03-27T02:28:57Z" level=debug msg="Control stream: completed handshake" 549s === CONT TestNew/Error_when_WslPath_returns_error 549s === CONT TestNew/Error_when_the_context_is_cancelled 549s --- PASS: TestNew (0.00s) 549s --- PASS: TestNew/Success (4.24s) 549s --- PASS: TestNew/Error_when_WslPath_returns_error (1.86s) 549s --- PASS: TestNew/Error_when_the_context_is_cancelled (0.00s) 549s === CONT TestReconnection/Success_connecting_after_failing_to_connect 549s time="2024-03-27T02:29:02Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 549s time="2024-03-27T02:29:02Z" 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\"" 549s time="2024-03-27T02:29:03Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 549s time="2024-03-27T02:29:03Z" level=info msg="wslInstanceMockService: dropped connection" 549s --- PASS: TestSend (14.18s) 549s === CONT TestConnect/Success 549s time="2024-03-27T02:29:03Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestSend1835515427/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 549s time="2024-03-27T02:29:03Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33779\"" 549s time="2024-03-27T02:29:04Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41373\"" 549s time="2024-03-27T02:29:05Z" level=info msg="Connecting to control stream at \"127.0.0.1:41373\"" 549s time="2024-03-27T02:29:05Z" level=debug msg="Control stream: starting handshake" 549s time="2024-03-27T02:29:05Z" level=info msg="wslInstanceMockService: Received incoming connection" 549s time="2024-03-27T02:29:07Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 549s time="2024-03-27T02:29:07Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 42907" 549s time="2024-03-27T02:29:07Z" level=debug msg="Control stream: completed handshake" 549s === CONT TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 549s time="2024-03-27T02:29:07Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33785\"" 549s time="2024-03-27T02:29:09Z" level=info msg="Connecting to control stream at \"127.0.0.1:33779\"" 549s time="2024-03-27T02:29:09Z" level=debug msg="Control stream: starting handshake" 549s time="2024-03-27T02:29:09Z" level=info msg="wslInstanceMockService: Received incoming connection" 549s time="2024-03-27T02:29:10Z" 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\"" 549s time="2024-03-27T02:29:10Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 45575" 549s time="2024-03-27T02:29:10Z" level=debug msg="Control stream: completed handshake" 549s === CONT TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port 549s time="2024-03-27T02:29:11Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectSuccess4241464403/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 549s time="2024-03-27T02:29:11Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:37175\"" 549s time="2024-03-27T02:29:12Z" level=info msg="Connecting to control stream at \"127.0.0.1:33785\"" 549s time="2024-03-27T02:29:12Z" level=debug msg="Control stream: starting handshake" 549s time="2024-03-27T02:29:12Z" level=info msg="wslInstanceMockService: Received incoming connection" 549s time="2024-03-27T02:29:13Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 549s time="2024-03-27T02:29:13Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Sending bad port 0" 549s === CONT TestConnect/Incomplete_handshake_because_Agent_never_receives 549s time="2024-03-27T02:29:13Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectIncomplete_handshake_because_Agent_sends_port_03951072571/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 549s time="2024-03-27T02:29:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:35747\"" 549s time="2024-03-27T02:29:16Z" level=info msg="Connecting to control stream at \"127.0.0.1:37175\"" 549s time="2024-03-27T02:29:16Z" level=debug msg="Control stream: starting handshake" 549s time="2024-03-27T02:29:16Z" level=info msg="wslInstanceMockService: Received incoming connection" 549s time="2024-03-27T02:29:17Z" 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\"" 549s time="2024-03-27T02:29:17Z" level=info msg="connection with \"TEST_DISTRO\": mock error: dropping stream before sending port" 549s time="2024-03-27T02:29:17Z" level=info msg="wslInstanceMockService: dropped connection" 549s === CONT TestConnect/Error_because_WindowsForwardedLocalhost_returns_error 549s time="2024-03-27T02:29:17Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectIncomplete_handshake_because_Agent_never_sends_a_port3210588161/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 549s time="2024-03-27T02:29:17Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:40101\"" 549s time="2024-03-27T02:29:18Z" level=info msg="Connecting to control stream at \"127.0.0.1:35747\"" 549s time="2024-03-27T02:29:18Z" level=debug msg="Control stream: starting handshake" 549s time="2024-03-27T02:29:18Z" level=info msg="wslInstanceMockService: Received incoming connection" 549s time="2024-03-27T02:29:18Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 549s time="2024-03-27T02:29:18Z" level=info msg="wslInstanceMockService: dropped connection" 549s === CONT TestConnect/No_connection_because_of_unreadable_port_file 549s time="2024-03-27T02:29:21Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34639\"" 549s === CONT TestConnect/No_connection_because_port_file_does_not_exist 549s time="2024-03-27T02:29:23Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectError_because_WindowsForwardedLocalhost_returns_error2448537103/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 549s time="2024-03-27T02:29:23Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43199\"" 549s === CONT TestConnect/No_connection_because_of_empty_port_file 549s time="2024-03-27T02:29:27Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44525\"" 549s time="2024-03-27T02:29:29Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_port_file_does_not_exist1501899469/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 549s === CONT TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name 549s time="2024-03-27T02:29:29Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:35527\"" 549s time="2024-03-27T02:29:32Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:42907: connect: connection refused" 549s === CONT TestConnect/No_connection_because_of_port_file_contains_the_wrong_port 549s time="2024-03-27T02:29:33Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_empty_port_file3648219799/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 549s time="2024-03-27T02:29:33Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:35035\"" 549s time="2024-03-27T02:29:35Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:45575: connect: connection refused" 549s time="2024-03-27T02:29:37Z" 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: " 549s time="2024-03-27T02:29:37Z" level=info msg="Connecting to control stream at \"127.0.0.1:35527\"" 549s time="2024-03-27T02:29:37Z" level=debug msg="Control stream: starting handshake" 549s time="2024-03-27T02:29:37Z" level=info msg="wslInstanceMockService: Received incoming connection" 549s time="2024-03-27T02:29:38Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:0: connect: connection refused" 549s time="2024-03-27T02:29:39Z" level=warning msg="wslInstanceMockService: dropped connection: new connection: did not receive info from WSL distro: rpc error: code = Canceled desc = context canceled" 549s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 549s time="2024-03-27T02:29:39Z" level=info msg="Connecting to control stream at \"127.0.0.1:40787\"" 549s --- PASS: TestConnect (0.00s) 549s --- PASS: TestConnect/No_connection_because_of_port_file_with_invalid_contents (5.11s) 549s --- PASS: TestConnect/Success (7.65s) 549s --- PASS: TestConnect/Incomplete_handshake_because_Agent_sends_port_:0 (5.85s) 549s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_sends_a_port (6.44s) 549s --- PASS: TestConnect/Incomplete_handshake_because_Agent_never_receives (7.78s) 549s --- PASS: TestConnect/Error_because_WindowsForwardedLocalhost_returns_error (5.78s) 549s --- PASS: TestConnect/No_connection_because_of_unreadable_port_file (5.99s) 549s --- PASS: TestConnect/No_connection_because_port_file_does_not_exist (5.83s) 549s --- PASS: TestConnect/No_connection_because_of_empty_port_file (5.70s) 549s --- PASS: TestConnect/Error_when_system_cannot_retrieve_the_WSL_distro_name (9.85s) 549s --- PASS: TestConnect/No_connection_because_of_port_file_contains_the_wrong_port (6.09s) 549s time="2024-03-27T02:29:39Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestConnectNo_connection_because_of_port_file_contains_the_wrong_port3215596640/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 549s time="2024-03-27T02:29:42Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39361\"" 549s time="2024-03-27T02:29:42Z" level=info msg="Connecting to control stream at \"127.0.0.1:39361\"" 549s time="2024-03-27T02:29:42Z" level=debug msg="Control stream: starting handshake" 549s time="2024-03-27T02:29:42Z" level=info msg="wslInstanceMockService: Received incoming connection" 549s time="2024-03-27T02:29:43Z" 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\"" 549s time="2024-03-27T02:29:43Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 41555" 549s time="2024-03-27T02:29:43Z" level=debug msg="Control stream: completed handshake" 549s time="2024-03-27T02:29:43Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:36089\"" 549s time="2024-03-27T02:29:44Z" level=info msg="Connecting to control stream at \"127.0.0.1:36089\"" 549s time="2024-03-27T02:29:44Z" level=debug msg="Control stream: starting handshake" 549s time="2024-03-27T02:29:44Z" level=info msg="wslInstanceMockService: Received incoming connection" 549s time="2024-03-27T02:29:45Z" 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\"" 549s time="2024-03-27T02:29:45Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 45111" 549s time="2024-03-27T02:29:45Z" level=debug msg="Control stream: completed handshake" 549s --- PASS: TestReconnection (0.00s) 549s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (7.23s) 549s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (5.79s) 549s PASS 549s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/controlstream 55.576s 585s ? github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/testutils [no test files] 699s === RUN TestNew 699s === PAUSE TestNew 699s === RUN TestServe 699s === PAUSE TestServe 699s === RUN TestServeAndQuit 699s === PAUSE TestServeAndQuit 699s === RUN TestReconnection 699s === PAUSE TestReconnection 699s === RUN TestWithProMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithProMock (0.00s) 699s === RUN TestWithWslPathMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithWslPathMock (0.00s) 699s === RUN TestWithWslInfoMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithWslInfoMock (0.00s) 699s === RUN TestWithCmdExeMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithCmdExeMock (0.00s) 699s === CONT TestNew 699s === RUN TestNew/Success 699s === PAUSE TestNew/Success 699s === RUN TestNew/Error_when_WslPath_returns_error 699s === PAUSE TestNew/Error_when_WslPath_returns_error 699s === CONT TestServeAndQuit 699s === RUN TestServeAndQuit/Success_with_graceful_quit 699s === PAUSE TestServeAndQuit/Success_with_graceful_quit 699s === RUN TestServeAndQuit/Success_with_forceful_quit 699s === PAUSE TestServeAndQuit/Success_with_forceful_quit 699s === RUN TestServeAndQuit/Success_with_double_quit 699s === PAUSE TestServeAndQuit/Success_with_double_quit 699s === RUN TestServeAndQuit/Error_due_to_quitting_before_serving 699s === PAUSE TestServeAndQuit/Error_due_to_quitting_before_serving 699s === CONT TestReconnection 699s === RUN TestReconnection/Success_connecting_after_failing_to_connect 699s === PAUSE TestReconnection/Success_connecting_after_failing_to_connect 699s === RUN TestReconnection/Success_connecting_after_previous_connection_dropped 699s === PAUSE TestReconnection/Success_connecting_after_previous_connection_dropped 699s === CONT TestServe 699s === RUN TestServe/Error_because_WindowsHostAddress_returns_error 699s === PAUSE TestServe/Error_because_WindowsHostAddress_returns_error 699s === RUN TestServe/Error_because_of_context_cancelled 699s === PAUSE TestServe/Error_because_of_context_cancelled 699s === RUN TestServe/Success 699s === PAUSE TestServe/Success 699s === RUN TestServe/Success_with_systemd_notifier_returning_true 699s === PAUSE TestServe/Success_with_systemd_notifier_returning_true 699s === RUN TestServe/No_connection_because_port_file_does_not_exist 699s === PAUSE TestServe/No_connection_because_port_file_does_not_exist 699s === RUN TestServe/No_connection_because_of_faulty_agent 699s === PAUSE TestServe/No_connection_because_of_faulty_agent 699s === RUN TestServe/Error_because_of_notifier_returning_error 699s === PAUSE TestServe/Error_because_of_notifier_returning_error 699s === CONT TestServeAndQuit/Success_with_graceful_quit 699s === CONT TestReconnection/Success_connecting_after_failing_to_connect 699s time="2024-03-27T02:29:02Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29:02Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29:02Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:38783\"" 699s time="2024-03-27T02:29:06Z" level=debug msg="Ready state sent to systemd" 699s time="2024-03-27T02:29:06Z" level=debug msg="Ready state sent to systemd" 699s time="2024-03-27T02:29:08Z" 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_connect2865832500/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect2865832500/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 699s time="2024-03-27T02:29:08Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 699s time="2024-03-27T02:29:08Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:39039\"" 699s time="2024-03-27T02:29:08Z" level=info msg="Connecting to control stream at \"127.0.0.1:38783\"" 699s time="2024-03-27T02:29:08Z" level=debug msg="Control stream: starting handshake" 699s time="2024-03-27T02:29:08Z" level=info msg="wslInstanceMockService: Received incoming connection" 699s time="2024-03-27T02:29:09Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 699s time="2024-03-27T02:29:09Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 36173" 699s time="2024-03-27T02:29:09Z" level=debug msg="Control stream: completed handshake" 699s time="2024-03-27T02:29:09Z" level=info msg="Connected to control stream" 699s time="2024-03-27T02:29:09Z" level=debug msg="Starting to serve gRPC requests" 699s time="2024-03-27T02:29:09Z" level=info msg="Serving gRPC requests on localhost:36173" 699s time="2024-03-27T02:29:09Z" level=debug msg="Updated systemd status to \"Serving\"" 699s time="2024-03-27T02:29:10Z" level=info msg="Retrying connection to control stream" 699s time="2024-03-27T02:29:10Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 699s time="2024-03-27T02:29:10Z" level=info msg="Stopping daemon requested." 699s time="2024-03-27T02:29:10Z" level=info msg="Waiting for active requests to close." 699s time="2024-03-27T02:29:10Z" level=debug msg="Updated systemd status to \"Stopped\"" 699s time="2024-03-27T02:29:10Z" level=debug msg="All connections have now ended." 699s time="2024-03-27T02:29:10Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_graceful_quit2607472619/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 699s === CONT TestServeAndQuit/Error_due_to_quitting_before_serving 699s time="2024-03-27T02:29:10Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29:10Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45105\"" 699s time="2024-03-27T02:29:11Z" level=info msg="Connecting to control stream at \"127.0.0.1:39039\"" 699s time="2024-03-27T02:29:11Z" level=debug msg="Control stream: starting handshake" 699s time="2024-03-27T02:29:11Z" level=info msg="wslInstanceMockService: Received incoming connection" 699s time="2024-03-27T02:29:13Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 699s time="2024-03-27T02:29:13Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 34847" 699s time="2024-03-27T02:29:13Z" level=debug msg="Control stream: completed handshake" 699s time="2024-03-27T02:29:13Z" level=info msg="Connected to control stream" 699s time="2024-03-27T02:29:13Z" level=debug msg="Starting to serve gRPC requests" 699s time="2024-03-27T02:29:13Z" level=info msg="Serving gRPC requests on localhost:34847" 699s time="2024-03-27T02:29:13Z" level=debug msg="Updated systemd status to \"Serving\"" 699s time="2024-03-27T02:29:13Z" level=debug msg="Updated systemd status to \"Stopped\"" 699s === CONT TestServeAndQuit/Success_with_double_quit 699s time="2024-03-27T02:29:13Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:44143\"" 699s time="2024-03-27T02:29:13Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29:16Z" level=debug msg="Ready state sent to systemd" 699s time="2024-03-27T02:29:18Z" level=info msg="Connecting to control stream at \"127.0.0.1:44143\"" 699s time="2024-03-27T02:29:18Z" level=debug msg="Control stream: starting handshake" 699s time="2024-03-27T02:29:18Z" level=info msg="wslInstanceMockService: Received incoming connection" 699s time="2024-03-27T02:29:18Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 699s time="2024-03-27T02:29:19Z" level=info msg="Stopping daemon requested." 699s time="2024-03-27T02:29:19Z" level=error msg="Serve error: lost connection to Windows Agent" 699s time="2024-03-27T02:29:19Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 699s time="2024-03-27T02:29:19Z" level=debug msg="Updated systemd status to \"Stopped\"" 699s === CONT TestServeAndQuit/Success_with_forceful_quit 699s time="2024-03-27T02:29:19Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestReconnectionSuccess_connecting_after_failing_to_connect2865832500/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 699s time="2024-03-27T02:29:19Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 699s time="2024-03-27T02:29:19Z" level=info msg="wslInstanceMockService: dropped connection" 699s time="2024-03-27T02:29:19Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29:19Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33709\"" 699s time="2024-03-27T02:29:21Z" 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\"" 699s time="2024-03-27T02:29:21Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 40887" 699s time="2024-03-27T02:29:21Z" level=debug msg="Control stream: completed handshake" 699s time="2024-03-27T02:29:21Z" level=info msg="Connected to control stream" 699s time="2024-03-27T02:29:21Z" level=debug msg="Starting to serve gRPC requests" 699s time="2024-03-27T02:29:21Z" level=info msg="Serving gRPC requests on localhost:40887" 699s time="2024-03-27T02:29:21Z" level=debug msg="Updated systemd status to \"Serving\"" 699s time="2024-03-27T02:29:22Z" level=info msg="Stopping daemon requested." 699s time="2024-03-27T02:29:22Z" level=info msg="Waiting for active requests to close." 699s time="2024-03-27T02:29:22Z" level=debug msg="Updated systemd status to \"Stopped\"" 699s time="2024-03-27T02:29:22Z" level=debug msg="All connections have now ended." 699s time="2024-03-27T02:29:22Z" level=info msg="Stopping daemon requested." 699s time="2024-03-27T02:29:22Z" level=info msg="Waiting for active requests to close." 699s time="2024-03-27T02:29:22Z" level=debug msg="All connections have now ended." 699s === CONT TestServe/Error_because_WindowsHostAddress_returns_error 699s time="2024-03-27T02:29:22Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29:22Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_double_quit3076811762/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 699s time="2024-03-27T02:29:22Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:34163\"" 699s time="2024-03-27T02:29:23Z" level=debug msg="Ready state sent to systemd" 699s time="2024-03-27T02:29:25Z" level=info msg="Connecting to control stream at \"127.0.0.1:33709\"" 699s time="2024-03-27T02:29:25Z" level=debug msg="Control stream: starting handshake" 699s time="2024-03-27T02:29:25Z" level=info msg="wslInstanceMockService: Received incoming connection" 699s === CONT TestReconnection/Success_connecting_after_previous_connection_dropped 699s time="2024-03-27T02:29:27Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29: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\"" 699s time="2024-03-27T02:29:27Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 44001" 699s time="2024-03-27T02:29:27Z" level=debug msg="Control stream: completed handshake" 699s time="2024-03-27T02:29:27Z" level=info msg="Connected to control stream" 699s time="2024-03-27T02:29:27Z" level=debug msg="Starting to serve gRPC requests" 699s time="2024-03-27T02:29:27Z" level=info msg="Serving gRPC requests on localhost:44001" 699s time="2024-03-27T02:29:27Z" level=debug msg="Updated systemd status to \"Serving\"" 699s time="2024-03-27T02:29:27Z" level=info msg="Stopping daemon requested." 699s time="2024-03-27T02:29:27Z" level=debug msg="Updated systemd status to \"Stopped\"" 699s --- PASS: TestServeAndQuit (0.00s) 699s --- PASS: TestServeAndQuit/Success_with_graceful_quit (7.73s) 699s --- PASS: TestServeAndQuit/Error_due_to_quitting_before_serving (3.65s) 699s --- PASS: TestServeAndQuit/Success_with_double_quit (8.12s) 699s --- PASS: TestServeAndQuit/Success_with_forceful_quit (8.33s) 699s time="2024-03-27T02:29:27Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeAndQuitSuccess_with_forceful_quit3124506375/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 699s === CONT TestNew/Success 699s time="2024-03-27T02:29:27Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29:31Z" level=debug msg="Ready state sent to systemd" 699s time="2024-03-27T02:29:31Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:43185\"" 699s === CONT TestServe/Error_because_of_notifier_returning_error 699s time="2024-03-27T02:29:31Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45395\"" 699s time="2024-03-27T02:29:31Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29:33Z" level=info msg="Connecting to control stream at \"127.0.0.1:43185\"" 699s time="2024-03-27T02:29:33Z" level=debug msg="Control stream: starting handshake" 699s time="2024-03-27T02:29:33Z" level=info msg="wslInstanceMockService: Received incoming connection" 699s time="2024-03-27T02:29:35Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:36173: connect: connection refused" 699s time="2024-03-27T02:29:35Z" level=info msg="Stopping daemon requested." 699s time="2024-03-27T02:29:36Z" level=warning msg="Could not change systemd status: couldn't update status to systemd: mock error" 699s === CONT TestServe/No_connection_because_of_faulty_agent 699s time="2024-03-27T02:29:36Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeError_because_of_notifier_returning_error537189897/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 699s time="2024-03-27T02:29:36Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 699s time="2024-03-27T02:29:36Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29:36Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 34957" 699s time="2024-03-27T02:29:36Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:33085\"" 699s time="2024-03-27T02:29:36Z" level=debug msg="Control stream: completed handshake" 699s time="2024-03-27T02:29:36Z" level=info msg="Connected to control stream" 699s time="2024-03-27T02:29:36Z" level=debug msg="Starting to serve gRPC requests" 699s time="2024-03-27T02:29:36Z" level=info msg="Serving gRPC requests on localhost:34957" 699s time="2024-03-27T02:29:36Z" level=debug msg="Updated systemd status to \"Serving\"" 699s time="2024-03-27T02:29:37Z" level=error msg="Serve error: lost connection to Windows Agent" 699s time="2024-03-27T02:29:37Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 699s time="2024-03-27T02:29:37Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45771\"" 699s time="2024-03-27T02:29:39Z" level=info msg="Retrying connection to control stream" 699s time="2024-03-27T02:29:39Z" level=debug msg="Updated systemd status to \"Not serving: retrying\"" 699s time="2024-03-27T02:29:40Z" level=info msg="Connecting to control stream at \"127.0.0.1:45771\"" 699s time="2024-03-27T02:29:40Z" level=debug msg="Control stream: starting handshake" 699s time="2024-03-27T02:29:40Z" level=info msg="wslInstanceMockService: Received incoming connection" 699s time="2024-03-27T02:29:41Z" level=info msg="Connecting to control stream at \"127.0.0.1:33085\"" 699s time="2024-03-27T02:29:41Z" level=debug msg="Control stream: starting handshake" 699s time="2024-03-27T02:29:41Z" level=info msg="wslInstanceMockService: Received incoming connection" 699s time="2024-03-27T02:29:41Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 699s time="2024-03-27T02:29:41Z" level=info msg="wslInstanceMockService: dropped connection" 699s time="2024-03-27T02:29:42Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 699s time="2024-03-27T02:29:42Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 44363" 699s time="2024-03-27T02:29:42Z" level=debug msg="Control stream: completed handshake" 699s time="2024-03-27T02:29:42Z" level=info msg="Connected to control stream" 699s time="2024-03-27T02:29:42Z" level=debug msg="Starting to serve gRPC requests" 699s time="2024-03-27T02:29:42Z" level=info msg="Serving gRPC requests on localhost:44363" 699s time="2024-03-27T02:29:42Z" level=debug msg="Updated systemd status to \"Serving\"" 699s time="2024-03-27T02:29: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" 699s time="2024-03-27T02:29:44Z" level=info msg="Retrying connection to control stream" 699s time="2024-03-27T02:29:45Z" level=info msg="Connecting to control stream at \"127.0.0.1:33085\"" 699s time="2024-03-27T02:29:45Z" level=debug msg="Control stream: starting handshake" 699s time="2024-03-27T02:29:45Z" level=info msg="wslInstanceMockService: Received incoming connection" 699s time="2024-03-27T02:29:45Z" level=info msg="wslInstanceMockService: mock error: dropping stream before first Recv" 699s time="2024-03-27T02:29:45Z" level=info msg="wslInstanceMockService: dropped connection" 699s time="2024-03-27T02:29:46Z" level=info msg="Stopping daemon requested." 699s time="2024-03-27T02:29:46Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:40887: connect: connection refused" 699s time="2024-03-27T02:29:47Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 699s time="2024-03-27T02:29:47Z" 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" 699s time="2024-03-27T02:29:47Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 699s time="2024-03-27T02:29:47Z" level=info msg="wslInstanceMockService: dropped connection" 699s time="2024-03-27T02:29:47Z" level=info msg="Stopping daemon requested." 699s time="2024-03-27T02:29:47Z" level=error msg="Serve error: lost connection to Windows Agent" 699s time="2024-03-27T02:29:47Z" level=debug msg="Updated systemd status to \"Not serving: waiting to retry\"" 699s time="2024-03-27T02:29:47Z" level=debug msg="Updated systemd status to \"Stopped\"" 699s --- PASS: TestReconnection (0.00s) 699s --- PASS: TestReconnection/Success_connecting_after_failing_to_connect (16.76s) 699s --- PASS: TestReconnection/Success_connecting_after_previous_connection_dropped (20.03s) 699s === CONT TestServe/No_connection_because_port_file_does_not_exist 699s time="2024-03-27T02:29:47Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29:47Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42547\"" 699s time="2024-03-27T02:29:49Z" level=info msg="Stopping daemon requested." 699s === CONT TestServe/Success_with_systemd_notifier_returning_true 699s time="2024-03-27T02:29:49Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:29:49Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:42201\"" 699s time="2024-03-27T02:29:52Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:44001: connect: connection refused" 699s time="2024-03-27T02:29:53Z" 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_exist220737429/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist220737429/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 699s time="2024-03-27T02:29:53Z" level=debug msg="Ready state sent to systemd" 699s time="2024-03-27T02:29:54Z" level=info msg="Connecting to control stream at \"127.0.0.1:42201\"" 699s time="2024-03-27T02:29:54Z" level=debug msg="Control stream: starting handshake" 699s time="2024-03-27T02:29:54Z" level=info msg="wslInstanceMockService: Received incoming connection" 699s time="2024-03-27T02:29:55Z" level=info msg="Retrying connection to control stream" 699s time="2024-03-27T02:29:56Z" 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\"" 699s time="2024-03-27T02:29:56Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 46101" 699s time="2024-03-27T02:29:56Z" level=debug msg="Control stream: completed handshake" 699s time="2024-03-27T02:29:56Z" level=info msg="Connected to control stream" 699s time="2024-03-27T02:29:56Z" level=debug msg="Starting to serve gRPC requests" 699s time="2024-03-27T02:29:56Z" level=info msg="Serving gRPC requests on localhost:46101" 699s time="2024-03-27T02:29:56Z" level=debug msg="Updated systemd status to \"Serving\"" 699s time="2024-03-27T02:29:56Z" 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_exist220737429/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist220737429/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 699s time="2024-03-27T02:30:00Z" level=info msg="Retrying connection to control stream" 699s time="2024-03-27T02:30:01Z" level=info msg="Stopping daemon requested." 699s time="2024-03-27T02:30:01Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 699s time="2024-03-27T02:30:01Z" level=warning msg="wslInstanceMockService: dropped connection: wslInstanceMockService: could not dial \"TEST_DISTRO\": dial tcp4 127.0.0.1:34957: connect: connection refused" 699s time="2024-03-27T02:30:02Z" 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_exist220737429/001/mnt/d/Users/TestUser/.ubuntupro/.address\": open /tmp/TestServeNo_connection_because_port_file_does_not_exist220737429/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 699s === CONT TestServe/Success 699s time="2024-03-27T02:30:02Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:30:02Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeNo_connection_because_port_file_does_not_exist220737429/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 699s time="2024-03-27T02:30:02Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:41867\"" 699s time="2024-03-27T02:30:03Z" level=info msg="Stopping daemon requested." 699s time="2024-03-27T02:30:07Z" level=info msg="Connecting to control stream at \"127.0.0.1:41867\"" 699s time="2024-03-27T02:30:07Z" level=debug msg="Updated systemd status to \"Stopped\"" 699s time="2024-03-27T02:30:07Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 699s time="2024-03-27T02:30:07Z" level=info msg="wslInstanceMockService: dropped connection" 699s === CONT TestServe/Error_because_of_context_cancelled 699s time="2024-03-27T02:30:07Z" level=debug msg="Building new daemon" 699s time="2024-03-27T02:30:07Z" level=info msg="MockWindowsAgent: Remove address file returned an error: remove /tmp/TestServeSuccess_with_systemd_notifier_returning_true1192526072/001/mnt/d/Users/TestUser/.ubuntupro/.address: no such file or directory" 699s time="2024-03-27T02:30:07Z" level=info msg="MockWindowsAgent: Windows-agent mock serving on \"127.0.0.1:45365\"" 699s time="2024-03-27T02:30:07Z" level=debug msg="Control stream: starting handshake" 699s time="2024-03-27T02:30:07Z" level=info msg="wslInstanceMockService: Received incoming connection" 699s time="2024-03-27T02:30:09Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": received info: wsl_name:\"TEST_DISTRO\" id:\"ubuntu\" version_id:\"22.04\" pretty_name:\"Ubuntu 22.04.1 LTS\" hostname:\"TEST_DISTRO_HOSTNAME\"" 699s time="2024-03-27T02:30:09Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": Reserved port 38547" 699s time="2024-03-27T02:30:09Z" level=debug msg="Control stream: completed handshake" 699s time="2024-03-27T02:30:09Z" level=info msg="Connected to control stream" 699s time="2024-03-27T02:30:09Z" level=debug msg="Starting to serve gRPC requests" 699s time="2024-03-27T02:30:09Z" level=info msg="Serving gRPC requests on localhost:38547" 699s === CONT TestNew/Error_when_WslPath_returns_error 699s time="2024-03-27T02:30:11Z" level=debug msg="Building new daemon" 699s --- PASS: TestNew (0.00s) 699s --- PASS: TestNew/Success (3.97s) 699s --- PASS: TestNew/Error_when_WslPath_returns_error (2.75s) 699s time="2024-03-27T02:30:14Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\": connected back via reserved port" 699s time="2024-03-27T02:30:15Z" level=info msg="Stopping daemon requested." 699s time="2024-03-27T02:32:14Z" level=info msg="wslInstanceMockService: Connection with \"TEST_DISTRO\" ended: rpc error: code = Canceled desc = context canceled" 699s time="2024-03-27T02:32:14Z" level=info msg="wslInstanceMockService: dropped connection" 699s --- PASS: TestServe (0.00s) 699s --- PASS: TestServe/Error_because_WindowsHostAddress_returns_error (5.52s) 699s --- PASS: TestServe/Error_because_of_notifier_returning_error (4.93s) 699s --- PASS: TestServe/No_connection_because_of_faulty_agent (13.03s) 699s --- PASS: TestServe/No_connection_because_port_file_does_not_exist (14.55s) 699s --- PASS: TestServe/Success_with_systemd_notifier_returning_true (18.05s) 699s --- PASS: TestServe/Error_because_of_context_cancelled (3.50s) 699s --- PASS: TestServe/Success (132.30s) 699s PASS 699s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/daemon 191.942s 699s === RUN TestInfo 699s === PAUSE TestInfo 699s === RUN TestWslDistroName 699s === PAUSE TestWslDistroName 699s === RUN TestUserProfileDir 699s === PAUSE TestUserProfileDir 699s === RUN TestProStatus 699s === PAUSE TestProStatus 699s === RUN TestProAttach 699s === PAUSE TestProAttach 699s === RUN TestProDetach 699s === PAUSE TestProDetach 699s === RUN TestLandscapeEnable 699s === PAUSE TestLandscapeEnable 699s === RUN TestWindowsHostAddress 699s === PAUSE TestWindowsHostAddress 699s === RUN TestLandscapeDisable 699s === PAUSE TestLandscapeDisable 699s === RUN TestWithProMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithProMock (0.00s) 699s === RUN TestWithLandscapeConfigMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithLandscapeConfigMock (0.00s) 699s === RUN TestWithWslPathMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithWslPathMock (0.00s) 699s === RUN TestWithWslInfoMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithWslInfoMock (0.00s) 699s === RUN TestWithCmdExeMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithCmdExeMock (0.00s) 699s === CONT TestInfo 699s === RUN TestInfo/Error_when_hostname_cannot_be_obtained 699s === PAUSE TestInfo/Error_when_hostname_cannot_be_obtained 699s === RUN TestInfo/Success 699s === PAUSE TestInfo/Success 699s === CONT TestProDetach 699s === RUN TestInfo/Error_when_WslDistroName_fails 699s === PAUSE TestInfo/Error_when_WslDistroName_fails 699s === RUN TestInfo/Error_when_pro_status_command_fails 699s === PAUSE TestInfo/Error_when_pro_status_command_fails 699s === RUN TestInfo/Error_when_pro_status_output_cannot_be_parsed 699s === PAUSE TestInfo/Error_when_pro_status_output_cannot_be_parsed 699s === RUN TestInfo/Error_when_/etc/os-release_cannot_be_read 699s === PAUSE TestInfo/Error_when_/etc/os-release_cannot_be_read 699s === RUN TestInfo/Error_whem_/etc/os-release_returns_bad_contents 699s === PAUSE TestInfo/Error_whem_/etc/os-release_returns_bad_contents 699s === RUN TestProDetach/success_on_unattached_distro 699s === PAUSE TestProDetach/success_on_unattached_distro 699s === RUN TestProDetach/success_on_attached_distro 699s === CONT TestLandscapeDisable 699s === PAUSE TestProDetach/success_on_attached_distro 699s === RUN TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 699s === RUN TestLandscapeDisable/Success 699s === PAUSE TestLandscapeDisable/Success 699s === RUN TestLandscapeDisable/Error_when_the_landscape-config_command_fails 699s === PAUSE TestLandscapeDisable/Error_when_the_landscape-config_command_fails 699s === CONT TestWindowsHostAddress 699s === RUN TestWindowsHostAddress/Success_without_NAT 699s === PAUSE TestWindowsHostAddress/Success_without_NAT 699s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 699s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 699s === RUN TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 699s === PAUSE TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 699s === RUN TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 699s === PAUSE TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 699s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 699s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 699s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 699s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 699s === RUN TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 699s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 699s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 699s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 699s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 699s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 699s === RUN TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 699s === PAUSE TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 699s === CONT TestLandscapeEnable 699s === RUN TestLandscapeEnable/Success 699s === PAUSE TestLandscapeEnable/Success 699s === RUN TestLandscapeEnable/Success_overriding_computer_title 699s === PAUSE TestLandscapeEnable/Success_overriding_computer_title 699s === RUN TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 699s === PAUSE TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 699s === RUN TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 699s === PAUSE TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 699s === RUN TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 699s === PAUSE TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 699s === RUN TestLandscapeEnable/Error_when_the_landscape-config_command_fails 699s === PAUSE TestLandscapeEnable/Error_when_the_landscape-config_command_fails 699s === RUN TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 699s === PAUSE TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 699s === CONT TestProStatus 699s === RUN TestProStatus/success_on_unattached_distro 699s === PAUSE TestProStatus/success_on_unattached_distro 699s === RUN TestProStatus/success_on_attached_distro 699s === PAUSE TestProStatus/success_on_attached_distro 699s === RUN TestProStatus/error_on_'pro_attach'_returning_bad_output 699s === PAUSE TestProStatus/error_on_'pro_attach'_returning_bad_output 699s === RUN TestProStatus/error_on_'pro_attach'_error 699s === PAUSE TestProStatus/error_on_'pro_attach'_error 699s === CONT TestProAttach 699s === RUN TestProAttach/success 699s === PAUSE TestProAttach/success 699s === RUN TestProAttach/error_on_'pro_attach'_error 699s === PAUSE TestProAttach/error_on_'pro_attach'_error 699s === CONT TestUserProfileDir 699s === RUN TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 699s === PAUSE TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 699s === RUN TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 699s === PAUSE TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 699s === RUN TestUserProfileDir/Error_on_wslpath_error 699s === PAUSE TestUserProfileDir/Error_on_wslpath_error 699s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 699s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 699s === RUN TestUserProfileDir/Error_when_cmd.exe_does_not_exist 699s === PAUSE TestUserProfileDir/Error_when_cmd.exe_does_not_exist 699s === RUN TestUserProfileDir/Error_on_cmd.exe_error 699s === PAUSE TestUserProfileDir/Error_on_cmd.exe_error 699s === RUN TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 699s === PAUSE TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 699s === RUN TestUserProfileDir/Success_with_cached_cmd.exe_path 699s === PAUSE TestUserProfileDir/Success_with_cached_cmd.exe_path 699s === RUN TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 699s === PAUSE TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 699s === RUN TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 699s === PAUSE TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 699s === CONT TestWslDistroName 699s === RUN TestWslDistroName/Success_using_wslpath 699s === PAUSE TestWslDistroName/Success_using_wslpath 699s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 699s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 699s === RUN TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 699s === PAUSE TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 699s === RUN TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 699s === PAUSE TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 699s === CONT TestInfo/Error_when_hostname_cannot_be_obtained 699s === PAUSE TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 699s === RUN TestProDetach/error_on_'pro_detach'_error_and_some_reason 699s === PAUSE TestProDetach/error_on_'pro_detach'_error_and_some_reason 699s === RUN TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 699s === PAUSE TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 699s === CONT TestInfo/Error_when_pro_status_output_cannot_be_parsed 699s === CONT TestInfo/Error_whem_/etc/os-release_returns_bad_contents 699s === CONT TestInfo/Error_when_/etc/os-release_cannot_be_read 699s === CONT TestInfo/Error_when_WslDistroName_fails 699s === CONT TestInfo/Error_when_pro_status_command_fails 699s === CONT TestInfo/Success 699s === CONT TestLandscapeDisable/Success 699s --- PASS: TestInfo (0.00s) 699s --- PASS: TestInfo/Error_when_pro_status_output_cannot_be_parsed (2.13s) 699s --- PASS: TestInfo/Error_when_hostname_cannot_be_obtained (2.14s) 699s --- PASS: TestInfo/Error_when_/etc/os-release_cannot_be_read (1.34s) 699s --- PASS: TestInfo/Error_whem_/etc/os-release_returns_bad_contents (1.70s) 699s --- PASS: TestInfo/Error_when_WslDistroName_fails (1.69s) 699s --- PASS: TestInfo/Error_when_pro_status_command_fails (1.90s) 699s --- PASS: TestInfo/Success (1.90s) 699s === CONT TestWindowsHostAddress/Success_without_NAT 699s === CONT TestLandscapeDisable/Error_when_the_landscape-config_command_fails 699s === CONT TestLandscapeEnable/Success 699s --- PASS: TestLandscapeDisable (0.00s) 699s --- PASS: TestLandscapeDisable/Success (1.52s) 699s --- PASS: TestLandscapeDisable/Error_when_the_landscape-config_command_fails (1.35s) 699s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP 699s === NAME TestLandscapeEnable/Success 699s system_test.go:456: testdata/TestLandscapeEnable/golden/success 699s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed 699s === CONT TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist 699s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP 699s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist 699s === CONT TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed 699s === CONT TestWindowsHostAddress/Error_when_wslinfo_returns_an_error 699s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback 699s === CONT TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback 699s === CONT TestProStatus/success_on_unattached_distro 699s --- PASS: TestWindowsHostAddress (0.00s) 699s --- PASS: TestWindowsHostAddress/Success_without_NAT (1.52s) 699s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_has_an_ill-formed_IP (1.84s) 699s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_is_ill-formed (1.04s) 699s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/proc/net/route_does_not_exist (1.00s) 699s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_has_an_ill-formed_IP (0.95s) 699s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_does_not_exist (0.99s) 699s --- PASS: TestWindowsHostAddress/Error_when_wslinfo_returns_an_error (0.89s) 699s --- PASS: TestWindowsHostAddress/Error_with_NAT_when_/etc/resolv.conf_is_ill-formed (0.96s) 699s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_not_loopback (0.93s) 699s --- PASS: TestWindowsHostAddress/Success_with_NAT,_nameserver_is_loopback (0.97s) 699s === CONT TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path 699s === CONT TestLandscapeEnable/Error_when_the_landscape-config_command_fails 699s === CONT TestLandscapeEnable/Error_when_the_config_file_cannot_be_written 699s === CONT TestLandscapeEnable/Error_when_the_file_cannot_be_parsed 699s === CONT TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 699s === CONT TestLandscapeEnable/Success_overriding_computer_title 699s time="2024-03-27T02:30:03Z" level=info msg="Landscape config contains key \"computer_title\". Its value will be overridden with TEST_DISTRO" 699s === NAME TestLandscapeEnable/Success_overriding_the_SSL_certficate_path 699s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_the_ssl_certficate_path 699s === CONT TestProAttach/success 699s === NAME TestLandscapeEnable/Success_overriding_computer_title 699s system_test.go:456: testdata/TestLandscapeEnable/golden/success_overriding_computer_title 699s --- PASS: TestLandscapeEnable (0.00s) 699s --- PASS: TestLandscapeEnable/Success (1.81s) 699s --- PASS: TestLandscapeEnable/Error_when_failing_to_override_the_SSL_certficate_path (1.42s) 699s --- PASS: TestLandscapeEnable/Error_when_the_config_file_cannot_be_written (0.01s) 699s --- PASS: TestLandscapeEnable/Error_when_the_file_cannot_be_parsed (0.00s) 699s --- PASS: TestLandscapeEnable/Error_when_the_landscape-config_command_fails (1.54s) 699s --- PASS: TestLandscapeEnable/Success_overriding_the_SSL_certficate_path (4.12s) 699s --- PASS: TestLandscapeEnable/Success_overriding_computer_title (2.60s) 699s === CONT TestProStatus/error_on_'pro_attach'_error 699s === CONT TestProStatus/error_on_'pro_attach'_returning_bad_output 699s === CONT TestProStatus/success_on_attached_distro 699s === CONT TestUserProfileDir/Success_with_a_single_9P_filesystem_mount 699s --- PASS: TestProStatus (0.00s) 699s --- PASS: TestProStatus/success_on_unattached_distro (1.42s) 699s --- PASS: TestProStatus/error_on_'pro_attach'_error (1.68s) 699s --- PASS: TestProStatus/error_on_'pro_attach'_returning_bad_output (1.90s) 699s --- PASS: TestProStatus/success_on_attached_distro (1.80s) 699s === CONT TestProAttach/error_on_'pro_attach'_error 699s --- PASS: TestProAttach (0.00s) 699s --- PASS: TestProAttach/success (1.52s) 699s --- PASS: TestProAttach/error_on_'pro_attach'_error (1.28s) 699s === CONT TestWslDistroName/Success_using_wslpath 699s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts 699s system_test.go:209: Removing default proc/mounts 699s === CONT TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts 699s === CONT TestUserProfileDir/Success_with_cached_cmd.exe_path 699s === CONT TestUserProfileDir/Error_when_wslpath_returns_a_bad_path 699s === CONT TestUserProfileDir/Error_on_cmd.exe_error 699s === CONT TestUserProfileDir/Error_when_cmd.exe_does_not_exist 699s === CONT TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts 699s system_test.go:209: Removing default proc/mounts 699s === CONT TestUserProfileDir/Error_on_wslpath_error 699s === CONT TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts 699s === CONT TestProDetach/success_on_unattached_distro 699s --- PASS: TestUserProfileDir (0.00s) 699s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_/proc/mounts (0.00s) 699s --- PASS: TestUserProfileDir/Success_with_a_single_9P_filesystem_mount (2.73s) 699s --- PASS: TestUserProfileDir/Success_with_multiple_9P_filesystem_mounts (2.45s) 699s --- PASS: TestUserProfileDir/Success_with_cached_cmd.exe_path (2.44s) 699s --- PASS: TestUserProfileDir/Error_on_cmd.exe_error (0.97s) 699s --- PASS: TestUserProfileDir/Error_when_cmd.exe_does_not_exist (0.00s) 699s --- PASS: TestUserProfileDir/Error_finding_cmd.exe_because_there_is_no_Windows_FS_in_/proc/mounts (0.00s) 699s --- PASS: TestUserProfileDir/Error_when_wslpath_returns_a_bad_path (1.88s) 699s --- PASS: TestUserProfileDir/Error_on_wslpath_error (1.85s) 699s --- PASS: TestUserProfileDir/Success_with_multiple_types_of_filesystem_mounts (1.80s) 699s === CONT TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME 699s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text 699s === CONT TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails 699s === CONT TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason 699s === CONT TestProDetach/error_on_'pro_detach'_error_with_bad_JSON 699s --- PASS: TestWslDistroName (0.00s) 699s --- PASS: TestWslDistroName/Success_using_wslpath (1.36s) 699s --- PASS: TestWslDistroName/Success_reading_from_WSL_DISTRO_NAME (0.00s) 699s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_returns_bad_text (0.94s) 699s --- PASS: TestWslDistroName/Error_when_WSL_DISTRO_NAME_is_empty_and_wslpath_fails (0.98s) 699s === CONT TestProDetach/error_on_'pro_detach'_error_and_some_reason 699s === CONT TestProDetach/success_on_attached_distro 699s --- PASS: TestProDetach (0.00s) 699s --- PASS: TestProDetach/success_on_unattached_distro (0.93s) 699s --- PASS: TestProDetach/error_on_'pro_detach'_returning_error_and_no_reason (0.95s) 699s --- PASS: TestProDetach/error_on_'pro_detach'_error_with_bad_JSON (0.89s) 699s --- PASS: TestProDetach/error_on_'pro_detach'_error_and_some_reason (0.95s) 699s --- PASS: TestProDetach/success_on_attached_distro (0.94s) 699s PASS 699s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/system 35.034s 699s === RUN TestApplyProToken 699s === PAUSE TestApplyProToken 699s === RUN TestApplyLandscapeConfig 699s === PAUSE TestApplyLandscapeConfig 699s === RUN TestWithProMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithProMock (0.00s) 699s === RUN TestWithLandscapeConfigMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithLandscapeConfigMock (0.00s) 699s === RUN TestWithWslPathMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithWslPathMock (0.00s) 699s === RUN TestWithWslInfoMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithWslInfoMock (0.00s) 699s === RUN TestWithCmdExeMock 699s mock_executables.go:576: Skipped because it is not a real test, but rather a mocked executable 699s --- SKIP: TestWithCmdExeMock (0.00s) 699s === CONT TestApplyProToken 699s === CONT TestApplyLandscapeConfig 699s === RUN TestApplyProToken/success_detaching_attached_machine 699s === PAUSE TestApplyProToken/success_detaching_attached_machine 699s === RUN TestApplyProToken/success_detaching_non-attached_machine 699s === PAUSE TestApplyProToken/success_detaching_non-attached_machine 699s === RUN TestApplyProToken/Error_calling_pro_attach 699s === PAUSE TestApplyProToken/Error_calling_pro_attach 699s === RUN TestApplyProToken/Error_calling_pro_status 699s === PAUSE TestApplyProToken/Error_calling_pro_status 699s === RUN TestApplyProToken/Error_getting_system_info 699s === PAUSE TestApplyProToken/Error_getting_system_info 699s === RUN TestApplyProToken/Error_cannot_send_info_to_stream 699s === PAUSE TestApplyProToken/Error_cannot_send_info_to_stream 699s === RUN TestApplyProToken/success_attaching_attached_machine 699s === RUN TestApplyLandscapeConfig/Success_enabling 699s === PAUSE TestApplyProToken/success_attaching_attached_machine 699s === PAUSE TestApplyLandscapeConfig/Success_enabling 699s === RUN TestApplyLandscapeConfig/Success_disabling 699s === PAUSE TestApplyLandscapeConfig/Success_disabling 699s === RUN TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 699s === PAUSE TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 699s === RUN TestApplyProToken/success_attaching_non-attached_machine 699s === PAUSE TestApplyProToken/success_attaching_non-attached_machine 699s === RUN TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 699s === PAUSE TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 699s === CONT TestApplyLandscapeConfig/Success_enabling 699s === RUN TestApplyProToken/Error_detaching_pro 699s === PAUSE TestApplyProToken/Error_detaching_pro 699s === CONT TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 699s time="2024-03-27T02:30:21Z" level=debug msg="Registering gRPC WSL instance service" 699s time="2024-03-27T02:30:21Z" level=debug msg="Registering gRPC WSL instance service" 699s === NAME TestApplyLandscapeConfig/Success_enabling 699s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:42831 699s === NAME TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails 699s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:38389 699s wslinstanceservice_test.go:167: Client connected to WslInstanceService 699s === NAME TestApplyLandscapeConfig/Success_enabling 699s wslinstanceservice_test.go:167: Client connected to WslInstanceService 699s time="2024-03-27T02:30:21Z" level=info msg="ApplyLandscapeConfig: received config: registering" 699s time="2024-03-27T02:30:21Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 699s === CONT TestApplyProToken/success_detaching_attached_machine 699s time="2024-03-27T02:30:22Z" level=debug msg="Registering gRPC WSL instance service" 699s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:35367 699s wslinstanceservice_test.go:108: Client connected to WslInstanceService 699s time="2024-03-27T02:30:22Z" level=info msg="ApplyProToken: Received empty token: detaching" 699s === NAME TestApplyLandscapeConfig/Success_enabling 699s wslinstanceservice_test.go:192: testdata/TestApplyLandscapeConfig/golden/success_enabling 699s === CONT TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails 699s time="2024-03-27T02:30:22Z" level=debug msg="Registering gRPC WSL instance service" 699s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:42429 699s wslinstanceservice_test.go:167: Client connected to WslInstanceService 699s time="2024-03-27T02:30:22Z" level=info msg="ApplyLandscapeConfig: received config: registering" 699s === CONT TestApplyLandscapeConfig/Success_disabling 699s time="2024-03-27T02:30:23Z" level=debug msg="Registering gRPC WSL instance service" 699s wslinstanceservice_test.go:167: Serving WslInstanceService on 127.0.0.1:46369 699s wslinstanceservice_test.go:167: Client connected to WslInstanceService 699s time="2024-03-27T02:30:23Z" level=info msg="ApplyLandscapeConfig: received empty config: disabling" 699s --- PASS: TestApplyLandscapeConfig (0.00s) 699s --- PASS: TestApplyLandscapeConfig/Error_disabling_when_landscape-config_--disable_fails (0.92s) 699s --- PASS: TestApplyLandscapeConfig/Success_enabling (0.95s) 699s --- PASS: TestApplyLandscapeConfig/Error_enabling_when_landscape-config_fails (0.92s) 699s --- PASS: TestApplyLandscapeConfig/Success_disabling (1.16s) 699s === CONT TestApplyProToken/Error_getting_system_info 699s time="2024-03-27T02:30:25Z" level=debug msg="Registering gRPC WSL instance service" 699s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:39003 699s === CONT TestApplyProToken/Error_detaching_pro 699s time="2024-03-27T02:30:25Z" level=debug msg="Registering gRPC WSL instance service" 699s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:44957 699s === NAME TestApplyProToken/Error_getting_system_info 699s wslinstanceservice_test.go:108: Client connected to WslInstanceService 699s time="2024-03-27T02:30:25Z" level=info msg="ApplyProToken: Received empty token: detaching" 699s === NAME TestApplyProToken/Error_detaching_pro 699s wslinstanceservice_test.go:108: Client connected to WslInstanceService 699s time="2024-03-27T02:30:25Z" level=info msg="ApplyProToken: Received empty token: detaching" 699s === CONT TestApplyProToken/success_attaching_non-attached_machine 699s time="2024-03-27T02:30:26Z" level=debug msg="Registering gRPC WSL instance service" 699s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:35581 699s wslinstanceservice_test.go:108: Client connected to WslInstanceService 699s time="2024-03-27T02:30:26Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 699s time="2024-03-27T02:30:27Z" 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_info2744001441/001/etc/os-release: no such file or directory" 699s === CONT TestApplyProToken/success_attaching_attached_machine 699s time="2024-03-27T02:30:27Z" level=debug msg="Registering gRPC WSL instance service" 699s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:39061 699s wslinstanceservice_test.go:108: Client connected to WslInstanceService 699s time="2024-03-27T02:30:27Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 699s === CONT TestApplyProToken/Error_cannot_send_info_to_stream 699s time="2024-03-27T02:30:29Z" level=debug msg="Registering gRPC WSL instance service" 699s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:33627 699s wslinstanceservice_test.go:108: Client connected to WslInstanceService 699s time="2024-03-27T02:30:29Z" level=info msg="ApplyProToken: Received empty token: detaching" 699s === CONT TestApplyProToken/Error_calling_pro_attach 699s time="2024-03-27T02:30:29Z" level=debug msg="Registering gRPC WSL instance service" 699s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:36735 699s wslinstanceservice_test.go:108: Client connected to WslInstanceService 699s time="2024-03-27T02:30:29Z" level=info msg="ApplyProToken: Received token \"***\": attaching" 699s time="2024-03-27T02:30:32Z" level=warning msg="ApplyProToken: could not send update via control stream: could not send system info: test error" 699s === CONT TestApplyProToken/Error_calling_pro_status 699s time="2024-03-27T02:30:32Z" level=debug msg="Registering gRPC WSL instance service" 699s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:39487 699s wslinstanceservice_test.go:108: Client connected to WslInstanceService 699s time="2024-03-27T02:30:32Z" level=info msg="ApplyProToken: Received empty token: detaching" 699s === CONT TestApplyProToken/success_detaching_non-attached_machine 699s time="2024-03-27T02:30:33Z" level=debug msg="Registering gRPC WSL instance service" 699s wslinstanceservice_test.go:108: Serving WslInstanceService on 127.0.0.1:32947 699s wslinstanceservice_test.go:108: Client connected to WslInstanceService 699s time="2024-03-27T02:30:33Z" level=info msg="ApplyProToken: Received empty token: detaching" 699s time="2024-03-27T02:30:34Z" 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:" 699s --- PASS: TestApplyProToken (0.00s) 699s --- PASS: TestApplyProToken/success_detaching_attached_machine (2.12s) 699s --- PASS: TestApplyProToken/Error_detaching_pro (1.86s) 699s --- PASS: TestApplyProToken/Error_getting_system_info (2.11s) 699s --- PASS: TestApplyProToken/success_attaching_non-attached_machine (2.91s) 699s --- PASS: TestApplyProToken/success_attaching_attached_machine (2.70s) 699s --- PASS: TestApplyProToken/Error_cannot_send_info_to_stream (2.34s) 699s --- PASS: TestApplyProToken/Error_calling_pro_attach (3.74s) 699s --- PASS: TestApplyProToken/Error_calling_pro_status (2.43s) 699s --- PASS: TestApplyProToken/success_detaching_non-attached_machine (1.68s) 699s PASS 699s ok github.com/canonical/ubuntu-pro-for-wsl/wsl-pro-service/internal/wslinstanceservice 13.295s 699s autopkgtest [02:32:15]: test command1: -----------------------] 699s autopkgtest [02:32:15]: test command1: - - - - - - - - - - results - - - - - - - - - - 699s command1 PASS 700s autopkgtest [02:32:16]: @@@@@@@@@@@@@@@@@@@@ summary 700s command1 PASS