0s autopkgtest [14:45:21]: starting date and time: 2025-06-19 14:45:21+0000 0s autopkgtest [14:45:21]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [14:45:21]: host juju-7f2275-prod-proposed-migration-environment-21; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.dj5h1_pf/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:requests --apt-upgrade python-stubserver --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=requests/2.32.3+dfsg-5ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-21@sto01-ppc64el-21.secgroup --name adt-questing-ppc64el-python-stubserver-20250619-144521-juju-7f2275-prod-proposed-migration-environment-21-5c3f9310-0829-4143-bea1-a147da177fbe --image adt/ubuntu-questing-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-21 --net-id=net_prod-autopkgtest-workers-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 67s autopkgtest [14:46:28]: testbed dpkg architecture: ppc64el 67s autopkgtest [14:46:28]: testbed apt version: 3.1.2 67s autopkgtest [14:46:28]: @@@@@@@@@@@@@@@@@@@@ test bed setup 67s autopkgtest [14:46:28]: testbed release detected to be: None 68s autopkgtest [14:46:29]: updating testbed package index (apt update) 68s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 68s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 68s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 68s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 68s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [17.4 kB] 68s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/restricted Sources [4716 B] 68s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [38.3 kB] 68s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [426 kB] 68s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el Packages [66.7 kB] 68s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/restricted ppc64el Packages [724 B] 68s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el Packages [340 kB] 68s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse ppc64el Packages [6448 B] 68s Fetched 1149 kB in 0s (2413 kB/s) 69s Reading package lists... 70s autopkgtest [14:46:31]: upgrading testbed (apt dist-upgrade and autopurge) 70s Reading package lists... 70s Building dependency tree... 70s Reading state information... 70s Calculating upgrade... 70s The following packages will be upgraded: 70s python3-requests 70s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 70s Need to get 53.1 kB of archives. 70s After this operation, 0 B of additional disk space will be used. 70s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el python3-requests all 2.32.3+dfsg-5ubuntu2 [53.1 kB] 71s Fetched 53.1 kB in 0s (2893 kB/s) 71s (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 ... 79652 files and directories currently installed.) 71s Preparing to unpack .../python3-requests_2.32.3+dfsg-5ubuntu2_all.deb ... 71s Unpacking python3-requests (2.32.3+dfsg-5ubuntu2) over (2.32.3+dfsg-5ubuntu1) ... 71s Setting up python3-requests (2.32.3+dfsg-5ubuntu2) ... 72s Reading package lists... 72s Building dependency tree... 72s Reading state information... 72s Solving dependencies... 72s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 74s autopkgtest [14:46:35]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 14:52:42 UTC 2025 74s autopkgtest [14:46:35]: @@@@@@@@@@@@@@@@@@@@ apt-source python-stubserver 75s Get:1 http://ftpmaster.internal/ubuntu questing/universe python-stubserver 1.1-4 (dsc) [2185 B] 75s Get:2 http://ftpmaster.internal/ubuntu questing/universe python-stubserver 1.1-4 (tar) [5895 B] 75s Get:3 http://ftpmaster.internal/ubuntu questing/universe python-stubserver 1.1-4 (diff) [5984 B] 76s gpgv: Signature made Sun Dec 4 21:20:09 2022 UTC 76s gpgv: using RSA key 77C9619C49D6A2CDCDF2FFAA428304A1748DCC7A 76s gpgv: Can't check signature: No public key 76s dpkg-source: warning: cannot verify inline signature for ./python-stubserver_1.1-4.dsc: no acceptable signature found 76s autopkgtest [14:46:37]: testing package python-stubserver version 1.1-4 76s autopkgtest [14:46:37]: build not needed 76s autopkgtest [14:46:37]: test run-unit-test: preparing testbed 76s Reading package lists... 76s Building dependency tree... 76s Reading state information... 76s Solving dependencies... 77s The following NEW packages will be installed: 77s python3-all python3-stubserver 77s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 77s Need to get 8696 B of archives. 77s After this operation, 42.0 kB of additional disk space will be used. 77s Get:1 http://ftpmaster.internal/ubuntu questing/main ppc64el python3-all ppc64el 3.13.4-1 [880 B] 77s Get:2 http://ftpmaster.internal/ubuntu questing/universe ppc64el python3-stubserver all 1.1-4 [7816 B] 77s Fetched 8696 B in 0s (665 kB/s) 77s Selecting previously unselected package python3-all. 77s (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 ... 79652 files and directories currently installed.) 77s Preparing to unpack .../python3-all_3.13.4-1_ppc64el.deb ... 77s Unpacking python3-all (3.13.4-1) ... 77s Selecting previously unselected package python3-stubserver. 77s Preparing to unpack .../python3-stubserver_1.1-4_all.deb ... 77s Unpacking python3-stubserver (1.1-4) ... 77s Setting up python3-all (3.13.4-1) ... 77s Setting up python3-stubserver (1.1-4) ... 78s autopkgtest [14:46:39]: test run-unit-test: [----------------------- 78s ************************************************************************** 78s # A new feature in cloud-init identified possible datasources for # 78s # this system as: # 78s # [] # 78s # However, the datasource used was: OpenStack # 78s # # 78s # In the future, cloud-init will only attempt to use datasources that # 78s # are identified or specifically configured. # 78s # For more information see # 78s # https://bugs.launchpad.net/bugs/1669675 # 78s # # 78s # If you are seeing this message, please file a bug against # 78s # cloud-init at # 78s # https://github.com/canonical/cloud-init/issues # 78s # Make sure to include the cloud provider your instance is # 78s # running on. # 78s # # 78s # After you have filed a bug, you can disable this warning by launching # 78s # your instance with the cloud-config below, or putting that content # 78s # into /etc/cloud/cloud.cfg.d/99-warnings.cfg # 78s # # 78s # #cloud-config # 78s # warnings: # 78s # dsid_missing_source: off # 78s ************************************************************************** 78s 78s Disable the warnings above by: 78s touch /home/ubuntu/.cloud-warnings.skip 78s or 78s touch /var/lib/cloud/instance/warnings/.skip 78s 'test.py' -> '/tmp/autopkgtest.SinW1O/autopkgtest_tmp/test.py' 78s Running testsuite with python3.13: 78s /tmp/autopkgtest.SinW1O/autopkgtest_tmp/test.py:42: SyntaxWarning: invalid escape sequence '\d' 78s self.server.expect(method="GET", url="/address/\d+$").and_return(mime_type="text/xml", file_content=data_file) 78s /tmp/autopkgtest.SinW1O/autopkgtest_tmp/test.py:54: SyntaxWarning: invalid escape sequence '\d' 78s self.server.expect(method="PUT", url="/address/\d+$", data_capture=capture).and_return(reply_code=201) 78s /tmp/autopkgtest.SinW1O/autopkgtest_tmp/test.py:92: SyntaxWarning: invalid escape sequence '\d' 78s self.server.expect(method="POST", url="address/\d+/inhabitant", data='<inhabitant name="Chris"/>').and_return(reply_code=204) 78s /tmp/autopkgtest.SinW1O/autopkgtest_tmp/test.py:97: SyntaxWarning: invalid escape sequence '\d' 78s self.server.expect(method="POST", url="address/\d+/inhabitant", data='Twas brillig and the slithy toves').and_return(reply_code=204) 78s /tmp/autopkgtest.SinW1O/autopkgtest_tmp/test.py:126: SyntaxWarning: invalid escape sequence '\d' 78s self.server.expect(method="POST", url="address/\d+/inhabitant", data='<inhabitant name="Chris"/>').and_return( 87s .....................EEEE. 87s ====================================================================== 87s ERROR: test_returns_additional_headers_for_expectation_with_data (__main__.WebTest.test_returns_additional_headers_for_expectation_with_data) 87s ---------------------------------------------------------------------- 87s Traceback (most recent call last): 87s File "/tmp/autopkgtest.SinW1O/autopkgtest_tmp/test.py", line 185, in test_returns_additional_headers_for_expectation_with_data 87s self.assertEqual(r.headers["some_header"], "foo") 87s ~~~~~~~~~^^^^^^^^^^^^^^^ 87s File "/usr/lib/python3/dist-packages/requests/structures.py", line 52, in __getitem__ 87s return self._store[key.lower()][1] 87s ~~~~~~~~~~~^^^^^^^^^^^^^ 87s KeyError: 'some_header' 87s 87s ====================================================================== 87s ERROR: test_returns_additional_headers_for_expectation_with_data (__main__.WebTest.test_returns_additional_headers_for_expectation_with_data) 87s ---------------------------------------------------------------------- 87s Traceback (most recent call last): 87s File "/tmp/autopkgtest.SinW1O/autopkgtest_tmp/test.py", line 22, in tearDown 87s self.server.stop() 87s ~~~~~~~~~~~~~~~~^^ 87s File "/usr/lib/python3/dist-packages/stubserver/webserver.py", line 67, in stop 87s self.verify() 87s ~~~~~~~~~~~^^ 87s File "/usr/lib/python3/dist-packages/stubserver/webserver.py", line 87, in verify 87s raise Exception("Unsatisfied expectations: " + "\n".join(failures)) 87s Exception: Unsatisfied expectations: POST /api/endpoint 87s data_capture: {} 87s 87s 87s ====================================================================== 87s ERROR: test_returns_additional_headers_for_expectation_without_data (__main__.WebTest.test_returns_additional_headers_for_expectation_without_data) 87s ---------------------------------------------------------------------- 87s Traceback (most recent call last): 87s File "/tmp/autopkgtest.SinW1O/autopkgtest_tmp/test.py", line 176, in test_returns_additional_headers_for_expectation_without_data 87s self.assertEqual(r.headers["some_header"], "foo") 87s ~~~~~~~~~^^^^^^^^^^^^^^^ 87s File "/usr/lib/python3/dist-packages/requests/structures.py", line 52, in __getitem__ 87s return self._store[key.lower()][1] 87s ~~~~~~~~~~~^^^^^^^^^^^^^ 87s KeyError: 'some_header' 87s 87s ====================================================================== 87s ERROR: test_returns_additional_headers_for_expectation_without_data (__main__.WebTest.test_returns_additional_headers_for_expectation_without_data) 87s ---------------------------------------------------------------------- 87s Traceback (most recent call last): 87s File "/tmp/autopkgtest.SinW1O/autopkgtest_tmp/test.py", line 22, in tearDown 87s self.server.stop() 87s ~~~~~~~~~~~~~~~~^^ 87s File "/usr/lib/python3/dist-packages/stubserver/webserver.py", line 67, in stop 87s self.verify() 87s ~~~~~~~~~~~^^ 87s File "/usr/lib/python3/dist-packages/stubserver/webserver.py", line 87, in verify 87s raise Exception("Unsatisfied expectations: " + "\n".join(failures)) 87s Exception: Unsatisfied expectations: GET /api/endpoint 87s data_capture: {} 87s 87s 87s ---------------------------------------------------------------------- 87s Ran 24 tests in 9.077s 87s 87s FAILED (errors=4) 87s autopkgtest [14:46:48]: test run-unit-test: -----------------------] 88s autopkgtest [14:46:49]: test run-unit-test: - - - - - - - - - - results - - - - - - - - - - 88s run-unit-test FAIL non-zero exit status 1 88s autopkgtest [14:46:49]: @@@@@@@@@@@@@@@@@@@@ summary 88s run-unit-test FAIL non-zero exit status 1 93s nova [W] Using flock in prodstack7-ppc64el 93s Creating nova instance adt-questing-ppc64el-python-stubserver-20250619-144521-juju-7f2275-prod-proposed-migration-environment-21-5c3f9310-0829-4143-bea1-a147da177fbe from image adt/ubuntu-questing-ppc64el-server-20250619.img (UUID 1c97422d-c646-492e-9581-3c98f213de4b)... 93s nova [W] Timed out waiting for 5803c11a-c9c1-46db-9da6-9709baff93d4 to get deleted.