0s autopkgtest [14:33:51]: starting date and time: 2025-06-19 14:33:51+0000 0s autopkgtest [14:33:51]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [14:33:51]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.yv9oxqz1/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:requests --apt-upgrade advocate --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-amd64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-14.secgroup --name adt-questing-amd64-advocate-20250619-143349-juju-7f2275-prod-proposed-migration-environment-2-c6ea7f42-694b-4d06-ad9b-98a6a4760707 --image adt/ubuntu-questing-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 106s autopkgtest [14:35:37]: testbed dpkg architecture: amd64 107s autopkgtest [14:35:38]: testbed apt version: 3.1.2 107s autopkgtest [14:35:38]: @@@@@@@@@@@@@@@@@@@@ test bed setup 107s autopkgtest [14:35:38]: testbed release detected to be: None 108s autopkgtest [14:35:39]: updating testbed package index (apt update) 108s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 108s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 108s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 108s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 108s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [17.4 kB] 108s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [38.3 kB] 109s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/restricted Sources [4716 B] 109s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [426 kB] 109s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [44.5 kB] 109s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [82.3 kB] 109s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/restricted amd64 Packages [13.5 kB] 109s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/restricted i386 Packages [2420 B] 109s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [376 kB] 109s Get:14 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [135 kB] 109s Get:15 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [5196 B] 109s Get:16 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [24.9 kB] 109s Fetched 1420 kB in 1s (2394 kB/s) 110s Reading package lists... 111s autopkgtest [14:35:42]: upgrading testbed (apt dist-upgrade and autopurge) 111s Reading package lists... 111s Building dependency tree... 111s Reading state information... 111s Calculating upgrade... 112s The following packages will be upgraded: 112s python3-requests 112s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 112s Need to get 53.1 kB of archives. 112s After this operation, 0 B of additional disk space will be used. 112s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 python3-requests all 2.32.3+dfsg-5ubuntu2 [53.1 kB] 112s Fetched 53.1 kB in 0s (2299 kB/s) 112s (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 ... 81475 files and directories currently installed.) 112s Preparing to unpack .../python3-requests_2.32.3+dfsg-5ubuntu2_all.deb ... 112s Unpacking python3-requests (2.32.3+dfsg-5ubuntu2) over (2.32.3+dfsg-5ubuntu1) ... 112s Setting up python3-requests (2.32.3+dfsg-5ubuntu2) ... 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 114s Solving dependencies... 114s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 116s autopkgtest [14:35:47]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr 6 15:05:05 UTC 2025 117s autopkgtest [14:35:48]: @@@@@@@@@@@@@@@@@@@@ apt-source advocate 118s Get:1 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (dsc) [2309 B] 118s Get:2 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (tar) [37.4 kB] 118s Get:3 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (diff) [6728 B] 118s gpgv: Signature made Sun Jan 12 00:02:12 2025 UTC 118s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 118s gpgv: issuer "tchet@debian.org" 118s gpgv: Can't check signature: No public key 118s dpkg-source: warning: cannot verify inline signature for ./advocate_1.0.0-7.dsc: no acceptable signature found 118s autopkgtest [14:35:49]: testing package advocate version 1.0.0-7 118s autopkgtest [14:35:49]: build not needed 119s autopkgtest [14:35:50]: test run-tests: preparing testbed 119s Reading package lists... 119s Building dependency tree... 119s Reading state information... 119s Solving dependencies... 120s The following NEW packages will be installed: 120s python3-advocate python3-all python3-iniconfig python3-ndg-httpsclient 120s python3-netifaces python3-pluggy python3-pyasn1 python3-pytest 120s python3-requests-futures python3-requests-mock 120s 0 upgraded, 10 newly installed, 0 to remove and 0 not upgraded. 120s Need to get 445 kB of archives. 120s After this operation, 2311 kB of additional disk space will be used. 120s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 python3-netifaces amd64 0.11.0-2build5 [18.8 kB] 120s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 python3-pyasn1 all 0.6.1-1 [56.4 kB] 120s Get:3 http://ftpmaster.internal/ubuntu questing/universe amd64 python3-ndg-httpsclient all 0.5.1-8 [25.2 kB] 120s Get:4 http://ftpmaster.internal/ubuntu questing/universe amd64 python3-advocate all 1.0.0-7 [34.7 kB] 120s Get:5 http://ftpmaster.internal/ubuntu questing/main amd64 python3-all amd64 3.13.4-1 [880 B] 120s Get:6 http://ftpmaster.internal/ubuntu questing/universe amd64 python3-iniconfig all 1.1.1-2 [6024 B] 120s Get:7 http://ftpmaster.internal/ubuntu questing/universe amd64 python3-pluggy all 1.5.0-1 [21.0 kB] 120s Get:8 http://ftpmaster.internal/ubuntu questing/universe amd64 python3-pytest all 8.3.5-2 [252 kB] 120s Get:9 http://ftpmaster.internal/ubuntu questing/universe amd64 python3-requests-futures all 1.0.2-1 [12.0 kB] 120s Get:10 http://ftpmaster.internal/ubuntu questing/universe amd64 python3-requests-mock all 1.12.1-3 [17.8 kB] 120s Fetched 445 kB in 0s (7317 kB/s) 120s Selecting previously unselected package python3-netifaces:amd64. 120s (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 ... 81475 files and directories currently installed.) 120s Preparing to unpack .../0-python3-netifaces_0.11.0-2build5_amd64.deb ... 120s Unpacking python3-netifaces:amd64 (0.11.0-2build5) ... 120s Selecting previously unselected package python3-pyasn1. 120s Preparing to unpack .../1-python3-pyasn1_0.6.1-1_all.deb ... 120s Unpacking python3-pyasn1 (0.6.1-1) ... 120s Selecting previously unselected package python3-ndg-httpsclient. 120s Preparing to unpack .../2-python3-ndg-httpsclient_0.5.1-8_all.deb ... 120s Unpacking python3-ndg-httpsclient (0.5.1-8) ... 120s Selecting previously unselected package python3-advocate. 120s Preparing to unpack .../3-python3-advocate_1.0.0-7_all.deb ... 120s Unpacking python3-advocate (1.0.0-7) ... 120s Selecting previously unselected package python3-all. 120s Preparing to unpack .../4-python3-all_3.13.4-1_amd64.deb ... 120s Unpacking python3-all (3.13.4-1) ... 120s Selecting previously unselected package python3-iniconfig. 120s Preparing to unpack .../5-python3-iniconfig_1.1.1-2_all.deb ... 120s Unpacking python3-iniconfig (1.1.1-2) ... 121s Selecting previously unselected package python3-pluggy. 121s Preparing to unpack .../6-python3-pluggy_1.5.0-1_all.deb ... 121s Unpacking python3-pluggy (1.5.0-1) ... 121s Selecting previously unselected package python3-pytest. 121s Preparing to unpack .../7-python3-pytest_8.3.5-2_all.deb ... 121s Unpacking python3-pytest (8.3.5-2) ... 121s Selecting previously unselected package python3-requests-futures. 121s Preparing to unpack .../8-python3-requests-futures_1.0.2-1_all.deb ... 121s Unpacking python3-requests-futures (1.0.2-1) ... 121s Selecting previously unselected package python3-requests-mock. 121s Preparing to unpack .../9-python3-requests-mock_1.12.1-3_all.deb ... 121s Unpacking python3-requests-mock (1.12.1-3) ... 121s Setting up python3-iniconfig (1.1.1-2) ... 121s Setting up python3-all (3.13.4-1) ... 121s Setting up python3-requests-mock (1.12.1-3) ... 121s Setting up python3-pluggy (1.5.0-1) ... 121s Setting up python3-netifaces:amd64 (0.11.0-2build5) ... 121s Setting up python3-pyasn1 (0.6.1-1) ... 121s Setting up python3-requests-futures (1.0.2-1) ... 121s Setting up python3-pytest (8.3.5-2) ... 122s Setting up python3-ndg-httpsclient (0.5.1-8) ... 122s Setting up python3-advocate (1.0.0-7) ... 122s Processing triggers for man-db (2.13.1-1) ... 124s autopkgtest [14:35:55]: test run-tests: [----------------------- 125s ============================= test session starts ============================== 125s platform linux -- Python 3.13.5, pytest-8.3.5, pluggy-1.5.0 -- /usr/bin/python3.13 125s cachedir: .pytest_cache 125s rootdir: /tmp/autopkgtest.7Y4xQY/autopkgtest_tmp 125s plugins: requests_mock-1.12.1, typeguard-4.4.2 125s collecting ... collected 48 items 125s 125s test/test_advocate.py::TestWrapperTests::test_unsafe_connect_raises SKIPPED [ 2%] 125s test/test_advocate.py::ValidateIPTests::test_dns64 PASSED [ 4%] 125s test/test_advocate.py::ValidateIPTests::test_ip_whitelist PASSED [ 6%] 125s test/test_advocate.py::ValidateIPTests::test_ip_whitelist_blacklist_conflict PASSED [ 8%] 125s test/test_advocate.py::ValidateIPTests::test_ipv4_mapped PASSED [ 10%] 125s test/test_advocate.py::ValidateIPTests::test_ipv6 PASSED [ 12%] 125s test/test_advocate.py::ValidateIPTests::test_link_local PASSED [ 14%] 125s test/test_advocate.py::ValidateIPTests::test_loopback PASSED [ 16%] 125s test/test_advocate.py::ValidateIPTests::test_manual_ip_blacklist PASSED [ 18%] 125s test/test_advocate.py::ValidateIPTests::test_multicast PASSED [ 20%] 125s test/test_advocate.py::ValidateIPTests::test_parsed PASSED [ 22%] 125s test/test_advocate.py::ValidateIPTests::test_private PASSED [ 25%] 125s test/test_advocate.py::ValidateIPTests::test_reserved PASSED [ 27%] 125s test/test_advocate.py::ValidateIPTests::test_safecurl_blacklist SKIPPED [ 29%] 125s test/test_advocate.py::ValidateIPTests::test_site_local PASSED [ 31%] 125s test/test_advocate.py::ValidateIPTests::test_sixtofour PASSED [ 33%] 125s test/test_advocate.py::ValidateIPTests::test_teredo PASSED [ 35%] 125s test/test_advocate.py::ValidateIPTests::test_unspecified PASSED [ 37%] 125s test/test_advocate.py::AddrInfoTests::test_default_port_whitelist PASSED [ 39%] 125s test/test_advocate.py::AddrInfoTests::test_local_address_handling PASSED [ 41%] 125s test/test_advocate.py::AddrInfoTests::test_malformed_addrinfo SKIPPED [ 43%] 125s test/test_advocate.py::AddrInfoTests::test_port_blacklist PASSED [ 45%] 125s test/test_advocate.py::AddrInfoTests::test_port_whitelist PASSED [ 47%] 125s test/test_advocate.py::AddrInfoTests::test_simple PASSED [ 50%] 125s test/test_advocate.py::AddrInfoTests::test_unexpected_proto SKIPPED [ 52%] 125s test/test_advocate.py::HostnameTests::test_embedded_null SKIPPED (Na...) [ 54%] 125s test/test_advocate.py::HostnameTests::test_idn SKIPPED (Nameserver d...) [ 56%] 125s test/test_advocate.py::HostnameTests::test_missing_canonname SKIPPED [ 58%] 125s test/test_advocate.py::HostnameTests::test_no_blacklist SKIPPED (Nam...) [ 60%] 125s test/test_advocate.py::ConnectionPoolingTests::test_connection_reuse SKIPPED [ 62%] 125s test/test_advocate.py::AdvocateWrapperTests::test_advocate_requests_api_wrapper FAILED [ 64%] 125s 125s =================================== FAILURES =================================== 125s ___________ AdvocateWrapperTests.test_advocate_requests_api_wrapper ____________ 125s 125s self = 125s 125s def test_advocate_requests_api_wrapper(self): 125s wrapper = RequestsAPIWrapper(validator=AddrValidator()) 125s local_validator = AddrValidator(ip_whitelist={ 125s ipaddress.ip_network("127.0.0.1"), 125s }) 125s local_wrapper = RequestsAPIWrapper(validator=local_validator) 125s 125s > self.assertRaises( 125s UnacceptableAddressException, 125s wrapper.get, "http://127.0.0.1:1/" 125s ) 125s 125s test/test_advocate.py:494: 125s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 125s /usr/lib/python3/dist-packages/advocate/api.py:251: in wrapped_func 125s return fun(*args, **kwargs) 125s /usr/lib/python3/dist-packages/advocate/api.py:108: in get 125s return request('get', url, **kwargs) 125s /usr/lib/python3/dist-packages/advocate/api.py:94: in request 125s response = sess.request(method=method, url=url, **kwargs) 125s /usr/lib/python3/dist-packages/requests/sessions.py:589: in request 125s resp = self.send(prep, **send_kwargs) 125s /usr/lib/python3/dist-packages/requests/sessions.py:703: in send 125s r = adapter.send(request, **kwargs) 125s /usr/lib/python3/dist-packages/requests/adapters.py:633: in send 125s conn = self.get_connection_with_tls_context( 125s /usr/lib/python3/dist-packages/requests/adapters.py:483: in get_connection_with_tls_context 125s proxy_manager = self.proxy_manager_for(proxy) 125s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 125s 125s def proxy_manager_for(self, proxy, **proxy_kwargs): 125s > raise ProxyDisabledException("Proxies cannot be used with Advocate") 125s E advocate.exceptions.ProxyDisabledException: Proxies cannot be used with Advocate 125s 125s /usr/lib/python3/dist-packages/advocate/adapters.py:31: ProxyDisabledException 125s =========================== short test summary info ============================ 125s FAILED test/test_advocate.py::AdvocateWrapperTests::test_advocate_requests_api_wrapper 125s !!!!!!!!!!!!!!!!!!!!!!!!!! stopping after 1 failures !!!!!!!!!!!!!!!!!!!!!!!!!!! 125s =================== 1 failed, 21 passed, 9 skipped in 0.16s ==================== 125s autopkgtest [14:35:56]: test run-tests: -----------------------] 126s run-tests FAIL non-zero exit status 1 126s autopkgtest [14:35:57]: test run-tests: - - - - - - - - - - results - - - - - - - - - - 126s autopkgtest [14:35:57]: @@@@@@@@@@@@@@@@@@@@ summary 126s run-tests FAIL non-zero exit status 1 142s nova [W] Skipping flock for amd64 142s Creating nova instance adt-questing-amd64-advocate-20250619-143349-juju-7f2275-prod-proposed-migration-environment-2-c6ea7f42-694b-4d06-ad9b-98a6a4760707 from image adt/ubuntu-questing-amd64-server-20250619.img (UUID f2fe812e-b164-45c0-b2ab-1b0b09a5b610)... 142s nova [W] Timed out waiting for 0326032e-cd63-40e9-9eba-03fcd5407022 to get deleted.