1s autopkgtest [14:34:06]: starting date and time: 2025-01-17 14:34:06+0000 1s autopkgtest [14:34:06]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 1s autopkgtest [14:34:06]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.xz7j2d99/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:py3dns --apt-upgrade py3dns --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=py3dns/4.0.2-3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-5.secgroup --name adt-plucky-s390x-py3dns-20250117-143405-juju-7f2275-prod-proposed-migration-environment-15-148517af-8ccc-410e-b10f-c49a8ce01af4 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --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,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 105s autopkgtest [14:35:50]: testbed dpkg architecture: s390x 106s autopkgtest [14:35:51]: testbed apt version: 2.9.18 106s autopkgtest [14:35:51]: @@@@@@@@@@@@@@@@@@@@ test bed setup 106s autopkgtest [14:35:51]: testbed release detected to be: None 107s autopkgtest [14:35:52]: updating testbed package index (apt update) 107s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 107s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 108s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 108s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 108s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 108s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.7 kB] 108s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [877 kB] 108s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [177 kB] 108s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [289 kB] 108s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [756 B] 108s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [974 kB] 108s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [5408 B] 108s Fetched 2423 kB in 1s (2422 kB/s) 109s Reading package lists... 109s Reading package lists... 110s Building dependency tree... 110s Reading state information... 110s Calculating upgrade... 110s The following packages will be upgraded: 110s usb.ids 110s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 110s Need to get 223 kB of archives. 110s After this operation, 0 B of additional disk space will be used. 110s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x usb.ids all 2025.01.14-1 [223 kB] 111s Fetched 223 kB in 0s (482 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 ... 55658 files and directories currently installed.) 111s Preparing to unpack .../usb.ids_2025.01.14-1_all.deb ... 111s Unpacking usb.ids (2025.01.14-1) over (2024.12.04-1) ... 111s Setting up usb.ids (2025.01.14-1) ... 111s Reading package lists... 111s Building dependency tree... 111s Reading state information... 111s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 111s autopkgtest [14:35:56]: upgrading testbed (apt dist-upgrade and autopurge) 111s Reading package lists... 112s Building dependency tree... 112s Reading state information... 112s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 112s Starting 2 pkgProblemResolver with broken count: 0 112s Done 112s Entering ResolveByKeep 112s 112s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 112s Reading package lists... 113s Building dependency tree... 113s Reading state information... 113s Starting pkgProblemResolver with broken count: 0 113s Starting 2 pkgProblemResolver with broken count: 0 113s Done 113s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s autopkgtest [14:36:00]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 116s autopkgtest [14:36:01]: @@@@@@@@@@@@@@@@@@@@ apt-source py3dns 117s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/universe py3dns 4.0.2-3 (dsc) [2313 B] 117s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe py3dns 4.0.2-3 (tar) [34.0 kB] 117s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe py3dns 4.0.2-3 (asc) [833 B] 117s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe py3dns 4.0.2-3 (diff) [29.2 kB] 117s gpgv: Signature made Sun Jan 12 16:55:00 2025 UTC 117s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 117s gpgv: issuer "tchet@debian.org" 117s gpgv: Can't check signature: No public key 117s dpkg-source: warning: cannot verify inline signature for ./py3dns_4.0.2-3.dsc: no acceptable signature found 117s autopkgtest [14:36:02]: testing package py3dns version 4.0.2-3 118s autopkgtest [14:36:03]: build not needed 118s autopkgtest [14:36:03]: test py3: preparing testbed 118s Reading package lists... 118s Building dependency tree... 118s Reading state information... 119s Starting pkgProblemResolver with broken count: 0 119s Starting 2 pkgProblemResolver with broken count: 0 119s Done 119s The following NEW packages will be installed: 119s libpython3.13-minimal libpython3.13-stdlib python3-all python3-dns 119s python3.13 python3.13-minimal 119s 0 upgraded, 6 newly installed, 0 to remove and 0 not upgraded. 119s Need to get 6081 kB of archives. 119s After this operation, 22.9 MB of additional disk space will be used. 119s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-minimal s390x 3.13.1-2 [880 kB] 120s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-minimal s390x 3.13.1-2 [2364 kB] 121s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-stdlib s390x 3.13.1-2 [2074 kB] 122s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13 s390x 3.13.1-2 [729 kB] 122s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x python3-all s390x 3.12.8-1 [894 B] 122s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x python3-dns all 4.0.2-3 [33.2 kB] 122s Fetched 6081 kB in 3s (1843 kB/s) 122s Selecting previously unselected package libpython3.13-minimal:s390x. 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 ... 55658 files and directories currently installed.) 122s Preparing to unpack .../0-libpython3.13-minimal_3.13.1-2_s390x.deb ... 122s Unpacking libpython3.13-minimal:s390x (3.13.1-2) ... 122s Selecting previously unselected package python3.13-minimal. 122s Preparing to unpack .../1-python3.13-minimal_3.13.1-2_s390x.deb ... 122s Unpacking python3.13-minimal (3.13.1-2) ... 123s Selecting previously unselected package libpython3.13-stdlib:s390x. 123s Preparing to unpack .../2-libpython3.13-stdlib_3.13.1-2_s390x.deb ... 123s Unpacking libpython3.13-stdlib:s390x (3.13.1-2) ... 123s Selecting previously unselected package python3.13. 123s Preparing to unpack .../3-python3.13_3.13.1-2_s390x.deb ... 123s Unpacking python3.13 (3.13.1-2) ... 123s Selecting previously unselected package python3-all. 123s Preparing to unpack .../4-python3-all_3.12.8-1_s390x.deb ... 123s Unpacking python3-all (3.12.8-1) ... 123s Selecting previously unselected package python3-dns. 123s Preparing to unpack .../5-python3-dns_4.0.2-3_all.deb ... 123s Unpacking python3-dns (4.0.2-3) ... 123s Setting up libpython3.13-minimal:s390x (3.13.1-2) ... 123s Setting up python3-dns (4.0.2-3) ... 123s Setting up python3.13-minimal (3.13.1-2) ... 123s Setting up libpython3.13-stdlib:s390x (3.13.1-2) ... 123s Setting up python3.13 (3.13.1-2) ... 124s Setting up python3-all (3.12.8-1) ... 124s Processing triggers for man-db (2.13.0-1) ... 125s Processing triggers for systemd (257-2ubuntu1) ... 126s autopkgtest [14:36:11]: test py3: [----------------------- 126s Testing with python3.13 126s .....................................................FFFF........EE. 126s ====================================================================== 126s ERROR: testNS (DNS.tests.test_base.TestBase.testNS) 126s Lookup NS record from SOA 126s ---------------------------------------------------------------------- 126s Traceback (most recent call last): 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/Base.py", line 274, in qry 126s self.sendUDPRequest(server) 126s ~~~~~~~~~~~~~~~~~~~^^^^^^^^ 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/Base.py", line 370, in sendUDPRequest 126s raise first_socket_error 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/Base.py", line 347, in sendUDPRequest 126s r=self.processUDPReply() 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/Base.py", line 138, in processUDPReply 126s (self.reply, self.from_address) = self.s.recvfrom(65535) 126s ~~~~~~~~~~~~~~~^^^^^^^ 126s ConnectionRefusedError: [Errno 111] Connection refused 126s 126s During handling of the above exception, another exception occurred: 126s 126s Traceback (most recent call last): 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/tests/test_base.py", line 158, in testNS 126s resp = dnsob.qry(qtype='NS',server=primary,aa=1) 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/Base.py", line 278, in qry 126s raise SocketError(reason) 126s DNS.Base.SocketError: [Errno 111] Connection refused 126s 126s ====================================================================== 126s ERROR: testNSD (DNS.tests.test_base.TestBase.testNSD) 126s Lookup NS record from SOA 126s ---------------------------------------------------------------------- 126s Traceback (most recent call last): 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/Base.py", line 323, in req 126s self.sendUDPRequest(server) 126s ~~~~~~~~~~~~~~~~~~~^^^^^^^^ 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/Base.py", line 370, in sendUDPRequest 126s raise first_socket_error 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/Base.py", line 347, in sendUDPRequest 126s r=self.processUDPReply() 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/Base.py", line 138, in processUDPReply 126s (self.reply, self.from_address) = self.s.recvfrom(65535) 126s ~~~~~~~~~~~~~~~^^^^^^^ 126s ConnectionRefusedError: [Errno 111] Connection refused 126s 126s During handling of the above exception, another exception occurred: 126s 126s Traceback (most recent call last): 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/tests/test_base.py", line 250, in testNSD 126s resp = dnsob.req(qtype='NS',server=primary,aa=1, timeout=1) 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/Base.py", line 327, in req 126s raise SocketError(reason) 126s DNS.Base.SocketError: [Errno 111] Connection refused 126s 126s ====================================================================== 126s FAIL: testDnsRequestA (DNS.tests.test_base.TestBase.testDnsRequestA) 126s ---------------------------------------------------------------------- 126s Traceback (most recent call last): 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/tests/test_base.py", line 39, in testDnsRequestA 126s self.assertEqual(a_response.answers[0]['data'],'93.184.215.14') 126s ~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 126s AssertionError: '23.215.0.132' != '93.184.215.14' 126s - 23.215.0.132 126s + 93.184.215.14 126s 126s 126s ====================================================================== 126s FAIL: testDnsRequestAAAA (DNS.tests.test_base.TestBase.testDnsRequestAAAA) 126s ---------------------------------------------------------------------- 126s Traceback (most recent call last): 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/tests/test_base.py", line 66, in testDnsRequestAAAA 126s self.assertEqual(aaaa_response.answers[0]['data'],'2606:2800:21f:cb07:6820:80da:af6b:8b2c') 126s ~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 126s AssertionError: '2600:1406:bc00:17::6007:810d' != '2606:2800:21f:cb07:6820:80da:af6b:8b2c' 126s - 2600:1406:bc00:17::6007:810d 126s + 2606:2800:21f:cb07:6820:80da:af6b:8b2c 126s 126s 126s ====================================================================== 126s FAIL: testDnsRequestAAAAD (DNS.tests.test_base.TestBase.testDnsRequestAAAAD) 126s ---------------------------------------------------------------------- 126s Traceback (most recent call last): 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/tests/test_base.py", line 185, in testDnsRequestAAAAD 126s self.assertEqual(aaaad_response.answers[0]['data'],b'&\x06(\x00\x02\x1f\xcb\x07h \x80\xda\xafk\x8b,') 126s ~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 126s AssertionError: b'&\x00\x14\x06\xbc\x00\x00\x17\x00\x00\x00\x00`\x07\x81(' != b'&\x06(\x00\x02\x1f\xcb\x07h \x80\xda\xafk\x8b,' 126s 126s ====================================================================== 126s FAIL: testDnsRequestAD (DNS.tests.test_base.TestBase.testDnsRequestAD) 126s ---------------------------------------------------------------------- 126s Traceback (most recent call last): 126s File "/tmp/autopkgtest.gKMr7c/build.ZOt/src/DNS/tests/test_base.py", line 173, in testDnsRequestAD 126s self.assertEqual(ad_response.answers[0]['data'],'93.184.215.14') 126s ~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 126s AssertionError: '96.7.128.186' != '93.184.215.14' 126s - 96.7.128.186 126s + 93.184.215.14 126s 126s 126s ---------------------------------------------------------------------- 126s Ran 68 tests in 0.310s 126s 126s FAILED (failures=4, errors=2) 126s Test run failed. 127s autopkgtest [14:36:12]: test py3: -----------------------] 127s autopkgtest [14:36:12]: test py3: - - - - - - - - - - results - - - - - - - - - - 127s py3 FAIL non-zero exit status 1 127s autopkgtest [14:36:12]: @@@@@@@@@@@@@@@@@@@@ summary 127s py3 FAIL non-zero exit status 1 132s nova [W] Using flock in prodstack6-s390x 132s flock: timeout while waiting to get lock 132s Creating nova instance adt-plucky-s390x-py3dns-20250117-143405-juju-7f2275-prod-proposed-migration-environment-15-148517af-8ccc-410e-b10f-c49a8ce01af4 from image adt/ubuntu-plucky-s390x-server-20250117.img (UUID 77043c30-ce55-43c6-ae27-2590a56e9de9)... 132s nova [W] Timed out waiting for 0e82c969-2418-4c45-b039-2109801e0965 to get deleted.