0s autopkgtest [10:37:54]: starting date and time: 2024-03-23 10:37:54+0000 0s autopkgtest [10:37:54]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [10:37:54]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ovpkgm9v/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:sphinx --apt-upgrade gpiozero --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=sphinx/7.2.6-6 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-arm64-3.secgroup --name adt-noble-arm64-gpiozero-20240323-102708-juju-7f2275-prod-proposed-migration-environment-3 --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/ 365s Creating nova instance adt-noble-arm64-gpiozero-20240323-102708-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-arm64-server-20240321.img (UUID 28ecccfc-4cb0-4cc6-a280-209e2d140e10)... 365s nova boot failed (attempt #1): 365s DEBUG (extension:189) found extension EntryPoint.parse('v1password = swiftclient.authv1:PasswordLoader') 365s DEBUG (extension:189) found extension EntryPoint.parse('noauth = cinderclient.contrib.noauth:CinderNoAuthLoader') 365s DEBUG (extension:189) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken') 365s DEBUG (extension:189) found extension EntryPoint.parse('none = keystoneauth1.loading._plugins.noauth:NoAuth') 365s DEBUG (extension:189) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password') 365s DEBUG (extension:189) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token') 365s DEBUG (extension:189) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password') 365s DEBUG (extension:189) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3adfspassword = keystoneauth1.extras._saml2._loading:ADFSPassword') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3applicationcredential = keystoneauth1.loading._plugins.identity.v3:ApplicationCredential') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3fedkerb = keystoneauth1.extras.kerberos._loading:MappedKerberos') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3kerberos = keystoneauth1.extras.kerberos._loading:Kerberos') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3multifactor = keystoneauth1.loading._plugins.identity.v3:MultiFactor') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3oauth1 = keystoneauth1.extras.oauth1._loading:V3OAuth1') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcaccesstoken = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAccessToken') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcclientcredentials = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectClientCredentials') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3samlpassword = keystoneauth1.extras._saml2._loading:Saml2Password') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3tokenlessauth = keystoneauth1.loading._plugins.identity.v3:TokenlessAuth') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3totp = keystoneauth1.loading._plugins.identity.v3:TOTP') 365s 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" 365s DEBUG (connectionpool:962) Starting new HTTPS connection (1): keystone.ps6.canonical.com:5000 365s DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "GET /v3 HTTP/1.1" 200 267 365s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 267 Content-Type: application/json Date: Sat, 23 Mar 2024 10:38:16 GMT Keep-Alive: timeout=75, max=1000 Server: Apache/2.4.52 (Ubuntu) Vary: X-Auth-Token x-openstack-request-id: req-4448dcba-733a-4117-8913-c1b0ef267d6c 365s 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"}]}} 365s DEBUG (session:946) GET call to https://keystone.ps6.canonical.com:5000/v3 used request id req-4448dcba-733a-4117-8913-c1b0ef267d6c 365s DEBUG (base:182) Making authentication request to https://keystone.ps6.canonical.com:5000/v3/auth/tokens 365s DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "POST /v3/auth/tokens HTTP/1.1" 201 12445 365s 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": ["8o5UhYV0Th2VE038EOtLpg"], "expires_at": "2024-03-24T10:38:16.000000Z", "issued_at": "2024-03-23T10:38:16.000000Z", "project": {"domain": {"id": "default", "name": "Default"}, "id": "bd8b3df3f03d43a0a9303b7c0434fdab", "name": "prod-proposed-migration-arm64_project"}, "is_domain": false, "roles": [{"id": "15cf997650e345b7b21c02b5cdb7d84a", "name": "member"}, {"id": "86bf3043974746c2bf52c2a9f362f986", "name": "reader"}], "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"}]}} 365s 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}afb77d9b242f51d40093315e7ecb4f2170b2b10c25ba958a89bb4b74840cca40" 365s 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}afb77d9b242f51d40093315e7ecb4f2170b2b10c25ba958a89bb4b74840cca40" 365s DEBUG (connectionpool:962) Starting new HTTPS connection (1): nova.ps6.canonical.com:8774 365s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1 HTTP/1.1" 302 0 365s RESP: [302] Connection: Keep-Alive Content-Length: 0 Content-Type: text/plain; charset=utf8 Date: Sat, 23 Mar 2024 10:38:17 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-23d676d0-4e10-4525-a201-37a779105c9a x-openstack-request-id: req-23d676d0-4e10-4525-a201-37a779105c9a 365s DEBUG (session:548) RESP: [302] Connection: Keep-Alive Content-Length: 0 Content-Type: text/plain; charset=utf8 Date: Sat, 23 Mar 2024 10:38:17 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-23d676d0-4e10-4525-a201-37a779105c9a x-openstack-request-id: req-23d676d0-4e10-4525-a201-37a779105c9a 365s RESP BODY: Omitted, Content-Type is set to text/plain; charset=utf8. Only application/json responses have their bodies logged. 365s DEBUG (session:580) RESP BODY: Omitted, Content-Type is set to text/plain; charset=utf8. Only application/json responses have their bodies logged. 365s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/ HTTP/1.1" 200 397 365s RESP: [200] Connection: Keep-Alive Content-Length: 397 Content-Type: application/json Date: Sat, 23 Mar 2024 10:38:17 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-79a32bb5-fa7d-4c41-be47-9c1d8f526d05 x-openstack-request-id: req-79a32bb5-fa7d-4c41-be47-9c1d8f526d05 365s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 397 Content-Type: application/json Date: Sat, 23 Mar 2024 10:38:17 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-79a32bb5-fa7d-4c41-be47-9c1d8f526d05 x-openstack-request-id: req-79a32bb5-fa7d-4c41-be47-9c1d8f526d05 365s 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"}]}} 365s 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"}]}} 365s GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/ used request id req-79a32bb5-fa7d-4c41-be47-9c1d8f526d05 365s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/ used request id req-79a32bb5-fa7d-4c41-be47-9c1d8f526d05 365s DEBUG (extension:189) found extension EntryPoint.parse('v1password = swiftclient.authv1:PasswordLoader') 365s DEBUG (extension:189) found extension EntryPoint.parse('noauth = cinderclient.contrib.noauth:CinderNoAuthLoader') 365s DEBUG (extension:189) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken') 365s DEBUG (extension:189) found extension EntryPoint.parse('none = keystoneauth1.loading._plugins.noauth:NoAuth') 365s DEBUG (extension:189) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password') 365s DEBUG (extension:189) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token') 365s DEBUG (extension:189) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password') 365s DEBUG (extension:189) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3adfspassword = keystoneauth1.extras._saml2._loading:ADFSPassword') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3applicationcredential = keystoneauth1.loading._plugins.identity.v3:ApplicationCredential') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3fedkerb = keystoneauth1.extras.kerberos._loading:MappedKerberos') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3kerberos = keystoneauth1.extras.kerberos._loading:Kerberos') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3multifactor = keystoneauth1.loading._plugins.identity.v3:MultiFactor') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3oauth1 = keystoneauth1.extras.oauth1._loading:V3OAuth1') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcaccesstoken = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAccessToken') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcclientcredentials = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectClientCredentials') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3samlpassword = keystoneauth1.extras._saml2._loading:Saml2Password') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3tokenlessauth = keystoneauth1.loading._plugins.identity.v3:TokenlessAuth') 365s DEBUG (extension:189) found extension EntryPoint.parse('v3totp = keystoneauth1.loading._plugins.identity.v3:TOTP') 365s DEBUG (session:517) REQ: curl -g -i -X GET https://glance.ps6.canonical.com:9292/v2/images/28ecccfc-4cb0-4cc6-a280-209e2d140e10 -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}afb77d9b242f51d40093315e7ecb4f2170b2b10c25ba958a89bb4b74840cca40" -H "X-OpenStack-Nova-API-Version: 2.87" 365s DEBUG (connectionpool:962) Starting new HTTPS connection (1): glance.ps6.canonical.com:9292 365s DEBUG (connectionpool:429) https://glance.ps6.canonical.com:9292 "GET /v2/images/28ecccfc-4cb0-4cc6-a280-209e2d140e10 HTTP/1.1" 200 1935 365s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 1935 Content-Type: application/json Date: Sat, 23 Mar 2024 10:38:17 GMT Keep-Alive: timeout=75, max=1000 Server: Apache/2.4.52 (Ubuntu) X-Openstack-Request-Id: req-9b1d1fec-3e13-48d0-a55c-640c9472bad7 365s 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": "543499e0-aeb9-4922-8880-c51a4fc18bf8", "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-20240321.img", "disk_format": "qcow2", "container_format": "bare", "visibility": "private", "size": 2306342912, "virtual_size": 21474836480, "status": "active", "checksum": "8198422d2c7534f56b5a2a46874755ab", "protected": false, "min_ram": 0, "min_disk": 20, "owner": "bd8b3df3f03d43a0a9303b7c0434fdab", "os_hidden": false, "os_hash_algo": "sha512", "os_hash_value": "c59ccc5319301a5ec5077e547fd66aac380403815cc5c00e980d9dce3efa9cc417db45604d4bf4c4e1913c2a5af61917b84fdf003c36adeb77990adb487af4ed", "id": "28ecccfc-4cb0-4cc6-a280-209e2d140e10", "created_at": "2024-03-21T11:24:35Z", "updated_at": "2024-03-21T11:25:04Z", "locations": [{"url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/28ecccfc-4cb0-4cc6-a280-209e2d140e10/snap", "metadata": {"store": "ceph"}}], "direct_url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/28ecccfc-4cb0-4cc6-a280-209e2d140e10/snap", "tags": [], "self": "/v2/images/28ecccfc-4cb0-4cc6-a280-209e2d140e10", "file": "/v2/images/28ecccfc-4cb0-4cc6-a280-209e2d140e10/file", "schema": "/v2/schemas/image", "stores": "ceph"} 365s DEBUG (session:936) GET call to image for https://glance.ps6.canonical.com:9292/v2/images/28ecccfc-4cb0-4cc6-a280-209e2d140e10 used request id req-9b1d1fec-3e13-48d0-a55c-640c9472bad7 365s 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}afb77d9b242f51d40093315e7ecb4f2170b2b10c25ba958a89bb4b74840cca40" -H "X-OpenStack-Nova-API-Version: 2.87" 365s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors/autopkgtest HTTP/1.1" 404 84 365s DEBUG (session:548) RESP: [404] Connection: Keep-Alive Content-Length: 84 Content-Type: application/json; charset=UTF-8 Date: Sat, 23 Mar 2024 10:38:17 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-2a658e79-f062-4235-a6c9-c12b250983cf x-openstack-request-id: req-2a658e79-f062-4235-a6c9-c12b250983cf 365s DEBUG (session:580) RESP BODY: {"itemNotFound": {"code": 404, "message": "Flavor autopkgtest could not be found."}} 365s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors/autopkgtest used request id req-2a658e79-f062-4235-a6c9-c12b250983cf 365s 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}afb77d9b242f51d40093315e7ecb4f2170b2b10c25ba958a89bb4b74840cca40" -H "X-OpenStack-Nova-API-Version: 2.87" 365s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors?is_public=None HTTP/1.1" 200 1739 365s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 1739 Content-Type: application/json Date: Sat, 23 Mar 2024 10:38:17 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-369f047e-7556-427c-b0c5-eed383c13fc6 x-openstack-request-id: req-369f047e-7556-427c-b0c5-eed383c13fc6 365s 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}]} 365s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors?is_public=None used request id req-369f047e-7556-427c-b0c5-eed383c13fc6 365s 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}afb77d9b242f51d40093315e7ecb4f2170b2b10c25ba958a89bb4b74840cca40" -H "X-OpenStack-Nova-API-Version: 2.87" 365s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors/8d27c5f0-65c7-4018-b3f7-50463875ab09 HTTP/1.1" 200 613 365s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 613 Content-Type: application/json Date: Sat, 23 Mar 2024 10:38:17 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-29dbfc86-f0f8-45e6-ad57-08998574bc07 x-openstack-request-id: req-29dbfc86-f0f8-45e6-ad57-08998574bc07 365s 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"}}} 365s 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-29dbfc86-f0f8-45e6-ad57-08998574bc07 365s 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}afb77d9b242f51d40093315e7ecb4f2170b2b10c25ba958a89bb4b74840cca40" -H "X-OpenStack-Nova-API-Version: 2.87" -d '{"server": {"name": "adt-noble-arm64-gpiozero-20240323-102708-juju-7f2275-prod-proposed-migration-environment-3", "imageRef": "28ecccfc-4cb0-4cc6-a280-209e2d140e10", "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-3.secgroup"}], "networks": [{"uuid": "6ea4839c-9760-4b54-ab7d-70a6312667d1"}]}}' 365s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "POST /v2.1/servers HTTP/1.1" 403 115 365s DEBUG (session:548) RESP: [403] Connection: Keep-Alive Content-Length: 115 Content-Type: application/json; charset=UTF-8 Date: Sat, 23 Mar 2024 10:38:17 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-2c4a28c7-4e42-4ce8-b219-9517f4196a13 x-openstack-request-id: req-2c4a28c7-4e42-4ce8-b219-9517f4196a13 365s DEBUG (session:580) RESP BODY: {"forbidden": {"code": 403, "message": "Quota exceeded for cores: Requested 2, but already used 140 of 140 cores"}} 365s DEBUG (session:936) POST call to compute for https://nova.ps6.canonical.com:8774/v2.1/servers used request id req-2c4a28c7-4e42-4ce8-b219-9517f4196a13 365s DEBUG (shell:822) Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-2c4a28c7-4e42-4ce8-b219-9517f4196a13) 365s Traceback (most recent call last): 365s File "/usr/lib/python3/dist-packages/novaclient/shell.py", line 820, in main 365s OpenStackComputeShell().main(argv) 365s File "/usr/lib/python3/dist-packages/novaclient/shell.py", line 742, in main 365s args.func(self.cs, args) 365s File "/usr/lib/python3/dist-packages/novaclient/v2/shell.py", line 971, in do_boot 365s server = cs.servers.create(*boot_args, **boot_kwargs) 365s File "/usr/lib/python3/dist-packages/novaclient/v2/servers.py", line 1477, in create 365s return self._boot(response_key, *boot_args, **boot_kwargs) 365s File "/usr/lib/python3/dist-packages/novaclient/v2/servers.py", line 836, in _boot 365s return self._create('/servers', body, response_key, 365s File "/usr/lib/python3/dist-packages/novaclient/base.py", line 363, in _create 365s resp, body = self.api.client.post(url, body=body) 365s File "/usr/lib/python3/dist-packages/keystoneauth1/adapter.py", line 392, in post 365s return self.request(url, 'POST', **kwargs) 365s File "/usr/lib/python3/dist-packages/novaclient/client.py", line 78, in request 365s raise exceptions.from_response(resp, body, url, method) 365s novaclient.exceptions.Forbidden: Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-2c4a28c7-4e42-4ce8-b219-9517f4196a13) 365s ERROR (Forbidden): Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-2c4a28c7-4e42-4ce8-b219-9517f4196a13) 368s autopkgtest [10:44:02]: testbed dpkg architecture: arm64 368s autopkgtest [10:44:02]: testbed apt version: 2.7.12 368s autopkgtest [10:44:02]: @@@@@@@@@@@@@@@@@@@@ test bed setup 368s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 369s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3969 kB] 370s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [494 kB] 370s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.9 kB] 370s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 370s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [667 kB] 370s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 370s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [33.7 kB] 370s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 370s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [4346 kB] 371s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 371s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [69.8 kB] 371s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 372s Fetched 9772 kB in 3s (3290 kB/s) 373s Reading package lists... 375s Reading package lists... 375s Building dependency tree... 375s Reading state information... 376s Calculating upgrade... 376s The following packages will be upgraded: 376s cloud-init fonts-ubuntu-console libbsd0 libc-bin libc6 locales 376s python3-markupsafe 376s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 376s Need to get 8268 kB of archives. 376s After this operation, 63.5 kB disk space will be freed. 376s Get:1 http://ftpmaster.internal/ubuntu noble/main arm64 libc6 arm64 2.39-0ubuntu6 [2776 kB] 377s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 libc-bin arm64 2.39-0ubuntu6 [591 kB] 377s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 libbsd0 arm64 0.12.1-1 [41.0 kB] 377s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 locales all 2.39-0ubuntu6 [4232 kB] 377s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 fonts-ubuntu-console all 0.869+git20240321-0ubuntu1 [18.7 kB] 377s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 python3-markupsafe arm64 2.1.5-1build1 [12.5 kB] 377s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 cloud-init all 24.1.2-0ubuntu1 [597 kB] 377s Preconfiguring packages ... 378s Fetched 8268 kB in 1s (9722 kB/s) 378s (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 ... 74820 files and directories currently installed.) 378s Preparing to unpack .../libc6_2.39-0ubuntu6_arm64.deb ... 378s Unpacking libc6:arm64 (2.39-0ubuntu6) over (2.39-0ubuntu2) ... 378s Setting up libc6:arm64 (2.39-0ubuntu6) ... 379s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 74820 files and directories currently installed.) 379s Preparing to unpack .../libc-bin_2.39-0ubuntu6_arm64.deb ... 379s Unpacking libc-bin (2.39-0ubuntu6) over (2.39-0ubuntu2) ... 379s Setting up libc-bin (2.39-0ubuntu6) ... 379s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 74820 files and directories currently installed.) 379s Preparing to unpack .../libbsd0_0.12.1-1_arm64.deb ... 379s Unpacking libbsd0:arm64 (0.12.1-1) over (0.11.8-1) ... 379s Preparing to unpack .../locales_2.39-0ubuntu6_all.deb ... 379s Unpacking locales (2.39-0ubuntu6) over (2.39-0ubuntu2) ... 380s Preparing to unpack .../fonts-ubuntu-console_0.869+git20240321-0ubuntu1_all.deb ... 380s Unpacking fonts-ubuntu-console (0.869+git20240321-0ubuntu1) over (0.869-0ubuntu1) ... 380s Preparing to unpack .../python3-markupsafe_2.1.5-1build1_arm64.deb ... 380s Unpacking python3-markupsafe (2.1.5-1build1) over (2.1.5-1) ... 380s Preparing to unpack .../cloud-init_24.1.2-0ubuntu1_all.deb ... 380s Unpacking cloud-init (24.1.2-0ubuntu1) over (24.1.1-0ubuntu1) ... 380s Setting up fonts-ubuntu-console (0.869+git20240321-0ubuntu1) ... 380s Setting up cloud-init (24.1.2-0ubuntu1) ... 381s Setting up locales (2.39-0ubuntu6) ... 382s Generating locales (this might take a while)... 384s en_US.UTF-8... done 384s Generation complete. 384s Setting up python3-markupsafe (2.1.5-1build1) ... 384s Setting up libbsd0:arm64 (0.12.1-1) ... 384s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 385s Processing triggers for man-db (2.12.0-3) ... 386s Processing triggers for libc-bin (2.39-0ubuntu6) ... 386s Reading package lists... 386s Building dependency tree... 386s Reading state information... 387s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 387s sh: Attempting to set up Debian/Ubuntu apt sources automatically 387s sh: Distribution appears to be Ubuntu 388s Reading package lists... 388s Building dependency tree... 388s Reading state information... 389s eatmydata is already the newest version (131-1). 389s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 389s Reading package lists... 389s Building dependency tree... 389s Reading state information... 390s dbus is already the newest version (1.14.10-4ubuntu1). 390s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 390s Reading package lists... 390s Building dependency tree... 390s Reading state information... 391s rng-tools-debian is already the newest version (2.4). 391s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 391s Reading package lists... 391s Building dependency tree... 391s Reading state information... 391s The following packages will be REMOVED: 391s cloud-init* python3-configobj* python3-debconf* 392s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 392s After this operation, 3256 kB disk space will be freed. 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 ... 74819 files and directories currently installed.) 392s Removing cloud-init (24.1.2-0ubuntu1) ... 392s Removing python3-configobj (5.0.8-3) ... 392s Removing python3-debconf (1.5.86) ... 392s Processing triggers for man-db (2.12.0-3) ... 393s (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 ... 74430 files and directories currently installed.) 393s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 393s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 393s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 393s invoke-rc.d: policy-rc.d denied execution of try-restart. 393s Reading package lists... 394s Building dependency tree... 394s Reading state information... 394s linux-generic is already the newest version (6.8.0-11.11+1). 394s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 395s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 395s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 395s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 397s Reading package lists... 397s Reading package lists... 397s Building dependency tree... 397s Reading state information... 397s Calculating upgrade... 398s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 398s Reading package lists... 398s Building dependency tree... 398s Reading state information... 398s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 399s autopkgtest [10:44:33]: rebooting testbed after setup commands that affected boot 426s autopkgtest [10:45:00]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 02:53:31 UTC 2024 429s autopkgtest [10:45:03]: @@@@@@@@@@@@@@@@@@@@ apt-source gpiozero 433s Get:1 http://ftpmaster.internal/ubuntu noble/universe gpiozero 2.0.1-0ubuntu1 (dsc) [2270 B] 433s Get:2 http://ftpmaster.internal/ubuntu noble/universe gpiozero 2.0.1-0ubuntu1 (tar) [8914 kB] 433s Get:3 http://ftpmaster.internal/ubuntu noble/universe gpiozero 2.0.1-0ubuntu1 (diff) [4332 B] 434s gpgv: Signature made Thu Feb 15 11:29:06 2024 UTC 434s gpgv: using RSA key C6D8FC68EE91033CB8BD913A3D633E44A057F8D5 434s gpgv: issuer "dave.jones@canonical.com" 434s gpgv: Can't check signature: No public key 434s dpkg-source: warning: cannot verify inline signature for ./gpiozero_2.0.1-0ubuntu1.dsc: no acceptable signature found 434s autopkgtest [10:45:08]: testing package gpiozero version 2.0.1-0ubuntu1 434s autopkgtest [10:45:08]: build not needed 435s autopkgtest [10:45:09]: test command1: preparing testbed 441s Reading package lists... 441s Building dependency tree... 441s Reading state information... 442s Starting pkgProblemResolver with broken count: 0 442s Starting 2 pkgProblemResolver with broken count: 0 442s Done 442s The following additional packages will be installed: 442s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 442s python-colorzero-doc python-gpiozero-doc python3-colorzero python3-doc 442s python3-gpiozero python3-iniconfig python3-packaging python3-pluggy 442s python3-pytest python3.12-doc sphinx-rtd-theme-common 442s Suggested packages: 442s python3-examples 442s Recommended packages: 442s javascript-common python3-lgpio python3-spidev 442s The following NEW packages will be installed: 442s autopkgtest-satdep fonts-font-awesome fonts-lato libjs-jquery 442s libjs-sphinxdoc libjs-underscore python-colorzero-doc python-gpiozero-doc 442s python3-colorzero python3-doc python3-gpiozero python3-iniconfig 442s python3-packaging python3-pluggy python3-pytest python3.12-doc 442s sphinx-rtd-theme-common 442s 0 upgraded, 17 newly installed, 0 to remove and 0 not upgraded. 442s Need to get 18.4 MB/18.4 MB of archives. 442s After this operation, 98.0 MB of additional disk space will be used. 442s Get:1 /tmp/autopkgtest.eMpe2l/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [728 B] 442s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 fonts-lato all 2.015-1 [2781 kB] 443s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 443s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 443s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 443s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 libjs-sphinxdoc all 7.2.6-4 [149 kB] 443s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 sphinx-rtd-theme-common all 2.0.0+dfsg-1 [1012 kB] 443s Get:8 http://ftpmaster.internal/ubuntu noble/universe arm64 python-colorzero-doc all 2.0-2 [41.9 kB] 443s Get:9 http://ftpmaster.internal/ubuntu noble/main arm64 python3.12-doc all 3.12.2-1 [12.0 MB] 443s Get:10 http://ftpmaster.internal/ubuntu noble/main arm64 python3-doc all 3.12.1-0ubuntu2 [9974 B] 443s Get:11 http://ftpmaster.internal/ubuntu noble/universe arm64 python-gpiozero-doc all 2.0.1-0ubuntu1 [935 kB] 443s Get:12 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-colorzero all 2.0-2 [22.7 kB] 443s Get:13 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-gpiozero all 2.0.1-0ubuntu1 [121 kB] 443s Get:14 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-iniconfig all 1.1.1-2 [6024 B] 443s Get:15 http://ftpmaster.internal/ubuntu noble/main arm64 python3-packaging all 23.2-1 [40.6 kB] 443s Get:16 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-pluggy all 1.4.0-1 [20.4 kB] 443s Get:17 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-pytest all 7.4.4-1 [305 kB] 444s Fetched 18.4 MB in 1s (16.2 MB/s) 444s Selecting previously unselected package fonts-lato. 444s (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 ... 74375 files and directories currently installed.) 444s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 444s Unpacking fonts-lato (2.015-1) ... 444s Selecting previously unselected package fonts-font-awesome. 444s Preparing to unpack .../01-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 444s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 445s Selecting previously unselected package libjs-jquery. 445s Preparing to unpack .../02-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 445s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 445s Selecting previously unselected package libjs-underscore. 445s Preparing to unpack .../03-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 445s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 445s Selecting previously unselected package libjs-sphinxdoc. 445s Preparing to unpack .../04-libjs-sphinxdoc_7.2.6-4_all.deb ... 445s Unpacking libjs-sphinxdoc (7.2.6-4) ... 445s Selecting previously unselected package sphinx-rtd-theme-common. 445s Preparing to unpack .../05-sphinx-rtd-theme-common_2.0.0+dfsg-1_all.deb ... 445s Unpacking sphinx-rtd-theme-common (2.0.0+dfsg-1) ... 445s Selecting previously unselected package python-colorzero-doc. 445s Preparing to unpack .../06-python-colorzero-doc_2.0-2_all.deb ... 445s Unpacking python-colorzero-doc (2.0-2) ... 445s Selecting previously unselected package python3.12-doc. 445s Preparing to unpack .../07-python3.12-doc_3.12.2-1_all.deb ... 445s Unpacking python3.12-doc (3.12.2-1) ... 445s Selecting previously unselected package python3-doc. 445s Preparing to unpack .../08-python3-doc_3.12.1-0ubuntu2_all.deb ... 445s Unpacking python3-doc (3.12.1-0ubuntu2) ... 445s Selecting previously unselected package python-gpiozero-doc. 445s Preparing to unpack .../09-python-gpiozero-doc_2.0.1-0ubuntu1_all.deb ... 445s Unpacking python-gpiozero-doc (2.0.1-0ubuntu1) ... 445s Selecting previously unselected package python3-colorzero. 445s Preparing to unpack .../10-python3-colorzero_2.0-2_all.deb ... 445s Unpacking python3-colorzero (2.0-2) ... 445s Selecting previously unselected package python3-gpiozero. 445s Preparing to unpack .../11-python3-gpiozero_2.0.1-0ubuntu1_all.deb ... 445s Unpacking python3-gpiozero (2.0.1-0ubuntu1) ... 445s Selecting previously unselected package python3-iniconfig. 445s Preparing to unpack .../12-python3-iniconfig_1.1.1-2_all.deb ... 445s Unpacking python3-iniconfig (1.1.1-2) ... 445s Selecting previously unselected package python3-packaging. 445s Preparing to unpack .../13-python3-packaging_23.2-1_all.deb ... 445s Unpacking python3-packaging (23.2-1) ... 445s Selecting previously unselected package python3-pluggy. 445s Preparing to unpack .../14-python3-pluggy_1.4.0-1_all.deb ... 445s Unpacking python3-pluggy (1.4.0-1) ... 445s Selecting previously unselected package python3-pytest. 445s Preparing to unpack .../15-python3-pytest_7.4.4-1_all.deb ... 445s Unpacking python3-pytest (7.4.4-1) ... 445s Selecting previously unselected package autopkgtest-satdep. 445s Preparing to unpack .../16-1-autopkgtest-satdep.deb ... 445s Unpacking autopkgtest-satdep (0) ... 445s Setting up python3-iniconfig (1.1.1-2) ... 446s Setting up fonts-lato (2.015-1) ... 446s Setting up python3-colorzero (2.0-2) ... 446s Setting up python3-packaging (23.2-1) ... 446s Setting up python3-gpiozero (2.0.1-0ubuntu1) ... 446s Setting up python3-pluggy (1.4.0-1) ... 446s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 446s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 446s Setting up sphinx-rtd-theme-common (2.0.0+dfsg-1) ... 446s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 446s Setting up python3-pytest (7.4.4-1) ... 446s Setting up libjs-sphinxdoc (7.2.6-4) ... 446s Setting up python3.12-doc (3.12.2-1) ... 446s Setting up python3-doc (3.12.1-0ubuntu2) ... 446s Setting up python-colorzero-doc (2.0-2) ... 446s Setting up python-gpiozero-doc (2.0.1-0ubuntu1) ... 446s Setting up autopkgtest-satdep (0) ... 446s Processing triggers for install-info (7.1-3) ... 447s Processing triggers for man-db (2.12.0-3) ... 451s (Reading database ... 76115 files and directories currently installed.) 451s Removing autopkgtest-satdep (0) ... 451s autopkgtest [10:45:25]: test command1: pytest-3 -rsx --tb=short -k 'not test_real_pins.py' tests/ 451s autopkgtest [10:45:25]: test command1: [----------------------- 453s ============================= test session starts ============================== 453s platform linux -- Python 3.12.2, pytest-7.4.4, pluggy-1.4.0 453s rootdir: /tmp/autopkgtest.eMpe2l/build.96T/src 453s configfile: setup.cfg 453s collected 472 items / 103 deselected / 369 selected 453s 459s tests/test_boards.py ................................................... [ 13%] 464s .................................... [ 23%] 464s tests/test_compat.py . [ 23%] 464s tests/test_devices.py ..................... [ 29%] 464s tests/test_fonts.py .. [ 30%] 469s tests/test_inputs.py ................................ [ 38%] 470s tests/test_internal_devices.py ............... [ 42%] 470s tests/test_mixins.py ........ [ 44%] 470s tests/test_mock_pin.py ................... [ 50%] 476s tests/test_outputs.py .................................................. [ 63%] 481s ......................................................... [ 79%] 481s tests/test_pins_data.py ................. [ 83%] 481s tests/test_spi_devices.py ................. [ 88%] 481s tests/test_tones.py ........ [ 90%] 486s tests/test_tools.py .............................. [ 98%] 486s tests/cli/test_pinout.py ..... [100%] 486s 486s =============================== warnings summary =============================== 486s tests/test_internal_devices.py: 14 warnings 486s /usr/lib/python3/dist-packages/gpiozero/internal_devices.py:595: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC). 486s now = datetime.utcnow().time() if self.utc else datetime.now().time() 486s 486s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 486s ============== 369 passed, 103 deselected, 14 warnings in 33.96s =============== 487s autopkgtest [10:46:01]: test command1: -----------------------] 488s command1 PASS 488s autopkgtest [10:46:02]: test command1: - - - - - - - - - - results - - - - - - - - - - 488s autopkgtest [10:46:02]: @@@@@@@@@@@@@@@@@@@@ summary 488s command1 PASS