0s autopkgtest [10:55:06]: starting date and time: 2024-11-13 10:55:06+0000 0s autopkgtest [10:55:06]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [10:55:06]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.jaa9njl0/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults,src:python3-stdlib-extensions --apt-upgrade anonip --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=python3-defaults/3.12.7-1 python3-stdlib-extensions/3.12.7-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-ppc64el-22.secgroup --name adt-plucky-ppc64el-anonip-20241113-100529-juju-7f2275-prod-proposed-migration-environment-2-21c22145-3af8-42e7-8659-4b7bd8dedc0a --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-ppc64el -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,keyserver.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/ 122s autopkgtest [10:57:08]: testbed dpkg architecture: ppc64el 122s autopkgtest [10:57:08]: testbed apt version: 2.9.8 122s autopkgtest [10:57:08]: @@@@@@@@@@@@@@@@@@@@ test bed setup 123s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 124s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [849 kB] 124s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [76.4 kB] 124s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB] 124s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 124s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [86.2 kB] 124s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [588 kB] 124s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [19.6 kB] 124s Fetched 1715 kB in 1s (1925 kB/s) 124s Reading package lists... 127s Reading package lists... 128s Building dependency tree... 128s Reading state information... 128s Calculating upgrade... 128s The following NEW packages will be installed: 128s python3.13-gdbm 128s The following packages will be upgraded: 128s libpython3-stdlib python3 python3-gdbm python3-minimal 129s 4 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 129s Need to get 102 kB of archives. 129s After this operation, 141 kB of additional disk space will be used. 129s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3-minimal ppc64el 3.12.7-1 [27.4 kB] 129s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3 ppc64el 3.12.7-1 [24.0 kB] 129s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el libpython3-stdlib ppc64el 3.12.7-1 [10.0 kB] 129s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.13-gdbm ppc64el 3.13.0-2 [31.5 kB] 129s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3-gdbm ppc64el 3.12.7-1 [8640 B] 129s Fetched 102 kB in 0s (279 kB/s) 130s (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 ... 73767 files and directories currently installed.) 130s Preparing to unpack .../python3-minimal_3.12.7-1_ppc64el.deb ... 130s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ... 130s Setting up python3-minimal (3.12.7-1) ... 130s (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 ... 73767 files and directories currently installed.) 130s Preparing to unpack .../python3_3.12.7-1_ppc64el.deb ... 130s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ... 130s Preparing to unpack .../libpython3-stdlib_3.12.7-1_ppc64el.deb ... 130s Unpacking libpython3-stdlib:ppc64el (3.12.7-1) over (3.12.6-0ubuntu1) ... 130s Selecting previously unselected package python3.13-gdbm. 130s Preparing to unpack .../python3.13-gdbm_3.13.0-2_ppc64el.deb ... 130s Unpacking python3.13-gdbm (3.13.0-2) ... 130s Preparing to unpack .../python3-gdbm_3.12.7-1_ppc64el.deb ... 130s Unpacking python3-gdbm:ppc64el (3.12.7-1) over (3.12.6-1ubuntu1) ... 130s Setting up python3.13-gdbm (3.13.0-2) ... 130s Setting up libpython3-stdlib:ppc64el (3.12.7-1) ... 130s Setting up python3 (3.12.7-1) ... 130s Setting up python3-gdbm:ppc64el (3.12.7-1) ... 130s Processing triggers for man-db (2.12.1-3) ... 132s Reading package lists... 132s Building dependency tree... 132s Reading state information... 133s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 133s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 133s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 133s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 133s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 134s Reading package lists... 134s Reading package lists... 135s Building dependency tree... 135s Reading state information... 135s Calculating upgrade... 135s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 135s Reading package lists... 136s Building dependency tree... 136s Reading state information... 136s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 139s autopkgtest [10:57:25]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 13:49:23 UTC 2024 139s autopkgtest [10:57:25]: @@@@@@@@@@@@@@@@@@@@ apt-source anonip 141s Get:1 http://ftpmaster.internal/ubuntu plucky/universe anonip 1.1.0-2 (dsc) [2034 B] 141s Get:2 http://ftpmaster.internal/ubuntu plucky/universe anonip 1.1.0-2 (tar) [13.4 kB] 141s Get:3 http://ftpmaster.internal/ubuntu plucky/universe anonip 1.1.0-2 (diff) [4232 B] 141s gpgv: Signature made Sun Oct 2 09:41:45 2022 UTC 141s gpgv: using RSA key EE01B7C2126C2847EADFC720C24B65A2672C8B12 141s gpgv: Can't check signature: No public key 141s dpkg-source: warning: cannot verify inline signature for ./anonip_1.1.0-2.dsc: no acceptable signature found 141s autopkgtest [10:57:27]: testing package anonip version 1.1.0-2 142s autopkgtest [10:57:28]: build not needed 142s autopkgtest [10:57:28]: test common-log-test: preparing testbed 143s Reading package lists... 144s Building dependency tree... 144s Reading state information... 144s Starting pkgProblemResolver with broken count: 0 144s Starting 2 pkgProblemResolver with broken count: 0 144s Done 144s The following additional packages will be installed: 144s anonip shunit2 144s Suggested packages: 144s python-anonip-doc 144s The following NEW packages will be installed: 144s anonip autopkgtest-satdep shunit2 144s 0 upgraded, 3 newly installed, 0 to remove and 0 not upgraded. 144s Need to get 49.2 kB/49.9 kB of archives. 144s After this operation, 184 kB of additional disk space will be used. 144s Get:1 /tmp/autopkgtest.GBtX0f/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [712 B] 145s Get:2 http://ftpmaster.internal/ubuntu plucky/universe ppc64el anonip all 1.1.0-2 [14.4 kB] 145s Get:3 http://ftpmaster.internal/ubuntu plucky/universe ppc64el shunit2 all 2.1.8-4 [34.8 kB] 145s Fetched 49.2 kB in 0s (143 kB/s) 145s Selecting previously unselected package anonip. 145s (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 ... 73774 files and directories currently installed.) 145s Preparing to unpack .../anonip_1.1.0-2_all.deb ... 145s Unpacking anonip (1.1.0-2) ... 145s Selecting previously unselected package shunit2. 145s Preparing to unpack .../shunit2_2.1.8-4_all.deb ... 145s Unpacking shunit2 (2.1.8-4) ... 145s Selecting previously unselected package autopkgtest-satdep. 145s Preparing to unpack .../1-autopkgtest-satdep.deb ... 145s Unpacking autopkgtest-satdep (0) ... 145s Setting up shunit2 (2.1.8-4) ... 145s Setting up anonip (1.1.0-2) ... 145s Setting up autopkgtest-satdep (0) ... 145s Processing triggers for man-db (2.12.1-3) ... 149s (Reading database ... 73831 files and directories currently installed.) 149s Removing autopkgtest-satdep (0) ... 150s autopkgtest [10:57:36]: test common-log-test: [----------------------- 150s test_apache_common_ipv4_log 150s test_apache_common_ipv6_log 150s test_nginx_default_ipv4_log 150s test_nginx_default_ipv6_log 150s 150s Ran 4 tests. 150s 150s OK 151s autopkgtest [10:57:37]: test common-log-test: -----------------------] 151s autopkgtest [10:57:37]: test common-log-test: - - - - - - - - - - results - - - - - - - - - - 151s common-log-test PASS 152s autopkgtest [10:57:38]: test pytest: preparing testbed 184s virt: nova [W] Using flock in prodstack6-ppc64el 184s virt: flock: timeout while waiting to get lock 184s virt: Creating nova instance adt-plucky-ppc64el-anonip-20241113-100529-juju-7f2275-prod-proposed-migration-environment-2-21c22145-3af8-42e7-8659-4b7bd8dedc0a from image adt/ubuntu-plucky-ppc64el-server-20241113.img (UUID 0c5715b6-5cca-4485-b8bf-b85dfd917a5f)... 184s virt: nova [W] Using flock in prodstack6-ppc64el 184s virt: Creating nova instance adt-plucky-ppc64el-anonip-20241113-100529-juju-7f2275-prod-proposed-migration-environment-2-21c22145-3af8-42e7-8659-4b7bd8dedc0a from image adt/ubuntu-plucky-ppc64el-server-20241113.img (UUID 0c5715b6-5cca-4485-b8bf-b85dfd917a5f)... 184s virt: nova [E] nova boot failed (attempt #0): 184s virt: nova [E] DEBUG (extension:189) found extension EntryPoint.parse('v1password = swiftclient.authv1:PasswordLoader') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('noauth = cinderclient.contrib.noauth:CinderNoAuthLoader') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('none = keystoneauth1.loading._plugins.noauth:NoAuth') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3adfspassword = keystoneauth1.extras._saml2._loading:ADFSPassword') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3applicationcredential = keystoneauth1.loading._plugins.identity.v3:ApplicationCredential') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3fedkerb = keystoneauth1.extras.kerberos._loading:MappedKerberos') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3kerberos = keystoneauth1.extras.kerberos._loading:Kerberos') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3multifactor = keystoneauth1.loading._plugins.identity.v3:MultiFactor') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3oauth1 = keystoneauth1.extras.oauth1._loading:V3OAuth1') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3oidcaccesstoken = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAccessToken') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3oidcclientcredentials = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectClientCredentials') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3samlpassword = keystoneauth1.extras._saml2._loading:Saml2Password') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3tokenlessauth = keystoneauth1.loading._plugins.identity.v3:TokenlessAuth') 184s virt: DEBUG (extension:189) found extension EntryPoint.parse('v3totp = keystoneauth1.loading._plugins.identity.v3:TOTP') 184s virt: 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" 184s virt: DEBUG (connectionpool:962) Starting new HTTPS connection (1): keystone.ps6.canonical.com:5000 184s virt: DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "GET /v3 HTTP/1.1" 200 267 184s virt: DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 267 Content-Type: application/json Date: Wed, 13 Nov 2024 10:58:07 GMT Keep-Alive: timeout=75, max=1000 Server: Apache/2.4.52 (Ubuntu) Vary: X-Auth-Token x-openstack-request-id: req-14ee4485-69d2-485d-8877-8e9becbc9ee1 184s virt: 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"}]}} 184s virt: DEBUG (session:946) GET call to https://keystone.ps6.canonical.com:5000/v3 used request id req-14ee4485-69d2-485d-8877-8e9becbc9ee1 184s virt: DEBUG (base:182) Making authentication request to https://keystone.ps6.canonical.com:5000/v3/auth/tokens 184s virt: DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "POST /v3/auth/tokens HTTP/1.1" 201 12525 1180s virt: DEBUG (base:187) {"token": {"methods": ["password"], "user": {"domain": {"id": "default", "name": "Default"}, "id": "c871debdeffd4cb8b69ce618fc4aa361", "name": "prod-proposed-migration-ppc64el", "password_expires_at": null}, "audit_ids": ["_A7olYu0SRGW7gQsudo: /var/tmp/autopkgtest-run-wrapper: command not found 1186s 1iBxXLA"], "expires_at": "2024-11-14T10:58:07.000000Z", "issued_at": "2024-11-13T10:58:07.000000Z", "project": {"domain": {"id": "default", "name": "Default"}, "id": "623df63b80274c21bc79a0c35e68d615", "name": "prod-proposed-migration-ppc64el_project"}, "is_domain": false, "roles": [{"id": "80674fb26dab46e9a6bd57e7f8193360", "name": "load-balancer_member"}, {"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/623df63b80274c21bc79a0c35e68d615", "region": "prodstack6"}, {"id": "5e19c32243c04a3faf1e5f9ab900c260", "interface": "internal", "region_id": "prodstack6", "url": "https://heat-internal.ps6.canonical.com:8004/v1/623df63b80274c21bc79a0c35e68d615", "region": "prodstack6"}, {"id": "b3fc5c88ec8543a691fec75726b86967", "interface": "admin", "region_id": "prodstack6", "url": "https://heat-admin.ps6.canonical.com:8004/v1/623df63b80274c21bc79a0c35e68d615", "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/623df63b80274c21bc79a0c35e68d615", "region": "prodstack6"}, {"id": "d2309a99724b4111827482c68a832c0c", "interface": "public", "region_id": "prodstack6", "url": "https://cinder.ps6.canonical.com:8776/v3/623df63b80274c21bc79a0c35e68d615", "region": "prodstackvirt: ERROR (CommandError): Unable to delete the specified server(s). 1186s Unexpected error: 1186s Traceback (most recent call last): 1186s File "/home/ubuntu/autopkgtest/lib/VirtSubproc.py", line 159, in execute_timeout 1186s (out, err) = sp.communicate(instr, timeout=1) 1186s File "/usr/lib/python3.8/subprocess.py", line 1028, in communicate 1186s stdout, stderr = self._communicate(input, endtime, timeout) 1186s File "/usr/lib/python3.8/subprocess.py", line 1869, in _communicate 1186s self._check_timeout(endtime, orig_timeout, stdout, stderr) 1186s File "/usr/lib/python3.8/subprocess.py", line 1072, in _check_timeout 1186s raise TimeoutExpired( 1186s subprocess.TimeoutExpired: Command '['/home/ubuntu/autopkgtest/ssh-setup/nova', 'revert', '--flavor', 'autopkgtest-ppc64el', '--security-groups', 'autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-ppc64el-22.secgroup', '--name', 'adt-plucky-ppc64el-anonip-20241113-100529-juju-7f2275-prod-proposed-migration-environment-2-21c22145-3af8-42e7-8659-4b7bd8dedc0a', '--image', 'adt/ubuntu-plucky-ppc64el-server', '--keyname', 'testbed-juju-7f2275-prod-proposed-migration-environment-2', '--net-id=net_prod-proposed-migration-ppc64el', '-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,keyserver.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/', '--uuid', '0bc67fe8-99a9-4a8d-ab65-b4a5fb1e4a64', '']' timed out after 1 seconds 1186s 1186s During handling of the above exception, another exception occurred: 1186s 1186s Traceback (most recent call last): 1186s File "/home/ubuntu/autopkgtest/lib/VirtSubproc.py", line 843, in mainloop 1186s command() 1186s File "/home/ubuntu/autopkgtest/lib/VirtSubproc.py", line 772, in command 1186s r = f(c, ce) 1186s File "/home/ubuntu/autopkgtest/lib/VirtSubproc.py", line 405, in cmd_revert 1186s caller.hook_revert() 1186s File "/home/ubuntu/autopkgtest/virt/autopkgtest-virt-ssh", line 431, in hook_revert 1186s host_setup('revert') 1186s File "/home/ubuntu/autopkgtest/virt/autopkgtest-virt-ssh", line 250, in host_setup 1186s execute_setup_script(command) 1186s File "/home/ubuntu/autopkgtest/virt/autopkgtest-virt-ssh", line 202, in execute_setup_script 1186s (status, out, err) = VirtSubproc.execute_timeout( 1186s File "/home/ubuntu/autopkgtest/lib/VirtSubproc.py", line 172, in execute_timeout 1186s sys.stderr.write(f"virt: {line}") 1186s BlockingIOError: [Errno 11] write could not complete without blocking 1189s autopkgtest [11:14:55]: ERROR: testbed failure: unexpected eof from the testbed