0s autopkgtest [02:06:17]: starting date and time: 2024-03-26 02:06:17+0000 0s autopkgtest [02:06:17]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [02:06:17]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.phzhekjp/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 --apt-upgrade genimage --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=qemu/1:8.2.1+ds-1ubuntu8 alsa-lib/1.2.11-1build1 at-spi2-core/2.51.90-3 ceph/19.2.0~git20240301.4c76c50-0ubuntu3 cups/2.4.7-1.2ubuntu1 curl/8.5.0-2ubuntu8 glib2.0/2.79.3-3ubuntu5 gnutls28/3.8.3-1.1ubuntu2 gtk+3.0/3.24.41-1.1ubuntu3 libpng1.6/1.6.43-3 libpsl/0.21.2-1.1 nettle/3.9.1-2.2 openssl/3.0.13-0ubuntu2 pipewire/1.0.4-2ubuntu2 usbredir/0.13.0-2.1 wp2latex/4.4~ds-1build1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-arm64-18.secgroup --name adt-noble-arm64-genimage-20240326-020617-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/ 682s Creating nova instance adt-noble-arm64-genimage-20240326-020617-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-arm64-server-20240326.img (UUID 2d366976-4689-4c6f-acab-b55163b07c34)... 682s nova boot failed (attempt #1): 682s DEBUG (extension:189) found extension EntryPoint.parse('v1password = swiftclient.authv1:PasswordLoader') 682s DEBUG (extension:189) found extension EntryPoint.parse('noauth = cinderclient.contrib.noauth:CinderNoAuthLoader') 682s DEBUG (extension:189) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken') 682s DEBUG (extension:189) found extension EntryPoint.parse('none = keystoneauth1.loading._plugins.noauth:NoAuth') 682s DEBUG (extension:189) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3adfspassword = keystoneauth1.extras._saml2._loading:ADFSPassword') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3applicationcredential = keystoneauth1.loading._plugins.identity.v3:ApplicationCredential') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3fedkerb = keystoneauth1.extras.kerberos._loading:MappedKerberos') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3kerberos = keystoneauth1.extras.kerberos._loading:Kerberos') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3multifactor = keystoneauth1.loading._plugins.identity.v3:MultiFactor') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oauth1 = keystoneauth1.extras.oauth1._loading:V3OAuth1') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcaccesstoken = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAccessToken') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcclientcredentials = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectClientCredentials') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3samlpassword = keystoneauth1.extras._saml2._loading:Saml2Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3tokenlessauth = keystoneauth1.loading._plugins.identity.v3:TokenlessAuth') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3totp = keystoneauth1.loading._plugins.identity.v3:TOTP') 682s 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" 682s DEBUG (connectionpool:962) Starting new HTTPS connection (1): keystone.ps6.canonical.com:5000 682s DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "GET /v3 HTTP/1.1" 200 267 682s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 267 Content-Type: application/json Date: Tue, 26 Mar 2024 02:06:40 GMT Keep-Alive: timeout=75, max=1000 Server: Apache/2.4.52 (Ubuntu) Vary: X-Auth-Token x-openstack-request-id: req-40831a8d-37a0-4d89-a30b-9d5f720401b1 682s 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"}]}} 682s DEBUG (session:946) GET call to https://keystone.ps6.canonical.com:5000/v3 used request id req-40831a8d-37a0-4d89-a30b-9d5f720401b1 682s DEBUG (base:182) Making authentication request to https://keystone.ps6.canonical.com:5000/v3/auth/tokens 682s DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "POST /v3/auth/tokens HTTP/1.1" 201 12445 682s 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": ["ovb_iuMmRB2DNCFrHHCqtQ"], "expires_at": "2024-03-27T02:06:40.000000Z", "issued_at": "2024-03-26T02:06:40.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"}]}} 682s 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}24c770e331c89d29cd62fe1733a910226662a9484ea1136c28bb6b3460495a69" 682s 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}24c770e331c89d29cd62fe1733a910226662a9484ea1136c28bb6b3460495a69" 682s DEBUG (connectionpool:962) Starting new HTTPS connection (1): nova.ps6.canonical.com:8774 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1 HTTP/1.1" 302 0 682s RESP: [302] Connection: Keep-Alive Content-Length: 0 Content-Type: text/plain; charset=utf8 Date: Tue, 26 Mar 2024 02:06:40 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-fcc733dc-57e3-4cec-b5ef-5b3414bb34f3 x-openstack-request-id: req-fcc733dc-57e3-4cec-b5ef-5b3414bb34f3 682s DEBUG (session:548) RESP: [302] Connection: Keep-Alive Content-Length: 0 Content-Type: text/plain; charset=utf8 Date: Tue, 26 Mar 2024 02:06:40 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-fcc733dc-57e3-4cec-b5ef-5b3414bb34f3 x-openstack-request-id: req-fcc733dc-57e3-4cec-b5ef-5b3414bb34f3 682s RESP BODY: Omitted, Content-Type is set to text/plain; charset=utf8. Only application/json responses have their bodies logged. 682s DEBUG (session:580) RESP BODY: Omitted, Content-Type is set to text/plain; charset=utf8. Only application/json responses have their bodies logged. 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/ HTTP/1.1" 200 397 682s RESP: [200] Connection: Keep-Alive Content-Length: 397 Content-Type: application/json Date: Tue, 26 Mar 2024 02:06:40 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-1d8eecbe-837c-40d2-8052-dafe2f6be336 x-openstack-request-id: req-1d8eecbe-837c-40d2-8052-dafe2f6be336 682s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 397 Content-Type: application/json Date: Tue, 26 Mar 2024 02:06:40 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-1d8eecbe-837c-40d2-8052-dafe2f6be336 x-openstack-request-id: req-1d8eecbe-837c-40d2-8052-dafe2f6be336 682s 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"}]}} 682s 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"}]}} 682s GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/ used request id req-1d8eecbe-837c-40d2-8052-dafe2f6be336 682s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/ used request id req-1d8eecbe-837c-40d2-8052-dafe2f6be336 682s DEBUG (extension:189) found extension EntryPoint.parse('v1password = swiftclient.authv1:PasswordLoader') 682s DEBUG (extension:189) found extension EntryPoint.parse('noauth = cinderclient.contrib.noauth:CinderNoAuthLoader') 682s DEBUG (extension:189) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken') 682s DEBUG (extension:189) found extension EntryPoint.parse('none = keystoneauth1.loading._plugins.noauth:NoAuth') 682s DEBUG (extension:189) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3adfspassword = keystoneauth1.extras._saml2._loading:ADFSPassword') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3applicationcredential = keystoneauth1.loading._plugins.identity.v3:ApplicationCredential') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3fedkerb = keystoneauth1.extras.kerberos._loading:MappedKerberos') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3kerberos = keystoneauth1.extras.kerberos._loading:Kerberos') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3multifactor = keystoneauth1.loading._plugins.identity.v3:MultiFactor') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oauth1 = keystoneauth1.extras.oauth1._loading:V3OAuth1') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcaccesstoken = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAccessToken') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcclientcredentials = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectClientCredentials') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3samlpassword = keystoneauth1.extras._saml2._loading:Saml2Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3tokenlessauth = keystoneauth1.loading._plugins.identity.v3:TokenlessAuth') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3totp = keystoneauth1.loading._plugins.identity.v3:TOTP') 682s DEBUG (session:517) REQ: curl -g -i -X GET https://glance.ps6.canonical.com:9292/v2/images/2d366976-4689-4c6f-acab-b55163b07c34 -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}24c770e331c89d29cd62fe1733a910226662a9484ea1136c28bb6b3460495a69" -H "X-OpenStack-Nova-API-Version: 2.87" 682s DEBUG (connectionpool:962) Starting new HTTPS connection (1): glance.ps6.canonical.com:9292 682s DEBUG (connectionpool:429) https://glance.ps6.canonical.com:9292 "GET /v2/images/2d366976-4689-4c6f-acab-b55163b07c34 HTTP/1.1" 200 1935 682s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 1935 Content-Type: application/json Date: Tue, 26 Mar 2024 02:06:41 GMT Keep-Alive: timeout=75, max=1000 Server: Apache/2.4.52 (Ubuntu) X-Openstack-Request-Id: req-b102cbad-1fc0-4507-9a30-2e1090ca123e 682s 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": "da17c128-e5ee-42c1-ab78-154b089c59df", "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-20240326.img", "disk_format": "qcow2", "container_format": "bare", "visibility": "private", "size": 2311979008, "virtual_size": 21474836480, "status": "active", "checksum": "9259ab0e06148597edbb476a4d73477e", "protected": false, "min_ram": 0, "min_disk": 20, "owner": "bd8b3df3f03d43a0a9303b7c0434fdab", "os_hidden": false, "os_hash_algo": "sha512", "os_hash_value": "951897245260e2ccec338ff7d3325559cc24b2748331e9d9a793be044894306f113b1b9769f41820e5c9074d9fa433d35c77d5a969dcb494163f383ef9735fbe", "id": "2d366976-4689-4c6f-acab-b55163b07c34", "created_at": "2024-03-26T00:55:01Z", "updated_at": "2024-03-26T00:55:29Z", "locations": [{"url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/2d366976-4689-4c6f-acab-b55163b07c34/snap", "metadata": {"store": "ceph"}}], "direct_url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/2d366976-4689-4c6f-acab-b55163b07c34/snap", "tags": [], "self": "/v2/images/2d366976-4689-4c6f-acab-b55163b07c34", "file": "/v2/images/2d366976-4689-4c6f-acab-b55163b07c34/file", "schema": "/v2/schemas/image", "stores": "ceph"} 682s DEBUG (session:936) GET call to image for https://glance.ps6.canonical.com:9292/v2/images/2d366976-4689-4c6f-acab-b55163b07c34 used request id req-b102cbad-1fc0-4507-9a30-2e1090ca123e 682s 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}24c770e331c89d29cd62fe1733a910226662a9484ea1136c28bb6b3460495a69" -H "X-OpenStack-Nova-API-Version: 2.87" 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors/autopkgtest HTTP/1.1" 404 84 682s DEBUG (session:548) RESP: [404] Connection: Keep-Alive Content-Length: 84 Content-Type: application/json; charset=UTF-8 Date: Tue, 26 Mar 2024 02:06:41 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-ad6eb1fc-475e-4ecb-bb3a-4ddbe0f3ca1e x-openstack-request-id: req-ad6eb1fc-475e-4ecb-bb3a-4ddbe0f3ca1e 682s DEBUG (session:580) RESP BODY: {"itemNotFound": {"code": 404, "message": "Flavor autopkgtest could not be found."}} 682s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors/autopkgtest used request id req-ad6eb1fc-475e-4ecb-bb3a-4ddbe0f3ca1e 682s 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}24c770e331c89d29cd62fe1733a910226662a9484ea1136c28bb6b3460495a69" -H "X-OpenStack-Nova-API-Version: 2.87" 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors?is_public=None HTTP/1.1" 200 1739 682s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 1739 Content-Type: application/json Date: Tue, 26 Mar 2024 02:06:41 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-8635770f-2531-4309-a963-b7c88d1f93ba x-openstack-request-id: req-8635770f-2531-4309-a963-b7c88d1f93ba 682s 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}]} 682s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors?is_public=None used request id req-8635770f-2531-4309-a963-b7c88d1f93ba 682s 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}24c770e331c89d29cd62fe1733a910226662a9484ea1136c28bb6b3460495a69" -H "X-OpenStack-Nova-API-Version: 2.87" 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors/8d27c5f0-65c7-4018-b3f7-50463875ab09 HTTP/1.1" 200 613 682s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 613 Content-Type: application/json Date: Tue, 26 Mar 2024 02:06:41 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-af73ef0e-1721-4bb5-b40e-5dea8d502132 x-openstack-request-id: req-af73ef0e-1721-4bb5-b40e-5dea8d502132 682s 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"}}} 682s 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-af73ef0e-1721-4bb5-b40e-5dea8d502132 682s 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}24c770e331c89d29cd62fe1733a910226662a9484ea1136c28bb6b3460495a69" -H "X-OpenStack-Nova-API-Version: 2.87" -d '{"server": {"name": "adt-noble-arm64-genimage-20240326-020617-juju-7f2275-prod-proposed-migration-environment-3", "imageRef": "2d366976-4689-4c6f-acab-b55163b07c34", "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-18.secgroup"}], "networks": [{"uuid": "6ea4839c-9760-4b54-ab7d-70a6312667d1"}]}}' 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "POST /v2.1/servers HTTP/1.1" 403 115 682s DEBUG (session:548) RESP: [403] Connection: Keep-Alive Content-Length: 115 Content-Type: application/json; charset=UTF-8 Date: Tue, 26 Mar 2024 02:06:41 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-0888a62e-26ff-430d-92f0-f53451b32aca x-openstack-request-id: req-0888a62e-26ff-430d-92f0-f53451b32aca 682s DEBUG (session:580) RESP BODY: {"forbidden": {"code": 403, "message": "Quota exceeded for cores: Requested 2, but already used 140 of 140 cores"}} 682s DEBUG (session:936) POST call to compute for https://nova.ps6.canonical.com:8774/v2.1/servers used request id req-0888a62e-26ff-430d-92f0-f53451b32aca 682s DEBUG (shell:822) Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-0888a62e-26ff-430d-92f0-f53451b32aca) 682s Traceback (most recent call last): 682s File "/usr/lib/python3/dist-packages/novaclient/shell.py", line 820, in main 682s OpenStackComputeShell().main(argv) 682s File "/usr/lib/python3/dist-packages/novaclient/shell.py", line 742, in main 682s args.func(self.cs, args) 682s File "/usr/lib/python3/dist-packages/novaclient/v2/shell.py", line 971, in do_boot 682s server = cs.servers.create(*boot_args, **boot_kwargs) 682s File "/usr/lib/python3/dist-packages/novaclient/v2/servers.py", line 1477, in create 682s return self._boot(response_key, *boot_args, **boot_kwargs) 682s File "/usr/lib/python3/dist-packages/novaclient/v2/servers.py", line 836, in _boot 682s return self._create('/servers', body, response_key, 682s File "/usr/lib/python3/dist-packages/novaclient/base.py", line 363, in _create 682s resp, body = self.api.client.post(url, body=body) 682s File "/usr/lib/python3/dist-packages/keystoneauth1/adapter.py", line 392, in post 682s return self.request(url, 'POST', **kwargs) 682s File "/usr/lib/python3/dist-packages/novaclient/client.py", line 78, in request 682s raise exceptions.from_response(resp, body, url, method) 682s novaclient.exceptions.Forbidden: Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-0888a62e-26ff-430d-92f0-f53451b32aca) 682s ERROR (Forbidden): Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-0888a62e-26ff-430d-92f0-f53451b32aca) 682s nova boot failed (attempt #2): 682s DEBUG (extension:189) found extension EntryPoint.parse('v1password = swiftclient.authv1:PasswordLoader') 682s DEBUG (extension:189) found extension EntryPoint.parse('noauth = cinderclient.contrib.noauth:CinderNoAuthLoader') 682s DEBUG (extension:189) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken') 682s DEBUG (extension:189) found extension EntryPoint.parse('none = keystoneauth1.loading._plugins.noauth:NoAuth') 682s DEBUG (extension:189) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3adfspassword = keystoneauth1.extras._saml2._loading:ADFSPassword') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3applicationcredential = keystoneauth1.loading._plugins.identity.v3:ApplicationCredential') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3fedkerb = keystoneauth1.extras.kerberos._loading:MappedKerberos') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3kerberos = keystoneauth1.extras.kerberos._loading:Kerberos') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3multifactor = keystoneauth1.loading._plugins.identity.v3:MultiFactor') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oauth1 = keystoneauth1.extras.oauth1._loading:V3OAuth1') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcaccesstoken = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAccessToken') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcclientcredentials = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectClientCredentials') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3samlpassword = keystoneauth1.extras._saml2._loading:Saml2Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3tokenlessauth = keystoneauth1.loading._plugins.identity.v3:TokenlessAuth') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3totp = keystoneauth1.loading._plugins.identity.v3:TOTP') 682s 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" 682s DEBUG (connectionpool:962) Starting new HTTPS connection (1): keystone.ps6.canonical.com:5000 682s DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "GET /v3 HTTP/1.1" 200 267 682s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 267 Content-Type: application/json Date: Tue, 26 Mar 2024 02:11:43 GMT Keep-Alive: timeout=75, max=1000 Server: Apache/2.4.52 (Ubuntu) Vary: X-Auth-Token x-openstack-request-id: req-ffea488d-4105-42b8-8b46-6de633481a29 682s 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"}]}} 682s DEBUG (session:946) GET call to https://keystone.ps6.canonical.com:5000/v3 used request id req-ffea488d-4105-42b8-8b46-6de633481a29 682s DEBUG (base:182) Making authentication request to https://keystone.ps6.canonical.com:5000/v3/auth/tokens 682s DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "POST /v3/auth/tokens HTTP/1.1" 201 12445 682s 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": ["6iJCPcO7Req5Q0vZ1TZd1g"], "expires_at": "2024-03-27T02:11:43.000000Z", "issued_at": "2024-03-26T02:11:43.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"}]}} 682s 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}f1130dfa370c2538b7f40684b40b2f4577137b57b581d117f9c6d45507546c38" 682s 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}f1130dfa370c2538b7f40684b40b2f4577137b57b581d117f9c6d45507546c38" 682s DEBUG (connectionpool:962) Starting new HTTPS connection (1): nova.ps6.canonical.com:8774 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1 HTTP/1.1" 302 0 682s RESP: [302] Connection: Keep-Alive Content-Length: 0 Content-Type: text/plain; charset=utf8 Date: Tue, 26 Mar 2024 02:11:43 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-7460c58f-58ef-4f0a-9287-b8c564589567 x-openstack-request-id: req-7460c58f-58ef-4f0a-9287-b8c564589567 682s DEBUG (session:548) RESP: [302] Connection: Keep-Alive Content-Length: 0 Content-Type: text/plain; charset=utf8 Date: Tue, 26 Mar 2024 02:11:43 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-7460c58f-58ef-4f0a-9287-b8c564589567 x-openstack-request-id: req-7460c58f-58ef-4f0a-9287-b8c564589567 682s RESP BODY: Omitted, Content-Type is set to text/plain; charset=utf8. Only application/json responses have their bodies logged. 682s DEBUG (session:580) RESP BODY: Omitted, Content-Type is set to text/plain; charset=utf8. Only application/json responses have their bodies logged. 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/ HTTP/1.1" 200 397 682s RESP: [200] Connection: Keep-Alive Content-Length: 397 Content-Type: application/json Date: Tue, 26 Mar 2024 02:11:43 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-8766e567-1835-446a-8d49-3d81ccc2a0a7 x-openstack-request-id: req-8766e567-1835-446a-8d49-3d81ccc2a0a7 682s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 397 Content-Type: application/json Date: Tue, 26 Mar 2024 02:11:43 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-8766e567-1835-446a-8d49-3d81ccc2a0a7 x-openstack-request-id: req-8766e567-1835-446a-8d49-3d81ccc2a0a7 682s 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"}]}} 682s 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"}]}} 682s GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/ used request id req-8766e567-1835-446a-8d49-3d81ccc2a0a7 682s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/ used request id req-8766e567-1835-446a-8d49-3d81ccc2a0a7 682s DEBUG (extension:189) found extension EntryPoint.parse('v1password = swiftclient.authv1:PasswordLoader') 682s DEBUG (extension:189) found extension EntryPoint.parse('noauth = cinderclient.contrib.noauth:CinderNoAuthLoader') 682s DEBUG (extension:189) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken') 682s DEBUG (extension:189) found extension EntryPoint.parse('none = keystoneauth1.loading._plugins.noauth:NoAuth') 682s DEBUG (extension:189) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3adfspassword = keystoneauth1.extras._saml2._loading:ADFSPassword') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3applicationcredential = keystoneauth1.loading._plugins.identity.v3:ApplicationCredential') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3fedkerb = keystoneauth1.extras.kerberos._loading:MappedKerberos') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3kerberos = keystoneauth1.extras.kerberos._loading:Kerberos') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3multifactor = keystoneauth1.loading._plugins.identity.v3:MultiFactor') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oauth1 = keystoneauth1.extras.oauth1._loading:V3OAuth1') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcaccesstoken = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAccessToken') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcclientcredentials = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectClientCredentials') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3samlpassword = keystoneauth1.extras._saml2._loading:Saml2Password') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3tokenlessauth = keystoneauth1.loading._plugins.identity.v3:TokenlessAuth') 682s DEBUG (extension:189) found extension EntryPoint.parse('v3totp = keystoneauth1.loading._plugins.identity.v3:TOTP') 682s DEBUG (session:517) REQ: curl -g -i -X GET https://glance.ps6.canonical.com:9292/v2/images/2d366976-4689-4c6f-acab-b55163b07c34 -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}f1130dfa370c2538b7f40684b40b2f4577137b57b581d117f9c6d45507546c38" -H "X-OpenStack-Nova-API-Version: 2.87" 682s DEBUG (connectionpool:962) Starting new HTTPS connection (1): glance.ps6.canonical.com:9292 682s DEBUG (connectionpool:429) https://glance.ps6.canonical.com:9292 "GET /v2/images/2d366976-4689-4c6f-acab-b55163b07c34 HTTP/1.1" 200 1935 682s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 1935 Content-Type: application/json Date: Tue, 26 Mar 2024 02:11:44 GMT Keep-Alive: timeout=75, max=1000 Server: Apache/2.4.52 (Ubuntu) X-Openstack-Request-Id: req-287f8133-d659-43d7-b8cb-3fb352b9c327 682s 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": "da17c128-e5ee-42c1-ab78-154b089c59df", "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-20240326.img", "disk_format": "qcow2", "container_format": "bare", "visibility": "private", "size": 2311979008, "virtual_size": 21474836480, "status": "active", "checksum": "9259ab0e06148597edbb476a4d73477e", "protected": false, "min_ram": 0, "min_disk": 20, "owner": "bd8b3df3f03d43a0a9303b7c0434fdab", "os_hidden": false, "os_hash_algo": "sha512", "os_hash_value": "951897245260e2ccec338ff7d3325559cc24b2748331e9d9a793be044894306f113b1b9769f41820e5c9074d9fa433d35c77d5a969dcb494163f383ef9735fbe", "id": "2d366976-4689-4c6f-acab-b55163b07c34", "created_at": "2024-03-26T00:55:01Z", "updated_at": "2024-03-26T00:55:29Z", "locations": [{"url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/2d366976-4689-4c6f-acab-b55163b07c34/snap", "metadata": {"store": "ceph"}}], "direct_url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/2d366976-4689-4c6f-acab-b55163b07c34/snap", "tags": [], "self": "/v2/images/2d366976-4689-4c6f-acab-b55163b07c34", "file": "/v2/images/2d366976-4689-4c6f-acab-b55163b07c34/file", "schema": "/v2/schemas/image", "stores": "ceph"} 682s DEBUG (session:936) GET call to image for https://glance.ps6.canonical.com:9292/v2/images/2d366976-4689-4c6f-acab-b55163b07c34 used request id req-287f8133-d659-43d7-b8cb-3fb352b9c327 682s 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}f1130dfa370c2538b7f40684b40b2f4577137b57b581d117f9c6d45507546c38" -H "X-OpenStack-Nova-API-Version: 2.87" 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors/autopkgtest HTTP/1.1" 404 84 682s DEBUG (session:548) RESP: [404] Connection: Keep-Alive Content-Length: 84 Content-Type: application/json; charset=UTF-8 Date: Tue, 26 Mar 2024 02:11:44 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-c98519de-6a2a-4590-8c00-6b8d8062f523 x-openstack-request-id: req-c98519de-6a2a-4590-8c00-6b8d8062f523 682s DEBUG (session:580) RESP BODY: {"itemNotFound": {"code": 404, "message": "Flavor autopkgtest could not be found."}} 682s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors/autopkgtest used request id req-c98519de-6a2a-4590-8c00-6b8d8062f523 682s 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}f1130dfa370c2538b7f40684b40b2f4577137b57b581d117f9c6d45507546c38" -H "X-OpenStack-Nova-API-Version: 2.87" 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors?is_public=None HTTP/1.1" 200 1739 682s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 1739 Content-Type: application/json Date: Tue, 26 Mar 2024 02:11:44 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-f793b4f8-13dd-4d25-9832-899b126d17af x-openstack-request-id: req-f793b4f8-13dd-4d25-9832-899b126d17af 682s 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}]} 682s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors?is_public=None used request id req-f793b4f8-13dd-4d25-9832-899b126d17af 682s 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}f1130dfa370c2538b7f40684b40b2f4577137b57b581d117f9c6d45507546c38" -H "X-OpenStack-Nova-API-Version: 2.87" 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors/8d27c5f0-65c7-4018-b3f7-50463875ab09 HTTP/1.1" 200 613 682s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 613 Content-Type: application/json Date: Tue, 26 Mar 2024 02:11:44 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-f145a806-7ad3-40ca-91c2-bd4e58e8b856 x-openstack-request-id: req-f145a806-7ad3-40ca-91c2-bd4e58e8b856 682s 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"}}} 682s 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-f145a806-7ad3-40ca-91c2-bd4e58e8b856 682s 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}f1130dfa370c2538b7f40684b40b2f4577137b57b581d117f9c6d45507546c38" -H "X-OpenStack-Nova-API-Version: 2.87" -d '{"server": {"name": "adt-noble-arm64-genimage-20240326-020617-juju-7f2275-prod-proposed-migration-environment-3", "imageRef": "2d366976-4689-4c6f-acab-b55163b07c34", "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-18.secgroup"}], "networks": [{"uuid": "6ea4839c-9760-4b54-ab7d-70a6312667d1"}]}}' 682s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "POST /v2.1/servers HTTP/1.1" 403 115 682s DEBUG (session:548) RESP: [403] Connection: Keep-Alive Content-Length: 115 Content-Type: application/json; charset=UTF-8 Date: Tue, 26 Mar 2024 02:11:44 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-37275ca5-b519-4612-ae0d-ebeded066c94 x-openstack-request-id: req-37275ca5-b519-4612-ae0d-ebeded066c94 682s DEBUG (session:580) RESP BODY: {"forbidden": {"code": 403, "message": "Quota exceeded for cores: Requested 2, but already used 140 of 140 cores"}} 682s DEBUG (session:936) POST call to compute for https://nova.ps6.canonical.com:8774/v2.1/servers used request id req-37275ca5-b519-4612-ae0d-ebeded066c94 682s DEBUG (shell:822) Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-37275ca5-b519-4612-ae0d-ebeded066c94) 682s Traceback (most recent call last): 682s File "/usr/lib/python3/dist-packages/novaclient/shell.py", line 820, in main 682s OpenStackComputeShell().main(argv) 682s File "/usr/lib/python3/dist-packages/novaclient/shell.py", line 742, in main 682s args.func(self.cs, args) 682s File "/usr/lib/python3/dist-packages/novaclient/v2/shell.py", line 971, in do_boot 682s server = cs.servers.create(*boot_args, **boot_kwargs) 682s File "/usr/lib/python3/dist-packages/novaclient/v2/servers.py", line 1477, in create 682s return self._boot(response_key, *boot_args, **boot_kwargs) 682s File "/usr/lib/python3/dist-packages/novaclient/v2/servers.py", line 836, in _boot 682s return self._create('/servers', body, response_key, 682s File "/usr/lib/python3/dist-packages/novaclient/base.py", line 363, in _create 682s resp, body = self.api.client.post(url, body=body) 682s File "/usr/lib/python3/dist-packages/keystoneauth1/adapter.py", line 392, in post 682s return self.request(url, 'POST', **kwargs) 682s File "/usr/lib/python3/dist-packages/novaclient/client.py", line 78, in request 682s raise exceptions.from_response(resp, body, url, method) 682s novaclient.exceptions.Forbidden: Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-37275ca5-b519-4612-ae0d-ebeded066c94) 682s ERROR (Forbidden): Quota exceeded for cores: Requested 2, but already used 140 of 140 cores (HTTP 403) (Request-ID: req-37275ca5-b519-4612-ae0d-ebeded066c94) 689s autopkgtest [02:17:46]: testbed dpkg architecture: arm64 690s autopkgtest [02:17:47]: testbed apt version: 2.7.12 690s autopkgtest [02:17:47]: @@@@@@@@@@@@@@@@@@@@ test bed setup 691s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 692s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.0 kB] 692s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [497 kB] 692s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [7592 B] 692s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [4032 kB] 692s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [715 kB] 692s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 692s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [43.0 kB] 692s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 692s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [4309 kB] 692s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 692s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [70.5 kB] 692s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 694s Fetched 9859 kB in 2s (4163 kB/s) 694s Reading package lists... 697s Reading package lists... 697s Building dependency tree... 697s Reading state information... 698s Calculating upgrade... 698s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 699s Reading package lists... 699s Building dependency tree... 699s Reading state information... 700s 0 upgraded, 0 newly installed, 0 to remove and 240 not upgraded. 703s sh: Attempting to set up Debian/Ubuntu apt sources automatically 703s sh: Distribution appears to be Ubuntu 704s Reading package lists... 704s Building dependency tree... 704s Reading state information... 705s eatmydata is already the newest version (131-1). 705s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 705s Reading package lists... 705s Building dependency tree... 705s Reading state information... 706s dbus is already the newest version (1.14.10-4ubuntu1). 706s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 706s Reading package lists... 706s Building dependency tree... 706s Reading state information... 707s rng-tools-debian is already the newest version (2.4). 707s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 707s Reading package lists... 707s Building dependency tree... 707s Reading state information... 707s The following packages will be REMOVED: 707s cloud-init* python3-configobj* python3-debconf* 708s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 708s After this operation, 3256 kB disk space will be freed. 708s (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.) 708s Removing cloud-init (24.1.2-0ubuntu1) ... 709s Removing python3-configobj (5.0.8-3) ... 709s Removing python3-debconf (1.5.86) ... 709s Processing triggers for man-db (2.12.0-3) ... 710s (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.) 710s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 712s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 712s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 712s invoke-rc.d: policy-rc.d denied execution of try-restart. 712s Reading package lists... 713s Building dependency tree... 713s Reading state information... 714s linux-generic is already the newest version (6.8.0-11.11+1). 714s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 716s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 716s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 716s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 726s Reading package lists... 728s Reading package lists... 728s Building dependency tree... 728s Reading state information... 728s Calculating upgrade... 729s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 729s Reading package lists... 730s Building dependency tree... 730s Reading state information... 731s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 733s autopkgtest [02:18:30]: rebooting testbed after setup commands that affected boot 949s autopkgtest [02:22:06]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 02:53:31 UTC 2024 952s autopkgtest [02:22:09]: @@@@@@@@@@@@@@@@@@@@ apt-source genimage 955s Get:1 http://ftpmaster.internal/ubuntu noble/universe genimage 17-2 (dsc) [2008 B] 955s Get:2 http://ftpmaster.internal/ubuntu noble/universe genimage 17-2 (tar) [94.9 kB] 955s Get:3 http://ftpmaster.internal/ubuntu noble/universe genimage 17-2 (diff) [3828 B] 955s gpgv: Signature made Fri Feb 16 20:05:55 2024 UTC 955s gpgv: using RSA key B8340990283D8D9BC1949AC74799A35146D12B35 955s gpgv: Can't check signature: No public key 955s dpkg-source: warning: cannot verify inline signature for ./genimage_17-2.dsc: no acceptable signature found 955s autopkgtest [02:22:12]: testing package genimage version 17-2 955s autopkgtest [02:22:12]: build not needed 956s autopkgtest [02:22:13]: test upstream-tests: preparing testbed 957s Reading package lists... 957s Building dependency tree... 957s Reading state information... 958s Starting pkgProblemResolver with broken count: 0 958s Starting 2 pkgProblemResolver with broken count: 0 958s Done 958s The following additional packages will be installed: 958s arm-trusted-firmware-tools genext2fs genimage genisoimage libconfuse-common 958s libconfuse2 liburing2 mtools qemu-utils 958s Suggested packages: 958s android-sdk-libsparse-utils squashfs-tools wodim cdrkit-doc floppyd 958s Recommended packages: 958s qemu-block-extra 958s The following NEW packages will be installed: 958s arm-trusted-firmware-tools autopkgtest-satdep genext2fs genimage genisoimage 958s libconfuse-common libconfuse2 liburing2 mtools qemu-utils 959s 0 upgraded, 10 newly installed, 0 to remove and 0 not upgraded. 959s Need to get 2789 kB/2790 kB of archives. 959s After this operation, 14.8 MB of additional disk space will be used. 959s Get:1 /tmp/autopkgtest.LlGcRQ/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [784 B] 959s Get:2 http://ftpmaster.internal/ubuntu noble/universe arm64 arm-trusted-firmware-tools arm64 2.10.0+dfsg-1 [31.4 kB] 959s Get:3 http://ftpmaster.internal/ubuntu noble/universe arm64 genext2fs arm64 1.5.0-3 [30.4 kB] 959s Get:4 http://ftpmaster.internal/ubuntu noble/universe arm64 libconfuse-common all 3.3-3 [5780 B] 959s Get:5 http://ftpmaster.internal/ubuntu noble/universe arm64 libconfuse2 arm64 3.3-3 [26.2 kB] 959s Get:6 http://ftpmaster.internal/ubuntu noble/universe arm64 genimage arm64 17-2 [54.2 kB] 959s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 genisoimage arm64 9:1.1.11-3.4 [383 kB] 959s Get:8 http://ftpmaster.internal/ubuntu noble/main arm64 liburing2 arm64 2.5-1 [21.5 kB] 959s Get:9 http://ftpmaster.internal/ubuntu noble/main arm64 mtools arm64 4.0.43-1 [196 kB] 959s Get:10 http://ftpmaster.internal/ubuntu noble/main arm64 qemu-utils arm64 1:8.2.1+ds-1ubuntu1 [2041 kB] 960s Fetched 2789 kB in 1s (4137 kB/s) 960s Selecting previously unselected package arm-trusted-firmware-tools. 960s (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.) 960s Preparing to unpack .../0-arm-trusted-firmware-tools_2.10.0+dfsg-1_arm64.deb ... 960s Unpacking arm-trusted-firmware-tools (2.10.0+dfsg-1) ... 960s Selecting previously unselected package genext2fs. 960s Preparing to unpack .../1-genext2fs_1.5.0-3_arm64.deb ... 960s Unpacking genext2fs (1.5.0-3) ... 960s Selecting previously unselected package libconfuse-common. 960s Preparing to unpack .../2-libconfuse-common_3.3-3_all.deb ... 960s Unpacking libconfuse-common (3.3-3) ... 960s Selecting previously unselected package libconfuse2:arm64. 960s Preparing to unpack .../3-libconfuse2_3.3-3_arm64.deb ... 960s Unpacking libconfuse2:arm64 (3.3-3) ... 960s Selecting previously unselected package genimage. 960s Preparing to unpack .../4-genimage_17-2_arm64.deb ... 960s Unpacking genimage (17-2) ... 960s Selecting previously unselected package genisoimage. 960s Preparing to unpack .../5-genisoimage_9%3a1.1.11-3.4_arm64.deb ... 960s Unpacking genisoimage (9:1.1.11-3.4) ... 960s Selecting previously unselected package liburing2:arm64. 960s Preparing to unpack .../6-liburing2_2.5-1_arm64.deb ... 960s Unpacking liburing2:arm64 (2.5-1) ... 960s Selecting previously unselected package mtools. 960s Preparing to unpack .../7-mtools_4.0.43-1_arm64.deb ... 960s Unpacking mtools (4.0.43-1) ... 960s Selecting previously unselected package qemu-utils. 960s Preparing to unpack .../8-qemu-utils_1%3a8.2.1+ds-1ubuntu1_arm64.deb ... 960s Unpacking qemu-utils (1:8.2.1+ds-1ubuntu1) ... 960s Selecting previously unselected package autopkgtest-satdep. 960s Preparing to unpack .../9-1-autopkgtest-satdep.deb ... 960s Unpacking autopkgtest-satdep (0) ... 960s Setting up genisoimage (9:1.1.11-3.4) ... 960s Setting up mtools (4.0.43-1) ... 960s Setting up arm-trusted-firmware-tools (2.10.0+dfsg-1) ... 960s Setting up genext2fs (1.5.0-3) ... 960s Setting up libconfuse-common (3.3-3) ... 960s Setting up liburing2:arm64 (2.5-1) ... 960s Setting up libconfuse2:arm64 (3.3-3) ... 960s Setting up genimage (17-2) ... 960s Setting up qemu-utils (1:8.2.1+ds-1ubuntu1) ... 960s Setting up autopkgtest-satdep (0) ... 960s Processing triggers for man-db (2.12.0-3) ... 963s Processing triggers for install-info (7.1-3) ... 963s Processing triggers for libc-bin (2.39-0ubuntu6) ... 965s (Reading database ... 74519 files and directories currently installed.) 965s Removing autopkgtest-satdep (0) ... 966s autopkgtest [02:22:23]: test upstream-tests: [----------------------- 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/cpio.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/cpio.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/cramfs.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/cramfs.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/exec-check.sh' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/exec-check.sh' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/exec-fail.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/exec-fail.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/exec.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/exec.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext.test' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext.test' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext2.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext2.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext2percent.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext2percent.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext2test-percent.0.dump' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext2test-percent.0.dump' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext2test-percent.1.dump' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext2test-percent.1.dump' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext2test.0.dump' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext2test.0.dump' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext2test.1.dump' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext2test.1.dump' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext3.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext3.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext3test.0.dump' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext3test.0.dump' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext3test.1.dump' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext3test.1.dump' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext4.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext4.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext4test.0.dump' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext4test.0.dump' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ext4test.1.dump' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext4test.1.dump' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/f2fs.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/f2fs.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/filesystem.test' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/filesystem.test' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/fip.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/fip.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/fit.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/fit.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/fit.its' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/fit.its' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/flash-types.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/flash-types.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/flash.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/flash.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/flash.md5' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/flash.md5' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/flash.test' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/flash.test' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/genimage.test' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/genimage.test' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/gpt-invalid-partition-type1.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/gpt-invalid-partition-type1.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/gpt-invalid-partition-type2.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/gpt-invalid-partition-type2.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/gpt-overlap1.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/gpt-overlap1.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/gpt-overlap2.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/gpt-overlap2.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/gpt-overlap3.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/gpt-overlap3.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/gpt-partition-types.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/gpt-partition-types.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/gpt-partition-types.fdisk' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/gpt-partition-types.fdisk' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage-fail1.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-fail1.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage-fail2.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-fail2.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage-fail3.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-fail3.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage-fail4.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-fail4.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage-fail5.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-fail5.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage-fail6.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-fail6.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage-fail7.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-fail7.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage-hybrid.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-hybrid.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage-hybrid.fdisk' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-hybrid.fdisk' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage-nopart.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-nopart.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage-nopart.hexdump' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-nopart.hexdump' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage.fdisk' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage.fdisk' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage.fdisk-2' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage.fdisk-2' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage2.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage2.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage4.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage4.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage4.fdisk' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage4.fdisk' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage5.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage5.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage5.fdisk' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage5.fdisk' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage6.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage6.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage6.fdisk' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage6.fdisk' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage7.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage7.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage7.fdisk' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage7.fdisk' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hole.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hole.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/include-aaa.fdisk' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include-aaa.fdisk' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/include-bbb.fdisk' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include-bbb.fdisk' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/include-ccc.fdisk' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include-ccc.fdisk' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/include-test.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include-test.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/include.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/include' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/include/aaa' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include/aaa' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/include/aaa/include-test.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include/aaa/include-test.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/include/bbb' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include/bbb' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/include/bbb/include-test.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include/bbb/include-test.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/iso.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/iso.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/jffs2.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/jffs2.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/jffs2.md5' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/jffs2.md5' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/misc.test' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/misc.test' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/mke2fs.0.dump' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/mke2fs.0.dump' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/mke2fs.conf' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/mke2fs.conf' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/mke2fs.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/mke2fs.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/qemu.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/qemu.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/qemu.qcow.gz' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/qemu.qcow.gz' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/rauc-openssl-ca' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/rauc-openssl-ca' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/rauc-openssl-ca/ca.cert.pem' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/rauc-openssl-ca/ca.cert.pem' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/rauc-openssl-ca/rauc.cert.pem' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/rauc-openssl-ca/rauc.cert.pem' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/rauc-openssl-ca/rauc.key.pem' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/rauc-openssl-ca/rauc.key.pem' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/rauc.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/rauc.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/sharness.sh' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/sharness.sh' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/sparse.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/sparse.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/squashfs.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/squashfs.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/tar.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/tar.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/test-setup.sh' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/test-setup.sh' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/test.raucb.info' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/test.raucb.info' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/test.raucb.info.new' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/test.raucb.info.new' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/test2.raucb.info' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/test2.raucb.info' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/test2.raucb.info.new' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/test2.raucb.info.new' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ubi.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ubi.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/ubifs.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ubifs.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/vfat.config' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/vfat.config' 966s '/tmp/autopkgtest.LlGcRQ/build.eHG/src/test/hdimage.test' -> '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage.test' 967s expecting success: 967s run_genimage_root ext2.config test.ext2 && 967s check_ext images/test.ext2 ext2test 4194304 genext2fs 967s 967s 1+0 records in 967s 1+0 records out 967s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00101428 s, 1.0 GB/s 967s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"" (stderr+stdout): 967s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"/*" (stderr+stdout): 967s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"" (stderr+stdout): 967s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp/root"" (stderr+stdout): 967s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images"" (stderr+stdout): 967s INFO: ext2(test.ext2): cmd: "genext2fs -d '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp/root' --size-in-blocks=4096 -i 16384 '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext2' " (stderr+stdout): 967s copying from directory /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp/root 967s INFO: ext2(test.ext2): cmd: "tune2fs -L 'ext2test' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext2'" (stderr+stdout): 967s tune2fs 1.47.0 (5-Feb-2023) 967s INFO: ext2(test.ext2): cmd: "e2fsck -pvfD '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext2'" (stderr+stdout): 967s ext2test: Filesystem did not have a UUID; generating one. 967s 967s 967s 51 inodes used (19.92%, out of 256) 967s 0 non-contiguous files (0.0%) 967s 0 non-contiguous directories (0.0%) 967s # of inodes with ind/dind/tind blocks: 1/0/0 967s 71 blocks used (1.73%, out of 4096) 967s 0 bad blocks 967s 0 large files 967s 967s 24 regular files 967s 18 directories 967s 0 character device files 967s 0 block device files 967s 0 fifos 967s 0 links 967s 0 symbolic links (0 fast symbolic links) 967s 0 sockets 967s ------------ 967s 42 files 967s INFO: ext2(test.ext2): cmd: "echo 'set_current_time 20000101000000 967s set_super_value mkfs_time 20000101000000 967s set_super_value lastcheck 20000101000000 967s set_super_value mtime 00000000' | debugfs -w '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext2'" (stderr+stdout): 967s debugfs 1.47.0 (5-Feb-2023) 967s debugfs: set_current_time 20000101000000 967s Setting current time to Sat Jan 1 00:00:00 2000 967s 967s debugfs: set_super_value mkfs_time 20000101000000 967s debugfs: set_super_value lastcheck 20000101000000 967s debugfs: set_super_value mtime 00000000 967s debugfs: ++ uuid='Filesystem UUID' 967s ++ seed='Directory Hash Seed:' 967s ++ csum1='Checksum: \|Group 0: (Blocks 1-4095) csum' 967s ++ csum2='Group 0: (Blocks 1-4095) \[ITABLE_ZEROED\]\| Checksum .*, unused inodes 205' 967s ++ dumpe2fs images/test.ext2 967s ++ grep -v '^\(Filesystem UUID\|Directory Hash Seed:\|Checksum: \|Group 0: (Blocks 1-4095) csum\|Group 0: (Blocks 1-4095) \[ITABLE_ZEROED\]\| Checksum .*, unused inodes 205\)' 967s dumpe2fs 1.47.0 (5-Feb-2023) 967s ++ sed -i 's/un\(signed_directory_hash\)/\1/' dump 967s ++ sed -i 's/^\(Journal size: \)1029k$/\11024k/' dump 967s ++ sed -i 's/^\( Block bitmap at .*\),\( Inode bitmap.*\)$/\1\n \2/' dump 967s ++ sed -i -e 's/^J\(ournal size:\) /Total j\1/' -e 's/^Journal length: \( *\)\(.*\)$/Total journal blocks:\1\2\nMax transaction length: \2\nFast commit length: 0/' -e '/^Overhead clusters:/d' dump 967s ++ '[' genext2fs = genext2fs ']' 967s +++ genext2fs --version 967s ++ '[' 'genext2fs 1.4.2' = 'genext2fs 1.4.1' ']' 967s ++ version=1 967s ++ test_cmp /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext2test.1.dump dump 967s ++ diff -u /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext2test.1.dump dump 967s ++ e2fsck -nf images/test.ext2 967s e2fsck 1.47.0 (5-Feb-2023) 967s Pass 1: Checking inodes, blocks, and sizes 967s Pass 2: Checking directory structure 967s Pass 3: Checking directory connectivity 967s Pass 4: Checking reference counts 967s Pass 5: Checking group summary information 967s ext2test: 51/256 files (0.0% non-contiguous), 71/4096 blocks 967s ++ debugfs -R 'ls -p' images/test.ext2 967s ++ IFS=/ 967s ++ read a b c d e x f 967s debugfs 1.47.0 (5-Feb-2023) 967s ++ case "${x}" in 967s ++ continue 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ continue 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ continue 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ debugfs -R 'rdump "with spaces" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext2 967s debugfs 1.47.0 (5-Feb-2023) 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ debugfs -R 'rdump "baz" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext2 967s debugfs 1.47.0 (5-Feb-2023) 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ debugfs -R 'rdump "foo" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext2 967s debugfs 1.47.0 (5-Feb-2023) 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ debugfs -R 'rdump "bar" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext2 967s debugfs 1.47.0 (5-Feb-2023) 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ continue 967s ++ read a b c d e x f 967s ++ check_size images/test.ext2 4194304 967s ++ local size 967s ++ get_size images/test.ext2 967s ++ local file=images/test.ext2 967s ++ '[' '!' -f images/test.ext2 ']' 967s +++ du -b images/test.ext2 967s ++ set -- 4194304 images/test.ext2 967s ++ size=4194304 967s ++ '[' 4194304 -ne 4194304 ']' 967s ++ check_root 967s ++ diff -ru '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.orig' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test' 967s ++ func_check 967s ++ local ret=0 967s ++ set +x 967s ok 1 - ext2 967s 967s expecting success: 967s run_genimage_root ext2percent.config test.ext2 && 967s check_ext images/test.ext2 ext2test-percent 69632 genext2fs 967s 967s 1+0 records in 967s 1+0 records out 967s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00093884 s, 1.1 GB/s 967s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"" (stderr+stdout): 967s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"/*" (stderr+stdout): 967s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"" (stderr+stdout): 967s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp/root"" (stderr+stdout): 967s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images"" (stderr+stdout): 967s INFO: ext2(test.ext2): cmd: "genext2fs -d '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp/root' --size-in-blocks=68 -i 16384 '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext2' " (stderr+stdout): 967s copying from directory /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp/root 967s INFO: ext2(test.ext2): cmd: "tune2fs -L 'ext2test' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext2'" (stderr+stdout): 967s tune2fs 1.47.0 (5-Feb-2023) 967s INFO: ext2(test.ext2): cmd: "e2fsck -pvfD '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext2'" (stderr+stdout): 967s ext2test: Filesystem did not have a UUID; generating one. 967s 967s 967s 51 inodes used (91.07%, out of 56) 967s 0 non-contiguous files (0.0%) 967s 0 non-contiguous directories (0.0%) 967s # of inodes with ind/dind/tind blocks: 1/0/0 967s 46 blocks used (67.65%, out of 68) 967s 0 bad blocks 967s 0 large files 967s 967s 24 regular files 967s 18 directories 967s 0 character device files 967s 0 block device files 967s 0 fifos 967s 0 links 967s 0 symbolic links (0 fast symbolic links) 967s 0 sockets 967s ------------ 967s 42 files 967s INFO: ext2(test.ext2): cmd: "echo 'set_current_time 20000101000000 967s set_super_value mkfs_time 20000101000000 967s set_super_value lastcheck 20000101000000 967s set_super_value mtime 00000000' | debugfs -w '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext2'" (stderr+stdout): 967s debugfs 1.47.0 (5-Feb-2023) 967s debugfs: set_current_time 20000101000000 967s Setting current time to Sat Jan 1 00:00:00 2000 967s 967s debugfs: set_super_value mkfs_time 20000101000000 967s debugfs: set_super_value lastcheck 20000101000000 967s debugfs: set_super_value mtime 00000000 967s debugfs: ++ uuid='Filesystem UUID' 967s ++ seed='Directory Hash Seed:' 967s ++ csum1='Checksum: \|Group 0: (Blocks 1-4095) csum' 967s ++ csum2='Group 0: (Blocks 1-4095) \[ITABLE_ZEROED\]\| Checksum .*, unused inodes 205' 967s ++ dumpe2fs images/test.ext2 967s ++ grep -v '^\(Filesystem UUID\|Directory Hash Seed:\|Checksum: \|Group 0: (Blocks 1-4095) csum\|Group 0: (Blocks 1-4095) \[ITABLE_ZEROED\]\| Checksum .*, unused inodes 205\)' 967s dumpe2fs 1.47.0 (5-Feb-2023) 967s ++ sed -i 's/un\(signed_directory_hash\)/\1/' dump 967s ++ sed -i 's/^\(Journal size: \)1029k$/\11024k/' dump 967s ++ sed -i 's/^\( Block bitmap at .*\),\( Inode bitmap.*\)$/\1\n \2/' dump 967s ++ sed -i -e 's/^J\(ournal size:\) /Total j\1/' -e 's/^Journal length: \( *\)\(.*\)$/Total journal blocks:\1\2\nMax transaction length: \2\nFast commit length: 0/' -e '/^Overhead clusters:/d' dump 967s ++ '[' genext2fs = genext2fs ']' 967s +++ genext2fs --version 967s ++ '[' 'genext2fs 1.4.2' = 'genext2fs 1.4.1' ']' 967s ++ version=1 967s ++ test_cmp /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext2test-percent.1.dump dump 967s ++ diff -u /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext2test-percent.1.dump dump 967s ++ e2fsck -nf images/test.ext2 967s e2fsck 1.47.0 (5-Feb-2023) 967s Pass 1: Checking inodes, blocks, and sizes 967s Pass 2: Checking directory structure 967s Pass 3: Checking directory connectivity 967s Pass 4: Checking reference counts 967s Pass 5: Checking group summary information 967s ext2test: 51/56 files (0.0% non-contiguous), 46/68 blocks 967s ++ debugfs -R 'ls -p' images/test.ext2 967s ++ IFS=/ 967s ++ read a b c d e x f 967s debugfs 1.47.0 (5-Feb-2023) 967s ++ case "${x}" in 967s ++ continue 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ continue 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ continue 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ debugfs -R 'rdump "with spaces" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext2 967s debugfs 1.47.0 (5-Feb-2023) 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ debugfs -R 'rdump "baz" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext2 967s debugfs 1.47.0 (5-Feb-2023) 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ debugfs -R 'rdump "foo" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext2 967s debugfs 1.47.0 (5-Feb-2023) 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ debugfs -R 'rdump "bar" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext2 967s debugfs 1.47.0 (5-Feb-2023) 967s ++ read a b c d e x f 967s ++ case "${x}" in 967s ++ continue 967s ++ read a b c d e x f 967s ++ check_size images/test.ext2 69632 967s ++ local size 967s ++ get_size images/test.ext2 967s ++ local file=images/test.ext2 967s ++ '[' '!' -f images/test.ext2 ']' 967s +++ du -b images/test.ext2 967s ++ set -- 69632 images/test.ext2 967s ++ size=69632 967s ++ '[' 69632 -ne 69632 ']' 967s ++ check_root 967s ++ diff -ru '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.orig' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test' 967s ++ func_check 967s ++ local ret=0 967s ++ set +x 967s ok 2 - ext2percent 967s 967s expecting success: 967s run_genimage_root ext3.config test.ext3 && 967s check_ext images/test.ext3 ext3test 4194304 genext2fs 967s 967s 1+0 records in 967s 1+0 records out 967s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.000956 s, 1.1 GB/s 968s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"" (stderr+stdout): 968s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"/*" (stderr+stdout): 968s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"" (stderr+stdout): 968s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp/root"" (stderr+stdout): 968s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images"" (stderr+stdout): 968s INFO: ext3(test.ext3): cmd: "genext2fs -d '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp/root' --size-in-blocks=4096 -i 16384 '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext3' " (stderr+stdout): 968s copying from directory /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp/root 968s INFO: ext3(test.ext3): cmd: "tune2fs -O 'has_journal' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext3'" (stderr+stdout): 968s tune2fs 1.47.0 (5-Feb-2023) 968s Creating journal inode: done 968s This filesystem will be automatically checked every 20 mounts or 968s 0 days, whichever comes first. Use tune2fs -c or -i to override. 968s INFO: ext3(test.ext3): cmd: "tune2fs -L 'ext3test' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext3'" (stderr+stdout): 968s tune2fs 1.47.0 (5-Feb-2023) 968s INFO: ext3(test.ext3): cmd: "e2fsck -pvfD '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext3'" (stderr+stdout): 968s ext3test: Filesystem did not have a UUID; generating one. 968s 968s 968s 51 inodes used (19.92%, out of 256) 968s 0 non-contiguous files (0.0%) 968s 0 non-contiguous directories (0.0%) 968s # of inodes with ind/dind/tind blocks: 1/0/0 968s 1100 blocks used (26.86%, out of 4096) 968s 0 bad blocks 968s 0 large files 968s 968s 24 regular files 968s 18 directories 968s 0 character device files 968s 0 block device files 968s 0 fifos 968s 0 links 968s 0 symbolic links (0 fast symbolic links) 968s 0 sockets 968s ------------ 968s 42 files 968s INFO: ext3(test.ext3): cmd: "echo 'set_current_time 20000101000000 968s set_super_value mkfs_time 20000101000000 968s set_super_value lastcheck 20000101000000 968s set_super_value mtime 00000000' | debugfs -w '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext3'" (stderr+stdout): 968s debugfs 1.47.0 (5-Feb-2023) 968s debugfs: set_current_time 20000101000000 968s Setting current time to Sat Jan 1 00:00:00 2000 968s 968s debugfs: set_super_value mkfs_time 20000101000000 968s debugfs: set_super_value lastcheck 20000101000000 968s debugfs: set_super_value mtime 00000000 968s debugfs: ++ uuid='Filesystem UUID' 968s ++ seed='Directory Hash Seed:' 968s ++ csum1='Checksum: \|Group 0: (Blocks 1-4095) csum' 968s ++ csum2='Group 0: (Blocks 1-4095) \[ITABLE_ZEROED\]\| Checksum .*, unused inodes 205' 968s ++ dumpe2fs images/test.ext3 968s dumpe2fs 1.47.0 (5-Feb-2023) 968s ++ grep -v '^\(Filesystem UUID\|Directory Hash Seed:\|Checksum: \|Group 0: (Blocks 1-4095) csum\|Group 0: (Blocks 1-4095) \[ITABLE_ZEROED\]\| Checksum .*, unused inodes 205\)' 968s ++ sed -i 's/un\(signed_directory_hash\)/\1/' dump 968s ++ sed -i 's/^\(Journal size: \)1029k$/\11024k/' dump 968s ++ sed -i 's/^\( Block bitmap at .*\),\( Inode bitmap.*\)$/\1\n \2/' dump 968s ++ sed -i -e 's/^J\(ournal size:\) /Total j\1/' -e 's/^Journal length: \( *\)\(.*\)$/Total journal blocks:\1\2\nMax transaction length: \2\nFast commit length: 0/' -e '/^Overhead clusters:/d' dump 968s ++ '[' genext2fs = genext2fs ']' 968s +++ genext2fs --version 968s ++ '[' 'genext2fs 1.4.2' = 'genext2fs 1.4.1' ']' 968s ++ version=1 968s ++ test_cmp /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext3test.1.dump dump 968s ++ diff -u /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext3test.1.dump dump 968s ++ e2fsck -nf images/test.ext3 968s e2fsck 1.47.0 (5-Feb-2023) 968s Pass 1: Checking inodes, blocks, and sizes 968s Pass 2: Checking directory structure 968s Pass 3: Checking directory connectivity 968s Pass 4: Checking reference counts 968s Pass 5: Checking group summary information 968s ext3test: 51/256 files (0.0% non-contiguous), 1100/4096 blocks 968s ++ debugfs -R 'ls -p' images/test.ext3 968s ++ IFS=/ 968s ++ read a b c d e x f 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "with spaces" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext3 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "baz" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext3 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "foo" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext3 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "bar" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext3 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ check_size images/test.ext3 4194304 968s ++ local size 968s ++ get_size images/test.ext3 968s ++ local file=images/test.ext3 968s ++ '[' '!' -f images/test.ext3 ']' 968s +++ du -b images/test.ext3 968s ++ set -- 4194304 images/test.ext3 968s ++ size=4194304 968s ++ '[' 4194304 -ne 4194304 ']' 968s ++ check_root 968s ++ diff -ru '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.orig' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test' 968s ++ func_check 968s ++ local ret=0 968s ++ set +x 968s ok 3 - ext3 968s 968s expecting success: 968s run_genimage ext4.config test.ext4 && 968s check_ext images/test.ext4 ext4test 4194304 genext2fs 968s 968s 1+0 records in 968s 1+0 records out 968s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00097204 s, 1.1 GB/s 968s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"" (stderr+stdout): 968s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"/*" (stderr+stdout): 968s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images"" (stderr+stdout): 968s INFO: ext4(test.ext4): cmd: "genext2fs -d 'root.orig' --size-in-blocks=4096 -i 16384 '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext4' " (stderr+stdout): 968s copying from directory root.orig 968s INFO: ext4(test.ext4): cmd: "tune2fs -O 'extents,uninit_bg,dir_index,has_journal' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext4'" (stderr+stdout): 968s tune2fs 1.47.0 (5-Feb-2023) 968s Creating journal inode: done 968s This filesystem will be automatically checked every 20 mounts or 968s 0 days, whichever comes first. Use tune2fs -c or -i to override. 968s INFO: ext4(test.ext4): cmd: "tune2fs -L 'ext4test' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext4'" (stderr+stdout): 968s tune2fs 1.47.0 (5-Feb-2023) 968s INFO: ext4(test.ext4): cmd: "e2fsck -pvfD '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext4'" (stderr+stdout): 968s ext4test: Filesystem did not have a UUID; generating one. 968s 968s ext4test: Adding dirhash hint to filesystem. 968s 968s 968s 51 inodes used (19.92%, out of 256) 968s 0 non-contiguous files (0.0%) 968s 0 non-contiguous directories (0.0%) 968s # of inodes with ind/dind/tind blocks: 1/0/0 968s Extent depth histogram: 1 968s 1095 blocks used (26.73%, out of 4096) 968s 0 bad blocks 968s 0 large files 968s 968s 24 regular files 968s 18 directories 968s 0 character device files 968s 0 block device files 968s 0 fifos 968s 0 links 968s 0 symbolic links (0 fast symbolic links) 968s 0 sockets 968s ------------ 968s 42 files 968s INFO: ext4(test.ext4): cmd: "echo 'set_current_time 20000101000000 968s set_super_value mkfs_time 20000101000000 968s set_super_value lastcheck 20000101000000 968s set_super_value mtime 00000000' | debugfs -w '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/test.ext4'" (stderr+stdout): 968s debugfs 1.47.0 (5-Feb-2023) 968s debugfs: set_current_time 20000101000000 968s Setting current time to Sat Jan 1 00:00:00 2000 968s 968s debugfs: set_super_value mkfs_time 20000101000000 968s debugfs: set_super_value lastcheck 20000101000000 968s debugfs: set_super_value mtime 00000000 968s debugfs: ++ uuid='Filesystem UUID' 968s ++ seed='Directory Hash Seed:' 968s ++ csum1='Checksum: \|Group 0: (Blocks 1-4095) csum' 968s ++ csum2='Group 0: (Blocks 1-4095) \[ITABLE_ZEROED\]\| Checksum .*, unused inodes 205' 968s ++ dumpe2fs images/test.ext4 968s ++ grep -v '^\(Filesystem UUID\|Directory Hash Seed:\|Checksum: \|Group 0: (Blocks 1-4095) csum\|Group 0: (Blocks 1-4095) \[ITABLE_ZEROED\]\| Checksum .*, unused inodes 205\)' 968s dumpe2fs 1.47.0 (5-Feb-2023) 968s ++ sed -i 's/un\(signed_directory_hash\)/\1/' dump 968s ++ sed -i 's/^\(Journal size: \)1029k$/\11024k/' dump 968s ++ sed -i 's/^\( Block bitmap at .*\),\( Inode bitmap.*\)$/\1\n \2/' dump 968s ++ sed -i -e 's/^J\(ournal size:\) /Total j\1/' -e 's/^Journal length: \( *\)\(.*\)$/Total journal blocks:\1\2\nMax transaction length: \2\nFast commit length: 0/' -e '/^Overhead clusters:/d' dump 968s ++ '[' genext2fs = genext2fs ']' 968s +++ genext2fs --version 968s ++ '[' 'genext2fs 1.4.2' = 'genext2fs 1.4.1' ']' 968s ++ version=1 968s ++ test_cmp /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext4test.1.dump dump 968s ++ diff -u /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/ext4test.1.dump dump 968s ++ e2fsck -nf images/test.ext4 968s e2fsck 1.47.0 (5-Feb-2023) 968s Pass 1: Checking inodes, blocks, and sizes 968s Pass 2: Checking directory structure 968s Pass 3: Checking directory connectivity 968s Pass 4: Checking reference counts 968s Pass 5: Checking group summary information 968s ext4test: 51/256 files (0.0% non-contiguous), 1095/4096 blocks 968s ++ debugfs -R 'ls -p' images/test.ext4 968s ++ IFS=/ 968s ++ read a b c d e x f 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "with spaces" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext4 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "baz" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext4 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "foo" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext4 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "bar" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/test.ext4 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ check_size images/test.ext4 4194304 968s ++ local size 968s ++ get_size images/test.ext4 968s ++ local file=images/test.ext4 968s ++ '[' '!' -f images/test.ext4 ']' 968s +++ du -b images/test.ext4 968s ++ set -- 4194304 images/test.ext4 968s ++ size=4194304 968s ++ '[' 4194304 -ne 4194304 ']' 968s ++ check_root 968s ++ diff -ru '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.orig' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test' 968s ++ func_check 968s ++ local ret=0 968s ++ set +x 968s ok 4 - ext4 968s 968s expecting success: 968s run_genimage_root mke2fs.config mke2fs.ext4 && 968s check_ext images/mke2fs.ext4 mke2fs 33554432 mke2fs 968s 968s 1+0 records in 968s 1+0 records out 968s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00096068 s, 1.1 GB/s 968s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"" (stderr+stdout): 968s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"/*" (stderr+stdout): 968s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp"" (stderr+stdout): 968s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp/root"" (stderr+stdout): 968s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images"" (stderr+stdout): 968s INFO: ext4(mke2fs.ext4): cmd: "MKE2FS_CONFIG="mke2fs.conf" mke2fs -t ext4 -I 256 -E 'root_owner=0:0,lazy_itable_init=0,lazy_journal_init=0' -O '^large_file' -O '^huge_file' -d '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/tmp/root' -U 12345678-1234-1234-1234-1234567890ab -E quotatype= -L mke2fs -O '^resize_inode,quota' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/mke2fs.ext4' 32768k" (stderr+stdout): 968s mke2fs 1.47.0 (5-Feb-2023) 968s Discarding device blocks: 0/32768 done 968s Creating filesystem with 32768 1k blocks and 8192 inodes 968s Filesystem UUID: 12345678-1234-1234-1234-1234567890ab 968s Superblock backups stored on blocks: 968s 8193, 24577 968s 968s Allocating group tables: 0/4 done 968s Writing inode tables: 0/4 done 968s Creating journal (4096 blocks): done 968s Copying files into the device: done 968s Writing superblocks and filesystem accounting information: 0/4 done 968s 968s INFO: ext4(mke2fs.ext4): cmd: "e2fsck -pvfD '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/mke2fs.ext4'" (stderr+stdout): 968s 968s 51 inodes used (0.62%, out of 8192) 968s 0 non-contiguous files (0.0%) 968s 0 non-contiguous directories (0.0%) 968s # of inodes with ind/dind/tind blocks: 0/0/0 968s Extent depth histogram: 43 968s 6188 blocks used (18.88%, out of 32768) 968s 0 bad blocks 968s 0 large files 968s 968s 24 regular files 968s 18 directories 968s 0 character device files 968s 0 block device files 968s 0 fifos 968s 0 links 968s 0 symbolic links (0 fast symbolic links) 968s 0 sockets 968s ------------ 968s 42 files 968s INFO: ext4(mke2fs.ext4): cmd: "echo 'set_current_time 20000101000000 968s set_super_value mkfs_time 20000101000000 968s set_super_value lastcheck 20000101000000 968s set_super_value mtime 00000000' | debugfs -w '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/images/mke2fs.ext4'" (stderr+stdout): 968s debugfs 1.47.0 (5-Feb-2023) 968s debugfs: set_current_time 20000101000000 968s Setting current time to Sat Jan 1 00:00:00 2000 968s 968s debugfs: set_super_value mkfs_time 20000101000000 968s debugfs: set_super_value lastcheck 20000101000000 968s debugfs: set_super_value mtime 00000000 968s debugfs: ++ uuid='Filesystem UUID' 968s ++ seed='Directory Hash Seed:' 968s ++ csum1='Checksum: \|Group 0: (Blocks 1-4095) csum' 968s ++ csum2='Group 0: (Blocks 1-4095) \[ITABLE_ZEROED\]\| Checksum .*, unused inodes 205' 968s ++ dumpe2fs images/mke2fs.ext4 968s ++ grep -v '^\(Filesystem UUID\|Directory Hash Seed:\|Checksum: \|Group 0: (Blocks 1-4095) csum\|Group 0: (Blocks 1-4095) \[ITABLE_ZEROED\]\| Checksum .*, unused inodes 205\)' 968s dumpe2fs 1.47.0 (5-Feb-2023) 968s ++ sed -i 's/un\(signed_directory_hash\)/\1/' dump 968s ++ sed -i 's/^\(Journal size: \)1029k$/\11024k/' dump 968s ++ sed -i 's/^\( Block bitmap at .*\),\( Inode bitmap.*\)$/\1\n \2/' dump 968s ++ sed -i -e 's/^J\(ournal size:\) /Total j\1/' -e 's/^Journal length: \( *\)\(.*\)$/Total journal blocks:\1\2\nMax transaction length: \2\nFast commit length: 0/' -e '/^Overhead clusters:/d' dump 968s ++ '[' mke2fs = genext2fs ']' 968s ++ version=0 968s ++ test_cmp /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/mke2fs.0.dump dump 968s ++ diff -u /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/mke2fs.0.dump dump 968s ++ e2fsck -nf images/mke2fs.ext4 968s e2fsck 1.47.0 (5-Feb-2023) 968s Pass 1: Checking inodes, blocks, and sizes 968s Pass 2: Checking directory structure 968s Pass 3: Checking directory connectivity 968s Pass 4: Checking reference counts 968s Pass 5: Checking group summary information 968s mke2fs: 51/8192 files (0.0% non-contiguous), 6188/32768 blocks 968s ++ debugfs -R 'ls -p' images/mke2fs.ext4 968s ++ IFS=/ 968s ++ read a b c d e x f 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "bar" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/mke2fs.ext4 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "baz" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/mke2fs.ext4 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "foo" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/mke2fs.ext4 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ debugfs -R 'rdump "with spaces" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test"' images/mke2fs.ext4 968s debugfs 1.47.0 (5-Feb-2023) 968s ++ read a b c d e x f 968s ++ case "${x}" in 968s ++ continue 968s ++ read a b c d e x f 968s ++ check_size images/mke2fs.ext4 33554432 968s ++ local size 968s ++ get_size images/mke2fs.ext4 968s ++ local file=images/mke2fs.ext4 968s ++ '[' '!' -f images/mke2fs.ext4 ']' 968s +++ du -b images/mke2fs.ext4 968s ++ set -- 33554432 images/mke2fs.ext4 968s ++ size=33554432 968s ++ '[' 33554432 -ne 33554432 ']' 968s ++ check_root 968s ++ diff -ru '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.orig' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.ext.test/root.test' 968s ++ func_check 968s ++ local ret=0 968s ++ set +x 968s ok 5 - mke2fs 968s 968s # passed all 5 test(s) 968s 1..5 968s expecting success: 968s run_genimage_root cpio.config test.cpio && 968s zcat images/test.cpio | cpio --extract -t | grep -v '^\.$' | sort > '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/file-list.test' && 968s check_size_range images/test.cpio 400 550 && 968s check_filelist 968s 968s 1+0 records in 968s 1+0 records out 968s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00096216 s, 1.1 GB/s 968s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"" (stderr+stdout): 968s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"/*" (stderr+stdout): 968s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"" (stderr+stdout): 968s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp/root"" (stderr+stdout): 968s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/images"" (stderr+stdout): 968s INFO: cpio(test.cpio): cmd: "(cd '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp/root' && find . | cpio -H 'newc' -o | gzip) > '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/images/test.cpio'" (stderr+stdout): 968s 10 blocks 968s 10 blocks 968s ok 1 - cpio 968s 968s skipping test: cramfs 968s run_genimage_root cramfs.config test.cramfs && 968s check_size images/test.cramfs 4096 968s 968s ok 2 # skip cramfs (missing mkcramfs) 968s 968s expecting success: 968s run_genimage_root iso.config test.iso && 968s check_size_range images/test.iso 300000 400000 968s 968s 1+0 records in 968s 1+0 records out 968s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00117072 s, 896 MB/s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"/*" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"" (stderr+stdout): 969s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp/root"" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/images"" (stderr+stdout): 969s INFO: iso(test.iso): cmd: "genisoimage -input-charset default -R -hide-rr-moved -V 'iso-test' -o '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/images/test.iso' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp/root'" (stderr+stdout): 969s Total translation table size: 0 969s Total rockridge attributes bytes: 5297 969s Total directory bytes: 32768 969s Path table size(bytes): 182 969s Max brk space used 1a000 969s 191 extents written (0 MB) 969s ok 3 - iso 969s 969s skipping test: f2fs 969s run_genimage_root f2fs.config test.f2fs && 969s fsck.f2fs images/test.f2fs 969s 969s ok 4 # skip f2fs (missing fsck_f2fs,sload_f2fs,mkfs_f2fs of mkfs_f2fs,sload_f2fs,fsck_f2fs) 969s 969s skipping test: squashfs 969s run_genimage_root squashfs.config test.squashfs && 969s check_size_range images/test.squashfs 4000 4100 && 969s unsquashfs -ls images/test.squashfs | sed -n '/squashfs-root/s;squashfs-root/;;p' | sort > '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/file-list.test' && 969s check_filelist 969s 969s ok 5 # skip squashfs (missing mksquashfs) 969s 969s expecting success: 969s run_genimage_root tar.config test.tar.gz && 969s check_size_range images/test.tar.gz 500 600 && 969s zcat images/test.tar.gz | tar -t | sed -n -e 's;/$;;' -e 's;^\./\(..*\)$;\1;p' | sort > '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/file-list.test' && 969s check_filelist 969s 969s 1+0 records in 969s 1+0 records out 969s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00101536 s, 1.0 GB/s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"/*" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"" (stderr+stdout): 969s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp/root"" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/images"" (stderr+stdout): 969s INFO: tar(test.tar.gz): cmd: "tar cz -f '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/images/test.tar.gz' -C '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp/root' ." (stderr+stdout): 969s ok 6 - tar 969s 969s expecting success: 969s run_genimage_root vfat.config test.vfat && 969s fsck.fat -p images/test.vfat | tee fsck.log && 969s test_must_fail grep -q 'Filesystem was changed' fsck.log && 969s check_size images/test.vfat 4193280 && 969s MTOOLS_SKIP_CHECK=1 mdir -/ -f -b -i images/test.vfat / | sed -e 's;^::/;;' -e 's;/$;;' | sort > '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/file-list.test' && 969s check_filelist 969s 969s 1+0 records in 969s 1+0 records out 969s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00104852 s, 1.0 GB/s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"/*" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp"" (stderr+stdout): 969s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp/root"" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/images"" (stderr+stdout): 969s INFO: vfat(test.vfat): cmd: "mkdosfs -n 'vfat-test' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/images/test.vfat'" (stderr+stdout): 969s mkfs.fat: Warning: lowercase labels might not work properly on some systems 969s mkfs.fat 4.2 (2021-01-31) 969s INFO: vfat(test.vfat): cmd: "MTOOLS_SKIP_CHECK=1 mcopy -sp -i '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/images/test.vfat' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.filesystem.test/tmp/root'/* ::" (stderr+stdout): 969s fsck.fat 4.2 (2021-01-31) 969s images/test.vfat: 41 files, 16/2028 clusters 969s ok 7 - vfat 969s 969s # passed all 7 test(s) 969s 1..7 969s expecting success: 969s setup_test_images && 969s run_genimage flash.config test.flash && 969s md5sum -c '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/flash.md5' 969s 969s 7+0 records in 969s 7+0 records out 969s 3584 bytes (3.6 kB, 3.5 KiB) copied, 0.00017256 s, 20.8 MB/s 969s 11+0 records in 969s 11+0 records out 969s 5632 bytes (5.6 kB, 5.5 KiB) copied, 0.00013484 s, 41.8 MB/s 969s 1+0 records in 969s 1+0 records out 969s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00122896 s, 853 MB/s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp"/*" (stderr+stdout): 969s DEBUG: flash(test.flash): adding implicit file rule for 'part1.img' 969s DEBUG: flash(test.flash): adding implicit file rule for 'part2.img' 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/images"" (stderr+stdout): 969s INFO: flash(test.flash): writing image partition 'part1' (0x100000@0x0) 969s DEBUG: flash(test.flash): copying 1048576 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/input/part1.img at offset 0 969s DEBUG: flash(test.flash): adding 1044992 0xff bytes at offset 3584 969s INFO: flash(test.flash): writing image partition 'part2' (0x100000@0x100000) 969s DEBUG: flash(test.flash): copying 1048576 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/input/part2.img at offset 1048576 969s DEBUG: flash(test.flash): adding 1042944 0xff bytes at offset 1054208 969s images/test.flash: OK 969s ok 1 - flash 969s 969s expecting success: 969s run_genimage_root jffs2.config test.jffs2 && 969s md5sum -c '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/jffs2.md5' 969s 969s 1+0 records in 969s 1+0 records out 969s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00101 s, 1.0 GB/s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp"/*" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp"" (stderr+stdout): 969s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp/root"" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/images"" (stderr+stdout): 969s INFO: jffs2(test.jffs2): cmd: "mkfs.jffs2 --eraseblock=131072 -d '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp/root' -o '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/images/test.jffs2' " (stderr+stdout): 969s images/test.jffs2: OK 969s ok 2 - jffs2 969s 969s expecting success: 969s run_genimage_root ubifs.config test.ubifs && 969s check_size_range images/test.ubifs 200000 300000 969s 969s 1+0 records in 969s 1+0 records out 969s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.000982 s, 1.1 GB/s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp"/*" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp"" (stderr+stdout): 969s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp/root"" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/images"" (stderr+stdout): 969s INFO: ubifs(test.ubifs): cmd: "mkfs.ubifs -d '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp/root' -e 15360 -m 512 -c 1092 -o '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/images/test.ubifs' " (stderr+stdout): 969s ok 3 - ubifs 969s 969s expecting success: 969s setup_ubi_images && 969s run_genimage ubi.config test.ubi && 969s check_size_range images/test.ubi 550000 600000 969s 969s 1+0 records in 969s 1+0 records out 969s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00101988 s, 1.0 GB/s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp"/*" (stderr+stdout): 969s DEBUG: ubi(test.ubi): adding implicit file rule for 'test.ubifs' 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/images"" (stderr+stdout): 969s INFO: ubi(test.ubi): cmd: "ubinize -s 512 -O 512 -p 16384 -m 512 -o '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/images/test.ubi' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.flash.test/tmp/ubi.ini' " (stderr+stdout): 969s ok 4 - ubi 969s 969s # passed all 4 test(s) 969s 1..4 969s expecting success: 969s setup_exec_files && 969s run_genimage_root exec.config 969s 7+0 records in 969s 7+0 records out 969s 3584 bytes (3.6 kB, 3.5 KiB) copied, 5.868e-05 s, 61.1 MB/s 969s 7+0 records in 969s 7+0 records out 969s 3584 bytes (3.6 kB, 3.5 KiB) copied, 5.008e-05 s, 71.6 MB/s 969s 7+0 records in 969s 7+0 records out 969s 3584 bytes (3.6 kB, 3.5 KiB) copied, 0.00015012 s, 23.9 MB/s 969s 7+0 records in 969s 7+0 records out 969s 3584 bytes (3.6 kB, 3.5 KiB) copied, 0.0001288 s, 27.8 MB/s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"/*" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"" (stderr+stdout): 969s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp/root"" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images"" (stderr+stdout): 969s INFO: file(file1.img): cmd: "./exec-check.sh file1.img" (stderr+stdout): 969s + : OUTPUTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images' 969s + : INPUTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input' 969s + : ROOTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/root.orig' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/root.orig' 969s + : TMPPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp' 969s + : IMAGE 969s + test file1.img = file1.img 969s + : IMAGEOUTFILE 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file1.img' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file1.img' 969s + : IMAGENAME 969s + test exec-test = exec-test 969s + : IMAGESIZE 969s + test 3584 = 3584 969s + : IMAGEMOUNTPOINT 969s + test '' = '' 969s + : IMAGEMOUNTPATH 969s + '[' '' = empty ']' 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp/root' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp/root' 969s INFO: file(file1.img): cmd: "cp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input/file1.img' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file1.img'" (stderr+stdout): 969s INFO: file(file2.img): cmd: "cp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input/file2.img' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file2.img'" (stderr+stdout): 969s INFO: file(file2.img): cmd: "./exec-check.sh file2.img" (stderr+stdout): 969s + : OUTPUTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images' 969s + : INPUTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input' 969s + : ROOTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/root.orig' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/root.orig' 969s + : TMPPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp' 969s + : IMAGE 969s + test file2.img = file2.img 969s + : IMAGEOUTFILE 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file2.img' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file2.img' 969s + : IMAGENAME 969s + test exec-test = exec-test 969s + : IMAGESIZE 969s + test 3584 = 3584 969s + : IMAGEMOUNTPOINT 969s + test '' = '' 969s + : IMAGEMOUNTPATH 969s + '[' '' = empty ']' 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp/root' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp/root' 969s INFO: file(file3.img): cmd: "./exec-check.sh file3.img empty" (stderr+stdout): 969s + : OUTPUTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images' 969s + : INPUTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input' 969s + : ROOTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/root.orig' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/root.orig' 969s + : TMPPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp' 969s + : IMAGE 969s + test file3.img = file3.img 969s + : IMAGEOUTFILE 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file3.img' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file3.img' 969s + : IMAGENAME 969s + test exec-test = exec-test 969s + : IMAGESIZE 969s + test 3584 = 3584 969s + : IMAGEMOUNTPOINT 969s + test '' = '' 969s + : IMAGEMOUNTPATH 969s + '[' empty = empty ']' 969s + test '' = '' 969s INFO: file(file3.img): cmd: "cp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input/file3.img' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file3.img'" (stderr+stdout): 969s INFO: file(file4.img): cmd: "cp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input/file4.img' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file4.img'" (stderr+stdout): 969s INFO: file(file4.img): cmd: "./exec-check.sh file4.img empty" (stderr+stdout): 969s + : OUTPUTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images' 969s + : INPUTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/input' 969s + : ROOTPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/root.orig' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/root.orig' 969s + : TMPPATH 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp' 969s + : IMAGE 969s + test file4.img = file4.img 969s + : IMAGEOUTFILE 969s + test '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file4.img' = '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images/file4.img' 969s + : IMAGENAME 969s + test exec-test = exec-test 969s + : IMAGESIZE 969s + test 3584 = 3584 969s + : IMAGEMOUNTPOINT 969s + test '' = '' 969s + : IMAGEMOUNTPATH 969s + '[' empty = empty ']' 969s + test '' = '' 969s ok 1 - exec 969s 969s expecting success: 969s setup_exec_files && 969s test_must_fail run_genimage_root exec-fail.config 969s 7+0 records in 969s 7+0 records out 969s 3584 bytes (3.6 kB, 3.5 KiB) copied, 5.572e-05 s, 64.3 MB/s 969s 7+0 records in 969s 7+0 records out 969s 3584 bytes (3.6 kB, 3.5 KiB) copied, 5.44e-05 s, 65.9 MB/s 969s 7+0 records in 969s 7+0 records out 969s 3584 bytes (3.6 kB, 3.5 KiB) copied, 5.38e-05 s, 66.6 MB/s 969s 7+0 records in 969s 7+0 records out 969s 3584 bytes (3.6 kB, 3.5 KiB) copied, 5.332e-05 s, 67.2 MB/s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"/*" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"" (stderr+stdout): 969s INFO: cmd: "cp -a "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/root.orig" "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp/root"" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images"" (stderr+stdout): 969s INFO: file(file1.img): cmd: "false" (stderr+stdout): 969s ERROR: file(file1.img): failed to generate file1.img 969s ok 2 - exec-fail 969s 969s expecting success: 969s run_genimage include.config && 969s sanitized_fdisk_sfdisk images/include.hdimage > include.fdisk && 969s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include-ccc.fdisk' include.fdisk 969s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"/*" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images"" (stderr+stdout): 969s INFO: hdimage(include.hdimage): adding partition 'ccc' (in MBR) ... 969s INFO: hdimage(include.hdimage): adding partition '[MBR]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT header]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT array]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT backup]' ... 969s INFO: hdimage(include.hdimage): writing GPT 969s INFO: hdimage(include.hdimage): writing protective MBR 969s INFO: hdimage(include.hdimage): writing MBR 969s ok 3 - includepath1 969s 969s expecting success: 969s extra_opts='--includepath=/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include/aaa' run_genimage include.config && 969s sanitized_fdisk_sfdisk images/include.hdimage > include.fdisk && 969s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include-aaa.fdisk' include.fdisk 969s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"/*" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images"" (stderr+stdout): 969s INFO: hdimage(include.hdimage): adding partition 'aaa' (in MBR) ... 969s INFO: hdimage(include.hdimage): adding partition '[MBR]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT header]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT array]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT backup]' ... 969s INFO: hdimage(include.hdimage): writing GPT 969s INFO: hdimage(include.hdimage): writing protective MBR 969s INFO: hdimage(include.hdimage): writing MBR 969s ok 4 - includepath2 969s 969s expecting success: 969s extra_opts='--includepath=/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include/bbb:/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include/aaa' run_genimage include.config && 969s sanitized_fdisk_sfdisk images/include.hdimage > include.fdisk && 969s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include-bbb.fdisk' include.fdisk 969s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"/*" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images"" (stderr+stdout): 969s INFO: hdimage(include.hdimage): adding partition 'bbb' (in MBR) ... 969s INFO: hdimage(include.hdimage): adding partition '[MBR]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT header]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT array]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT backup]' ... 969s INFO: hdimage(include.hdimage): writing GPT 969s INFO: hdimage(include.hdimage): writing protective MBR 969s INFO: hdimage(include.hdimage): writing MBR 969s ok 5 - includepath3 969s 969s expecting success: 969s extra_opts='--includepath=.:/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include/bbb' run_genimage include.config && 969s sanitized_fdisk_sfdisk images/include.hdimage > include.fdisk && 969s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include-ccc.fdisk' include.fdisk 969s 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"" (stderr+stdout): 969s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/tmp"/*" (stderr+stdout): 969s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.genimage.test/images"" (stderr+stdout): 969s INFO: hdimage(include.hdimage): adding partition 'ccc' (in MBR) ... 969s INFO: hdimage(include.hdimage): adding partition '[MBR]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT header]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT array]' ... 969s INFO: hdimage(include.hdimage): adding partition '[GPT backup]' ... 969s INFO: hdimage(include.hdimage): writing GPT 969s INFO: hdimage(include.hdimage): writing protective MBR 969s INFO: hdimage(include.hdimage): writing MBR 969s ok 6 - includepath4 969s 969s skipping test: includepath5 969s extra_opts='--includepath=/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/include/aaa' test_must_fail run_genimage include.config 969s 969s ok 7 # skip includepath5 (missing !includepath) 969s 969s # passed all 7 test(s) 969s 1..7 969s expecting success: 969s setup_test_images && 969s run_genimage hdimage.config test.hdimage && 969s check_size images/test.hdimage 10485760 && 969s sfdisk_validate images/test.hdimage && 969s check_disk_usage_range images/test.hdimage 40960 57344 && 969s sanitized_fdisk_sfdisk images/test.hdimage > hdimage.fdisk && 969s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage.fdisk' hdimage.fdisk && 969s check_size images/test.hdimage-2 11539968 && 969s sfdisk_validate images/test.hdimage-2 && 969s check_disk_usage_range images/test.hdimage-2 61290 65376 && 969s sanitized_fdisk_sfdisk images/test.hdimage-2 > hdimage.fdisk-2 && 969s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage.fdisk-2' hdimage.fdisk-2 969s 969s 7+0 records in 969s 7+0 records out 969s 3584 bytes (3.6 kB, 3.5 KiB) copied, 6.82e-05 s, 52.6 MB/s 969s 11+0 records in 969s 11+0 records out 969s 5632 bytes (5.6 kB, 5.5 KiB) copied, 6.688e-05 s, 84.2 MB/s 969s 1+0 records in 969s 1+0 records out 969s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.0011694 s, 897 MB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'part1.img' 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'part3.img' 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'part2.img' 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: hdimage(test.hdimage): adding partition 'part1' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 1048576 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 1052160 970s INFO: hdimage(test.hdimage): adding partition 'part2' (in MBR) from 'part3.img' ... 970s INFO: hdimage(test.hdimage): adding partition 'part3' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 3145728 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 3149312 970s INFO: hdimage(test.hdimage): adding partition 'part4' (in MBR) from 'part2.img' ... 970s INFO: hdimage(test.hdimage): writing EBR 970s DEBUG: hdimage(test.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 5242880 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 5248512 970s INFO: hdimage(test.hdimage): adding partition 'part5' (in MBR) from 'part1.img' ... 970s INFO: hdimage(test.hdimage): writing EBR 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 7340032 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 7343616 970s INFO: hdimage(test.hdimage): adding partition 'part6' (in MBR) from 'part2.img' ... 970s INFO: hdimage(test.hdimage): writing EBR 970s DEBUG: hdimage(test.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 9437184 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 9442816 970s INFO: hdimage(test.hdimage): adding partition '[MBR]' ... 970s INFO: hdimage(test.hdimage): writing MBR 970s INFO: hdimage(test.hdimage-2): adding partition 'part1' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage-2): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 1048576 970s DEBUG: hdimage(test.hdimage-2): adding 0 0 bytes at offset 1052160 970s INFO: hdimage(test.hdimage-2): adding partition 'part2' (in MBR) from 'part2.img' ... 970s INFO: hdimage(test.hdimage-2): writing EBR 970s DEBUG: hdimage(test.hdimage-2): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 3145728 970s DEBUG: hdimage(test.hdimage-2): adding 0 0 bytes at offset 3151360 970s INFO: hdimage(test.hdimage-2): adding partition 'part3' (in MBR) from 'part1.img' ... 970s INFO: hdimage(test.hdimage-2): writing EBR 970s DEBUG: hdimage(test.hdimage-2): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 5242880 970s DEBUG: hdimage(test.hdimage-2): adding 0 0 bytes at offset 5246464 970s INFO: hdimage(test.hdimage-2): adding partition 'part4' (in MBR) from 'part2.img' ... 970s INFO: hdimage(test.hdimage-2): writing EBR 970s DEBUG: hdimage(test.hdimage-2): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 7340032 970s DEBUG: hdimage(test.hdimage-2): adding 0 0 bytes at offset 7345664 970s INFO: hdimage(test.hdimage-2): adding partition 'part5' (in MBR) from 'part1.img' ... 970s INFO: hdimage(test.hdimage-2): writing EBR 970s DEBUG: hdimage(test.hdimage-2): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 9437184 970s DEBUG: hdimage(test.hdimage-2): adding 0 0 bytes at offset 9440768 970s INFO: hdimage(test.hdimage-2): adding partition 'part6' (in MBR) from 'part2.img' ... 970s INFO: hdimage(test.hdimage-2): writing EBR 970s DEBUG: hdimage(test.hdimage-2): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 11534336 970s DEBUG: hdimage(test.hdimage-2): adding 0 0 bytes at offset 11539968 970s INFO: hdimage(test.hdimage-2): adding partition '[MBR]' ... 970s INFO: hdimage(test.hdimage-2): writing MBR 970s ok 1 - hdimage 970s 970s expecting success: 970s setup_test_images && 970s test_must_fail run_genimage hdimage2.config test.hdimage 970s 970s 7+0 records in 970s 7+0 records out 970s 3584 bytes (3.6 kB, 3.5 KiB) copied, 9.616e-05 s, 37.3 MB/s 970s 11+0 records in 970s 11+0 records out 970s 5632 bytes (5.6 kB, 5.5 KiB) copied, 5.672e-05 s, 99.3 MB/s 970s 1+0 records in 970s 1+0 records out 970s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00103304 s, 1.0 GB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'part2.img' 970s ERROR: hdimage(test.hdimage): part part1 size (5120) too small for part2.img (5632) 970s ok 2 - hdimage2 970s 970s expecting success: 970s setup_test_images && 970s run_genimage hdimage4.config test.hdimage && 970s check_size images/test.hdimage 7360512 && 970s sfdisk_validate images/test.hdimage && 970s sanitized_fdisk_sfdisk images/test.hdimage > hdimage4.fdisk && 970s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage4.fdisk' hdimage4.fdisk 970s 970s 7+0 records in 970s 7+0 records out 970s 3584 bytes (3.6 kB, 3.5 KiB) copied, 0.0001588 s, 22.6 MB/s 970s 11+0 records in 970s 11+0 records out 970s 5632 bytes (5.6 kB, 5.5 KiB) copied, 5.308e-05 s, 106 MB/s 970s 1+0 records in 970s 1+0 records out 970s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.0011106 s, 944 MB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'part1.img' 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'part2.img' 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: hdimage(test.hdimage): adding partition 'part1' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 1048576 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 1052160 970s INFO: hdimage(test.hdimage): adding partition 'part2' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(test.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 2097152 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 2102784 970s INFO: hdimage(test.hdimage): adding partition 'part3' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 3145728 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 3149312 970s INFO: hdimage(test.hdimage): adding partition 'part4' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(test.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 4194304 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 4199936 970s INFO: hdimage(test.hdimage): adding partition 'part5' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 5242880 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 5246464 970s INFO: hdimage(test.hdimage): adding partition 'part6' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(test.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 6291456 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 6297088 970s INFO: hdimage(test.hdimage): adding partition '[MBR]' ... 970s INFO: hdimage(test.hdimage): adding partition '[GPT header]' ... 970s INFO: hdimage(test.hdimage): adding partition '[GPT array]' ... 970s INFO: hdimage(test.hdimage): adding partition '[GPT backup]' ... 970s INFO: hdimage(test.hdimage): writing GPT 970s INFO: hdimage(test.hdimage): writing protective MBR 970s INFO: hdimage(test.hdimage): writing MBR 970s ok 3 - hdimage4 970s 970s expecting success: 970s setup_test_images && 970s run_genimage hdimage5.config test.hdimage && 970s check_size images/test.hdimage 7360512 && 970s sanitized_fdisk_sfdisk images/test.hdimage > hdimage5.fdisk && 970s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage5.fdisk' hdimage5.fdisk 970s 970s 7+0 records in 970s 7+0 records out 970s 3584 bytes (3.6 kB, 3.5 KiB) copied, 6.44e-05 s, 55.7 MB/s 970s 11+0 records in 970s 11+0 records out 970s 5632 bytes (5.6 kB, 5.5 KiB) copied, 5.788e-05 s, 97.3 MB/s 970s 1+0 records in 970s 1+0 records out 970s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00100952 s, 1.0 GB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'part1.img' 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'part2.img' 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: hdimage(test.hdimage): adding partition 'part1' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 1048576 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 1052160 970s INFO: hdimage(test.hdimage): adding partition 'part2' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(test.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 2097152 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 2102784 970s INFO: hdimage(test.hdimage): adding partition 'part3' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 3145728 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 3149312 970s INFO: hdimage(test.hdimage): adding partition 'part4' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(test.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 4194304 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 4199936 970s INFO: hdimage(test.hdimage): adding partition 'part5' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 5242880 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 5246464 970s INFO: hdimage(test.hdimage): adding partition 'part6' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(test.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 6291456 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 6297088 970s INFO: hdimage(test.hdimage): adding partition '[MBR]' ... 970s INFO: hdimage(test.hdimage): adding partition '[GPT header]' ... 970s INFO: hdimage(test.hdimage): adding partition '[GPT array]' ... 970s INFO: hdimage(test.hdimage): adding partition '[GPT backup]' ... 970s INFO: hdimage(test.hdimage): writing GPT 970s INFO: hdimage(test.hdimage): writing protective MBR 970s INFO: hdimage(test.hdimage): writing MBR 970s The backup GPT table is not on the end of the device. 970s ok 4 - hdimage5 970s 970s expecting success: 970s setup_test_images && 970s run_genimage hdimage6.config test.hdimage && 970s check_size images/test.hdimage 28082176 && 970s sanitized_fdisk_sfdisk images/test.hdimage > hdimage6.fdisk && 970s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage6.fdisk' hdimage6.fdisk 970s 970s 7+0 records in 970s 7+0 records out 970s 3584 bytes (3.6 kB, 3.5 KiB) copied, 6.136e-05 s, 58.4 MB/s 970s 11+0 records in 970s 11+0 records out 970s 5632 bytes (5.6 kB, 5.5 KiB) copied, 5.804e-05 s, 97.0 MB/s 970s 1+0 records in 970s 1+0 records out 970s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00133008 s, 788 MB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: hdimage(test.hdimage): adding partition 'part1' (in MBR) ... 970s INFO: hdimage(test.hdimage): adding partition 'part2' (in MBR) ... 970s INFO: hdimage(test.hdimage): adding partition 'part3' (in MBR) ... 970s INFO: hdimage(test.hdimage): writing EBR 970s INFO: hdimage(test.hdimage): adding partition 'part4' (in MBR) ... 970s INFO: hdimage(test.hdimage): writing EBR 970s INFO: hdimage(test.hdimage): adding partition 'part5' (in MBR) ... 970s INFO: hdimage(test.hdimage): writing EBR 970s INFO: hdimage(test.hdimage): adding partition '[MBR]' ... 970s INFO: hdimage(test.hdimage): writing MBR 970s ok 5 - hdimage6 970s 970s expecting success: 970s setup_test_images && 970s run_genimage hdimage7.config && 970s sfdisk_validate images/test.hdimage && 970s sanitized_fdisk_sfdisk images/test.hdimage > hdimage7.fdisk && 970s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage7.fdisk' hdimage7.fdisk 970s 970s 7+0 records in 970s 7+0 records out 970s 3584 bytes (3.6 kB, 3.5 KiB) copied, 6.584e-05 s, 54.4 MB/s 970s 11+0 records in 970s 11+0 records out 970s 5632 bytes (5.6 kB, 5.5 KiB) copied, 6.264e-05 s, 89.9 MB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'part1.img' 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'part2.img' 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: hdimage(test.hdimage): adding partition 'rescue' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 2097152 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 2100736 970s INFO: hdimage(test.hdimage): adding partition 'rootfs-A' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(test.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 4194304 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 4199936 970s INFO: hdimage(test.hdimage): adding partition 'rootfs-B' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(test.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 12582912 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 12588544 970s INFO: hdimage(test.hdimage): adding partition 'reserved' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 20971520 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 20975104 970s INFO: hdimage(test.hdimage): adding partition 'sys' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 21102592 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 21106176 970s INFO: hdimage(test.hdimage): adding partition 'data' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 25296896 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 25300480 970s INFO: hdimage(test.hdimage): adding partition 'bootloader' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(test.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 1048576 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 1054208 970s INFO: hdimage(test.hdimage): adding partition 'env-1' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 819200 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 822784 970s INFO: hdimage(test.hdimage): adding partition 'env-2' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 851968 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 855552 970s INFO: hdimage(test.hdimage): adding partition 'ucode' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 983040 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 986624 970s INFO: hdimage(test.hdimage): adding partition 'SPL' from 'part1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 1024 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 4608 970s INFO: hdimage(test.hdimage): adding partition '[MBR]' ... 970s INFO: hdimage(test.hdimage): adding partition '[GPT header]' ... 970s INFO: hdimage(test.hdimage): adding partition '[GPT array]' ... 970s INFO: hdimage(test.hdimage): adding partition '[GPT backup]' ... 970s INFO: hdimage(test.hdimage): writing GPT 970s INFO: hdimage(test.hdimage): writing protective MBR 970s INFO: hdimage(test.hdimage): writing MBR 970s ok 6 - hdimage7 970s 970s expecting success: 970s setup_test_images && 970s run_genimage hdimage-hybrid.config && 970s sfdisk_validate images/hybrid.hdimage && 970s sanitized_fdisk_sfdisk images/hybrid.hdimage > hdimage-hybrid.fdisk && 970s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-hybrid.fdisk' hdimage-hybrid.fdisk && 970s dd if=images/hybrid.hdimage count=1 | hexdump -C > hybrid.sector0 && 970s dd if=images/mbr.hdimage count=1 | hexdump -C > mbr.sector0 && 970s test_cmp hybrid.sector0 mbr.sector0 970s 970s 7+0 records in 970s 7+0 records out 970s 3584 bytes (3.6 kB, 3.5 KiB) copied, 5.196e-05 s, 69.0 MB/s 970s 11+0 records in 970s 11+0 records out 970s 5632 bytes (5.6 kB, 5.5 KiB) copied, 5.184e-05 s, 109 MB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(hybrid.hdimage): adding implicit file rule for 'part1.img' 970s DEBUG: hdimage(hybrid.hdimage): adding implicit file rule for 'part2.img' 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: hdimage(hybrid.hdimage): adding partition 'part1' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(hybrid.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 1048576 970s DEBUG: hdimage(hybrid.hdimage): adding 0 0 bytes at offset 1052160 970s INFO: hdimage(hybrid.hdimage): adding partition 'part2' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(hybrid.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 2097152 970s DEBUG: hdimage(hybrid.hdimage): adding 0 0 bytes at offset 2102784 970s INFO: hdimage(hybrid.hdimage): adding partition 'part3' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(hybrid.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 3145728 970s DEBUG: hdimage(hybrid.hdimage): adding 0 0 bytes at offset 3149312 970s INFO: hdimage(hybrid.hdimage): adding partition 'part4' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(hybrid.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 4194304 970s DEBUG: hdimage(hybrid.hdimage): adding 0 0 bytes at offset 4199936 970s INFO: hdimage(hybrid.hdimage): adding partition 'part5' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(hybrid.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 5242880 970s DEBUG: hdimage(hybrid.hdimage): adding 0 0 bytes at offset 5246464 970s INFO: hdimage(hybrid.hdimage): adding partition 'part6' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(hybrid.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 6291456 970s DEBUG: hdimage(hybrid.hdimage): adding 0 0 bytes at offset 6297088 970s INFO: hdimage(hybrid.hdimage): adding partition '[MBR]' ... 970s INFO: hdimage(hybrid.hdimage): adding partition '[GPT header]' ... 970s INFO: hdimage(hybrid.hdimage): adding partition '[GPT array]' ... 970s INFO: hdimage(hybrid.hdimage): adding partition '[GPT backup]' ... 970s INFO: hdimage(hybrid.hdimage): writing GPT 970s INFO: hdimage(hybrid.hdimage): writing hybrid MBR 970s INFO: hdimage(mbr.hdimage): adding partition 'part1' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(mbr.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 1048576 970s DEBUG: hdimage(mbr.hdimage): adding 0 0 bytes at offset 1052160 970s INFO: hdimage(mbr.hdimage): adding partition 'part2' (in MBR) from 'part2.img' ... 970s DEBUG: hdimage(mbr.hdimage): copying 5632 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part2.img at offset 2097152 970s DEBUG: hdimage(mbr.hdimage): adding 0 0 bytes at offset 2102784 970s INFO: hdimage(mbr.hdimage): adding partition 'part3' (in MBR) from 'part1.img' ... 970s DEBUG: hdimage(mbr.hdimage): copying 3584 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/part1.img at offset 3145728 970s DEBUG: hdimage(mbr.hdimage): adding 0 0 bytes at offset 3149312 970s INFO: hdimage(mbr.hdimage): adding partition 'fake-gpt' (in MBR) ... 970s INFO: hdimage(mbr.hdimage): adding partition '[MBR]' ... 970s INFO: hdimage(mbr.hdimage): writing MBR 970s 1+0 records in 970s 1+0 records out 970s 512 bytes copied, 7.008e-05 s, 7.3 MB/s 970s 1+0 records in 970s 1+0 records out 970s 512 bytes copied, 0.0001482 s, 3.5 MB/s 970s ok 7 - hdimage-hybrid 970s 970s expecting success: 970s setup_test_images && 970s test_must_fail run_genimage hdimage-fail1.config && 970s test_must_fail run_genimage hdimage-fail2.config && 970s test_must_fail run_genimage hdimage-fail3.config && 970s test_must_fail run_genimage hdimage-fail4.config && 970s test_must_fail run_genimage hdimage-fail5.config && 970s test_must_fail run_genimage hdimage-fail6.config && 970s test_must_fail run_genimage hdimage-fail7.config 970s 970s 7+0 records in 970s 7+0 records out 970s 3584 bytes (3.6 kB, 3.5 KiB) copied, 5.268e-05 s, 68.0 MB/s 970s 11+0 records in 970s 11+0 records out 970s 5632 bytes (5.6 kB, 5.5 KiB) copied, 5.14e-05 s, 110 MB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s ERROR: hdimage(test.hdimage): part part1: 'partition-type-uuid' is only valid for gpt and hybrid partition-table-type 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s ERROR: hdimage(test.hdimage): part part1: 'partition-type' is only valid for mbr and hybrid partition-table-type 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s ERROR: hdimage(test.hdimage): part part1 size must not be zero 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s ERROR: hdimage(test.hdimage): 'disk-uuid' is only valid for gpt and hybrid partition-table-type 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s ERROR: hdimage(test.hdimage): 'disk-signature' is only valid for mbr and hybrid partition-table-type 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s ERROR: hdimage(test.hdimage): part part4 overlaps with previous partition 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s ERROR: hdimage(test.hdimage): hybrid MBR partitions (4) exceeds maximum of 3 970s ok 8 - hdimage syntax 970s 970s expecting success: 970s setup_gpt_files && 970s test_must_fail run_genimage gpt-overlap1.config 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.gpt-overlap1): adding implicit file rule for '3K.img' 970s DEBUG: hdimage(test.gpt-overlap1): adding implicit file rule for '70K.img' 970s ERROR: hdimage(test.gpt-overlap1): partition [GPT array] (offset 0x400, size 0x4000) overlaps previous partition SPL (offset 0x400, size 0x6000) 970s ERROR: hdimage(test.gpt-overlap1): bootloaders, etc. that overlap with the partition table must declare the overlapping area as a hole. 970s ok 9 - gpt-overlap1 970s 970s expecting success: 970s setup_gpt_files && 970s run_genimage gpt-overlap2.config 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.gpt-overlap2): adding implicit file rule for '3K.img' 970s DEBUG: hdimage(test.gpt-overlap2): adding implicit file rule for '70K.img' 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: hdimage(test.gpt-overlap2): adding partition 'SPL' from '3K.img' ... 970s DEBUG: hdimage(test.gpt-overlap2): copying 3072 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/3K.img at offset 1024 970s DEBUG: hdimage(test.gpt-overlap2): adding 3072 0 bytes at offset 1024 970s INFO: hdimage(test.gpt-overlap2): adding partition 'aaa' (in MBR) from '70K.img' ... 970s DEBUG: hdimage(test.gpt-overlap2): copying 71680 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/70K.img at offset 1048576 970s DEBUG: hdimage(test.gpt-overlap2): adding 71680 0 bytes at offset 1048576 970s INFO: hdimage(test.gpt-overlap2): adding partition '[MBR]' ... 970s INFO: hdimage(test.gpt-overlap2): adding partition '[GPT header]' ... 970s INFO: hdimage(test.gpt-overlap2): adding partition '[GPT array]' ... 970s INFO: hdimage(test.gpt-overlap2): adding partition '[GPT backup]' ... 970s INFO: hdimage(test.gpt-overlap2): writing GPT 970s INFO: hdimage(test.gpt-overlap2): writing protective MBR 970s INFO: hdimage(test.gpt-overlap2): writing MBR 970s ok 10 - gpt-overlap2 970s 970s expecting success: 970s setup_gpt_files && 970s test_must_fail run_genimage gpt-overlap3.config 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.gpt-overlap3): adding implicit file rule for '3K.img' 970s DEBUG: hdimage(test.gpt-overlap3): adding implicit file rule for '70K.img' 970s ERROR: hdimage(test.gpt-overlap3): partition [GPT array] (offset 0x7800, size 0x4000) overlaps previous partition aaa (offset 0x8000, size 0x100000) 970s ok 11 - gpt-overlap3 970s 970s expecting success: 970s run_genimage gpt-partition-types.config && 970s sfdisk_validate images/gpt-partition-types.img && 970s sanitized_fdisk_sfdisk images/gpt-partition-types.img > gpt-partition-types.fdisk && 970s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/gpt-partition-types.fdisk' gpt-partition-types.fdisk 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: hdimage(gpt-partition-types.img): adding partition 'part1' (in MBR) ... 970s INFO: hdimage(gpt-partition-types.img): adding partition 'part2' (in MBR) ... 970s INFO: hdimage(gpt-partition-types.img): adding partition 'part3' (in MBR) ... 970s INFO: hdimage(gpt-partition-types.img): adding partition 'part4' (in MBR) ... 970s INFO: hdimage(gpt-partition-types.img): adding partition 'part5' (in MBR) ... 970s INFO: hdimage(gpt-partition-types.img): adding partition '[MBR]' ... 970s INFO: hdimage(gpt-partition-types.img): adding partition '[GPT header]' ... 970s INFO: hdimage(gpt-partition-types.img): adding partition '[GPT array]' ... 970s INFO: hdimage(gpt-partition-types.img): adding partition '[GPT backup]' ... 970s INFO: hdimage(gpt-partition-types.img): writing GPT 970s INFO: hdimage(gpt-partition-types.img): writing protective MBR 970s INFO: hdimage(gpt-partition-types.img): writing MBR 970s ok 12 - gpt-partition-types 970s 970s expecting success: 970s test_must_fail run_genimage gpt-invalid-partition-type1.config && 970s test_must_fail run_genimage gpt-invalid-partition-type2.config 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s ERROR: hdimage(gpt-partition-types-fail.img): part part1 has invalid type shortcut: unknown-shortcut 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s ERROR: hdimage(gpt-partition-types-fail.img): part part1 has invalid type shortcut: 30f239f3-275c-4e0d-9b61-Ka385b7b80f9 970s ok 13 - gpt-invalid-partition-types 970s 970s expecting success: 970s setup_gpt_files && 970s run_genimage hole.config 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.hole): adding implicit file rule for '3K.img' 970s DEBUG: file(70K.img): added hole (440, 33792) 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: file(70K.img): cmd: "cp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/70K.img' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images/70K.img'" (stderr+stdout): 970s INFO: hdimage(test.hole): adding partition 'bootloader' from '70K.img' ... 970s DEBUG: hdimage(test.hole): copying 71680 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images/70K.img at offset 0 970s DEBUG: hdimage(test.hole): adding 71680 0 bytes at offset 0 970s INFO: hdimage(test.hole): adding partition 'foo' (in MBR) from '3K.img' ... 970s DEBUG: hdimage(test.hole): copying 3072 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/3K.img at offset 132096 970s DEBUG: hdimage(test.hole): adding 3072 0 bytes at offset 132096 970s INFO: hdimage(test.hole): adding partition '[MBR]' ... 970s INFO: hdimage(test.hole): adding partition '[GPT header]' ... 970s INFO: hdimage(test.hole): adding partition '[GPT array]' ... 970s INFO: hdimage(test.hole): adding partition '[GPT backup]' ... 970s INFO: hdimage(test.hole): writing GPT 970s INFO: hdimage(test.hole): writing protective MBR 970s INFO: hdimage(test.hole): writing MBR 970s ok 14 - bootloader-hole1 970s 970s expecting success: 970s setup_gpt_files && 970s GPT_LOCATION=64K test_must_fail run_genimage hole.config 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.hole): adding implicit file rule for '3K.img' 970s DEBUG: file(70K.img): added hole (440, 33792) 970s ERROR: hdimage(test.hole): partition [GPT array] (offset 0x10000, size 0x4000) overlaps previous partition bootloader (offset 0x0, size 0x11800) 970s ERROR: hdimage(test.hole): bootloaders, etc. that overlap with the partition table must declare the overlapping area as a hole. 970s ok 15 - bootloader-hole2 970s 970s expecting success: 970s setup_gpt_files && 970s GPT_LOCATION=70K run_genimage hole.config 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.hole): adding implicit file rule for '3K.img' 970s DEBUG: file(70K.img): added hole (440, 33792) 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: file(70K.img): cmd: "cp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/70K.img' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images/70K.img'" (stderr+stdout): 970s INFO: hdimage(test.hole): adding partition 'bootloader' from '70K.img' ... 970s DEBUG: hdimage(test.hole): copying 71680 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images/70K.img at offset 0 970s DEBUG: hdimage(test.hole): adding 71680 0 bytes at offset 0 970s INFO: hdimage(test.hole): adding partition 'foo' (in MBR) from '3K.img' ... 970s DEBUG: hdimage(test.hole): copying 3072 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/3K.img at offset 132096 970s DEBUG: hdimage(test.hole): adding 3072 0 bytes at offset 132096 970s INFO: hdimage(test.hole): adding partition '[MBR]' ... 970s INFO: hdimage(test.hole): adding partition '[GPT header]' ... 970s INFO: hdimage(test.hole): adding partition '[GPT array]' ... 970s INFO: hdimage(test.hole): adding partition '[GPT backup]' ... 970s INFO: hdimage(test.hole): writing GPT 970s INFO: hdimage(test.hole): writing protective MBR 970s INFO: hdimage(test.hole): writing MBR 970s ok 16 - bootloader-hole3 970s 970s expecting success: 970s setup_gpt_files && 970s OFFSET=64K test_must_fail run_genimage hole.config 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.hole): adding implicit file rule for '3K.img' 970s DEBUG: file(70K.img): added hole (440, 33792) 970s ERROR: hdimage(test.hole): partition foo (offset 0x20400, size 0xc00) overlaps previous partition bootloader (offset 0x10000, size 0x11800) 970s ok 17 - bootloader-hole4 970s 970s expecting success: 970s setup_gpt_files && 970s OFFSET=128K run_genimage hole.config 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.hole): adding implicit file rule for '3K.img' 970s DEBUG: file(70K.img): added hole (440, 33792) 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: file(70K.img): cmd: "cp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/70K.img' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images/70K.img'" (stderr+stdout): 970s INFO: hdimage(test.hole): adding partition 'bootloader' from '70K.img' ... 970s DEBUG: hdimage(test.hole): copying 71680 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images/70K.img at offset 131072 970s DEBUG: hdimage(test.hole): adding 71680 0 bytes at offset 131072 970s INFO: hdimage(test.hole): adding partition 'foo' (in MBR) from '3K.img' ... 970s DEBUG: hdimage(test.hole): copying 3072 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/3K.img at offset 132096 970s DEBUG: hdimage(test.hole): adding 3072 0 bytes at offset 132096 970s INFO: hdimage(test.hole): adding partition '[MBR]' ... 970s INFO: hdimage(test.hole): adding partition '[GPT header]' ... 970s INFO: hdimage(test.hole): adding partition '[GPT array]' ... 970s INFO: hdimage(test.hole): adding partition '[GPT backup]' ... 970s INFO: hdimage(test.hole): writing GPT 970s INFO: hdimage(test.hole): writing protective MBR 970s INFO: hdimage(test.hole): writing MBR 970s ok 18 - bootloader-hole5 970s 970s expecting success: 970s dd if=/dev/zero bs=1 count=100 | tr '\000' '\377' > input/block1.img && 970s dd if=/dev/zero bs=1 count=50 | tr '\000' '\252' > input/block2.img && 970s dd if=/dev/zero bs=1 count=75 | tr '\000' '\167' > input/block3.img && 970s run_genimage hdimage-nopart.config && 970s hexdump -C images/test.hdimage > 'hdimage-nopart.hexdump' && 970s test_cmp 'hdimage-nopart.hexdump' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/hdimage-nopart.hexdump' 970s 970s 100+0 records in 970s 100+0 records out 970s 100 bytes copied, 9.212e-05 s, 1.1 MB/s 970s 50+0 records in 970s 50+0 records out 970s 50 bytes copied, 6.196e-05 s, 807 kB/s 970s 75+0 records in 970s 75+0 records out 970s 75 bytes copied, 7.588e-05 s, 988 kB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/tmp"/*" (stderr+stdout): 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'block1.img' 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'block2.img' 970s DEBUG: hdimage(test.hdimage): adding implicit file rule for 'block3.img' 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/images"" (stderr+stdout): 970s INFO: hdimage(test.hdimage): adding partition 'part1' from 'block1.img' ... 970s DEBUG: hdimage(test.hdimage): copying 100 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/block1.img at offset 0 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 100 970s INFO: hdimage(test.hdimage): adding partition 'part2' from 'block2.img' ... 970s DEBUG: hdimage(test.hdimage): copying 50 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/block2.img at offset 104 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 154 970s INFO: hdimage(test.hdimage): adding partition 'part3' from 'block3.img' ... 970s DEBUG: hdimage(test.hdimage): copying 75 bytes from /tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.hdimage.test/input/block3.img at offset 256 970s DEBUG: hdimage(test.hdimage): adding 0 0 bytes at offset 331 970s ok 19 - hdimage no-partition 970s 970s # passed all 19 test(s) 970s 1..19 970s expecting success: 970s setup_test_images && 970s run_genimage qemu.config test.qcow && 970s qemu-img check images/test.qcow && 970s zcat '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/qemu.qcow.gz' > qemu.qcow && 970s qemu-img compare images/test.qcow qemu.qcow 970s 970s 7+0 records in 970s 7+0 records out 970s 3584 bytes (3.6 kB, 3.5 KiB) copied, 0.0001624 s, 22.1 MB/s 970s 11+0 records in 970s 11+0 records out 970s 5632 bytes (5.6 kB, 5.5 KiB) copied, 0.00013832 s, 40.7 MB/s 970s 1+0 records in 970s 1+0 records out 970s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00098068 s, 1.1 GB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/tmp"/*" (stderr+stdout): 970s DEBUG: qemu(test.qcow): adding implicit file rule for 'part1.img' 970s DEBUG: qemu(test.qcow): adding implicit file rule for 'part2.img' 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/images"" (stderr+stdout): 970s INFO: qemu(test.qcow): adding partition part1 from part1.img ... 970s INFO: qemu(test.qcow): adding partition part2 from part2.img ... 970s INFO: qemu(test.qcow): cmd: "qemu-img convert -O qcow2 '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/input/part1.img' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/input/part2.img' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/images/test.qcow'" (stderr+stdout): 970s No errors were found on the image. 970s Image end offset: 262144 970s 7+0 records in 970s 7+0 records out 970s Images are identical. 970s ok 1 - qemu 970s 970s expecting success: 970s setup_fit_its && 970s run_genimage fit.config test.fit 970s 970s 3584 bytes (3.6 kB, 3.5 KiB) copied, 0.00016496 s, 21.7 MB/s 970s 11+0 records in 970s 11+0 records out 970s 5632 bytes (5.6 kB, 5.5 KiB) copied, 0.00015412 s, 36.5 MB/s 970s 1+0 records in 970s 1+0 records out 970s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00114252 s, 918 MB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/tmp"/*" (stderr+stdout): 970s DEBUG: fit(test.fit): adding implicit file rule for 'part1.img' 970s DEBUG: fit(test.fit): adding implicit file rule for 'part2.img' 970s DEBUG: fit(test.fit): adding implicit file rule for 'fit.its' 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/images"" (stderr+stdout): 970s INFO: fit(test.fit): cmd: "cat '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/input/fit.its' > '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/tmp/fit.its'" (stderr+stdout): 970s INFO: fit(test.fit): cmd: "mkimage -r -f '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/tmp/fit.its' '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/images/test.fit'" (stderr+stdout): 970s FIT description: Test FIT image 970s Created: Tue Mar 26 02:22:28 2024 970s Image 0 (kernel) 970s Description: kernel 970s Created: Tue Mar 26 02:22:28 2024 970s Type: Kernel Image 970s Compression: uncompressed 970s Data Size: 3584 Bytes = 3.50 KiB = 0.00 MiB 970s Architecture: ARM 970s OS: Linux 970s Load Address: unavailable 970s Entry Point: unavailable 970s Image 1 (ramdisk) 970s Description: ramdisk 970s Created: Tue Mar 26 02:22:28 2024 970s Type: RAMDisk Image 970s Compression: uncompressed 970s Data Size: 5632 Bytes = 5.50 KiB = 0.01 MiB 970s Architecture: ARM 970s OS: Linux 970s Load Address: unavailable 970s Entry Point: unavailable 970s Default Configuration: 'conf' 970s Configuration 0 (conf) 970s Description: test dummy config 970s Kernel: kernel 970s Init Ramdisk: ramdisk 970s ok 2 - fit 970s 970s skipping test: rauc 970s setup_rauc && 970s run_genimage rauc.config test.raucb && 970s rauc info --keyring input/rauc-openssl-ca/ca.cert.pem --cert input/rauc-openssl-ca/ca.cert.pem images/test.raucb | grep -v cms_get_enveloped_type | sed -e 's;O = Test Org, CN = ;/O=Test Org/CN=;' -e '/Bundle Format:[ \t]*plain$/d' > test.raucb.info && 970s if ! grep -r 'Filename:' test.raucb.info; then 970s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/test.raucb.info' test.raucb.info 970s else 970s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/test.raucb.info.new' test.raucb.info 970s fi 970s rauc info --keyring input/rauc-openssl-ca/ca.cert.pem --cert input/rauc-openssl-ca/ca.cert.pem images/test2.raucb | grep -v cms_get_enveloped_type | sed -e 's;O = Test Org, CN = ;/O=Test Org/CN=;' -e '/Bundle Format:[ \t]*plain$/d' > test2.raucb.info && 970s if ! grep -r 'Filename:' test2.raucb.info; then 970s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/test2.raucb.info' test2.raucb.info 970s else 970s test_cmp '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/test/test2.raucb.info.new' test2.raucb.info 970s fi 970s 970s ok 3 # skip rauc (missing rauc) 970s 970s skipping test: android-sparse 970s setup_test_images && 970s # make sure there is a 4*32k hole at the end 970s i=16 970s truncate --size=$[i*(i+1)*i*i*512+32768*4] input/interleaved 970s for i in `seq 16`; do 970s dd if=/dev/urandom of=input/interleaved conv=notrunc seek=$[i*i] count=$[i] bs=$[i*i*512] || break 970s done && 970s dd if=/dev/urandom of=input/not-aligned count=9 bs=1024 970s run_genimage sparse.config && 970s # simg2img will expand the partial block 970s truncate --size=12k input/not-aligned 970s md5sum images/test.hdimage input/interleaved input/not-aligned > md5sum && 970s rm images/test.hdimage input/interleaved input/not-aligned && 970s check_size_range images/interleaved.sparse 9732464 9732636 && 970s simg2img images/test.sparse images/test.hdimage && 970s simg2img images/interleaved.sparse input/interleaved && 970s simg2img images/not-aligned.sparse input/not-aligned && 970s md5sum -c md5sum 970s 970s ok 4 # skip android-sparse (missing simg2img) 970s 970s expecting success: 970s setup_test_images && 970s run_genimage fip.config test.fip && 970s check_size_range images/test.fip 12804 13056 && 970s fiptool info images/test.fip 970s 970s 7+0 records in 970s 7+0 records out 970s 3584 bytes (3.6 kB, 3.5 KiB) copied, 8.064e-05 s, 44.4 MB/s 970s 11+0 records in 970s 11+0 records out 970s 5632 bytes (5.6 kB, 5.5 KiB) copied, 0.00012732 s, 44.2 MB/s 970s 1+0 records in 970s 1+0 records out 970s 1048576 bytes (1.0 MB, 1.0 MiB) copied, 0.00102988 s, 1.0 GB/s 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/tmp"" (stderr+stdout): 970s INFO: cmd: "rm -rf "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/tmp"/*" (stderr+stdout): 970s DEBUG: fip(test.fip): adding implicit file rule for 'part2.img' 970s DEBUG: fip(test.fip): adding implicit file rule for 'part1.img' 970s INFO: cmd: "mkdir -p "/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/images"" (stderr+stdout): 970s INFO: fip(test.fip): cmd: "fiptool create --tos-fw '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/input/part2.img' --tos-fw-extra1 '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/input/part1.img' --fw-config '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/input/part1.img' --align 64 '/tmp/autopkgtest.LlGcRQ/autopkgtest_tmp/trash directory.misc.test/images/test.fip'" (stderr+stdout): 970s Secure Payload BL32 (Trusted OS): offset=0xC0, size=0x1600, cmdline="--tos-fw" 970s Secure Payload BL32 Extra1 (Trusted OS Extra1): offset=0x16C0, size=0xE00, cmdline="--tos-fw-extra1" 970s FW_CONFIG: offset=0x24C0, size=0xE00, cmdline="--fw-config" 970s ok 5 - fip 970s 970s # passed all 5 test(s) 970s 1..5 971s autopkgtest [02:22:28]: test upstream-tests: -----------------------] 971s autopkgtest [02:22:28]: test upstream-tests: - - - - - - - - - - results - - - - - - - - - - 971s upstream-tests PASS 972s autopkgtest [02:22:29]: @@@@@@@@@@@@@@@@@@@@ summary 972s upstream-tests PASS