0s autopkgtest [17:42:39]: starting date and time: 2025-07-02 17:42:39+0000 0s autopkgtest [17:42:39]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message 0s autopkgtest [17:42:39]: host juju-7f2275-prod-proposed-migration-environment-21; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.immzhe01/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:qemu --apt-upgrade freedom-maker --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=qemu/1:9.2.1+ds-1ubuntu5.1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-21@sto01-ppc64el-19.secgroup --name adt-plucky-ppc64el-freedom-maker-20250702-170112-juju-7f2275-prod-proposed-migration-environment-21-38725ca5-47db-43af-8330-62a5ee2aea96 --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-21 --net-id=net_prod-autopkgtest-workers-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-plucky-ppc64el-freedom-maker-20250702-170112-juju-7f2275-prod-proposed-migration-environment-21-38725ca5-47db-43af-8330-62a5ee2aea96 from image adt/ubuntu-plucky-ppc64el-server-20250702.img (UUID e0d74eeb-3df1-4791-9746-3b1962125163)... 56s autopkgtest [17:43:35]: testbed dpkg architecture: ppc64el 56s autopkgtest [17:43:35]: testbed apt version: 3.0.0 56s autopkgtest [17:43:35]: @@@@@@@@@@@@@@@@@@@@ test bed setup 57s autopkgtest [17:43:36]: testbed release detected to be: None 57s autopkgtest [17:43:36]: updating testbed package index (apt update) 57s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [265 kB] 57s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 57s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 58s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 58s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [28.0 kB] 58s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [3108 B] 58s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [7940 B] 58s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9056 B] 58s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [54.4 kB] 58s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el c-n-f Metadata [288 B] 58s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [724 B] 58s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el c-n-f Metadata [120 B] 58s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [43.5 kB] 58s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el c-n-f Metadata [8688 B] 58s Get:15 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [732 B] 58s Get:16 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el c-n-f Metadata [172 B] 59s Fetched 422 kB in 1s (621 kB/s) 60s Reading package lists... 61s autopkgtest [17:43:40]: upgrading testbed (apt dist-upgrade and autopurge) 61s Reading package lists... 61s Building dependency tree... 61s Reading state information... 61s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 61s Starting 2 pkgProblemResolver with broken count: 0 61s Done 61s Entering ResolveByKeep 62s 62s Calculating upgrade... 62s The following packages will be upgraded: 62s linux-firmware 62s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 62s Need to get 575 MB of archives. 62s After this operation, 5120 B of additional disk space will be used. 62s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el linux-firmware ppc64el 20250317.git1d4c88ee-0ubuntu1.3 [575 MB] 71s Fetched 575 MB in 9s (63.2 MB/s) 71s (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 ... 78709 files and directories currently installed.) 71s Preparing to unpack .../linux-firmware_20250317.git1d4c88ee-0ubuntu1.3_ppc64el.deb ... 71s Unpacking linux-firmware (20250317.git1d4c88ee-0ubuntu1.3) over (20250317.git1d4c88ee-0ubuntu1.2) ... 76s Setting up linux-firmware (20250317.git1d4c88ee-0ubuntu1.3) ... 76s Processing triggers for initramfs-tools (0.147ubuntu1) ... 76s update-initramfs: Generating /boot/initrd.img-6.14.0-23-generic 84s Reading package lists... 84s Building dependency tree... 84s Reading state information... 84s Starting pkgProblemResolver with broken count: 0 84s Starting 2 pkgProblemResolver with broken count: 0 84s Done 84s Solving dependencies... 84s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 85s autopkgtest [17:44:04]: rebooting testbed after setup commands that affected boot 122s autopkgtest [17:44:41]: testbed running kernel: Linux 6.14.0-23-generic #23-Ubuntu SMP Fri Jun 13 22:20:18 UTC 2025 124s autopkgtest [17:44:43]: @@@@@@@@@@@@@@@@@@@@ apt-source freedom-maker 126s Get:1 http://ftpmaster.internal/ubuntu plucky/universe freedom-maker 0.33 (dsc) [2015 B] 126s Get:2 http://ftpmaster.internal/ubuntu plucky/universe freedom-maker 0.33 (tar) [45.6 kB] 126s gpgv: Signature made Fri Feb 9 17:46:29 2024 UTC 126s gpgv: using RSA key 7D6ADB750F91085589484BE677C0C75E7B650808 126s gpgv: issuer "jvalleroy@mailbox.org" 126s gpgv: Can't check signature: No public key 126s dpkg-source: warning: cannot verify inline signature for ./freedom-maker_0.33.dsc: no acceptable signature found 126s autopkgtest [17:44:45]: testing package freedom-maker version 0.33 126s autopkgtest [17:44:45]: build not needed 127s autopkgtest [17:44:46]: test command1: preparing testbed 127s Reading package lists... 127s Building dependency tree... 127s Reading state information... 127s Starting pkgProblemResolver with broken count: 0 127s Starting 2 pkgProblemResolver with broken count: 0 127s Done 127s The following NEW packages will be installed: 127s debootstrap freedom-maker git git-man liberror-perl liburing2 python3-all 127s qemu-user qemu-user-binfmt qemu-user-static qemu-utils sshpass u-boot-tools 127s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 127s Need to get 30.3 MB of archives. 127s After this operation, 269 MB of additional disk space will be used. 127s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el liberror-perl all 0.17030-1 [23.5 kB] 127s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el git-man all 1:2.48.1-0ubuntu1 [1148 kB] 128s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el git ppc64el 1:2.48.1-0ubuntu1 [7338 kB] 128s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el liburing2 ppc64el 2.9-1 [29.0 kB] 128s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-all ppc64el 3.13.3-1 [880 B] 128s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el qemu-user ppc64el 1:9.2.1+ds-1ubuntu5.1 [18.9 MB] 128s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el qemu-user-binfmt ppc64el 1:9.2.1+ds-1ubuntu5.1 [1956 B] 128s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el qemu-user-static ppc64el 1:9.2.1+ds-1ubuntu5.1 [15.1 kB] 128s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el qemu-utils ppc64el 1:9.2.1+ds-1ubuntu5.1 [2420 kB] 128s Get:10 http://ftpmaster.internal/ubuntu plucky/main ppc64el u-boot-tools ppc64el 2025.01-1~0ubuntu2 [325 kB] 128s Get:11 http://ftpmaster.internal/ubuntu plucky/main ppc64el debootstrap all 1.0.140 [50.1 kB] 128s Get:12 http://ftpmaster.internal/ubuntu plucky/universe ppc64el sshpass ppc64el 1.10-0.1 [11.9 kB] 129s Get:13 http://ftpmaster.internal/ubuntu plucky/universe ppc64el freedom-maker all 0.33 [54.2 kB] 129s Fetched 30.3 MB in 1s (26.1 MB/s) 129s Selecting previously unselected package liberror-perl. 129s (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 ... 78709 files and directories currently installed.) 129s Preparing to unpack .../00-liberror-perl_0.17030-1_all.deb ... 129s Unpacking liberror-perl (0.17030-1) ... 129s Selecting previously unselected package git-man. 129s Preparing to unpack .../01-git-man_1%3a2.48.1-0ubuntu1_all.deb ... 129s Unpacking git-man (1:2.48.1-0ubuntu1) ... 129s Selecting previously unselected package git. 129s Preparing to unpack .../02-git_1%3a2.48.1-0ubuntu1_ppc64el.deb ... 129s Unpacking git (1:2.48.1-0ubuntu1) ... 129s Selecting previously unselected package liburing2:ppc64el. 129s Preparing to unpack .../03-liburing2_2.9-1_ppc64el.deb ... 129s Unpacking liburing2:ppc64el (2.9-1) ... 129s Selecting previously unselected package python3-all. 129s Preparing to unpack .../04-python3-all_3.13.3-1_ppc64el.deb ... 129s Unpacking python3-all (3.13.3-1) ... 129s Selecting previously unselected package qemu-user. 129s Preparing to unpack .../05-qemu-user_1%3a9.2.1+ds-1ubuntu5.1_ppc64el.deb ... 129s Unpacking qemu-user (1:9.2.1+ds-1ubuntu5.1) ... 130s Selecting previously unselected package qemu-user-binfmt. 130s Preparing to unpack .../06-qemu-user-binfmt_1%3a9.2.1+ds-1ubuntu5.1_ppc64el.deb ... 130s Unpacking qemu-user-binfmt (1:9.2.1+ds-1ubuntu5.1) ... 130s Selecting previously unselected package qemu-user-static. 130s Preparing to unpack .../07-qemu-user-static_1%3a9.2.1+ds-1ubuntu5.1_ppc64el.deb ... 130s Unpacking qemu-user-static (1:9.2.1+ds-1ubuntu5.1) ... 130s Selecting previously unselected package qemu-utils. 130s Preparing to unpack .../08-qemu-utils_1%3a9.2.1+ds-1ubuntu5.1_ppc64el.deb ... 130s Unpacking qemu-utils (1:9.2.1+ds-1ubuntu5.1) ... 130s Selecting previously unselected package u-boot-tools. 130s Preparing to unpack .../09-u-boot-tools_2025.01-1~0ubuntu2_ppc64el.deb ... 130s Unpacking u-boot-tools (2025.01-1~0ubuntu2) ... 130s Selecting previously unselected package debootstrap. 130s Preparing to unpack .../10-debootstrap_1.0.140_all.deb ... 130s Unpacking debootstrap (1.0.140) ... 130s Selecting previously unselected package sshpass. 130s Preparing to unpack .../11-sshpass_1.10-0.1_ppc64el.deb ... 130s Unpacking sshpass (1.10-0.1) ... 130s Selecting previously unselected package freedom-maker. 130s Preparing to unpack .../12-freedom-maker_0.33_all.deb ... 130s Unpacking freedom-maker (0.33) ... 130s Setting up debootstrap (1.0.140) ... 130s Setting up python3-all (3.13.3-1) ... 130s Setting up sshpass (1.10-0.1) ... 130s Setting up liberror-perl (0.17030-1) ... 130s Setting up qemu-user (1:9.2.1+ds-1ubuntu5.1) ... 130s Setting up u-boot-tools (2025.01-1~0ubuntu2) ... 130s Setting up git-man (1:2.48.1-0ubuntu1) ... 130s Setting up liburing2:ppc64el (2.9-1) ... 130s Setting up qemu-user-binfmt (1:9.2.1+ds-1ubuntu5.1) ... 130s Setting up qemu-user-static (1:9.2.1+ds-1ubuntu5.1) ... 130s Setting up git (1:2.48.1-0ubuntu1) ... 130s Setting up qemu-utils (1:9.2.1+ds-1ubuntu5.1) ... 130s Setting up freedom-maker (0.33) ... 131s Processing triggers for systemd (257.4-1ubuntu3.1) ... 131s Processing triggers for man-db (2.13.0-1) ... 133s Processing triggers for libc-bin (2.41-6ubuntu1) ... 134s autopkgtest [17:44:53]: test command1: set -e ; for py in $(py3versions -r 2>/dev/null) ; do cd "$AUTOPKGTEST_TMP" ; echo "Testing with $py:" ; $py -c "import freedommaker; print(freedommaker)" ; done 134s autopkgtest [17:44:53]: test command1: [----------------------- 134s Testing with python3.13: 134s 135s autopkgtest [17:44:54]: test command1: -----------------------] 135s autopkgtest [17:44:54]: test command1: - - - - - - - - - - results - - - - - - - - - - 135s command1 PASS 135s autopkgtest [17:44:54]: test command2: preparing testbed 148s Traceback (most recent call last): 148s File "/usr/lib/python3/dist-packages/urllib3/contrib/pyopenssl.py", line 485, in wrap_socket 148s cnx.do_handshake() 148s File "/usr/lib/python3/dist-packages/OpenSSL/SSL.py", line 1915, in do_handshake 148s self._raise_ssl_error(self._ssl, result) 148s File "/usr/lib/python3/dist-packages/OpenSSL/SSL.py", line 1640, in _raise_ssl_error 148s raise SysCallError(-1, "Unexpected EOF") 148s OpenSSL.SSL.SysCallError: (-1, 'Unexpected EOF') 148s 148s During handling of the above exception, another exception occurred: 148s 148s Traceback (most recent call last): 148s File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 666, in urlopen 148s httplib_response = self._make_request( 148s File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 377, in _make_request 148s self._validate_conn(conn) 148s File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 1001, in _validate_conn 148s conn.connect() 148s File "/usr/lib/python3/dist-packages/urllib3/connection.py", line 366, in connect 148s self.sock = ssl_wrap_socket( 148s File "/usr/lib/python3/dist-packages/urllib3/util/ssl_.py", line 370, in ssl_wrap_socket 148s return context.wrap_socket(sock, server_hostname=server_hostname) 148s File "/usr/lib/python3/dist-packages/urllib3/contrib/pyopenssl.py", line 491, in wrap_socket 148s raise ssl.SSLError("bad handshake: %r" % e) 148s ssl.SSLError: ("bad handshake: SysCallError(-1, 'Unexpected EOF')",) 148s 148s During handling of the above exception, another exception occurred: 148s 148s Traceback (most recent call last): 148s File "/usr/lib/python3/dist-packages/requests/adapters.py", line 439, in send 148s resp = conn.urlopen( 148s File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 720, in urlopen 148s retries = retries.increment( 148s File "/usr/lib/python3/dist-packages/urllib3/util/retry.py", line 438, in increment 148s raise MaxRetryError(_pool, url, error or ResponseError(cause)) 148s urllib3.exceptions.MaxRetryError: HTTPSConnectionPool(host='glance.ps7.canonical.com', port=9292): Max retries exceeded with url: /v2/images?limit=20 (Caused by SSLError(SSLError("bad handshake: SysCallError(-1, 'Unexpected EOF')"))) 148s 148s During handling of the above exception, another exception occurred: 148s 148s Traceback (most recent call last): 148s File "/usr/lib/python3/dist-packages/keystoneauth1/session.py", line 1004, in _send_request 148s resp = self.session.request(method, url, **kwargs) 148s File "/usr/lib/python3/dist-packages/requests/sessions.py", line 535, in request 148s resp = self.send(prep, **send_kwargs) 148s File "/usr/lib/python3/dist-packages/requests/sessions.py", line 648, in send 148s r = adapter.send(request, **kwargs) 148s File "/usr/lib/python3/dist-packages/requests/adapters.py", line 514, in send 148s raise SSLError(e, request=request) 148s requests.exceptions.SSLError: HTTPSConnectionPool(host='glance.ps7.canonical.com', port=9292): Max retries exceeded with url: /v2/images?limit=20 (Caused by SSLError(SSLError("bad handshake: SysCallError(-1, 'Unexpected EOF')"))) 148s 148s During handling of the above exception, another exception occurred: 148s 148s Traceback (most recent call last): 148s File "", line 27, in 148s File "/usr/lib/python3/dist-packages/glanceclient/common/utils.py", line 581, in __next__ 148s return self._next() 148s File "/usr/lib/python3/dist-packages/glanceclient/common/utils.py", line 570, in _next 148s obj, resp = next(self._self_wrapped) 148s File "/usr/lib/python3/dist-packages/glanceclient/v2/images.py", line 184, in list 148s for image, resp in paginate(url, page_size, limit): 148s File "/usr/lib/python3/dist-packages/glanceclient/v2/images.py", line 111, in paginate 148s resp, body = self.http_client.get(next_url, headers=req_id_hdr) 148s File "/usr/lib/python3/dist-packages/keystoneauth1/adapter.py", line 386, in get 148s return self.request(url, 'GET', **kwargs) 148s File "/usr/lib/python3/dist-packages/glanceclient/common/http.py", line 368, in request 148s resp = super(SessionClient, 148s File "/usr/lib/python3/dist-packages/keystoneauth1/adapter.py", line 248, in request 148s return self.session.request(url, method, **kwargs) 148s File "/usr/lib/python3/dist-packages/keystoneauth1/session.py", line 913, in request 148s resp = send(**kwargs) 148s File "/usr/lib/python3/dist-packages/keystoneauth1/session.py", line 1008, in _send_request 148s raise exceptions.SSLError(msg) 148s keystoneauth1.exceptions.connection.SSLError: SSL exception connecting to https://glance.ps7.canonical.com:9292/v2/images?limit=20: HTTPSConnectionPool(host='glance.ps7.canonical.com', port=9292): Max retries exceeded with url: /v2/images?limit=20 (Caused by SSLError(SSLError("bad handshake: SysCallError(-1, 'Unexpected EOF')"))) 148s ------- nova console-log 25c1e724-c98f-4de6-8474-9c8776bd0933 (adt-plucky-ppc64el-freedom-maker-20250702-170112-juju-7f2275-prod-proposed-migration-environment-21-38725ca5-47db-43af-8330-62a5ee2aea96) ------ 163s ERROR (CommandError): No server with a name or ID of '25c1e724-c98f-4de6-8474-9c8776bd0933' exists. 163s --------------------------------------------------- 163s ------- nova show 25c1e724-c98f-4de6-8474-9c8776bd0933 (adt-plucky-ppc64el-freedom-maker-20250702-170112-juju-7f2275-prod-proposed-migration-environment-21-38725ca5-47db-43af-8330-62a5ee2aea96) ------ 166s ERROR (CommandError): No server with a name or ID of '25c1e724-c98f-4de6-8474-9c8776bd0933' exists. 166s --------------------------------------------------- 1584s sudo: /tmp/autopkgtest-run-wrapper: command not found 1589s : failure: failed to run setup script '/home/ubuntu/autopkgtest/ssh-setup/nova' with command 'revert' 1589s autopkgtest [18:09:08]: ERROR: testbed failure: unexpected eof from the testbed