0s autopkgtest [16:54:12]: starting date and time: 2025-06-17 16:54:12+0000 0s autopkgtest [16:54:12]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [16:54:12]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.khtasexz/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-arm64-4.secgroup --name adt-questing-arm64-advocate-20250617-165412-juju-7f2275-prod-proposed-migration-environment-20-808d9d31-9624-48aa-8b73-59af007a0f5c --image adt/ubuntu-questing-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 145s autopkgtest [16:56:37]: testbed dpkg architecture: arm64 145s autopkgtest [16:56:37]: testbed apt version: 3.1.2 146s autopkgtest [16:56:38]: @@@@@@@@@@@@@@@@@@@@ test bed setup 146s autopkgtest [16:56:38]: testbed release detected to be: None 147s autopkgtest [16:56:39]: updating testbed package index (apt update) 148s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 148s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 148s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 148s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 148s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [17.4 kB] 148s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [392 kB] 148s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/restricted Sources [4716 B] 148s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [36.0 kB] 148s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main arm64 Packages [58.7 kB] 148s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/restricted arm64 Packages [18.2 kB] 148s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe arm64 Packages [311 kB] 148s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse arm64 Packages [13.0 kB] 148s Fetched 1100 kB in 1s (1266 kB/s) 150s Reading package lists... 150s autopkgtest [16:56:42]: upgrading testbed (apt dist-upgrade and autopurge) 150s Reading package lists... 150s Building dependency tree... 150s Reading state information... 151s Calculating upgrade... 151s The following packages will be upgraded: 151s libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 python3-requests 151s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 151s Need to get 5233 kB of archives. 151s After this operation, 291 kB disk space will be freed. 151s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/universe arm64 libpython3.12t64 arm64 3.12.10-1 [2314 kB] 152s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/universe arm64 libpython3.12-stdlib arm64 3.12.10-1 [2029 kB] 153s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/universe arm64 libpython3.12-minimal arm64 3.12.10-1 [836 kB] 153s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main arm64 python3-requests all 2.32.3+dfsg-5ubuntu2 [53.1 kB] 153s Fetched 5233 kB in 2s (2609 kB/s) 154s (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 ... 118701 files and directories currently installed.) 154s Preparing to unpack .../libpython3.12t64_3.12.10-1_arm64.deb ... 154s Unpacking libpython3.12t64:arm64 (3.12.10-1) over (3.12.8-3) ... 154s Preparing to unpack .../libpython3.12-stdlib_3.12.10-1_arm64.deb ... 154s Unpacking libpython3.12-stdlib:arm64 (3.12.10-1) over (3.12.8-3) ... 154s Preparing to unpack .../libpython3.12-minimal_3.12.10-1_arm64.deb ... 154s Unpacking libpython3.12-minimal:arm64 (3.12.10-1) over (3.12.8-3) ... 154s Preparing to unpack .../python3-requests_2.32.3+dfsg-5ubuntu2_all.deb ... 154s Unpacking python3-requests (2.32.3+dfsg-5ubuntu2) over (2.32.3+dfsg-5ubuntu1) ... 154s Setting up libpython3.12-minimal:arm64 (3.12.10-1) ... 154s Setting up python3-requests (2.32.3+dfsg-5ubuntu2) ... 154s Setting up libpython3.12-stdlib:arm64 (3.12.10-1) ... 154s Setting up libpython3.12t64:arm64 (3.12.10-1) ... 154s Processing triggers for libc-bin (2.41-6ubuntu2) ... 155s Reading package lists... 155s Building dependency tree... 155s Reading state information... 155s Solving dependencies... 155s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 158s autopkgtest [16:56:50]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr 6 14:37:51 UTC 2025 158s autopkgtest [16:56:50]: @@@@@@@@@@@@@@@@@@@@ apt-source advocate 160s Get:1 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (dsc) [2309 B] 160s Get:2 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (tar) [37.4 kB] 160s Get:3 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (diff) [6728 B] 160s gpgv: Signature made Sun Jan 12 00:02:12 2025 UTC 160s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 160s gpgv: issuer "tchet@debian.org" 160s gpgv: Can't check signature: No public key 160s dpkg-source: warning: cannot verify inline signature for ./advocate_1.0.0-7.dsc: no acceptable signature found 160s autopkgtest [16:56:52]: testing package advocate version 1.0.0-7 160s autopkgtest [16:56:52]: build not needed 160s autopkgtest [16:56:52]: test run-tests: preparing testbed 161s Reading package lists... 161s Building dependency tree... 161s Reading state information... 161s Solving dependencies... 161s The following NEW packages will be installed: 161s python3-advocate python3-all python3-iniconfig python3-ndg-httpsclient 161s python3-netifaces python3-packaging python3-pluggy python3-pyasn1 161s python3-pytest python3-requests-futures python3-requests-mock 162s 0 upgraded, 11 newly installed, 0 to remove and 0 not upgraded. 162s Need to get 498 kB of archives. 162s After this operation, 2609 kB of additional disk space will be used. 162s Get:1 http://ftpmaster.internal/ubuntu questing/main arm64 python3-netifaces arm64 0.11.0-2build5 [18.2 kB] 162s Get:2 http://ftpmaster.internal/ubuntu questing/main arm64 python3-pyasn1 all 0.6.1-1 [56.4 kB] 162s Get:3 http://ftpmaster.internal/ubuntu questing/universe arm64 python3-ndg-httpsclient all 0.5.1-8 [25.2 kB] 162s Get:4 http://ftpmaster.internal/ubuntu questing/universe arm64 python3-advocate all 1.0.0-7 [34.7 kB] 162s Get:5 http://ftpmaster.internal/ubuntu questing/main arm64 python3-all arm64 3.13.4-1 [880 B] 162s Get:6 http://ftpmaster.internal/ubuntu questing/universe arm64 python3-iniconfig all 1.1.1-2 [6024 B] 162s Get:7 http://ftpmaster.internal/ubuntu questing/main arm64 python3-packaging all 25.0-1 [52.8 kB] 162s Get:8 http://ftpmaster.internal/ubuntu questing/universe arm64 python3-pluggy all 1.5.0-1 [21.0 kB] 162s Get:9 http://ftpmaster.internal/ubuntu questing/universe arm64 python3-pytest all 8.3.5-2 [252 kB] 162s Get:10 http://ftpmaster.internal/ubuntu questing/universe arm64 python3-requests-futures all 1.0.2-1 [12.0 kB] 162s Get:11 http://ftpmaster.internal/ubuntu questing/universe arm64 python3-requests-mock all 1.12.1-3 [17.8 kB] 162s Fetched 498 kB in 1s (820 kB/s) 162s Selecting previously unselected package python3-netifaces:arm64. 163s (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 ... 118701 files and directories currently installed.) 163s Preparing to unpack .../00-python3-netifaces_0.11.0-2build5_arm64.deb ... 163s Unpacking python3-netifaces:arm64 (0.11.0-2build5) ... 163s Selecting previously unselected package python3-pyasn1. 163s Preparing to unpack .../01-python3-pyasn1_0.6.1-1_all.deb ... 163s Unpacking python3-pyasn1 (0.6.1-1) ... 163s Selecting previously unselected package python3-ndg-httpsclient. 163s Preparing to unpack .../02-python3-ndg-httpsclient_0.5.1-8_all.deb ... 163s Unpacking python3-ndg-httpsclient (0.5.1-8) ... 163s Selecting previously unselected package python3-advocate. 163s Preparing to unpack .../03-python3-advocate_1.0.0-7_all.deb ... 163s Unpacking python3-advocate (1.0.0-7) ... 163s Selecting previously unselected package python3-all. 163s Preparing to unpack .../04-python3-all_3.13.4-1_arm64.deb ... 163s Unpacking python3-all (3.13.4-1) ... 163s Selecting previously unselected package python3-iniconfig. 163s Preparing to unpack .../05-python3-iniconfig_1.1.1-2_all.deb ... 163s Unpacking python3-iniconfig (1.1.1-2) ... 163s Selecting previously unselected package python3-packaging. 163s Preparing to unpack .../06-python3-packaging_25.0-1_all.deb ... 163s Unpacking python3-packaging (25.0-1) ... 163s Selecting previously unselected package python3-pluggy. 163s Preparing to unpack .../07-python3-pluggy_1.5.0-1_all.deb ... 163s Unpacking python3-pluggy (1.5.0-1) ... 163s Selecting previously unselected package python3-pytest. 163s Preparing to unpack .../08-python3-pytest_8.3.5-2_all.deb ... 163s Unpacking python3-pytest (8.3.5-2) ... 163s Selecting previously unselected package python3-requests-futures. 163s Preparing to unpack .../09-python3-requests-futures_1.0.2-1_all.deb ... 163s Unpacking python3-requests-futures (1.0.2-1) ... 163s Selecting previously unselected package python3-requests-mock. 163s Preparing to unpack .../10-python3-requests-mock_1.12.1-3_all.deb ... 163s Unpacking python3-requests-mock (1.12.1-3) ... 163s Setting up python3-iniconfig (1.1.1-2) ... 163s Setting up python3-all (3.13.4-1) ... 163s Setting up python3-packaging (25.0-1) ... 163s Setting up python3-requests-mock (1.12.1-3) ... 163s Setting up python3-pluggy (1.5.0-1) ... 163s Setting up python3-netifaces:arm64 (0.11.0-2build5) ... 163s Setting up python3-pyasn1 (0.6.1-1) ... 164s Setting up python3-requests-futures (1.0.2-1) ... 164s Setting up python3-pytest (8.3.5-2) ... 164s Setting up python3-ndg-httpsclient (0.5.1-8) ... 164s Setting up python3-advocate (1.0.0-7) ... 164s Processing triggers for man-db (2.13.1-1) ... 166s autopkgtest [16:56:58]: test run-tests: [----------------------- 166s ************************************************************************** 166s # A new feature in cloud-init identified possible datasources for # 166s # this system as: # 166s # [] # 166s # However, the datasource used was: OpenStack # 166s # # 166s # In the future, cloud-init will only attempt to use datasources that # 166s # are identified or specifically configured. # 166s # For more information see # 166s # https://bugs.launchpad.net/bugs/1669675 # 166s # # 166s # If you are seeing this message, please file a bug against # 166s # cloud-init at # 166s # https://github.com/canonical/cloud-init/issues # 166s # Make sure to include the cloud provider your instance is # 166s # running on. # 166s # # 166s # After you have filed a bug, you can disable this warning by launching # 166s # your instance with the cloud-config below, or putting that content # 166s # into /etc/cloud/cloud.cfg.d/99-warnings.cfg # 166s # # 166s # #cloud-config # 166s # warnings: # 166s # dsid_missing_source: off # 166s ************************************************************************** 166s 166s Disable the warnings above by: 166s touch /home/ubuntu/.cloud-warnings.skip 166s or 166s touch /var/lib/cloud/instance/warnings/.skip 167s ============================= test session starts ============================== 167s platform linux -- Python 3.13.5, pytest-8.3.5, pluggy-1.5.0 -- /usr/bin/python3.13 167s cachedir: .pytest_cache 167s rootdir: /tmp/autopkgtest.Fxl5U3/autopkgtest_tmp 167s plugins: typeguard-4.4.2, requests_mock-1.12.1 167s collecting ... collected 48 items 167s 167s test/test_advocate.py::TestWrapperTests::test_unsafe_connect_raises SKIPPED [ 2%] 167s test/test_advocate.py::ValidateIPTests::test_dns64 PASSED [ 4%] 167s test/test_advocate.py::ValidateIPTests::test_ip_whitelist PASSED [ 6%] 167s test/test_advocate.py::ValidateIPTests::test_ip_whitelist_blacklist_conflict PASSED [ 8%] 167s test/test_advocate.py::ValidateIPTests::test_ipv4_mapped PASSED [ 10%] 167s test/test_advocate.py::ValidateIPTests::test_ipv6 PASSED [ 12%] 167s test/test_advocate.py::ValidateIPTests::test_link_local PASSED [ 14%] 167s test/test_advocate.py::ValidateIPTests::test_loopback PASSED [ 16%] 167s test/test_advocate.py::ValidateIPTests::test_manual_ip_blacklist PASSED [ 18%] 167s test/test_advocate.py::ValidateIPTests::test_multicast PASSED [ 20%] 167s test/test_advocate.py::ValidateIPTests::test_parsed PASSED [ 22%] 167s test/test_advocate.py::ValidateIPTests::test_private PASSED [ 25%] 167s test/test_advocate.py::ValidateIPTests::test_reserved PASSED [ 27%] 167s test/test_advocate.py::ValidateIPTests::test_safecurl_blacklist SKIPPED [ 29%] 167s test/test_advocate.py::ValidateIPTests::test_site_local PASSED [ 31%] 167s test/test_advocate.py::ValidateIPTests::test_sixtofour PASSED [ 33%] 167s test/test_advocate.py::ValidateIPTests::test_teredo PASSED [ 35%] 167s test/test_advocate.py::ValidateIPTests::test_unspecified PASSED [ 37%] 167s test/test_advocate.py::AddrInfoTests::test_default_port_whitelist PASSED [ 39%] 167s test/test_advocate.py::AddrInfoTests::test_local_address_handling PASSED [ 41%] 167s test/test_advocate.py::AddrInfoTests::test_malformed_addrinfo SKIPPED [ 43%] 167s test/test_advocate.py::AddrInfoTests::test_port_blacklist PASSED [ 45%] 167s test/test_advocate.py::AddrInfoTests::test_port_whitelist PASSED [ 47%] 167s test/test_advocate.py::AddrInfoTests::test_simple PASSED [ 50%] 167s test/test_advocate.py::AddrInfoTests::test_unexpected_proto SKIPPED [ 52%] 167s test/test_advocate.py::HostnameTests::test_embedded_null SKIPPED (Na...) [ 54%] 167s test/test_advocate.py::HostnameTests::test_idn SKIPPED (Nameserver d...) [ 56%] 167s test/test_advocate.py::HostnameTests::test_missing_canonname SKIPPED [ 58%] 167s test/test_advocate.py::HostnameTests::test_no_blacklist SKIPPED (Nam...) [ 60%] 167s test/test_advocate.py::ConnectionPoolingTests::test_connection_reuse SKIPPED [ 62%] 167s test/test_advocate.py::AdvocateWrapperTests::test_advocate_requests_api_wrapper PASSED [ 64%] 167s test/test_advocate.py::AdvocateWrapperTests::test_advocate_requests_api_wrapper_hostnames SKIPPED [ 66%] 167s test/test_advocate.py::AdvocateWrapperTests::test_advocate_requests_api_wrapper_req_methods PASSED [ 68%] 167s test/test_advocate.py::AdvocateWrapperTests::test_blacklist_hostname SKIPPED [ 70%] 167s test/test_advocate.py::AdvocateWrapperTests::test_connect_without_local_addresses SKIPPED [ 72%] 167s test/test_advocate.py::AdvocateWrapperTests::test_get SKIPPED (netwo...) [ 75%] 167s test/test_advocate.py::AdvocateWrapperTests::test_mount_disabled PASSED [ 77%] 167s test/test_advocate.py::AdvocateWrapperTests::test_proxy_attempt_throws PASSED [ 79%] 167s test/test_advocate.py::AdvocateWrapperTests::test_redirect SKIPPED [ 81%] 167s test/test_advocate.py::AdvocateWrapperTests::test_validator PASSED [ 83%] 167s test/test_advocate.py::AdvocateWrapperTests::test_wrapper_getattr_fallback PASSED [ 85%] 167s test/test_advocate.py::AdvocateWrapperTests::test_wrapper_session_pickle PASSED [ 87%] 167s test/test_advocate.py::AdvocateWrapperTests::test_wrapper_session_subclass PASSED [ 89%] 167s test/test_advocate.py::AdvocateFuturesTest::test_advocate_wrapper_futures SKIPPED [ 91%] 167s test/test_advocate.py::AdvocateFuturesTest::test_custom_validator SKIPPED [ 93%] 167s test/test_advocate.py::AdvocateFuturesTest::test_get SKIPPED (networ...) [ 95%] 167s test/test_advocate.py::AdvocateFuturesTest::test_many_workers SKIPPED [ 97%] 167s test/test_advocate.py::AdvocateFuturesTest::test_passing_session SKIPPED [100%] 167s 167s ======================== 29 passed, 19 skipped in 0.12s ======================== 167s autopkgtest [16:56:59]: test run-tests: -----------------------] 168s run-tests PASS 168s autopkgtest [16:57:00]: test run-tests: - - - - - - - - - - results - - - - - - - - - - 168s autopkgtest [16:57:00]: @@@@@@@@@@@@@@@@@@@@ summary 168s run-tests PASS 194s nova [W] Using flock in prodstack6-arm64 194s Creating nova instance adt-questing-arm64-advocate-20250617-165412-juju-7f2275-prod-proposed-migration-environment-20-808d9d31-9624-48aa-8b73-59af007a0f5c from image adt/ubuntu-questing-arm64-server-20250617.img (UUID 44c62701-8812-4c2a-ad88-37a56306a1c9)... 194s nova [W] Timed out waiting for cea47488-8c89-4f0a-aeab-6456fc704000 to get deleted.