0s autopkgtest [07:43:15]: starting date and time: 2025-02-19 07:43:15+0000 0s autopkgtest [07:43:15]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [07:43:15]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.litv651s/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-ppc64el-10.secgroup --name adt-plucky-ppc64el-py3dns-20250219-074315-juju-7f2275-prod-proposed-migration-environment-2-ff795d0c-f913-445e-b08c-8faa97f57f1d --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-ppc64el -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/ 120s autopkgtest [07:45:15]: testbed dpkg architecture: ppc64el 121s autopkgtest [07:45:16]: testbed apt version: 2.9.29 121s autopkgtest [07:45:16]: @@@@@@@@@@@@@@@@@@@@ test bed setup 121s autopkgtest [07:45:16]: testbed release detected to be: None 122s autopkgtest [07:45:17]: updating testbed package index (apt update) 123s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 123s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 123s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 123s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 123s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 123s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [73.3 kB] 123s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.6 kB] 123s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [734 kB] 123s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [91.9 kB] 123s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [760 B] 123s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [638 kB] 123s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [6324 B] 124s Fetched 1673 kB in 1s (1614 kB/s) 124s Reading package lists... 126s Reading package lists... 126s Building dependency tree... 126s Reading state information... 126s Calculating upgrade... 126s The following NEW packages will be installed: 126s libapt-pkg7.0 126s The following packages will be upgraded: 126s apt apt-utils dhcpcd-base rsyslog 126s 4 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 126s Need to get 3731 kB of archives. 126s After this operation, 3926 kB of additional disk space will be used. 126s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el libapt-pkg7.0 ppc64el 2.9.30 [1152 kB] 127s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el apt ppc64el 2.9.30 [1439 kB] 127s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el apt-utils ppc64el 2.9.30 [228 kB] 127s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el dhcpcd-base ppc64el 1:10.1.0-7 [280 kB] 127s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el rsyslog ppc64el 8.2412.0-2ubuntu1 [632 kB] 127s Fetched 3731 kB in 1s (4661 kB/s) 127s Selecting previously unselected package libapt-pkg7.0:ppc64el. 128s (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 ... 106322 files and directories currently installed.) 128s Preparing to unpack .../libapt-pkg7.0_2.9.30_ppc64el.deb ... 128s Unpacking libapt-pkg7.0:ppc64el (2.9.30) ... 128s Setting up libapt-pkg7.0:ppc64el (2.9.30) ... 128s (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 ... 106371 files and directories currently installed.) 128s Preparing to unpack .../apt_2.9.30_ppc64el.deb ... 128s Unpacking apt (2.9.30) over (2.9.29) ... 128s Setting up apt (2.9.30) ... 129s (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 ... 106371 files and directories currently installed.) 129s Preparing to unpack .../apt-utils_2.9.30_ppc64el.deb ... 129s Unpacking apt-utils (2.9.30) over (2.9.29) ... 129s Preparing to unpack .../dhcpcd-base_1%3a10.1.0-7_ppc64el.deb ... 129s Unpacking dhcpcd-base (1:10.1.0-7) over (1:10.1.0-6) ... 129s Preparing to unpack .../rsyslog_8.2412.0-2ubuntu1_ppc64el.deb ... 129s Unpacking rsyslog (8.2412.0-2ubuntu1) over (8.2412.0-1ubuntu1) ... 129s Setting up apt-utils (2.9.30) ... 129s Setting up rsyslog (8.2412.0-2ubuntu1) ... 129s info: The user `syslog' is already a member of `adm'. 130s Setting up dhcpcd-base (1:10.1.0-7) ... 130s Processing triggers for man-db (2.13.0-1) ... 132s Processing triggers for libc-bin (2.40-4ubuntu1) ... 133s Reading package lists... 133s Building dependency tree... 133s Reading state information... 133s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 133s autopkgtest [07:45:28]: upgrading testbed (apt dist-upgrade and autopurge) 133s Reading package lists... 134s Building dependency tree... 134s Reading state information... 134s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 134s Starting 2 pkgProblemResolver with broken count: 0 134s Done 134s Entering ResolveByKeep 134s 135s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 135s Reading package lists... 135s Building dependency tree... 135s Reading state information... 135s Starting pkgProblemResolver with broken count: 0 135s Starting 2 pkgProblemResolver with broken count: 0 135s Done 136s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 136s autopkgtest [07:45:31]: rebooting testbed after setup commands that affected boot 170s autopkgtest [07:46:05]: testbed running kernel: Linux 6.12.0-15-generic #15-Ubuntu SMP Tue Feb 4 16:32:08 UTC 2025 173s autopkgtest [07:46:08]: @@@@@@@@@@@@@@@@@@@@ apt-source py3dns 175s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/universe py3dns 4.0.2-3 (dsc) [2313 B] 175s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe py3dns 4.0.2-3 (tar) [34.0 kB] 175s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe py3dns 4.0.2-3 (asc) [833 B] 175s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe py3dns 4.0.2-3 (diff) [29.2 kB] 175s gpgv: Signature made Sun Jan 12 16:55:00 2025 UTC 175s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 175s gpgv: issuer "tchet@debian.org" 175s gpgv: Can't check signature: No public key 175s dpkg-source: warning: cannot verify inline signature for ./py3dns_4.0.2-3.dsc: no acceptable signature found 175s autopkgtest [07:46:10]: testing package py3dns version 4.0.2-3 175s autopkgtest [07:46:10]: build not needed 176s autopkgtest [07:46:11]: test py3: preparing testbed 176s Reading package lists... 176s Building dependency tree... 176s Reading state information... 176s Starting pkgProblemResolver with broken count: 0 176s Starting 2 pkgProblemResolver with broken count: 0 176s Done 177s The following NEW packages will be installed: 177s python3-all python3-dns python3.12 python3.12-minimal 177s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 177s Need to get 3170 kB of archives. 177s After this operation, 10.0 MB of additional disk space will be used. 177s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.12-minimal ppc64el 3.12.9-1 [2465 kB] 177s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3.12 ppc64el 3.12.9-1 [671 kB] 177s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-all ppc64el 3.13.1-1~exp2 [896 B] 177s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el python3-dns all 4.0.2-3 [33.2 kB] 178s Fetched 3170 kB in 1s (4351 kB/s) 178s Selecting previously unselected package python3.12-minimal. 178s (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 ... 106371 files and directories currently installed.) 178s Preparing to unpack .../python3.12-minimal_3.12.9-1_ppc64el.deb ... 178s Unpacking python3.12-minimal (3.12.9-1) ... 178s Selecting previously unselected package python3.12. 178s Preparing to unpack .../python3.12_3.12.9-1_ppc64el.deb ... 178s Unpacking python3.12 (3.12.9-1) ... 178s Selecting previously unselected package python3-all. 178s Preparing to unpack .../python3-all_3.13.1-1~exp2_ppc64el.deb ... 178s Unpacking python3-all (3.13.1-1~exp2) ... 178s Selecting previously unselected package python3-dns. 178s Preparing to unpack .../python3-dns_4.0.2-3_all.deb ... 178s Unpacking python3-dns (4.0.2-3) ... 178s Setting up python3.12-minimal (3.12.9-1) ... 179s Setting up python3.12 (3.12.9-1) ... 180s Setting up python3-all (3.13.1-1~exp2) ... 180s Setting up python3-dns (4.0.2-3) ... 181s Processing triggers for man-db (2.13.0-1) ... 181s Processing triggers for systemd (257.2-3ubuntu1) ... 183s autopkgtest [07:46:18]: test py3: [----------------------- 183s Testing with python3.12 183s .....................................................FFFF........EE. 183s ====================================================================== 183s ERROR: testNS (DNS.tests.test_base.TestBase.testNS) 183s Lookup NS record from SOA 183s ---------------------------------------------------------------------- 183s Traceback (most recent call last): 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/Base.py", line 274, in qry 183s self.sendUDPRequest(server) 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/Base.py", line 370, in sendUDPRequest 183s raise first_socket_error 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/Base.py", line 347, in sendUDPRequest 183s r=self.processUDPReply() 183s ^^^^^^^^^^^^^^^^^^^^^^ 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/Base.py", line 138, in processUDPReply 183s (self.reply, self.from_address) = self.s.recvfrom(65535) 183s ^^^^^^^^^^^^^^^^^^^^^^ 183s ConnectionRefusedError: [Errno 111] Connection refused 183s 183s During handling of the above exception, another exception occurred: 183s 183s Traceback (most recent call last): 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/tests/test_base.py", line 158, in testNS 183s resp = dnsob.qry(qtype='NS',server=primary,aa=1) 183s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/Base.py", line 278, in qry 183s raise SocketError(reason) 183s DNS.Base.SocketError: [Errno 111] Connection refused 183s 183s ====================================================================== 183s ERROR: testNSD (DNS.tests.test_base.TestBase.testNSD) 183s Lookup NS record from SOA 183s ---------------------------------------------------------------------- 183s Traceback (most recent call last): 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/Base.py", line 323, in req 183s self.sendUDPRequest(server) 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/Base.py", line 370, in sendUDPRequest 183s raise first_socket_error 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/Base.py", line 347, in sendUDPRequest 183s r=self.processUDPReply() 183s ^^^^^^^^^^^^^^^^^^^^^^ 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/Base.py", line 138, in processUDPReply 183s (self.reply, self.from_address) = self.s.recvfrom(65535) 183s ^^^^^^^^^^^^^^^^^^^^^^ 183s ConnectionRefusedError: [Errno 111] Connection refused 183s 183s During handling of the above exception, another exception occurred: 183s 183s Traceback (most recent call last): 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/tests/test_base.py", line 250, in testNSD 183s resp = dnsob.req(qtype='NS',server=primary,aa=1, timeout=1) 183s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/Base.py", line 327, in req 183s raise SocketError(reason) 183s DNS.Base.SocketError: [Errno 111] Connection refused 183s 183s ====================================================================== 183s FAIL: testDnsRequestA (DNS.tests.test_base.TestBase.testDnsRequestA) 183s ---------------------------------------------------------------------- 183s Traceback (most recent call last): 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/tests/test_base.py", line 39, in testDnsRequestA 183s self.assertEqual(a_response.answers[0]['data'],'93.184.215.14') 183s AssertionError: '96.7.128.186' != '93.184.215.14' 183s - 96.7.128.186 183s + 93.184.215.14 183s 183s 183s ====================================================================== 183s FAIL: testDnsRequestAAAA (DNS.tests.test_base.TestBase.testDnsRequestAAAA) 183s ---------------------------------------------------------------------- 183s Traceback (most recent call last): 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/tests/test_base.py", line 66, in testDnsRequestAAAA 183s self.assertEqual(aaaa_response.answers[0]['data'],'2606:2800:21f:cb07:6820:80da:af6b:8b2c') 183s AssertionError: '2600:1406:bc00:17::6007:810d' != '2606:2800:21f:cb07:6820:80da:af6b:8b2c' 183s - 2600:1406:bc00:17::6007:810d 183s + 2606:2800:21f:cb07:6820:80da:af6b:8b2c 183s 183s 183s ====================================================================== 183s FAIL: testDnsRequestAAAAD (DNS.tests.test_base.TestBase.testDnsRequestAAAAD) 183s ---------------------------------------------------------------------- 183s Traceback (most recent call last): 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/tests/test_base.py", line 185, in testDnsRequestAAAAD 183s self.assertEqual(aaaad_response.answers[0]['data'],b'&\x06(\x00\x02\x1f\xcb\x07h \x80\xda\xafk\x8b,') 183s AssertionError: b'&\x00\x14\x06\xbc\x00\x00\x17\x00\x00\x00\x00`\x07\x81\r' != b'&\x06(\x00\x02\x1f\xcb\x07h \x80\xda\xafk\x8b,' 183s 183s ====================================================================== 183s FAIL: testDnsRequestAD (DNS.tests.test_base.TestBase.testDnsRequestAD) 183s ---------------------------------------------------------------------- 183s Traceback (most recent call last): 183s File "/tmp/autopkgtest.mlg4XO/build.Ui8/src/DNS/tests/test_base.py", line 173, in testDnsRequestAD 183s self.assertEqual(ad_response.answers[0]['data'],'93.184.215.14') 183s AssertionError: '23.215.0.133' != '93.184.215.14' 183s - 23.215.0.133 183s + 93.184.215.14 183s 183s 183s ---------------------------------------------------------------------- 183s Ran 68 tests in 0.567s 183s 183s FAILED (failures=4, errors=2) 183s Test run failed. 184s autopkgtest [07:46:19]: test py3: -----------------------] 184s autopkgtest [07:46:19]: test py3: - - - - - - - - - - results - - - - - - - - - - 184s py3 FAIL non-zero exit status 1 185s autopkgtest [07:46:20]: @@@@@@@@@@@@@@@@@@@@ summary 185s py3 FAIL non-zero exit status 1 203s nova [W] Using flock in prodstack6-ppc64el 203s flock: timeout while waiting to get lock 203s Creating nova instance adt-plucky-ppc64el-py3dns-20250219-074315-juju-7f2275-prod-proposed-migration-environment-2-ff795d0c-f913-445e-b08c-8faa97f57f1d from image adt/ubuntu-plucky-ppc64el-server-20250218.img (UUID 9318aa34-3d3c-43c5-86d2-aaf9390f2f5d)... 203s nova [W] Timed out waiting for 7cb32ecc-d4fc-4ab3-966c-510841728c49 to get deleted.