0s autopkgtest [16:59:02]: starting date and time: 2025-06-17 16:59:02+0000 0s autopkgtest [16:59:02]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [16:59:02]: host juju-7f2275-prod-proposed-migration-environment-23; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.a_4sixh6/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-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-23@bos03-s390x-13.secgroup --name adt-questing-s390x-advocate-20250617-165901-juju-7f2275-prod-proposed-migration-environment-23-37985f7d-1a95-4e45-a538-740e7b11b11d --image adt/ubuntu-questing-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-23 --net-id=net_prod-proposed-migration-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 81s autopkgtest [17:00:23]: testbed dpkg architecture: s390x 81s autopkgtest [17:00:23]: testbed apt version: 3.1.2 82s autopkgtest [17:00:24]: @@@@@@@@@@@@@@@@@@@@ test bed setup 82s autopkgtest [17:00:24]: testbed release detected to be: None 83s autopkgtest [17:00:25]: updating testbed package index (apt update) 83s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 83s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 83s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 83s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 84s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [17.4 kB] 84s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [36.0 kB] 84s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/restricted Sources [4716 B] 84s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [392 kB] 84s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [57.2 kB] 84s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/restricted s390x Packages [716 B] 84s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [301 kB] 84s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [5880 B] 84s Fetched 1064 kB in 1s (1238 kB/s) 84s Reading package lists... 85s autopkgtest [17:00:27]: upgrading testbed (apt dist-upgrade and autopurge) 85s Reading package lists... 85s Building dependency tree... 85s Reading state information... 85s Calculating upgrade... 85s The following packages will be upgraded: 85s initramfs-tools initramfs-tools-bin initramfs-tools-core 85s libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 python3-requests 85s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 85s Need to get 5539 kB of archives. 85s After this operation, 258 kB disk space will be freed. 85s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x initramfs-tools-bin s390x 0.149ubuntu1 [32.2 kB] 86s Get:2 http://ftpmaster.internal/ubuntu questing/main s390x initramfs-tools all 0.149ubuntu1 [8616 B] 86s Get:3 http://ftpmaster.internal/ubuntu questing/main s390x initramfs-tools-core all 0.149ubuntu1 [50.4 kB] 86s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12t64 s390x 3.12.10-1 [2509 kB] 86s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-stdlib s390x 3.12.10-1 [2049 kB] 86s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-minimal s390x 3.12.10-1 [836 kB] 87s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main s390x python3-requests all 2.32.3+dfsg-5ubuntu2 [53.1 kB] 87s Fetched 5539 kB in 1s (3962 kB/s) 87s (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 ... 82196 files and directories currently installed.) 87s Preparing to unpack .../0-initramfs-tools-bin_0.149ubuntu1_s390x.deb ... 87s Unpacking initramfs-tools-bin (0.149ubuntu1) over (0.148.1ubuntu1) ... 87s Preparing to unpack .../1-initramfs-tools_0.149ubuntu1_all.deb ... 87s Unpacking initramfs-tools (0.149ubuntu1) over (0.148.1ubuntu1) ... 87s Preparing to unpack .../2-initramfs-tools-core_0.149ubuntu1_all.deb ... 87s Unpacking initramfs-tools-core (0.149ubuntu1) over (0.148.1ubuntu1) ... 87s Preparing to unpack .../3-libpython3.12t64_3.12.10-1_s390x.deb ... 87s Unpacking libpython3.12t64:s390x (3.12.10-1) over (3.12.8-3) ... 87s Preparing to unpack .../4-libpython3.12-stdlib_3.12.10-1_s390x.deb ... 87s Unpacking libpython3.12-stdlib:s390x (3.12.10-1) over (3.12.8-3) ... 87s Preparing to unpack .../5-libpython3.12-minimal_3.12.10-1_s390x.deb ... 87s Unpacking libpython3.12-minimal:s390x (3.12.10-1) over (3.12.8-3) ... 87s Preparing to unpack .../6-python3-requests_2.32.3+dfsg-5ubuntu2_all.deb ... 87s Unpacking python3-requests (2.32.3+dfsg-5ubuntu2) over (2.32.3+dfsg-5ubuntu1) ... 87s Setting up libpython3.12-minimal:s390x (3.12.10-1) ... 87s Setting up python3-requests (2.32.3+dfsg-5ubuntu2) ... 87s Setting up initramfs-tools-bin (0.149ubuntu1) ... 87s Setting up libpython3.12-stdlib:s390x (3.12.10-1) ... 87s Setting up libpython3.12t64:s390x (3.12.10-1) ... 87s Setting up initramfs-tools-core (0.149ubuntu1) ... 87s Setting up initramfs-tools (0.149ubuntu1) ... 87s update-initramfs: deferring update (trigger activated) 87s Processing triggers for man-db (2.13.1-1) ... 88s Processing triggers for libc-bin (2.41-6ubuntu2) ... 88s Processing triggers for initramfs-tools (0.149ubuntu1) ... 88s update-initramfs: Generating /boot/initrd.img-6.14.0-15-generic 88s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 90s Using config file '/etc/zipl.conf' 90s Building bootmap in '/boot' 90s Adding IPL section 'ubuntu' (default) 90s Preparing boot device for LD-IPL: vda (0000). 90s Done. 91s Reading package lists... 91s Building dependency tree... 91s Reading state information... 91s Solving dependencies... 91s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 91s autopkgtest [17:00:33]: rebooting testbed after setup commands that affected boot 111s autopkgtest [17:00:53]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 13:39:00 UTC 2025 113s autopkgtest [17:00:55]: @@@@@@@@@@@@@@@@@@@@ apt-source advocate 114s Get:1 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (dsc) [2309 B] 114s Get:2 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (tar) [37.4 kB] 114s Get:3 http://ftpmaster.internal/ubuntu questing/universe advocate 1.0.0-7 (diff) [6728 B] 115s gpgv: Signature made Sun Jan 12 00:02:12 2025 UTC 115s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 115s gpgv: issuer "tchet@debian.org" 115s gpgv: Can't check signature: No public key 115s dpkg-source: warning: cannot verify inline signature for ./advocate_1.0.0-7.dsc: no acceptable signature found 115s autopkgtest [17:00:57]: testing package advocate version 1.0.0-7 115s autopkgtest [17:00:57]: build not needed 115s autopkgtest [17:00:57]: test run-tests: preparing testbed 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s Solving dependencies... 116s The following NEW packages will be installed: 116s python3-advocate python3-all python3-iniconfig python3-ndg-httpsclient 116s python3-netifaces python3-packaging python3-pluggy python3-pyasn1 116s python3-pytest python3-requests-futures python3-requests-mock 116s 0 upgraded, 11 newly installed, 0 to remove and 0 not upgraded. 116s Need to get 498 kB of archives. 116s After this operation, 2564 kB of additional disk space will be used. 116s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x python3-netifaces s390x 0.11.0-2build5 [18.6 kB] 116s Get:2 http://ftpmaster.internal/ubuntu questing/main s390x python3-pyasn1 all 0.6.1-1 [56.4 kB] 116s Get:3 http://ftpmaster.internal/ubuntu questing/universe s390x python3-ndg-httpsclient all 0.5.1-8 [25.2 kB] 116s Get:4 http://ftpmaster.internal/ubuntu questing/universe s390x python3-advocate all 1.0.0-7 [34.7 kB] 116s Get:5 http://ftpmaster.internal/ubuntu questing/main s390x python3-all s390x 3.13.4-1 [880 B] 116s Get:6 http://ftpmaster.internal/ubuntu questing/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 116s Get:7 http://ftpmaster.internal/ubuntu questing/main s390x python3-packaging all 25.0-1 [52.8 kB] 116s Get:8 http://ftpmaster.internal/ubuntu questing/universe s390x python3-pluggy all 1.5.0-1 [21.0 kB] 116s Get:9 http://ftpmaster.internal/ubuntu questing/universe s390x python3-pytest all 8.3.5-2 [252 kB] 116s Get:10 http://ftpmaster.internal/ubuntu questing/universe s390x python3-requests-futures all 1.0.2-1 [12.0 kB] 116s Get:11 http://ftpmaster.internal/ubuntu questing/universe s390x python3-requests-mock all 1.12.1-3 [17.8 kB] 116s Fetched 498 kB in 1s (908 kB/s) 116s Selecting previously unselected package python3-netifaces:s390x. 116s (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 ... 82197 files and directories currently installed.) 116s Preparing to unpack .../00-python3-netifaces_0.11.0-2build5_s390x.deb ... 116s Unpacking python3-netifaces:s390x (0.11.0-2build5) ... 116s Selecting previously unselected package python3-pyasn1. 116s Preparing to unpack .../01-python3-pyasn1_0.6.1-1_all.deb ... 116s Unpacking python3-pyasn1 (0.6.1-1) ... 116s Selecting previously unselected package python3-ndg-httpsclient. 116s Preparing to unpack .../02-python3-ndg-httpsclient_0.5.1-8_all.deb ... 116s Unpacking python3-ndg-httpsclient (0.5.1-8) ... 116s Selecting previously unselected package python3-advocate. 117s Preparing to unpack .../03-python3-advocate_1.0.0-7_all.deb ... 117s Unpacking python3-advocate (1.0.0-7) ... 117s Selecting previously unselected package python3-all. 117s Preparing to unpack .../04-python3-all_3.13.4-1_s390x.deb ... 117s Unpacking python3-all (3.13.4-1) ... 117s Selecting previously unselected package python3-iniconfig. 117s Preparing to unpack .../05-python3-iniconfig_1.1.1-2_all.deb ... 117s Unpacking python3-iniconfig (1.1.1-2) ... 117s Selecting previously unselected package python3-packaging. 117s Preparing to unpack .../06-python3-packaging_25.0-1_all.deb ... 117s Unpacking python3-packaging (25.0-1) ... 117s Selecting previously unselected package python3-pluggy. 117s Preparing to unpack .../07-python3-pluggy_1.5.0-1_all.deb ... 117s Unpacking python3-pluggy (1.5.0-1) ... 117s Selecting previously unselected package python3-pytest. 117s Preparing to unpack .../08-python3-pytest_8.3.5-2_all.deb ... 117s Unpacking python3-pytest (8.3.5-2) ... 117s Selecting previously unselected package python3-requests-futures. 117s Preparing to unpack .../09-python3-requests-futures_1.0.2-1_all.deb ... 117s Unpacking python3-requests-futures (1.0.2-1) ... 117s Selecting previously unselected package python3-requests-mock. 117s Preparing to unpack .../10-python3-requests-mock_1.12.1-3_all.deb ... 117s Unpacking python3-requests-mock (1.12.1-3) ... 117s Setting up python3-iniconfig (1.1.1-2) ... 117s Setting up python3-all (3.13.4-1) ... 117s Setting up python3-packaging (25.0-1) ... 117s Setting up python3-requests-mock (1.12.1-3) ... 117s Setting up python3-pluggy (1.5.0-1) ... 117s Setting up python3-netifaces:s390x (0.11.0-2build5) ... 117s Setting up python3-pyasn1 (0.6.1-1) ... 117s Setting up python3-requests-futures (1.0.2-1) ... 117s Setting up python3-pytest (8.3.5-2) ... 117s Setting up python3-ndg-httpsclient (0.5.1-8) ... 117s Setting up python3-advocate (1.0.0-7) ... 117s Processing triggers for man-db (2.13.1-1) ... 119s autopkgtest [17:01:01]: test run-tests: [----------------------- 119s ************************************************************************** 119s # A new feature in cloud-init identified possible datasources for # 119s # this system as: # 119s # [] # 119s # However, the datasource used was: OpenStack # 119s # # 119s # In the future, cloud-init will only attempt to use datasources that # 119s # are identified or specifically configured. # 119s # For more information see # 119s # https://bugs.launchpad.net/bugs/1669675 # 119s # # 119s # If you are seeing this message, please file a bug against # 119s # cloud-init at # 119s # https://github.com/canonical/cloud-init/issues # 119s # Make sure to include the cloud provider your instance is # 119s # running on. # 119s # # 119s # After you have filed a bug, you can disable this warning by launching # 119s # your instance with the cloud-config below, or putting that content # 119s # into /etc/cloud/cloud.cfg.d/99-warnings.cfg # 119s # # 119s # #cloud-config # 119s # warnings: # 119s # dsid_missing_source: off # 119s ************************************************************************** 119s 119s Disable the warnings above by: 119s touch /home/ubuntu/.cloud-warnings.skip 119s or 119s touch /var/lib/cloud/instance/warnings/.skip 119s ============================= test session starts ============================== 119s platform linux -- Python 3.13.5, pytest-8.3.5, pluggy-1.5.0 -- /usr/bin/python3.13 119s cachedir: .pytest_cache 119s rootdir: /tmp/autopkgtest.XyhIBx/autopkgtest_tmp 119s plugins: requests_mock-1.12.1, typeguard-4.4.2 119s collecting ... collected 48 items 119s 119s test/test_advocate.py::TestWrapperTests::test_unsafe_connect_raises SKIPPED [ 2%] 119s test/test_advocate.py::ValidateIPTests::test_dns64 PASSED [ 4%] 119s test/test_advocate.py::ValidateIPTests::test_ip_whitelist PASSED [ 6%] 119s test/test_advocate.py::ValidateIPTests::test_ip_whitelist_blacklist_conflict PASSED [ 8%] 119s test/test_advocate.py::ValidateIPTests::test_ipv4_mapped PASSED [ 10%] 119s test/test_advocate.py::ValidateIPTests::test_ipv6 PASSED [ 12%] 119s test/test_advocate.py::ValidateIPTests::test_link_local PASSED [ 14%] 119s test/test_advocate.py::ValidateIPTests::test_loopback PASSED [ 16%] 119s test/test_advocate.py::ValidateIPTests::test_manual_ip_blacklist PASSED [ 18%] 119s test/test_advocate.py::ValidateIPTests::test_multicast PASSED [ 20%] 119s test/test_advocate.py::ValidateIPTests::test_parsed PASSED [ 22%] 119s test/test_advocate.py::ValidateIPTests::test_private PASSED [ 25%] 119s test/test_advocate.py::ValidateIPTests::test_reserved PASSED [ 27%] 119s test/test_advocate.py::ValidateIPTests::test_safecurl_blacklist SKIPPED [ 29%] 119s test/test_advocate.py::ValidateIPTests::test_site_local PASSED [ 31%] 119s test/test_advocate.py::ValidateIPTests::test_sixtofour PASSED [ 33%] 119s test/test_advocate.py::ValidateIPTests::test_teredo PASSED [ 35%] 119s test/test_advocate.py::ValidateIPTests::test_unspecified PASSED [ 37%] 119s test/test_advocate.py::AddrInfoTests::test_default_port_whitelist PASSED [ 39%] 119s test/test_advocate.py::AddrInfoTests::test_local_address_handling PASSED [ 41%] 119s test/test_advocate.py::AddrInfoTests::test_malformed_addrinfo SKIPPED [ 43%] 119s test/test_advocate.py::AddrInfoTests::test_port_blacklist PASSED [ 45%] 119s test/test_advocate.py::AddrInfoTests::test_port_whitelist PASSED [ 47%] 119s test/test_advocate.py::AddrInfoTests::test_simple PASSED [ 50%] 119s test/test_advocate.py::AddrInfoTests::test_unexpected_proto SKIPPED [ 52%] 119s test/test_advocate.py::HostnameTests::test_embedded_null SKIPPED (Na...) [ 54%] 119s test/test_advocate.py::HostnameTests::test_idn SKIPPED (Nameserver d...) [ 56%] 119s test/test_advocate.py::HostnameTests::test_missing_canonname SKIPPED [ 58%] 119s test/test_advocate.py::HostnameTests::test_no_blacklist SKIPPED (Nam...) [ 60%] 119s test/test_advocate.py::ConnectionPoolingTests::test_connection_reuse SKIPPED [ 62%] 119s test/test_advocate.py::AdvocateWrapperTests::test_advocate_requests_api_wrapper PASSED [ 64%] 119s test/test_advocate.py::AdvocateWrapperTests::test_advocate_requests_api_wrapper_hostnames SKIPPED [ 66%] 119s test/test_advocate.py::AdvocateWrapperTests::test_advocate_requests_api_wrapper_req_methods PASSED [ 68%] 119s test/test_advocate.py::AdvocateWrapperTests::test_blacklist_hostname SKIPPED [ 70%] 119s test/test_advocate.py::AdvocateWrapperTests::test_connect_without_local_addresses SKIPPED [ 72%] 119s test/test_advocate.py::AdvocateWrapperTests::test_get SKIPPED (netwo...) [ 75%] 119s test/test_advocate.py::AdvocateWrapperTests::test_mount_disabled PASSED [ 77%] 119s test/test_advocate.py::AdvocateWrapperTests::test_proxy_attempt_throws PASSED [ 79%] 119s test/test_advocate.py::AdvocateWrapperTests::test_redirect SKIPPED [ 81%] 119s test/test_advocate.py::AdvocateWrapperTests::test_validator PASSED [ 83%] 119s test/test_advocate.py::AdvocateWrapperTests::test_wrapper_getattr_fallback PASSED [ 85%] 119s test/test_advocate.py::AdvocateWrapperTests::test_wrapper_session_pickle PASSED [ 87%] 119s test/test_advocate.py::AdvocateWrapperTests::test_wrapper_session_subclass PASSED [ 89%] 119s test/test_advocate.py::AdvocateFuturesTest::test_advocate_wrapper_futures SKIPPED [ 91%] 119s test/test_advocate.py::AdvocateFuturesTest::test_custom_validator SKIPPED [ 93%] 119s test/test_advocate.py::AdvocateFuturesTest::test_get SKIPPED (networ...) [ 95%] 119s test/test_advocate.py::AdvocateFuturesTest::test_many_workers SKIPPED [ 97%] 119s test/test_advocate.py::AdvocateFuturesTest::test_passing_session SKIPPED [100%] 119s 119s ======================== 29 passed, 19 skipped in 0.06s ======================== 120s autopkgtest [17:01:02]: test run-tests: -----------------------] 120s autopkgtest [17:01:02]: test run-tests: - - - - - - - - - - results - - - - - - - - - - 120s run-tests PASS 120s autopkgtest [17:01:02]: @@@@@@@@@@@@@@@@@@@@ summary 120s run-tests PASS 139s nova [W] Using flock in prodstack6-s390x 139s Creating nova instance adt-questing-s390x-advocate-20250617-165901-juju-7f2275-prod-proposed-migration-environment-23-37985f7d-1a95-4e45-a538-740e7b11b11d from image adt/ubuntu-questing-s390x-server-20250617.img (UUID c548ae27-782e-4636-945c-1e994bac7c38)... 139s nova [W] Timed out waiting for ea583cb7-ea2e-406b-af54-3833d82d75c0 to get deleted.