0s autopkgtest [21:46:02]: starting date and time: 2024-11-14 21:46:02+0000 0s autopkgtest [21:46:02]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [21:46:02]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.6izty2ud/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_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\n" >> /etc/environment' --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 -- lxd -r lxd-armhf-10.145.243.232 lxd-armhf-10.145.243.232:autopkgtest/ubuntu/plucky/armhf 55s autopkgtest [21:46:57]: testbed dpkg architecture: armhf 57s autopkgtest [21:46:59]: testbed apt version: 2.9.8 57s autopkgtest [21:46:59]: @@@@@@@@@@@@@@@@@@@@ test bed setup 65s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 65s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1037 kB] 65s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 65s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [16.1 kB] 65s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [106 kB] 65s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf Packages [115 kB] 65s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf Packages [741 kB] 65s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf Packages [4136 B] 66s Fetched 2100 kB in 1s (2224 kB/s) 66s Reading package lists... 82s tee: /proc/self/fd/2: Permission denied 106s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 106s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 106s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 106s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 108s Reading package lists... 108s Reading package lists... 108s Building dependency tree... 108s Reading state information... 109s Calculating upgrade... 110s The following packages will be upgraded: 110s libcap-ng0 pastebinit python3-systemd 110s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 110s Need to get 72.8 kB of archives. 110s After this operation, 89.1 kB of additional disk space will be used. 110s Get:1 http://ftpmaster.internal/ubuntu plucky/main armhf libcap-ng0 armhf 0.8.5-3build1 [13.8 kB] 110s Get:2 http://ftpmaster.internal/ubuntu plucky/main armhf pastebinit all 1.7.1-1 [14.9 kB] 110s Get:3 http://ftpmaster.internal/ubuntu plucky/main armhf python3-systemd armhf 235-1build5 [44.0 kB] 111s Fetched 72.8 kB in 0s (195 kB/s) 111s (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 ... 59560 files and directories currently installed.) 111s Preparing to unpack .../libcap-ng0_0.8.5-3build1_armhf.deb ... 111s Unpacking libcap-ng0:armhf (0.8.5-3build1) over (0.8.5-1) ... 111s Setting up libcap-ng0:armhf (0.8.5-3build1) ... 111s (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 ... 59560 files and directories currently installed.) 111s Preparing to unpack .../pastebinit_1.7.1-1_all.deb ... 111s Unpacking pastebinit (1.7.1-1) over (1.7.0-1) ... 111s Preparing to unpack .../python3-systemd_235-1build5_armhf.deb ... 111s Unpacking python3-systemd (235-1build5) over (235-1build4) ... 111s Setting up pastebinit (1.7.1-1) ... 111s Setting up python3-systemd (235-1build5) ... 111s Processing triggers for man-db (2.12.1-3) ... 112s Processing triggers for libc-bin (2.40-1ubuntu3) ... 112s Reading package lists... 113s Building dependency tree... 113s Reading state information... 114s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s autopkgtest [21:47:57]: rebooting testbed after setup commands that affected boot 182s autopkgtest [21:49:04]: testbed running kernel: Linux 6.8.0-48-generic #48~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Mon Oct 7 11:49:53 UTC 2 208s autopkgtest [21:49:30]: @@@@@@@@@@@@@@@@@@@@ apt-source lavacli 218s Get:1 http://ftpmaster.internal/ubuntu plucky/universe lavacli 1.2-1 (dsc) [2181 B] 218s Get:2 http://ftpmaster.internal/ubuntu plucky/universe lavacli 1.2-1 (tar) [70.3 kB] 218s Get:3 http://ftpmaster.internal/ubuntu plucky/universe lavacli 1.2-1 (diff) [15.3 kB] 218s gpgv: Signature made Fri Dec 17 18:28:19 2021 UTC 218s gpgv: using RSA key B2DEE66036C40829FCD0F10CFC0DB1BBCD460BDE 218s gpgv: Can't check signature: No public key 218s dpkg-source: warning: cannot verify inline signature for ./lavacli_1.2-1.dsc: no acceptable signature found 218s autopkgtest [21:49:40]: testing package lavacli version 1.2-1 220s autopkgtest [21:49:42]: build not needed 222s autopkgtest [21:49:44]: test testsuite: preparing testbed 232s Reading package lists... 232s Building dependency tree... 232s Reading state information... 232s Starting pkgProblemResolver with broken count: 0 233s Starting 2 pkgProblemResolver with broken count: 0 233s Done 234s The following additional packages will be installed: 234s lavacli libnorm1t64 libpgm-5.3-0t64 libsodium23 libzmq5 python3-aiohttp 234s python3-aiosignal python3-async-timeout python3-frozenlist python3-iniconfig 234s python3-multidict python3-packaging python3-pluggy python3-py python3-pytest 234s python3-yarl python3-zmq 234s Suggested packages: 234s subversion 234s Recommended packages: 234s libjs-jquery python3-aiodns 234s The following NEW packages will be installed: 234s autopkgtest-satdep lavacli libnorm1t64 libpgm-5.3-0t64 libsodium23 libzmq5 234s python3-aiohttp python3-aiosignal python3-async-timeout python3-frozenlist 234s python3-iniconfig python3-multidict python3-packaging python3-pluggy 234s python3-py python3-pytest python3-yarl python3-zmq 234s 0 upgraded, 18 newly installed, 0 to remove and 0 not upgraded. 234s Need to get 1986 kB/1987 kB of archives. 234s After this operation, 6807 kB of additional disk space will be used. 234s Get:1 /tmp/autopkgtest.m5emNa/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [716 B] 234s Get:2 http://ftpmaster.internal/ubuntu plucky/universe armhf python3-multidict armhf 6.1.0-1 [31.3 kB] 234s Get:3 http://ftpmaster.internal/ubuntu plucky/universe armhf python3-yarl armhf 1.9.4-1 [67.1 kB] 234s Get:4 http://ftpmaster.internal/ubuntu plucky/universe armhf python3-async-timeout all 4.0.3-1 [6412 B] 234s Get:5 http://ftpmaster.internal/ubuntu plucky/universe armhf python3-frozenlist armhf 1.5.0-1 [43.8 kB] 234s Get:6 http://ftpmaster.internal/ubuntu plucky/universe armhf python3-aiosignal all 1.3.1-1 [5172 B] 234s Get:7 http://ftpmaster.internal/ubuntu plucky/universe armhf python3-aiohttp armhf 3.9.5-1 [274 kB] 234s Get:8 http://ftpmaster.internal/ubuntu plucky/universe armhf python3-py all 1.11.0-2 [72.7 kB] 234s Get:9 http://ftpmaster.internal/ubuntu plucky/universe armhf libnorm1t64 armhf 1.5.9+dfsg-3.1build1 [206 kB] 234s Get:10 http://ftpmaster.internal/ubuntu plucky/universe armhf libpgm-5.3-0t64 armhf 5.3.128~dfsg-2.1build1 [171 kB] 234s Get:11 http://ftpmaster.internal/ubuntu plucky/main armhf libsodium23 armhf 1.0.18-1build3 [139 kB] 234s Get:12 http://ftpmaster.internal/ubuntu plucky/universe armhf libzmq5 armhf 4.3.5-1build2 [262 kB] 234s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf python3-zmq armhf 24.0.1-5build2 [352 kB] 234s Get:14 http://ftpmaster.internal/ubuntu plucky/universe armhf lavacli all 1.2-1 [37.0 kB] 234s Get:15 http://ftpmaster.internal/ubuntu plucky/universe armhf python3-iniconfig all 1.1.1-2 [6024 B] 234s Get:16 http://ftpmaster.internal/ubuntu plucky/main armhf python3-packaging all 24.1-1 [41.4 kB] 234s Get:17 http://ftpmaster.internal/ubuntu plucky/universe armhf python3-pluggy all 1.5.0-1 [21.0 kB] 234s Get:18 http://ftpmaster.internal/ubuntu plucky/universe armhf python3-pytest all 8.3.3-1 [251 kB] 235s Fetched 1986 kB in 1s (3170 kB/s) 235s Selecting previously unselected package python3-multidict. 235s (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 ... 59565 files and directories currently installed.) 235s Preparing to unpack .../00-python3-multidict_6.1.0-1_armhf.deb ... 235s Unpacking python3-multidict (6.1.0-1) ... 235s Selecting previously unselected package python3-yarl. 235s Preparing to unpack .../01-python3-yarl_1.9.4-1_armhf.deb ... 235s Unpacking python3-yarl (1.9.4-1) ... 235s Selecting previously unselected package python3-async-timeout. 235s Preparing to unpack .../02-python3-async-timeout_4.0.3-1_all.deb ... 235s Unpacking python3-async-timeout (4.0.3-1) ... 235s Selecting previously unselected package python3-frozenlist. 235s Preparing to unpack .../03-python3-frozenlist_1.5.0-1_armhf.deb ... 235s Unpacking python3-frozenlist (1.5.0-1) ... 235s Selecting previously unselected package python3-aiosignal. 235s Preparing to unpack .../04-python3-aiosignal_1.3.1-1_all.deb ... 235s Unpacking python3-aiosignal (1.3.1-1) ... 235s Selecting previously unselected package python3-aiohttp. 235s Preparing to unpack .../05-python3-aiohttp_3.9.5-1_armhf.deb ... 235s Unpacking python3-aiohttp (3.9.5-1) ... 235s Selecting previously unselected package python3-py. 235s Preparing to unpack .../06-python3-py_1.11.0-2_all.deb ... 235s Unpacking python3-py (1.11.0-2) ... 235s Selecting previously unselected package libnorm1t64:armhf. 235s Preparing to unpack .../07-libnorm1t64_1.5.9+dfsg-3.1build1_armhf.deb ... 235s Unpacking libnorm1t64:armhf (1.5.9+dfsg-3.1build1) ... 235s Selecting previously unselected package libpgm-5.3-0t64:armhf. 235s Preparing to unpack .../08-libpgm-5.3-0t64_5.3.128~dfsg-2.1build1_armhf.deb ... 235s Unpacking libpgm-5.3-0t64:armhf (5.3.128~dfsg-2.1build1) ... 235s Selecting previously unselected package libsodium23:armhf. 235s Preparing to unpack .../09-libsodium23_1.0.18-1build3_armhf.deb ... 235s Unpacking libsodium23:armhf (1.0.18-1build3) ... 235s Selecting previously unselected package libzmq5:armhf. 235s Preparing to unpack .../10-libzmq5_4.3.5-1build2_armhf.deb ... 235s Unpacking libzmq5:armhf (4.3.5-1build2) ... 235s Selecting previously unselected package python3-zmq. 235s Preparing to unpack .../11-python3-zmq_24.0.1-5build2_armhf.deb ... 235s Unpacking python3-zmq (24.0.1-5build2) ... 235s Selecting previously unselected package lavacli. 235s Preparing to unpack .../12-lavacli_1.2-1_all.deb ... 235s Unpacking lavacli (1.2-1) ... 235s Selecting previously unselected package python3-iniconfig. 235s Preparing to unpack .../13-python3-iniconfig_1.1.1-2_all.deb ... 235s Unpacking python3-iniconfig (1.1.1-2) ... 236s Selecting previously unselected package python3-packaging. 236s Preparing to unpack .../14-python3-packaging_24.1-1_all.deb ... 236s Unpacking python3-packaging (24.1-1) ... 236s Selecting previously unselected package python3-pluggy. 236s Preparing to unpack .../15-python3-pluggy_1.5.0-1_all.deb ... 236s Unpacking python3-pluggy (1.5.0-1) ... 236s Selecting previously unselected package python3-pytest. 236s Preparing to unpack .../16-python3-pytest_8.3.3-1_all.deb ... 236s Unpacking python3-pytest (8.3.3-1) ... 236s Selecting previously unselected package autopkgtest-satdep. 236s Preparing to unpack .../17-1-autopkgtest-satdep.deb ... 236s Unpacking autopkgtest-satdep (0) ... 236s Setting up python3-iniconfig (1.1.1-2) ... 236s Setting up libnorm1t64:armhf (1.5.9+dfsg-3.1build1) ... 236s Setting up libsodium23:armhf (1.0.18-1build3) ... 236s Setting up python3-py (1.11.0-2) ... 236s Setting up python3-multidict (6.1.0-1) ... 236s Setting up python3-frozenlist (1.5.0-1) ... 236s Setting up python3-aiosignal (1.3.1-1) ... 236s Setting up python3-async-timeout (4.0.3-1) ... 237s Setting up libpgm-5.3-0t64:armhf (5.3.128~dfsg-2.1build1) ... 237s Setting up python3-packaging (24.1-1) ... 237s Setting up python3-pluggy (1.5.0-1) ... 237s Setting up python3-yarl (1.9.4-1) ... 237s Setting up libzmq5:armhf (4.3.5-1build2) ... 237s Setting up python3-pytest (8.3.3-1) ... 237s Setting up python3-aiohttp (3.9.5-1) ... 238s Setting up python3-zmq (24.0.1-5build2) ... 238s Setting up lavacli (1.2-1) ... 238s Setting up autopkgtest-satdep (0) ... 238s Processing triggers for man-db (2.12.1-3) ... 239s Processing triggers for libc-bin (2.40-1ubuntu3) ... 251s (Reading database ... 60199 files and directories currently installed.) 251s Removing autopkgtest-satdep (0) ... 256s autopkgtest [21:50:18]: test testsuite: [----------------------- 259s ============================= test session starts ============================== 259s platform linux -- Python 3.12.7, pytest-8.3.3, pluggy-1.5.0 259s rootdir: /tmp/autopkgtest.m5emNa/autopkgtest_tmp 259s plugins: typeguard-4.4.1 259s collected 199 items 259s 260s tests/test_aliases.py ............. [ 6%] 260s tests/test_device_types.py ........FF............... [ 19%] 261s tests/test_devices.py ................................... [ 36%] 261s tests/test_events.py ............ [ 42%] 261s tests/test_helpers.py .. [ 43%] 261s tests/test_identities.py ............. [ 50%] 262s tests/test_jobs.py ......................................... [ 70%] 262s tests/test_lavacli.py ... [ 72%] 263s tests/test_results.py .............. [ 79%] 263s tests/test_system.py ............ [ 85%] 263s tests/test_tags.py ........ [ 89%] 263s tests/test_workers.py ..................... [100%] 263s 263s =================================== FAILURES =================================== 263s _________________________ test_dt_hc_get_before_2018_4 _________________________ 263s 263s setup = None 263s monkeypatch = <_pytest.monkeypatch.MonkeyPatch object at 0xed244270> 263s capsys = <_pytest.capture.CaptureFixture object at 0xed244648> 263s 263s def test_dt_hc_get_before_2018_4(setup, monkeypatch, capsys): 263s version = "2018.2" 263s monkeypatch.setattr( 263s sys, "argv", ["lavacli", "device-types", "health-check", "get", "mydt"] 263s ) 263s monkeypatch.setattr( 263s xmlrpc.client.ServerProxy, 263s "data", 263s [ 263s {"request": "system.version", "args": (), "ret": version}, 263s {"request": None, "args": (), "ret": []}, 263s ], 263s ) 263s with pytest.raises(SystemExit): 263s main() 263s > assert ( # nosec 263s capsys.readouterr()[1] 263s == """usage: lavacli device-types [-h] {add,aliases,list,show,template,update} ... 263s lavacli device-types: error: argument sub_sub_command: invalid choice: 'health-check' (choose from 'add', 'aliases', 'list', 'show', 'template', 'update') 263s """ 263s ) 263s E assert 'usage: lavac...te, update)\n' == "usage: lavac..., 'update')\n" 263s E 263s E Skipping 166 identical leading characters in diff, use -v to show 263s E - oose from 'add', 'aliases', 'list', 'show', 'template', 'update') 263s E ? - - - - - - - - - - - - 263s E + oose from add, aliases, list, show, template, update) 263s 263s tests/test_device_types.py:227: AssertionError 263s _________________________ test_dt_hc_set_before_2018_4 _________________________ 263s 263s setup = None 263s monkeypatch = <_pytest.monkeypatch.MonkeyPatch object at 0xed203300> 263s capsys = <_pytest.capture.CaptureFixture object at 0xed203ab0> 263s 263s def test_dt_hc_set_before_2018_4(setup, monkeypatch, capsys): 263s version = "2018.2" 263s monkeypatch.setattr( 263s sys, "argv", ["lavacli", "device-types", "health-check", "set", "mydt"] 263s ) 263s monkeypatch.setattr( 263s xmlrpc.client.ServerProxy, 263s "data", 263s [ 263s {"request": "system.version", "args": (), "ret": version}, 263s {"request": None, "args": (), "ret": []}, 263s ], 263s ) 263s with pytest.raises(SystemExit): 263s main() 263s > assert ( # nosec 263s capsys.readouterr()[1] 263s == """usage: lavacli device-types [-h] {add,aliases,list,show,template,update} ... 263s lavacli device-types: error: argument sub_sub_command: invalid choice: 'health-check' (choose from 'add', 'aliases', 'list', 'show', 'template', 'update') 263s """ 263s ) 263s E assert 'usage: lavac...te, update)\n' == "usage: lavac..., 'update')\n" 263s E 263s E Skipping 166 identical leading characters in diff, use -v to show 263s E - oose from 'add', 'aliases', 'list', 'show', 'template', 'update') 263s E ? - - - - - - - - - - - - 263s E + oose from add, aliases, list, show, template, update) 263s 263s tests/test_device_types.py:250: AssertionError 263s =============================== warnings summary =============================== 263s tests/test_events.py: 114 warnings 263s /usr/lib/python3/dist-packages/lavacli/commands/events.py:208: DeprecationWarning: zmq.utils.strtypes is deprecated in pyzmq 23. 263s (topic, _, dt, username, data) = (u(m) for m in msg) 263s 263s tests/test_events.py::test_events_wait_device 263s tests/test_events.py::test_events_wait_device_state 263s tests/test_events.py::test_events_wait_device_health 263s tests/test_events.py::test_events_wait_job 263s tests/test_events.py::test_events_wait_worker 263s tests/test_events.py::test_events_wait_worker_invalid_message 263s /usr/lib/python3/dist-packages/lavacli/commands/events.py:268: DeprecationWarning: zmq.utils.strtypes is deprecated in pyzmq 23. 263s sock.setsockopt(zmq.SUBSCRIBE, b(config.get("events", {}).get("topic", ""))) 263s 263s tests/test_events.py: 106 warnings 263s /usr/lib/python3/dist-packages/lavacli/commands/events.py:293: DeprecationWarning: zmq.utils.strtypes is deprecated in pyzmq 23. 263s (topic, _uuid, _dt, _username, data) = (u(m) for m in msg) 263s 263s tests/test_jobs.py::test_jobs_logs_failure_comment_and_polling 263s /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). 263s "dt": datetime.datetime.utcnow().isoformat(), 263s 263s tests/test_jobs.py::test_jobs_resubmit_follow 263s /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). 263s "dt": datetime.datetime.utcnow().isoformat(), 263s 263s tests/test_jobs.py::test_jobs_run 263s /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). 263s "dt": datetime.datetime.utcnow().isoformat(), 263s 263s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 263s =========================== short test summary info ============================ 263s FAILED tests/test_device_types.py::test_dt_hc_get_before_2018_4 - assert 'usa... 263s FAILED tests/test_device_types.py::test_dt_hc_set_before_2018_4 - assert 'usa... 263s ================= 2 failed, 197 passed, 229 warnings in 4.64s ================== 264s autopkgtest [21:50:26]: test testsuite: -----------------------] 268s testsuite FAIL non-zero exit status 1 268s autopkgtest [21:50:30]: test testsuite: - - - - - - - - - - results - - - - - - - - - - 272s autopkgtest [21:50:34]: @@@@@@@@@@@@@@@@@@@@ summary 272s testsuite FAIL non-zero exit status 1