0s autopkgtest [12:02:20]: starting date and time: 2024-11-13 12:02:20+0000 0s autopkgtest [12:02:20]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [12:02:20]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.qa4sb8y0/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 ndms2-client --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-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-9.secgroup --name adt-plucky-s390x-ndms2-client-20241113-120220-juju-7f2275-prod-proposed-migration-environment-2-341adcc5-6e91-491f-ad36-0020fd450c86 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -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/ 116s autopkgtest [12:04:16]: testbed dpkg architecture: s390x 117s autopkgtest [12:04:17]: testbed apt version: 2.9.8 117s autopkgtest [12:04:17]: @@@@@@@@@@@@@@@@@@@@ test bed setup 118s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 118s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [76.4 kB] 118s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB] 118s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 118s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [849 kB] 118s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [85.8 kB] 118s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [565 kB] 118s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [16.6 kB] 119s Fetched 1689 kB in 1s (1403 kB/s) 119s Reading package lists... 121s Reading package lists... 121s Building dependency tree... 121s Reading state information... 121s Calculating upgrade... 121s The following NEW packages will be installed: 121s python3.13-gdbm 121s The following packages will be upgraded: 121s libgpgme11t64 libpython3-stdlib python3 python3-gdbm python3-minimal 121s 5 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 121s Need to get 252 kB of archives. 121s After this operation, 98.3 kB of additional disk space will be used. 121s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-minimal s390x 3.12.7-1 [27.4 kB] 122s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3 s390x 3.12.7-1 [24.0 kB] 122s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpython3-stdlib s390x 3.12.7-1 [10.0 kB] 122s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-gdbm s390x 3.13.0-2 [31.0 kB] 122s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-gdbm s390x 3.12.7-1 [8642 B] 122s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libgpgme11t64 s390x 1.23.2-5ubuntu4 [151 kB] 122s Fetched 252 kB in 0s (554 kB/s) 122s (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 ... 55510 files and directories currently installed.) 122s Preparing to unpack .../python3-minimal_3.12.7-1_s390x.deb ... 122s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ... 122s Setting up python3-minimal (3.12.7-1) ... 122s (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 ... 55510 files and directories currently installed.) 122s Preparing to unpack .../python3_3.12.7-1_s390x.deb ... 122s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ... 122s Preparing to unpack .../libpython3-stdlib_3.12.7-1_s390x.deb ... 122s Unpacking libpython3-stdlib:s390x (3.12.7-1) over (3.12.6-0ubuntu1) ... 122s Selecting previously unselected package python3.13-gdbm. 122s Preparing to unpack .../python3.13-gdbm_3.13.0-2_s390x.deb ... 122s Unpacking python3.13-gdbm (3.13.0-2) ... 122s Preparing to unpack .../python3-gdbm_3.12.7-1_s390x.deb ... 122s Unpacking python3-gdbm:s390x (3.12.7-1) over (3.12.6-1ubuntu1) ... 122s Preparing to unpack .../libgpgme11t64_1.23.2-5ubuntu4_s390x.deb ... 122s Unpacking libgpgme11t64:s390x (1.23.2-5ubuntu4) over (1.18.0-4.1ubuntu4) ... 122s Setting up libgpgme11t64:s390x (1.23.2-5ubuntu4) ... 122s Setting up python3.13-gdbm (3.13.0-2) ... 122s Setting up libpython3-stdlib:s390x (3.12.7-1) ... 122s Setting up python3 (3.12.7-1) ... 123s Setting up python3-gdbm:s390x (3.12.7-1) ... 123s Processing triggers for man-db (2.12.1-3) ... 123s Processing triggers for libc-bin (2.40-1ubuntu3) ... 123s Reading package lists... 123s Building dependency tree... 123s Reading state information... 124s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 124s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 124s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 124s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 124s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 125s Reading package lists... 125s Reading package lists... 125s Building dependency tree... 125s Reading state information... 125s Calculating upgrade... 125s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 125s Reading package lists... 125s Building dependency tree... 125s Reading state information... 126s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 128s autopkgtest [12:04:28]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 129s autopkgtest [12:04:29]: @@@@@@@@@@@@@@@@@@@@ apt-source ndms2-client 130s Get:1 http://ftpmaster.internal/ubuntu plucky/universe ndms2-client 0.1.3-2 (dsc) [2194 B] 130s Get:2 http://ftpmaster.internal/ubuntu plucky/universe ndms2-client 0.1.3-2 (tar) [10.6 kB] 130s Get:3 http://ftpmaster.internal/ubuntu plucky/universe ndms2-client 0.1.3-2 (diff) [2452 B] 130s gpgv: Signature made Fri Sep 6 08:00:19 2024 UTC 130s gpgv: using RSA key FB8ACFA78C726089C38AD0269605A1098C63B92A 130s gpgv: Can't check signature: No public key 130s dpkg-source: warning: cannot verify inline signature for ./ndms2-client_0.1.3-2.dsc: no acceptable signature found 130s autopkgtest [12:04:30]: testing package ndms2-client version 0.1.3-2 131s autopkgtest [12:04:31]: build not needed 131s autopkgtest [12:04:31]: test run-tests: preparing testbed 132s Reading package lists... 132s Building dependency tree... 132s Reading state information... 133s Starting pkgProblemResolver with broken count: 0 133s Starting 2 pkgProblemResolver with broken count: 0 133s Done 133s The following additional packages will be installed: 133s libpython3.13-minimal libpython3.13-stdlib python3-all python3-iniconfig 133s python3-ndms2-client python3-packaging python3-pluggy python3-pytest 133s python3.13 python3.13-minimal 133s Suggested packages: 133s python3.13-venv python3.13-doc binfmt-support 133s The following NEW packages will be installed: 133s autopkgtest-satdep libpython3.13-minimal libpython3.13-stdlib python3-all 133s python3-iniconfig python3-ndms2-client python3-packaging python3-pluggy 133s python3-pytest python3.13 python3.13-minimal 133s 0 upgraded, 11 newly installed, 0 to remove and 0 not upgraded. 133s Need to get 6184 kB/6185 kB of archives. 133s After this operation, 24.1 MB of additional disk space will be used. 133s Get:1 /tmp/autopkgtest.wn3Qwh/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [728 B] 133s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-minimal s390x 3.13.0-2 [877 kB] 133s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-minimal s390x 3.13.0-2 [2172 kB] 134s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-stdlib s390x 3.13.0-2 [2086 kB] 134s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13 s390x 3.13.0-2 [719 kB] 134s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-all s390x 3.12.7-1 [890 B] 134s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 134s Get:8 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-ndms2-client all 0.1.3-2 [8458 B] 134s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x python3-packaging all 24.1-1 [41.4 kB] 134s Get:10 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-pluggy all 1.5.0-1 [21.0 kB] 134s Get:11 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-pytest all 8.3.3-1 [251 kB] 134s Fetched 6184 kB in 1s (5612 kB/s) 134s Selecting previously unselected package libpython3.13-minimal:s390x. 134s (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 ... 55517 files and directories currently installed.) 134s Preparing to unpack .../00-libpython3.13-minimal_3.13.0-2_s390x.deb ... 134s Unpacking libpython3.13-minimal:s390x (3.13.0-2) ... 134s Selecting previously unselected package python3.13-minimal. 134s Preparing to unpack .../01-python3.13-minimal_3.13.0-2_s390x.deb ... 134s Unpacking python3.13-minimal (3.13.0-2) ... 134s Selecting previously unselected package libpython3.13-stdlib:s390x. 134s Preparing to unpack .../02-libpython3.13-stdlib_3.13.0-2_s390x.deb ... 134s Unpacking libpython3.13-stdlib:s390x (3.13.0-2) ... 134s Selecting previously unselected package python3.13. 134s Preparing to unpack .../03-python3.13_3.13.0-2_s390x.deb ... 134s Unpacking python3.13 (3.13.0-2) ... 134s Selecting previously unselected package python3-all. 134s Preparing to unpack .../04-python3-all_3.12.7-1_s390x.deb ... 134s Unpacking python3-all (3.12.7-1) ... 134s Selecting previously unselected package python3-iniconfig. 134s Preparing to unpack .../05-python3-iniconfig_1.1.1-2_all.deb ... 134s Unpacking python3-iniconfig (1.1.1-2) ... 134s Selecting previously unselected package python3-ndms2-client. 134s Preparing to unpack .../06-python3-ndms2-client_0.1.3-2_all.deb ... 134s Unpacking python3-ndms2-client (0.1.3-2) ... 134s Selecting previously unselected package python3-packaging. 134s Preparing to unpack .../07-python3-packaging_24.1-1_all.deb ... 134s Unpacking python3-packaging (24.1-1) ... 134s Selecting previously unselected package python3-pluggy. 134s Preparing to unpack .../08-python3-pluggy_1.5.0-1_all.deb ... 134s Unpacking python3-pluggy (1.5.0-1) ... 134s Selecting previously unselected package python3-pytest. 134s Preparing to unpack .../09-python3-pytest_8.3.3-1_all.deb ... 134s Unpacking python3-pytest (8.3.3-1) ... 134s Selecting previously unselected package autopkgtest-satdep. 134s Preparing to unpack .../10-1-autopkgtest-satdep.deb ... 134s Unpacking autopkgtest-satdep (0) ... 134s Setting up python3-iniconfig (1.1.1-2) ... 135s Setting up python3-ndms2-client (0.1.3-2) ... 135s Setting up libpython3.13-minimal:s390x (3.13.0-2) ... 135s Setting up python3-packaging (24.1-1) ... 135s Setting up python3-pluggy (1.5.0-1) ... 135s Setting up python3.13-minimal (3.13.0-2) ... 136s Setting up libpython3.13-stdlib:s390x (3.13.0-2) ... 136s Setting up python3.13 (3.13.0-2) ... 137s Setting up python3-pytest (8.3.3-1) ... 138s Setting up python3-all (3.12.7-1) ... 138s Setting up autopkgtest-satdep (0) ... 138s Processing triggers for man-db (2.12.1-3) ... 138s Processing triggers for systemd (256.5-2ubuntu4) ... 140s (Reading database ... 56429 files and directories currently installed.) 140s Removing autopkgtest-satdep (0) ... 141s autopkgtest [12:04:41]: test run-tests: [----------------------- 141s ============================= test session starts ============================== 141s platform linux -- Python 3.13.0, pytest-8.3.3, pluggy-1.5.0 -- /usr/bin/python3.13 141s cachedir: .pytest_cache 141s rootdir: /tmp/autopkgtest.wn3Qwh/autopkgtest_tmp 141s plugins: typeguard-4.4.1 141s collecting ... collected 12 items 141s 141s tests/test_check_command_result.py::test_check_command_result_positive[0] FAILED [ 8%] 141s 141s =================================== FAILURES =================================== 141s ____________________ test_check_command_result_positive[0] _____________________ 141s 141s positive_results = ['Network::Interface::Base: "WifiMaster0/AccessPoint1": interface is up.'] 141s 141s def test_check_command_result_positive(positive_results: List[str]) -> None: 141s > from ndms2_client.client import _check_command_result 141s 141s tests/test_check_command_result.py:12: 141s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 141s /usr/lib/python3/dist-packages/ndms2_client/__init__.py:1: in 141s from .connection import Connection, ConnectionException, TelnetConnection 141s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 141s 141s import logging 141s import re 141s > from telnetlib import Telnet 141s E ModuleNotFoundError: No module named 'telnetlib' 141s 141s /usr/lib/python3/dist-packages/ndms2_client/connection.py:3: ModuleNotFoundError 141s =========================== short test summary info ============================ 141s FAILED tests/test_check_command_result.py::test_check_command_result_positive[0] 141s !!!!!!!!!!!!!!!!!!!!!!!!!! stopping after 1 failures !!!!!!!!!!!!!!!!!!!!!!!!!!! 141s ============================== 1 failed in 0.02s =============================== 141s autopkgtest [12:04:41]: test run-tests: -----------------------] 142s autopkgtest [12:04:42]: test run-tests: - - - - - - - - - - results - - - - - - - - - - 142s run-tests FAIL non-zero exit status 1 142s autopkgtest [12:04:42]: @@@@@@@@@@@@@@@@@@@@ summary 142s run-tests FAIL non-zero exit status 1 155s virt: nova [W] Using flock in prodstack6-s390x 155s virt: flock: timeout while waiting to get lock 155s virt: Creating nova instance adt-plucky-s390x-ndms2-client-20241113-120220-juju-7f2275-prod-proposed-migration-environment-2-341adcc5-6e91-491f-ad36-0020fd450c86 from image adt/ubuntu-plucky-s390x-server-20241113.img (UUID e740277e-1f72-40ae-bfbe-46030537c71c)...