0s autopkgtest [13:18:33]: starting date and time: 2024-11-15 13:18:33+0000 0s autopkgtest [13:18:33]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [13:18:33]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.w4hcfci3/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:pyzmq --apt-upgrade lavacli --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=pyzmq/24.0.1-5build2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-7.secgroup --name adt-plucky-amd64-lavacli-20241115-131833-juju-7f2275-prod-proposed-migration-environment-2-fec3c4b3-a2e9-4a82-81dd-986e5765efaa --image adt/ubuntu-plucky-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-amd64 -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/ 46s autopkgtest [13:19:19]: testbed dpkg architecture: amd64 46s autopkgtest [13:19:19]: testbed apt version: 2.9.8 46s autopkgtest [13:19:19]: @@@@@@@@@@@@@@@@@@@@ test bed setup 47s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 47s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1052 kB] 47s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 47s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [94.5 kB] 47s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.5 kB] 47s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [176 kB] 47s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [109 kB] 47s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [32.6 kB] 47s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [295 kB] 47s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [831 kB] 47s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [740 B] 47s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [9416 B] 48s Fetched 2696 kB in 1s (3407 kB/s) 48s Reading package lists... 50s Reading package lists... 50s Building dependency tree... 50s Reading state information... 50s Calculating upgrade... 50s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 50s Reading package lists... 51s Building dependency tree... 51s Reading state information... 51s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 51s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 51s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 51s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 51s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 52s Reading package lists... 52s Reading package lists... 52s Building dependency tree... 52s Reading state information... 53s Calculating upgrade... 53s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 53s Reading package lists... 53s Building dependency tree... 53s Reading state information... 53s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 56s autopkgtest [13:19:29]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 13:41:20 UTC 2024 56s autopkgtest [13:19:29]: @@@@@@@@@@@@@@@@@@@@ apt-source lavacli 58s Get:1 http://ftpmaster.internal/ubuntu plucky/universe lavacli 1.2-1 (dsc) [2181 B] 58s Get:2 http://ftpmaster.internal/ubuntu plucky/universe lavacli 1.2-1 (tar) [70.3 kB] 58s Get:3 http://ftpmaster.internal/ubuntu plucky/universe lavacli 1.2-1 (diff) [15.3 kB] 58s gpgv: Signature made Fri Dec 17 18:28:19 2021 UTC 58s gpgv: using RSA key B2DEE66036C40829FCD0F10CFC0DB1BBCD460BDE 58s gpgv: Can't check signature: No public key 58s dpkg-source: warning: cannot verify inline signature for ./lavacli_1.2-1.dsc: no acceptable signature found 58s autopkgtest [13:19:31]: testing package lavacli version 1.2-1 58s autopkgtest [13:19:31]: build not needed 59s autopkgtest [13:19:32]: test testsuite: preparing testbed 60s Reading package lists... 60s Building dependency tree... 60s Reading state information... 60s Starting pkgProblemResolver with broken count: 0 60s Starting 2 pkgProblemResolver with broken count: 0 60s Done 61s The following additional packages will be installed: 61s lavacli libnorm1t64 libpgm-5.3-0t64 libsodium23 libzmq5 python3-aiohttp 61s python3-aiosignal python3-async-timeout python3-frozenlist python3-iniconfig 61s python3-multidict python3-packaging python3-pluggy python3-py python3-pytest 61s python3-yarl python3-zmq 61s Suggested packages: 61s subversion 61s Recommended packages: 61s libjs-jquery python3-aiodns 61s The following NEW packages will be installed: 61s autopkgtest-satdep lavacli libnorm1t64 libpgm-5.3-0t64 libsodium23 libzmq5 61s python3-aiohttp python3-aiosignal python3-async-timeout python3-frozenlist 61s python3-iniconfig python3-multidict python3-packaging python3-pluggy 61s python3-py python3-pytest python3-yarl python3-zmq 61s 0 upgraded, 18 newly installed, 0 to remove and 0 not upgraded. 61s Need to get 2034 kB/2034 kB of archives. 61s After this operation, 8407 kB of additional disk space will be used. 61s Get:1 /tmp/autopkgtest.kyIA6E/1-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [716 B] 61s Get:2 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-multidict amd64 6.1.0-1 [34.3 kB] 61s Get:3 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-yarl amd64 1.9.4-1 [71.8 kB] 61s Get:4 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-async-timeout all 4.0.3-1 [6412 B] 61s Get:5 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-frozenlist amd64 1.5.0-1 [53.2 kB] 61s Get:6 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-aiosignal all 1.3.1-1 [5172 B] 61s Get:7 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-aiohttp amd64 3.9.5-1 [285 kB] 61s Get:8 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-py all 1.11.0-2 [72.7 kB] 61s Get:9 http://ftpmaster.internal/ubuntu plucky/universe amd64 libnorm1t64 amd64 1.5.9+dfsg-3.1build1 [154 kB] 61s Get:10 http://ftpmaster.internal/ubuntu plucky/universe amd64 libpgm-5.3-0t64 amd64 5.3.128~dfsg-2.1build1 [167 kB] 61s Get:11 http://ftpmaster.internal/ubuntu plucky/main amd64 libsodium23 amd64 1.0.18-1build3 [161 kB] 61s Get:12 http://ftpmaster.internal/ubuntu plucky/universe amd64 libzmq5 amd64 4.3.5-1build2 [260 kB] 61s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 python3-zmq amd64 24.0.1-5build2 [406 kB] 61s Get:14 http://ftpmaster.internal/ubuntu plucky/universe amd64 lavacli all 1.2-1 [37.0 kB] 61s Get:15 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-iniconfig all 1.1.1-2 [6024 B] 61s Get:16 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-packaging all 24.1-1 [41.4 kB] 61s Get:17 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-pluggy all 1.5.0-1 [21.0 kB] 61s Get:18 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-pytest all 8.3.3-1 [251 kB] 62s Fetched 2034 kB in 1s (3273 kB/s) 62s Selecting previously unselected package python3-multidict. 62s (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 ... 75576 files and directories currently installed.) 62s Preparing to unpack .../00-python3-multidict_6.1.0-1_amd64.deb ... 62s Unpacking python3-multidict (6.1.0-1) ... 62s Selecting previously unselected package python3-yarl. 62s Preparing to unpack .../01-python3-yarl_1.9.4-1_amd64.deb ... 62s Unpacking python3-yarl (1.9.4-1) ... 62s Selecting previously unselected package python3-async-timeout. 62s Preparing to unpack .../02-python3-async-timeout_4.0.3-1_all.deb ... 62s Unpacking python3-async-timeout (4.0.3-1) ... 62s Selecting previously unselected package python3-frozenlist. 62s Preparing to unpack .../03-python3-frozenlist_1.5.0-1_amd64.deb ... 62s Unpacking python3-frozenlist (1.5.0-1) ... 62s Selecting previously unselected package python3-aiosignal. 62s Preparing to unpack .../04-python3-aiosignal_1.3.1-1_all.deb ... 62s Unpacking python3-aiosignal (1.3.1-1) ... 62s Selecting previously unselected package python3-aiohttp. 62s Preparing to unpack .../05-python3-aiohttp_3.9.5-1_amd64.deb ... 62s Unpacking python3-aiohttp (3.9.5-1) ... 62s Selecting previously unselected package python3-py. 62s Preparing to unpack .../06-python3-py_1.11.0-2_all.deb ... 62s Unpacking python3-py (1.11.0-2) ... 62s Selecting previously unselected package libnorm1t64:amd64. 62s Preparing to unpack .../07-libnorm1t64_1.5.9+dfsg-3.1build1_amd64.deb ... 62s Unpacking libnorm1t64:amd64 (1.5.9+dfsg-3.1build1) ... 62s Selecting previously unselected package libpgm-5.3-0t64:amd64. 62s Preparing to unpack .../08-libpgm-5.3-0t64_5.3.128~dfsg-2.1build1_amd64.deb ... 62s Unpacking libpgm-5.3-0t64:amd64 (5.3.128~dfsg-2.1build1) ... 62s Selecting previously unselected package libsodium23:amd64. 62s Preparing to unpack .../09-libsodium23_1.0.18-1build3_amd64.deb ... 62s Unpacking libsodium23:amd64 (1.0.18-1build3) ... 62s Selecting previously unselected package libzmq5:amd64. 62s Preparing to unpack .../10-libzmq5_4.3.5-1build2_amd64.deb ... 62s Unpacking libzmq5:amd64 (4.3.5-1build2) ... 62s Selecting previously unselected package python3-zmq. 62s Preparing to unpack .../11-python3-zmq_24.0.1-5build2_amd64.deb ... 62s Unpacking python3-zmq (24.0.1-5build2) ... 62s Selecting previously unselected package lavacli. 62s Preparing to unpack .../12-lavacli_1.2-1_all.deb ... 62s Unpacking lavacli (1.2-1) ... 62s Selecting previously unselected package python3-iniconfig. 62s Preparing to unpack .../13-python3-iniconfig_1.1.1-2_all.deb ... 62s Unpacking python3-iniconfig (1.1.1-2) ... 62s Selecting previously unselected package python3-packaging. 62s Preparing to unpack .../14-python3-packaging_24.1-1_all.deb ... 62s Unpacking python3-packaging (24.1-1) ... 62s Selecting previously unselected package python3-pluggy. 62s Preparing to unpack .../15-python3-pluggy_1.5.0-1_all.deb ... 62s Unpacking python3-pluggy (1.5.0-1) ... 62s Selecting previously unselected package python3-pytest. 62s Preparing to unpack .../16-python3-pytest_8.3.3-1_all.deb ... 62s Unpacking python3-pytest (8.3.3-1) ... 62s Selecting previously unselected package autopkgtest-satdep. 62s Preparing to unpack .../17-1-autopkgtest-satdep.deb ... 62s Unpacking autopkgtest-satdep (0) ... 62s Setting up python3-iniconfig (1.1.1-2) ... 62s Setting up libnorm1t64:amd64 (1.5.9+dfsg-3.1build1) ... 62s Setting up libsodium23:amd64 (1.0.18-1build3) ... 62s Setting up python3-py (1.11.0-2) ... 62s Setting up python3-multidict (6.1.0-1) ... 63s Setting up python3-frozenlist (1.5.0-1) ... 63s Setting up python3-aiosignal (1.3.1-1) ... 63s Setting up python3-async-timeout (4.0.3-1) ... 63s Setting up libpgm-5.3-0t64:amd64 (5.3.128~dfsg-2.1build1) ... 63s Setting up python3-packaging (24.1-1) ... 63s Setting up python3-pluggy (1.5.0-1) ... 63s Setting up python3-yarl (1.9.4-1) ... 63s Setting up libzmq5:amd64 (4.3.5-1build2) ... 63s Setting up python3-pytest (8.3.3-1) ... 63s Setting up python3-aiohttp (3.9.5-1) ... 63s Setting up python3-zmq (24.0.1-5build2) ... 64s Setting up lavacli (1.2-1) ... 64s Setting up autopkgtest-satdep (0) ... 64s Processing triggers for man-db (2.13.0-1) ... 64s Processing triggers for libc-bin (2.40-1ubuntu3) ... 67s (Reading database ... 76210 files and directories currently installed.) 67s Removing autopkgtest-satdep (0) ... 67s autopkgtest [13:19:40]: test testsuite: [----------------------- 68s ============================= test session starts ============================== 68s platform linux -- Python 3.12.7, pytest-8.3.3, pluggy-1.5.0 68s rootdir: /tmp/autopkgtest.kyIA6E/autopkgtest_tmp 68s plugins: typeguard-4.4.1 68s collected 199 items 68s 68s tests/test_aliases.py ............. [ 6%] 69s tests/test_device_types.py ........FF............... [ 19%] 69s tests/test_devices.py ................................... [ 36%] 69s tests/test_events.py ............ [ 42%] 69s tests/test_helpers.py .. [ 43%] 70s tests/test_identities.py ............. [ 50%] 70s tests/test_jobs.py ......................................... [ 70%] 70s tests/test_lavacli.py ... [ 72%] 70s tests/test_results.py .............. [ 79%] 71s tests/test_system.py ............ [ 85%] 71s tests/test_tags.py ........ [ 89%] 71s tests/test_workers.py ..................... [100%] 71s 71s =================================== FAILURES =================================== 71s _________________________ test_dt_hc_get_before_2018_4 _________________________ 71s 71s setup = None 71s monkeypatch = <_pytest.monkeypatch.MonkeyPatch object at 0x7efc4e737fb0> 71s capsys = <_pytest.capture.CaptureFixture object at 0x7efc4e735fd0> 71s 71s def test_dt_hc_get_before_2018_4(setup, monkeypatch, capsys): 71s version = "2018.2" 71s monkeypatch.setattr( 71s sys, "argv", ["lavacli", "device-types", "health-check", "get", "mydt"] 71s ) 71s monkeypatch.setattr( 71s xmlrpc.client.ServerProxy, 71s "data", 71s [ 71s {"request": "system.version", "args": (), "ret": version}, 71s {"request": None, "args": (), "ret": []}, 71s ], 71s ) 71s with pytest.raises(SystemExit): 71s main() 71s > assert ( # nosec 71s capsys.readouterr()[1] 71s == """usage: lavacli device-types [-h] {add,aliases,list,show,template,update} ... 71s lavacli device-types: error: argument sub_sub_command: invalid choice: 'health-check' (choose from 'add', 'aliases', 'list', 'show', 'template', 'update') 71s """ 71s ) 71s E assert 'usage: lavac...te, update)\n' == "usage: lavac..., 'update')\n" 71s E 71s E Skipping 166 identical leading characters in diff, use -v to show 71s E - oose from 'add', 'aliases', 'list', 'show', 'template', 'update') 71s E ? - - - - - - - - - - - - 71s E + oose from add, aliases, list, show, template, update) 71s 71s tests/test_device_types.py:227: AssertionError 71s _________________________ test_dt_hc_set_before_2018_4 _________________________ 71s 71s setup = None 71s monkeypatch = <_pytest.monkeypatch.MonkeyPatch object at 0x7efc4e846780> 71s capsys = <_pytest.capture.CaptureFixture object at 0x7efc4e844140> 71s 71s def test_dt_hc_set_before_2018_4(setup, monkeypatch, capsys): 71s version = "2018.2" 71s monkeypatch.setattr( 71s sys, "argv", ["lavacli", "device-types", "health-check", "set", "mydt"] 71s ) 71s monkeypatch.setattr( 71s xmlrpc.client.ServerProxy, 71s "data", 71s [ 71s {"request": "system.version", "args": (), "ret": version}, 71s {"request": None, "args": (), "ret": []}, 71s ], 71s ) 71s with pytest.raises(SystemExit): 71s main() 71s > assert ( # nosec 71s capsys.readouterr()[1] 71s == """usage: lavacli device-types [-h] {add,aliases,list,show,template,update} ... 71s lavacli device-types: error: argument sub_sub_command: invalid choice: 'health-check' (choose from 'add', 'aliases', 'list', 'show', 'template', 'update') 71s """ 71s ) 71s E assert 'usage: lavac...te, update)\n' == "usage: lavac..., 'update')\n" 71s E 71s E Skipping 166 identical leading characters in diff, use -v to show 71s E - oose from 'add', 'aliases', 'list', 'show', 'template', 'update') 71s E ? - - - - - - - - - - - - 71s E + oose from add, aliases, list, show, template, update) 71s 71s tests/test_device_types.py:250: AssertionError 71s =============================== warnings summary =============================== 71s tests/test_events.py: 114 warnings 71s /usr/lib/python3/dist-packages/lavacli/commands/events.py:208: DeprecationWarning: zmq.utils.strtypes is deprecated in pyzmq 23. 71s (topic, _, dt, username, data) = (u(m) for m in msg) 71s 71s tests/test_events.py::test_events_wait_device 71s tests/test_events.py::test_events_wait_device_state 71s tests/test_events.py::test_events_wait_device_health 71s tests/test_events.py::test_events_wait_job 71s tests/test_events.py::test_events_wait_worker 71s tests/test_events.py::test_events_wait_worker_invalid_message 71s /usr/lib/python3/dist-packages/lavacli/commands/events.py:268: DeprecationWarning: zmq.utils.strtypes is deprecated in pyzmq 23. 71s sock.setsockopt(zmq.SUBSCRIBE, b(config.get("events", {}).get("topic", ""))) 71s 71s tests/test_events.py: 106 warnings 71s /usr/lib/python3/dist-packages/lavacli/commands/events.py:293: DeprecationWarning: zmq.utils.strtypes is deprecated in pyzmq 23. 71s (topic, _uuid, _dt, _username, data) = (u(m) for m in msg) 71s 71s tests/test_jobs.py::test_jobs_logs_failure_comment_and_polling 71s /usr/lib/python3/dist-packages/lavacli/commands/jobs.py:550: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC). 71s "dt": datetime.datetime.utcnow().isoformat(), 71s 71s tests/test_jobs.py::test_jobs_resubmit_follow 71s /usr/lib/python3/dist-packages/lavacli/commands/jobs.py:606: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC). 71s "dt": datetime.datetime.utcnow().isoformat(), 71s 71s tests/test_jobs.py::test_jobs_run 71s /usr/lib/python3/dist-packages/lavacli/commands/jobs.py:647: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC). 71s "dt": datetime.datetime.utcnow().isoformat(), 71s 71s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 71s =========================== short test summary info ============================ 71s FAILED tests/test_device_types.py::test_dt_hc_get_before_2018_4 - assert 'usa... 71s FAILED tests/test_device_types.py::test_dt_hc_set_before_2018_4 - assert 'usa... 71s ================= 2 failed, 197 passed, 229 warnings in 3.48s ================== 71s autopkgtest [13:19:44]: test testsuite: -----------------------] 72s autopkgtest [13:19:45]: test testsuite: - - - - - - - - - - results - - - - - - - - - - 72s testsuite FAIL non-zero exit status 1 72s autopkgtest [13:19:45]: @@@@@@@@@@@@@@@@@@@@ summary 72s testsuite FAIL non-zero exit status 1 84s virt: nova [W] Skipping flock for amd64 84s virt: Creating nova instance adt-plucky-amd64-lavacli-20241115-131833-juju-7f2275-prod-proposed-migration-environment-2-fec3c4b3-a2e9-4a82-81dd-986e5765efaa from image adt/ubuntu-plucky-amd64-server-20241115.img (UUID 9bb4dd9c-454f-45da-a125-77b9d27ed145)...