0s autopkgtest [22:08:57]: starting date and time: 2025-05-03 22:08:57+0000
  0s autopkgtest [22:08:57]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production'
  0s autopkgtest [22:08:57]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.w1l8upd5/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:pytest --apt-upgrade aiocoap --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=pytest/8.3.5-2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-ppc64el-11.secgroup --name adt-questing-ppc64el-aiocoap-20250503-220857-juju-7f2275-prod-proposed-migration-environment-20-edd134fc-c47f-4b72-bb2f-736e4a85cff7 --image adt/ubuntu-questing-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/
 89s autopkgtest [22:10:26]: testbed dpkg architecture: ppc64el
 89s autopkgtest [22:10:26]: testbed apt version: 3.0.0
 89s autopkgtest [22:10:26]: @@@@@@@@@@@@@@@@@@@@ test bed setup
 90s autopkgtest [22:10:27]: testbed release detected to be: None
 90s autopkgtest [22:10:27]: updating testbed package index (apt update)
 91s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB]
 91s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease
 91s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease
 91s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease
 91s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [147 kB]
 91s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [33.6 kB]
 91s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [1412 kB]
 91s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el Packages [190 kB]
 91s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el Packages [1323 kB]
 92s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse ppc64el Packages [32.0 kB]
 92s Fetched 3248 kB in 1s (2231 kB/s)
 93s Reading package lists...
 93s autopkgtest [22:10:30]: upgrading testbed (apt dist-upgrade and autopurge)
 94s Reading package lists...
 94s Building dependency tree...
 94s Reading state information...
 94s Calculating upgrade...Starting pkgProblemResolver with broken count: 0
 94s Starting 2 pkgProblemResolver with broken count: 0
 94s Done
 95s Entering ResolveByKeep
 95s 
 95s Calculating upgrade...
 95s The following packages will be upgraded:
 95s   cloud-init cloud-init-base debianutils libmbim-glib4 libmbim-proxy
 95s   libnftnl11 libpython3.12-minimal libpython3.12-stdlib libpython3.12t64
 95s   libqmi-glib5 libqmi-proxy libsepol2 python3-lazr.restfulclient
 95s 13 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 95s Need to get 8004 kB of archives.
 95s After this operation, 78.8 kB disk space will be freed.
 95s Get:1 http://ftpmaster.internal/ubuntu questing/main ppc64el debianutils ppc64el 5.22 [93.9 kB]
 95s Get:2 http://ftpmaster.internal/ubuntu questing/main ppc64el libsepol2 ppc64el 3.8.1-1 [411 kB]
 95s Get:3 http://ftpmaster.internal/ubuntu questing/main ppc64el libnftnl11 ppc64el 1.2.9-1 [77.1 kB]
 95s Get:4 http://ftpmaster.internal/ubuntu questing/main ppc64el cloud-init-base all 25.2~1g7a0265d3-0ubuntu1 [619 kB]
 96s Get:5 http://ftpmaster.internal/ubuntu questing/main ppc64el libmbim-proxy ppc64el 1.32.0-1 [6352 B]
 96s Get:6 http://ftpmaster.internal/ubuntu questing/main ppc64el libmbim-glib4 ppc64el 1.32.0-1 [258 kB]
 96s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12t64 ppc64el 3.12.10-1 [2558 kB]
 96s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12-stdlib ppc64el 3.12.10-1 [2105 kB]
 96s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12-minimal ppc64el 3.12.10-1 [841 kB]
 96s Get:10 http://ftpmaster.internal/ubuntu questing/main ppc64el libqmi-proxy ppc64el 1.36.0-1 [6236 B]
 96s Get:11 http://ftpmaster.internal/ubuntu questing/main ppc64el libqmi-glib5 ppc64el 1.36.0-1 [976 kB]
 96s Get:12 http://ftpmaster.internal/ubuntu questing/main ppc64el python3-lazr.restfulclient all 0.14.6-3 [51.0 kB]
 96s Get:13 http://ftpmaster.internal/ubuntu questing/main ppc64el cloud-init all 25.2~1g7a0265d3-0ubuntu1 [2106 B]
 96s Preconfiguring packages ...
 96s Fetched 8004 kB in 1s (9419 kB/s)
 96s (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 ... 107205 files and directories currently installed.)
 96s Preparing to unpack .../debianutils_5.22_ppc64el.deb ...
 96s Unpacking debianutils (5.22) over (5.21) ...
 96s Setting up debianutils (5.22) ...
 97s (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 ... 107205 files and directories currently installed.)
 97s Preparing to unpack .../libsepol2_3.8.1-1_ppc64el.deb ...
 97s Unpacking libsepol2:ppc64el (3.8.1-1) over (3.7-1) ...
 97s Setting up libsepol2:ppc64el (3.8.1-1) ...
 97s (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 ... 107205 files and directories currently installed.)
 97s Preparing to unpack .../00-libnftnl11_1.2.9-1_ppc64el.deb ...
 97s Unpacking libnftnl11:ppc64el (1.2.9-1) over (1.2.8-1) ...
 97s Preparing to unpack .../01-cloud-init-base_25.2~1g7a0265d3-0ubuntu1_all.deb ...
 97s Unpacking cloud-init-base (25.2~1g7a0265d3-0ubuntu1) over (25.1.1-0ubuntu2) ...
 97s Preparing to unpack .../02-libmbim-proxy_1.32.0-1_ppc64el.deb ...
 97s Unpacking libmbim-proxy (1.32.0-1) over (1.31.2-0ubuntu4) ...
 97s Preparing to unpack .../03-libmbim-glib4_1.32.0-1_ppc64el.deb ...
 97s Unpacking libmbim-glib4:ppc64el (1.32.0-1) over (1.31.2-0ubuntu4) ...
 97s Preparing to unpack .../04-libpython3.12t64_3.12.10-1_ppc64el.deb ...
 97s Unpacking libpython3.12t64:ppc64el (3.12.10-1) over (3.12.8-3) ...
 97s Preparing to unpack .../05-libpython3.12-stdlib_3.12.10-1_ppc64el.deb ...
 97s Unpacking libpython3.12-stdlib:ppc64el (3.12.10-1) over (3.12.8-3) ...
 97s Preparing to unpack .../06-libpython3.12-minimal_3.12.10-1_ppc64el.deb ...
 97s Unpacking libpython3.12-minimal:ppc64el (3.12.10-1) over (3.12.8-3) ...
 97s Preparing to unpack .../07-libqmi-proxy_1.36.0-1_ppc64el.deb ...
 97s Unpacking libqmi-proxy (1.36.0-1) over (1.35.6-1) ...
 97s Preparing to unpack .../08-libqmi-glib5_1.36.0-1_ppc64el.deb ...
 97s Unpacking libqmi-glib5:ppc64el (1.36.0-1) over (1.35.6-1) ...
 98s Preparing to unpack .../09-python3-lazr.restfulclient_0.14.6-3_all.deb ...
 98s Unpacking python3-lazr.restfulclient (0.14.6-3) over (0.14.6-2) ...
 98s Preparing to unpack .../10-cloud-init_25.2~1g7a0265d3-0ubuntu1_all.deb ...
 98s Unpacking cloud-init (25.2~1g7a0265d3-0ubuntu1) over (25.1.1-0ubuntu2) ...
 98s Setting up libpython3.12-minimal:ppc64el (3.12.10-1) ...
 98s Setting up libnftnl11:ppc64el (1.2.9-1) ...
 98s Setting up cloud-init-base (25.2~1g7a0265d3-0ubuntu1) ...
 99s Setting up python3-lazr.restfulclient (0.14.6-3) ...
 99s Setting up libmbim-glib4:ppc64el (1.32.0-1) ...
 99s Setting up cloud-init (25.2~1g7a0265d3-0ubuntu1) ...
 99s Setting up libpython3.12-stdlib:ppc64el (3.12.10-1) ...
 99s Setting up libmbim-proxy (1.32.0-1) ...
 99s Setting up libpython3.12t64:ppc64el (3.12.10-1) ...
 99s Setting up libqmi-glib5:ppc64el (1.36.0-1) ...
 99s Setting up libqmi-proxy (1.36.0-1) ...
 99s Processing triggers for libc-bin (2.41-6ubuntu1) ...
 99s Processing triggers for rsyslog (8.2412.0-2ubuntu2) ...
100s Processing triggers for man-db (2.13.0-1) ...
101s Reading package lists...
101s Building dependency tree...
101s Reading state information...
102s Starting pkgProblemResolver with broken count: 0
102s Starting 2 pkgProblemResolver with broken count: 0
102s Done
102s Solving dependencies...
102s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
102s autopkgtest [22:10:39]: rebooting testbed after setup commands that affected boot
136s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds...
144s autopkgtest [22:11:21]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr  6 14:52:42 UTC 2025
148s autopkgtest [22:11:25]: @@@@@@@@@@@@@@@@@@@@ apt-source aiocoap
151s Get:1 http://ftpmaster.internal/ubuntu questing/universe aiocoap 0.4.12-4 (dsc) [1512 B]
151s Get:2 http://ftpmaster.internal/ubuntu questing/universe aiocoap 0.4.12-4 (tar) [309 kB]
151s Get:3 http://ftpmaster.internal/ubuntu questing/universe aiocoap 0.4.12-4 (diff) [5884 B]
151s gpgv: Signature made Sun Feb  9 11:21:36 2025 UTC
151s gpgv:                using DSA key BAF6C64436107850D4227106B3255C6D55878D8C
151s gpgv:                issuer "mazen@debian.org"
151s gpgv: Can't check signature: No public key
151s dpkg-source: warning: cannot verify inline signature for ./aiocoap_0.4.12-4.dsc: no acceptable signature found
151s autopkgtest [22:11:28]: testing package aiocoap version 0.4.12-4
151s autopkgtest [22:11:28]: build not needed
152s autopkgtest [22:11:29]: test unittest: preparing testbed
152s Reading package lists...
152s Building dependency tree...
152s Reading state information...
152s Starting pkgProblemResolver with broken count: 0
152s Starting 2 pkgProblemResolver with broken count: 0
152s Done
152s The following NEW packages will be installed:
152s   python3-aiocoap python3-all python3-iniconfig python3-packaging
152s   python3-pluggy python3-pytest
153s 0 upgraded, 6 newly installed, 0 to remove and 0 not upgraded.
153s Need to get 505 kB of archives.
153s After this operation, 2458 kB of additional disk space will be used.
153s Get:1 http://ftpmaster.internal/ubuntu questing/universe ppc64el python3-aiocoap all 0.4.12-4 [173 kB]
153s Get:2 http://ftpmaster.internal/ubuntu questing/main ppc64el python3-all ppc64el 3.13.3-1 [880 B]
153s Get:3 http://ftpmaster.internal/ubuntu questing/universe ppc64el python3-iniconfig all 1.1.1-2 [6024 B]
153s Get:4 http://ftpmaster.internal/ubuntu questing/main ppc64el python3-packaging all 24.2-1 [51.5 kB]
153s Get:5 http://ftpmaster.internal/ubuntu questing/universe ppc64el python3-pluggy all 1.5.0-1 [21.0 kB]
153s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el python3-pytest all 8.3.5-2 [252 kB]
153s Fetched 505 kB in 0s (1050 kB/s)
153s Selecting previously unselected package python3-aiocoap.
153s (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 ... 107205 files and directories currently installed.)
153s Preparing to unpack .../0-python3-aiocoap_0.4.12-4_all.deb ...
153s Unpacking python3-aiocoap (0.4.12-4) ...
154s Selecting previously unselected package python3-all.
154s Preparing to unpack .../1-python3-all_3.13.3-1_ppc64el.deb ...
154s Unpacking python3-all (3.13.3-1) ...
154s Selecting previously unselected package python3-iniconfig.
154s Preparing to unpack .../2-python3-iniconfig_1.1.1-2_all.deb ...
154s Unpacking python3-iniconfig (1.1.1-2) ...
154s Selecting previously unselected package python3-packaging.
154s Preparing to unpack .../3-python3-packaging_24.2-1_all.deb ...
154s Unpacking python3-packaging (24.2-1) ...
154s Selecting previously unselected package python3-pluggy.
154s Preparing to unpack .../4-python3-pluggy_1.5.0-1_all.deb ...
154s Unpacking python3-pluggy (1.5.0-1) ...
154s Selecting previously unselected package python3-pytest.
154s Preparing to unpack .../5-python3-pytest_8.3.5-2_all.deb ...
154s Unpacking python3-pytest (8.3.5-2) ...
154s Setting up python3-iniconfig (1.1.1-2) ...
154s Setting up python3-all (3.13.3-1) ...
154s Setting up python3-aiocoap (0.4.12-4) ...
154s Setting up python3-packaging (24.2-1) ...
154s Setting up python3-pluggy (1.5.0-1) ...
154s Setting up python3-pytest (8.3.5-2) ...
155s Processing triggers for man-db (2.13.0-1) ...
156s autopkgtest [22:11:33]: test unittest: [-----------------------
156s === python3.13 ===
157s Python version: 3.13.3 (main, Apr  8 2025, 19:55:40) [GCC 14.2.0]
157s aiocoap version: 0.4.12
157s Modules missing for subsystems:
157s     dtls: missing DTLSSocket
157s     oscore: missing cbor2, filelock, ge25519, lakers-python
157s     linkheader: everything there
157s     prettyprint: missing cbor2, cbor-diag
157s     ws: missing websockets
157s Python platform: linux
157s Default server transports:  tcpserver:tcpclient:tlsserver:tlsclient:udp6
157s Selected server transports: tcpserver:tcpclient:tlsserver:tlsclient:udp6
157s Default client transports:  tcpclient:tlsclient:udp6
157s Selected client transports: tcpclient:tlsclient:udp6
157s SO_REUSEPORT available (default, selected): True, True
157s ============================= test session starts ==============================
157s platform linux -- Python 3.13.3, pytest-8.3.5, pluggy-1.5.0
157s rootdir: /tmp/autopkgtest.UmbT5S/autopkgtest_tmp
157s plugins: typeguard-4.4.2
157s collected 219 items
157s 
158s tests/test_blockwise.py ..s.                                             [  1%]
160s tests/test_client.py ........                                            [  5%]
163s tests/test_commandline.py ......                                         [  8%]
163s tests/test_credentials.py ..s                                            [  9%]
163s tests/test_dtls.py sssssssssssss                                         [ 15%]
163s tests/test_edhoc.py ssss                                                 [ 17%]
163s tests/test_encoding.py ...........                                       [ 22%]
164s tests/test_fileserver.py .                                               [ 22%]
164s tests/test_group_oscore.py ssssssssssssssssssssssssssss                  [ 35%]
167s tests/test_noncoap_client.py ........ss                                  [ 40%]
169s tests/test_noncoap_tcp_client.py ...........                             [ 45%]
173s tests/test_observe.py .......x....                                       [ 50%]
173s tests/test_oscore.py sssssssssssssssssssssss                             [ 61%]
173s tests/test_oscore_plugtest.py ssssssssssssssssssssssssssssssss           [ 75%]
173s tests/test_protocol.py ...                                               [ 77%]
173s tests/test_rd_examples.py ..                                             [ 78%]
174s tests/test_reverseproxy.py ..                                            [ 78%]
184s tests/test_server.py ..........................sssssssssssss             [ 96%]
184s tests/test_timeoutdict.py .                                              [ 97%]
185s tests/test_tls.py .                                                      [ 97%]
185s tests/test_uri_handling.py ....                                          [ 99%]
185s tests/test_util_cryptography.py s                                        [100%]
185s 
185s ================= 100 passed, 118 skipped, 1 xfailed in 27.85s =================
185s autopkgtest [22:12:02]: test unittest: -----------------------]
186s unittest             PASS
186s autopkgtest [22:12:03]: test unittest:  - - - - - - - - - - results - - - - - - - - - -
186s autopkgtest [22:12:03]: @@@@@@@@@@@@@@@@@@@@ summary
186s unittest             PASS
191s nova [W] Using flock in prodstack6-ppc64el
191s Creating nova instance adt-questing-ppc64el-aiocoap-20250503-220857-juju-7f2275-prod-proposed-migration-environment-20-edd134fc-c47f-4b72-bb2f-736e4a85cff7 from image adt/ubuntu-questing-ppc64el-server-20250503.img (UUID 1e63be20-33d6-474d-9c56-b247a2188377)...
191s nova [W] Timed out waiting for 39685992-4f27-438d-9f33-2304afb093d0 to get deleted.