0s autopkgtest [20:05:01]: starting date and time: 2025-06-05 20:05:01+0000 0s autopkgtest [20:05:01]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [20:05:01]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.sounczsj/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults --apt-upgrade advocate --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=python3-defaults/3.13.4-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-s390x-2.secgroup --name adt-questing-s390x-advocate-20250605-200501-juju-7f2275-prod-proposed-migration-environment-2-a3636af0-a816-4037-82a1-efc7e5303f75 --image adt/ubuntu-questing-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 64s autopkgtest [20:06:05]: testbed dpkg architecture: s390x 64s autopkgtest [20:06:05]: testbed apt version: 3.1.0ubuntu3 64s autopkgtest [20:06:05]: @@@@@@@@@@@@@@@@@@@@ test bed setup 64s autopkgtest [20:06:05]: testbed release detected to be: None 65s autopkgtest [20:06:06]: updating testbed package index (apt update) 65s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 66s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 66s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 66s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 66s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [55.3 kB] 66s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/restricted Sources [3168 B] 66s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [13.0 kB] 66s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [438 kB] 66s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [72.9 kB] 66s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/restricted s390x Packages [716 B] 66s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [351 kB] 66s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [4128 B] 66s Fetched 1187 kB in 0s (2405 kB/s) 67s Reading package lists... 67s autopkgtest [20:06:08]: upgrading testbed (apt dist-upgrade and autopurge) 67s Reading package lists... 68s Building dependency tree... 68s Reading state information... 68s Calculating upgrade... 69s The following packages will be upgraded: 69s libpython3-stdlib libpython3.13 libpython3.13-minimal libpython3.13-stdlib 69s python3 python3-minimal python3.13 python3.13-gdbm python3.13-minimal 69s 9 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 69s Need to get 8670 kB of archives. 69s After this operation, 12.3 kB of additional disk space will be used. 69s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x libpython3.13 s390x 3.13.4-1 [2522 kB] 69s Get:2 http://ftpmaster.internal/ubuntu questing/main s390x python3.13 s390x 3.13.4-1 [744 kB] 69s Get:3 http://ftpmaster.internal/ubuntu questing/main s390x python3.13-minimal s390x 3.13.4-1 [2375 kB] 70s Get:4 http://ftpmaster.internal/ubuntu questing/main s390x libpython3.13-minimal s390x 3.13.4-1 [883 kB] 70s Get:5 http://ftpmaster.internal/ubuntu questing/main s390x libpython3.13-stdlib s390x 3.13.4-1 [2053 kB] 70s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main s390x python3-minimal s390x 3.13.4-1 [27.9 kB] 70s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main s390x python3 s390x 3.13.4-1 [24.0 kB] 70s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libpython3-stdlib s390x 3.13.4-1 [10.5 kB] 70s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x python3.13-gdbm s390x 3.13.4-1 [31.9 kB] 70s Fetched 8670 kB in 1s (13.5 MB/s) 72s (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 ... 60226 files and directories currently installed.) 72s Preparing to unpack .../libpython3.13_3.13.4-1_s390x.deb ... 72s Unpacking libpython3.13:s390x (3.13.4-1) over (3.13.3-4) ... 72s Preparing to unpack .../python3.13_3.13.4-1_s390x.deb ... 72s Unpacking python3.13 (3.13.4-1) over (3.13.3-4) ... 72s Preparing to unpack .../python3.13-minimal_3.13.4-1_s390x.deb ... 73s Unpacking python3.13-minimal (3.13.4-1) over (3.13.3-4) ... 73s Preparing to unpack .../libpython3.13-minimal_3.13.4-1_s390x.deb ... 73s Unpacking libpython3.13-minimal:s390x (3.13.4-1) over (3.13.3-4) ... 73s Preparing to unpack .../libpython3.13-stdlib_3.13.4-1_s390x.deb ... 74s Unpacking libpython3.13-stdlib:s390x (3.13.4-1) over (3.13.3-4) ... 74s Setting up libpython3.13-minimal:s390x (3.13.4-1) ... 74s Setting up python3.13-minimal (3.13.4-1) ... 76s (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 ... 60226 files and directories currently installed.) 76s Preparing to unpack .../python3-minimal_3.13.4-1_s390x.deb ... 76s Unpacking python3-minimal (3.13.4-1) over (3.13.3-1) ... 76s Setting up python3-minimal (3.13.4-1) ... 76s (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 ... 60226 files and directories currently installed.) 76s Preparing to unpack .../python3_3.13.4-1_s390x.deb ... 76s Unpacking python3 (3.13.4-1) over (3.13.3-1) ... 76s Preparing to unpack .../libpython3-stdlib_3.13.4-1_s390x.deb ... 76s Unpacking libpython3-stdlib:s390x (3.13.4-1) over (3.13.3-1) ... 76s Preparing to unpack .../python3.13-gdbm_3.13.4-1_s390x.deb ... 76s Unpacking python3.13-gdbm (3.13.4-1) over (3.13.3-4) ... 76s Setting up libpython3.13-stdlib:s390x (3.13.4-1) ... 76s Setting up python3.13-gdbm (3.13.4-1) ... 76s Setting up libpython3-stdlib:s390x (3.13.4-1) ... 76s Setting up libpython3.13:s390x (3.13.4-1) ... 76s Setting up python3.13 (3.13.4-1) ... 77s Setting up python3 (3.13.4-1) ... 77s Processing triggers for man-db (2.13.1-1) ... 78s Processing triggers for libc-bin (2.41-6ubuntu2) ... 78s Processing triggers for systemd (257.4-1ubuntu3) ... 78s Reading package lists... 78s Building dependency tree... 78s Reading state information... 78s Solving dependencies... 79s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 81s autopkgtest [20:06:22]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 13:39:00 UTC 2025 81s autopkgtest [20:06:22]: @@@@@@@@@@@@@@@@@@@@ apt-source advocate 82s Get:1 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (dsc) [2309 B] 82s Get:2 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (tar) [37.4 kB] 82s Get:3 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (diff) [6728 B] 82s gpgv: Signature made Sun Jan 12 00:02:12 2025 UTC 82s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 82s gpgv: issuer "tchet@debian.org" 82s gpgv: Can't check signature: No public key 82s dpkg-source: warning: cannot verify inline signature for ./advocate_1.0.0-7.dsc: no acceptable signature found 82s autopkgtest [20:06:23]: testing package advocate version 1.0.0-7 82s autopkgtest [20:06:23]: build not needed 82s autopkgtest [20:06:23]: test run-tests: preparing testbed 83s Reading package lists... 83s Building dependency tree... 83s Reading state information... 83s Solving dependencies... 83s The following NEW packages will be installed: 83s python3-advocate python3-all python3-iniconfig python3-ndg-httpsclient 83s python3-netifaces python3-pluggy python3-pyasn1 python3-pytest 83s python3-requests-futures python3-requests-mock 83s 0 upgraded, 10 newly installed, 0 to remove and 0 not upgraded. 83s Need to get 445 kB of archives. 83s After this operation, 2307 kB of additional disk space will be used. 83s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x python3-netifaces s390x 0.11.0-2build5 [18.6 kB] 83s Get:2 http://ftpmaster.internal/ubuntu questing/main s390x python3-pyasn1 all 0.6.1-1 [56.4 kB] 83s Get:3 http://ftpmaster.internal/ubuntu questing/universe s390x python3-ndg-httpsclient all 0.5.1-8 [25.2 kB] 83s Get:4 http://ftpmaster.internal/ubuntu questing/universe s390x python3-advocate all 1.0.0-7 [34.7 kB] 83s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main s390x python3-all s390x 3.13.4-1 [880 B] 83s Get:6 http://ftpmaster.internal/ubuntu questing/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 83s Get:7 http://ftpmaster.internal/ubuntu questing/universe s390x python3-pluggy all 1.5.0-1 [21.0 kB] 83s Get:8 http://ftpmaster.internal/ubuntu questing/universe s390x python3-pytest all 8.3.5-2 [252 kB] 83s Get:9 http://ftpmaster.internal/ubuntu questing/universe s390x python3-requests-futures all 1.0.2-1 [12.0 kB] 83s Get:10 http://ftpmaster.internal/ubuntu questing/universe s390x python3-requests-mock all 1.12.1-3 [17.8 kB] 83s Fetched 445 kB in 0s (2129 kB/s) 83s Selecting previously unselected package python3-netifaces:s390x. 83s (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 ... 60226 files and directories currently installed.) 83s Preparing to unpack .../0-python3-netifaces_0.11.0-2build5_s390x.deb ... 83s Unpacking python3-netifaces:s390x (0.11.0-2build5) ... 83s Selecting previously unselected package python3-pyasn1. 83s Preparing to unpack .../1-python3-pyasn1_0.6.1-1_all.deb ... 83s Unpacking python3-pyasn1 (0.6.1-1) ... 84s Selecting previously unselected package python3-ndg-httpsclient. 84s Preparing to unpack .../2-python3-ndg-httpsclient_0.5.1-8_all.deb ... 84s Unpacking python3-ndg-httpsclient (0.5.1-8) ... 84s Selecting previously unselected package python3-advocate. 84s Preparing to unpack .../3-python3-advocate_1.0.0-7_all.deb ... 84s Unpacking python3-advocate (1.0.0-7) ... 84s Selecting previously unselected package python3-all. 84s Preparing to unpack .../4-python3-all_3.13.4-1_s390x.deb ... 84s Unpacking python3-all (3.13.4-1) ... 84s Selecting previously unselected package python3-iniconfig. 84s Preparing to unpack .../5-python3-iniconfig_1.1.1-2_all.deb ... 84s Unpacking python3-iniconfig (1.1.1-2) ... 84s Selecting previously unselected package python3-pluggy. 84s Preparing to unpack .../6-python3-pluggy_1.5.0-1_all.deb ... 84s Unpacking python3-pluggy (1.5.0-1) ... 84s Selecting previously unselected package python3-pytest. 84s Preparing to unpack .../7-python3-pytest_8.3.5-2_all.deb ... 84s Unpacking python3-pytest (8.3.5-2) ... 84s Selecting previously unselected package python3-requests-futures. 84s Preparing to unpack .../8-python3-requests-futures_1.0.2-1_all.deb ... 84s Unpacking python3-requests-futures (1.0.2-1) ... 84s Selecting previously unselected package python3-requests-mock. 84s Preparing to unpack .../9-python3-requests-mock_1.12.1-3_all.deb ... 84s Unpacking python3-requests-mock (1.12.1-3) ... 84s Setting up python3-iniconfig (1.1.1-2) ... 84s Setting up python3-all (3.13.4-1) ... 84s Setting up python3-requests-mock (1.12.1-3) ... 84s Setting up python3-pluggy (1.5.0-1) ... 84s Setting up python3-netifaces:s390x (0.11.0-2build5) ... 84s Setting up python3-pyasn1 (0.6.1-1) ... 84s Setting up python3-requests-futures (1.0.2-1) ... 84s Setting up python3-pytest (8.3.5-2) ... 84s Setting up python3-ndg-httpsclient (0.5.1-8) ... 84s Setting up python3-advocate (1.0.0-7) ... 84s Processing triggers for man-db (2.13.1-1) ... 86s autopkgtest [20:06:27]: test run-tests: [----------------------- 86s ============================= test session starts ============================== 86s platform linux -- Python 3.13.4, pytest-8.3.5, pluggy-1.5.0 -- /usr/bin/python3.13 86s cachedir: .pytest_cache 86s rootdir: /tmp/autopkgtest.p8XnZy/autopkgtest_tmp 86s plugins: typeguard-4.4.2, requests_mock-1.12.1 86s collecting ... collected 48 items 86s 86s test/test_advocate.py::TestWrapperTests::test_unsafe_connect_raises SKIPPED [ 2%] 86s test/test_advocate.py::ValidateIPTests::test_dns64 PASSED [ 4%] 86s test/test_advocate.py::ValidateIPTests::test_ip_whitelist PASSED [ 6%] 86s test/test_advocate.py::ValidateIPTests::test_ip_whitelist_blacklist_conflict PASSED [ 8%] 86s test/test_advocate.py::ValidateIPTests::test_ipv4_mapped PASSED [ 10%] 86s test/test_advocate.py::ValidateIPTests::test_ipv6 PASSED [ 12%] 86s test/test_advocate.py::ValidateIPTests::test_link_local PASSED [ 14%] 86s test/test_advocate.py::ValidateIPTests::test_loopback PASSED [ 16%] 86s test/test_advocate.py::ValidateIPTests::test_manual_ip_blacklist PASSED [ 18%] 86s test/test_advocate.py::ValidateIPTests::test_multicast PASSED [ 20%] 86s test/test_advocate.py::ValidateIPTests::test_parsed PASSED [ 22%] 86s test/test_advocate.py::ValidateIPTests::test_private PASSED [ 25%] 86s test/test_advocate.py::ValidateIPTests::test_reserved PASSED [ 27%] 86s test/test_advocate.py::ValidateIPTests::test_safecurl_blacklist SKIPPED [ 29%] 86s test/test_advocate.py::ValidateIPTests::test_site_local PASSED [ 31%] 86s test/test_advocate.py::ValidateIPTests::test_sixtofour PASSED [ 33%] 86s test/test_advocate.py::ValidateIPTests::test_teredo PASSED [ 35%] 86s test/test_advocate.py::ValidateIPTests::test_unspecified PASSED [ 37%] 86s test/test_advocate.py::AddrInfoTests::test_default_port_whitelist PASSED [ 39%] 86s test/test_advocate.py::AddrInfoTests::test_local_address_handling PASSED [ 41%] 86s test/test_advocate.py::AddrInfoTests::test_malformed_addrinfo SKIPPED [ 43%] 86s test/test_advocate.py::AddrInfoTests::test_port_blacklist PASSED [ 45%] 86s test/test_advocate.py::AddrInfoTests::test_port_whitelist PASSED [ 47%] 86s test/test_advocate.py::AddrInfoTests::test_simple PASSED [ 50%] 86s test/test_advocate.py::AddrInfoTests::test_unexpected_proto SKIPPED [ 52%] 86s test/test_advocate.py::HostnameTests::test_embedded_null SKIPPED (Na...) [ 54%] 86s test/test_advocate.py::HostnameTests::test_idn SKIPPED (Nameserver d...) [ 56%] 86s test/test_advocate.py::HostnameTests::test_missing_canonname SKIPPED [ 58%] 86s test/test_advocate.py::HostnameTests::test_no_blacklist SKIPPED (Nam...) [ 60%] 86s test/test_advocate.py::ConnectionPoolingTests::test_connection_reuse SKIPPED [ 62%] 86s test/test_advocate.py::AdvocateWrapperTests::test_advocate_requests_api_wrapper FAILED [ 64%] 86s 86s =================================== FAILURES =================================== 86s ___________ AdvocateWrapperTests.test_advocate_requests_api_wrapper ____________ 86s 86s self = 86s 86s def test_advocate_requests_api_wrapper(self): 86s wrapper = RequestsAPIWrapper(validator=AddrValidator()) 86s local_validator = AddrValidator(ip_whitelist={ 86s ipaddress.ip_network("127.0.0.1"), 86s }) 86s local_wrapper = RequestsAPIWrapper(validator=local_validator) 86s 86s > self.assertRaises( 86s UnacceptableAddressException, 86s wrapper.get, "http://127.0.0.1:1/" 86s ) 86s 86s test/test_advocate.py:494: 86s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 86s /usr/lib/python3/dist-packages/advocate/api.py:251: in wrapped_func 86s return fun(*args, **kwargs) 86s /usr/lib/python3/dist-packages/advocate/api.py:108: in get 86s return request('get', url, **kwargs) 86s /usr/lib/python3/dist-packages/advocate/api.py:94: in request 86s response = sess.request(method=method, url=url, **kwargs) 86s /usr/lib/python3/dist-packages/requests/sessions.py:589: in request 86s resp = self.send(prep, **send_kwargs) 86s /usr/lib/python3/dist-packages/requests/sessions.py:703: in send 86s r = adapter.send(request, **kwargs) 86s /usr/lib/python3/dist-packages/requests/adapters.py:633: in send 86s conn = self.get_connection_with_tls_context( 86s /usr/lib/python3/dist-packages/requests/adapters.py:483: in get_connection_with_tls_context 86s proxy_manager = self.proxy_manager_for(proxy) 86s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 86s 86s def proxy_manager_for(self, proxy, **proxy_kwargs): 86s > raise ProxyDisabledException("Proxies cannot be used with Advocate") 86s E advocate.exceptions.ProxyDisabledException: Proxies cannot be used with Advocate 86s 86s /usr/lib/python3/dist-packages/advocate/adapters.py:31: ProxyDisabledException 86s =========================== short test summary info ============================ 86s FAILED test/test_advocate.py::AdvocateWrapperTests::test_advocate_requests_api_wrapper 86s !!!!!!!!!!!!!!!!!!!!!!!!!! stopping after 1 failures !!!!!!!!!!!!!!!!!!!!!!!!!!! 86s =================== 1 failed, 21 passed, 9 skipped in 0.08s ==================== 87s autopkgtest [20:06:28]: test run-tests: -----------------------] 87s autopkgtest [20:06:28]: test run-tests: - - - - - - - - - - results - - - - - - - - - - 87s run-tests FAIL non-zero exit status 1 87s autopkgtest [20:06:28]: @@@@@@@@@@@@@@@@@@@@ summary 87s run-tests FAIL non-zero exit status 1 91s nova [W] Using flock in prodstack7-s390x 91s Creating nova instance adt-questing-s390x-advocate-20250605-200501-juju-7f2275-prod-proposed-migration-environment-2-a3636af0-a816-4037-82a1-efc7e5303f75 from image adt/ubuntu-questing-s390x-server-20250605.img (UUID d4380d2b-8b01-41e5-a7a5-52ada3daeeb8)... 91s nova [W] Timed out waiting for de3e6f30-b600-4e9b-808a-bc9b42d4d4a0 to get deleted.