2026-07-18 03:04:37.438926 | Job console starting 2026-07-18 03:04:37.459665 | Updating git repos 2026-07-18 03:04:37.526923 | Cloning repos into workspace 2026-07-18 03:04:37.614998 | Restoring repo states 2026-07-18 03:04:37.634493 | Merging changes 2026-07-18 03:04:37.634520 | Checking out repos 2026-07-18 03:04:37.809811 | Preparing playbooks 2026-07-18 03:04:39.543108 | Running Ansible setup 2026-07-18 03:04:44.796812 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-18 03:04:45.760808 | 2026-07-18 03:04:45.761032 | PLAY [Base pre] 2026-07-18 03:04:45.782024 | 2026-07-18 03:04:45.782227 | TASK [Setup log path fact] 2026-07-18 03:04:45.805382 | debian-bookworm | ok 2026-07-18 03:04:45.828702 | 2026-07-18 03:04:45.828904 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-18 03:04:45.900646 | debian-bookworm | ok 2026-07-18 03:04:45.916673 | 2026-07-18 03:04:45.916843 | TASK [emit-job-header : Print job information] 2026-07-18 03:04:46.005267 | # Job Information 2026-07-18 03:04:46.005553 | Ansible Version: 2.16.14 2026-07-18 03:04:46.005591 | Job: container-image-inventory-reconciler-unit-tests 2026-07-18 03:04:46.005627 | Pipeline: periodic-daily 2026-07-18 03:04:46.005650 | Executor: 521e9411259a 2026-07-18 03:04:46.005671 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2026-07-18 03:04:46.005692 | Event ID: f01e9786a4de4da2b4da8471d107055f 2026-07-18 03:04:46.011189 | 2026-07-18 03:04:46.011367 | LOOP [emit-job-header : Print node information] 2026-07-18 03:04:46.154817 | debian-bookworm | ok: 2026-07-18 03:04:46.155574 | debian-bookworm | # Node Information 2026-07-18 03:04:46.155637 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-18 03:04:46.155664 | debian-bookworm | Hostname: debian 2026-07-18 03:04:46.155687 | debian-bookworm | Username: zuul 2026-07-18 03:04:46.155708 | debian-bookworm | Distro: Debian 12.15 2026-07-18 03:04:46.155732 | debian-bookworm | Provider: regiocloud-a 2026-07-18 03:04:46.155753 | debian-bookworm | Region: 2026-07-18 03:04:46.155774 | debian-bookworm | Label: debian-bookworm 2026-07-18 03:04:46.155794 | debian-bookworm | Product Name: OpenStack Nova 2026-07-18 03:04:46.155815 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feeb:7b38 2026-07-18 03:04:46.176600 | 2026-07-18 03:04:46.176763 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-18 03:04:46.941182 | debian-bookworm -> localhost | changed 2026-07-18 03:04:46.954233 | 2026-07-18 03:04:46.954401 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-18 03:04:48.791721 | debian-bookworm -> localhost | changed 2026-07-18 03:04:48.837624 | 2026-07-18 03:04:48.837820 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-18 03:04:49.650880 | debian-bookworm -> localhost | ok 2026-07-18 03:04:49.658284 | 2026-07-18 03:04:49.658424 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-18 03:04:49.716736 | debian-bookworm | ok 2026-07-18 03:04:49.762638 | debian-bookworm | included: /var/lib/zuul/builds/cd1525241af14a79bb4bfb0cdcd7837d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-18 03:04:49.775965 | 2026-07-18 03:04:49.776082 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-18 03:04:51.670922 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-18 03:04:51.671133 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/cd1525241af14a79bb4bfb0cdcd7837d/work/cd1525241af14a79bb4bfb0cdcd7837d_id_rsa 2026-07-18 03:04:51.671171 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/cd1525241af14a79bb4bfb0cdcd7837d/work/cd1525241af14a79bb4bfb0cdcd7837d_id_rsa.pub 2026-07-18 03:04:51.671198 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-18 03:04:51.671223 | debian-bookworm -> localhost | SHA256:x9uazTPGWl4zqaNPNLbyDbkh5i0VxOWsXm2qMwc5qwg zuul-build-sshkey 2026-07-18 03:04:51.671258 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-18 03:04:51.671281 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-18 03:04:51.671304 | debian-bookworm -> localhost | | . .. | 2026-07-18 03:04:51.671355 | debian-bookworm -> localhost | | oo | 2026-07-18 03:04:51.671380 | debian-bookworm -> localhost | | . o | 2026-07-18 03:04:51.671401 | debian-bookworm -> localhost | | . .. . | 2026-07-18 03:04:51.671421 | debian-bookworm -> localhost | | S o =o. o| 2026-07-18 03:04:51.671448 | debian-bookworm -> localhost | | . *== + | 2026-07-18 03:04:51.671469 | debian-bookworm -> localhost | | E =oX+* | 2026-07-18 03:04:51.671489 | debian-bookworm -> localhost | | . +.&XO.o | 2026-07-18 03:04:51.671510 | debian-bookworm -> localhost | | . BB@B. | 2026-07-18 03:04:51.671530 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-18 03:04:51.671585 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.007029 2026-07-18 03:04:51.678303 | 2026-07-18 03:04:51.678430 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-18 03:04:51.737820 | debian-bookworm | ok 2026-07-18 03:04:51.752199 | debian-bookworm | included: /var/lib/zuul/builds/cd1525241af14a79bb4bfb0cdcd7837d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-18 03:04:51.798298 | 2026-07-18 03:04:51.798484 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-18 03:04:51.851944 | debian-bookworm | skipping: Conditional result was False 2026-07-18 03:04:51.859273 | 2026-07-18 03:04:51.859414 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-18 03:04:52.772379 | debian-bookworm | changed 2026-07-18 03:04:52.779051 | 2026-07-18 03:04:52.779133 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-18 03:04:53.067729 | debian-bookworm | ok 2026-07-18 03:04:53.076239 | 2026-07-18 03:04:53.076365 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-18 03:04:53.732480 | debian-bookworm | changed 2026-07-18 03:04:53.747609 | 2026-07-18 03:04:53.747935 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-18 03:04:54.456050 | debian-bookworm | changed 2026-07-18 03:04:54.463430 | 2026-07-18 03:04:54.463551 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-18 03:04:54.487838 | debian-bookworm | skipping: Conditional result was False 2026-07-18 03:04:54.493772 | 2026-07-18 03:04:54.493863 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-18 03:04:54.932736 | debian-bookworm -> localhost | changed 2026-07-18 03:04:54.955799 | 2026-07-18 03:04:54.955924 | TASK [add-build-sshkey : Add back temp key] 2026-07-18 03:04:55.349621 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/cd1525241af14a79bb4bfb0cdcd7837d/work/cd1525241af14a79bb4bfb0cdcd7837d_id_rsa (zuul-build-sshkey) 2026-07-18 03:04:55.349839 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014572 2026-07-18 03:04:55.357363 | 2026-07-18 03:04:55.357469 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-18 03:04:55.788661 | debian-bookworm | ok 2026-07-18 03:04:55.793922 | 2026-07-18 03:04:55.794026 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-18 03:04:55.854916 | debian-bookworm | skipping: Conditional result was False 2026-07-18 03:04:55.865540 | 2026-07-18 03:04:55.865655 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-18 03:04:56.474425 | debian-bookworm | ok 2026-07-18 03:04:56.497508 | 2026-07-18 03:04:56.497627 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-18 03:04:56.593300 | debian-bookworm | ok 2026-07-18 03:04:56.604458 | 2026-07-18 03:04:56.604582 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-18 03:04:57.144306 | debian-bookworm -> localhost | ok 2026-07-18 03:04:57.156205 | 2026-07-18 03:04:57.156396 | TASK [validate-host : Collect information about the host] 2026-07-18 03:04:58.297091 | debian-bookworm | ok 2026-07-18 03:04:58.326869 | 2026-07-18 03:04:58.327011 | TASK [validate-host : Sanitize hostname] 2026-07-18 03:04:58.502859 | debian-bookworm | ok 2026-07-18 03:04:58.508938 | 2026-07-18 03:04:58.509097 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-18 03:04:59.804707 | debian-bookworm -> localhost | changed 2026-07-18 03:04:59.813128 | 2026-07-18 03:04:59.813284 | TASK [validate-host : Collect information about zuul worker] 2026-07-18 03:05:00.543576 | debian-bookworm | ok 2026-07-18 03:05:00.549645 | 2026-07-18 03:05:00.549785 | TASK [validate-host : Write out all zuul information for each host] 2026-07-18 03:05:02.339458 | debian-bookworm -> localhost | changed 2026-07-18 03:05:02.359874 | 2026-07-18 03:05:02.360097 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-18 03:05:02.704334 | debian-bookworm | ok 2026-07-18 03:05:02.712200 | 2026-07-18 03:05:02.712349 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-18 03:05:04.389426 | debian-bookworm | changed: 2026-07-18 03:05:04.389610 | debian-bookworm | cd+++++++++ src/ 2026-07-18 03:05:04.389646 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-18 03:05:04.389670 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-18 03:05:04.389692 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2026-07-18 03:05:04.389713 | debian-bookworm | Containerfile 2026-07-18 03:05:04.389869 | debian-bookworm | =2.0 (from Jinja2==3.1.6->-r files/requirements.txt (line 1)) 2026-07-18 03:05:31.859519 | debian-bookworm | Downloading markupsafe-3.0.3-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2026-07-18 03:05:31.927435 | debian-bookworm | Collecting PyYAML>=5.1 (from ansible-core==2.19.3->-r files/requirements.txt (line 2)) 2026-07-18 03:05:31.938964 | debian-bookworm | Downloading pyyaml-6.0.3-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2026-07-18 03:05:32.225133 | debian-bookworm | Collecting cryptography (from ansible-core==2.19.3->-r files/requirements.txt (line 2)) 2026-07-18 03:05:32.237477 | debian-bookworm | Downloading cryptography-49.0.0-cp311-abi3-manylinux_2_34_x86_64.whl.metadata (4.3 kB) 2026-07-18 03:05:32.286849 | debian-bookworm | Collecting packaging (from ansible-core==2.19.3->-r files/requirements.txt (line 2)) 2026-07-18 03:05:32.298983 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl.metadata (3.5 kB) 2026-07-18 03:05:32.335992 | debian-bookworm | Collecting resolvelib<2.0.0,>=0.5.3 (from ansible-core==2.19.3->-r files/requirements.txt (line 2)) 2026-07-18 03:05:32.347922 | debian-bookworm | Downloading resolvelib-1.2.1-py3-none-any.whl.metadata (3.7 kB) 2026-07-18 03:05:32.398950 | debian-bookworm | Collecting requests<3.0,>=2.20.0 (from pynetbox==7.8.0->-r files/requirements.txt (line 5)) 2026-07-18 03:05:32.410374 | debian-bookworm | Downloading requests-2.34.2-py3-none-any.whl.metadata (4.8 kB) 2026-07-18 03:05:32.446810 | debian-bookworm | Collecting iniconfig>=1.0.1 (from pytest==9.1.1->-r files/test-requirements.txt (line 2)) 2026-07-18 03:05:32.460264 | debian-bookworm | Downloading iniconfig-2.3.0-py3-none-any.whl.metadata (2.5 kB) 2026-07-18 03:05:32.502680 | debian-bookworm | Collecting pluggy<2,>=1.5 (from pytest==9.1.1->-r files/test-requirements.txt (line 2)) 2026-07-18 03:05:32.515187 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB) 2026-07-18 03:05:32.563059 | debian-bookworm | Collecting pygments>=2.7.2 (from pytest==9.1.1->-r files/test-requirements.txt (line 2)) 2026-07-18 03:05:32.574823 | debian-bookworm | Downloading pygments-2.20.0-py3-none-any.whl.metadata (2.5 kB) 2026-07-18 03:05:33.101846 | debian-bookworm | Collecting coverage>=7.10.6 (from coverage[toml]>=7.10.6->pytest-cov==7.1.0->-r files/test-requirements.txt (line 3)) 2026-07-18 03:05:33.114992 | debian-bookworm | Downloading coverage-7.15.2-cp311-cp311-manylinux1_x86_64.manylinux_2_28_x86_64.manylinux_2_5_x86_64.whl.metadata (8.6 kB) 2026-07-18 03:05:33.256661 | debian-bookworm | Collecting charset_normalizer<4,>=2 (from requests<3.0,>=2.20.0->pynetbox==7.8.0->-r files/requirements.txt (line 5)) 2026-07-18 03:05:33.268675 | debian-bookworm | Downloading charset_normalizer-3.4.9-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (41 kB) 2026-07-18 03:05:33.310207 | debian-bookworm | Collecting idna<4,>=2.5 (from requests<3.0,>=2.20.0->pynetbox==7.8.0->-r files/requirements.txt (line 5)) 2026-07-18 03:05:33.321414 | debian-bookworm | Downloading idna-3.18-py3-none-any.whl.metadata (6.1 kB) 2026-07-18 03:05:33.364766 | debian-bookworm | Collecting urllib3<3,>=1.26 (from requests<3.0,>=2.20.0->pynetbox==7.8.0->-r files/requirements.txt (line 5)) 2026-07-18 03:05:33.376410 | debian-bookworm | Downloading urllib3-2.7.0-py3-none-any.whl.metadata (6.9 kB) 2026-07-18 03:05:33.418375 | debian-bookworm | Collecting certifi>=2023.5.7 (from requests<3.0,>=2.20.0->pynetbox==7.8.0->-r files/requirements.txt (line 5)) 2026-07-18 03:05:33.429527 | debian-bookworm | Downloading certifi-2026.6.17-py3-none-any.whl.metadata (2.5 kB) 2026-07-18 03:05:33.626124 | debian-bookworm | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.19.3->-r files/requirements.txt (line 2)) 2026-07-18 03:05:33.637898 | debian-bookworm | Downloading cffi-2.1.0-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.5 kB) 2026-07-18 03:05:33.669376 | debian-bookworm | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.19.3->-r files/requirements.txt (line 2)) 2026-07-18 03:05:33.681192 | debian-bookworm | Downloading pycparser-3.0-py3-none-any.whl.metadata (8.2 kB) 2026-07-18 03:05:33.704955 | debian-bookworm | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-07-18 03:05:33.740503 | debian-bookworm | Downloading ansible_core-2.19.3-py3-none-any.whl (2.4 MB) 2026-07-18 03:05:33.796541 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 2.4/2.4 MB 50.4 MB/s 0:00:00 2026-07-18 03:05:33.812649 | debian-bookworm | Downloading dynaconf-3.3.2-py3-none-any.whl (270 kB) 2026-07-18 03:05:33.841080 | debian-bookworm | Downloading netaddr-1.3.0-py3-none-any.whl (2.3 MB) 2026-07-18 03:05:34.057187 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 2.3/2.3 MB 9.7 MB/s 0:00:00 2026-07-18 03:05:34.073234 | debian-bookworm | Downloading pynetbox-7.8.0-py3-none-any.whl (54 kB) 2026-07-18 03:05:34.101118 | debian-bookworm | Downloading pytest-9.1.1-py3-none-any.whl (386 kB) 2026-07-18 03:05:34.130309 | debian-bookworm | Downloading pytest_cov-7.1.0-py3-none-any.whl (22 kB) 2026-07-18 03:05:34.155108 | debian-bookworm | Downloading pytest_mock-3.15.1-py3-none-any.whl (10 kB) 2026-07-18 03:05:34.177790 | debian-bookworm | Downloading loguru-0.7.3-py3-none-any.whl (61 kB) 2026-07-18 03:05:34.200369 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-07-18 03:05:34.225828 | debian-bookworm | Downloading requests-2.34.2-py3-none-any.whl (73 kB) 2026-07-18 03:05:34.249072 | debian-bookworm | Downloading charset_normalizer-3.4.9-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (221 kB) 2026-07-18 03:05:34.275230 | debian-bookworm | Downloading idna-3.18-py3-none-any.whl (65 kB) 2026-07-18 03:05:34.296618 | debian-bookworm | Downloading resolvelib-1.2.1-py3-none-any.whl (18 kB) 2026-07-18 03:05:34.318274 | debian-bookworm | Downloading urllib3-2.7.0-py3-none-any.whl (131 kB) 2026-07-18 03:05:34.343020 | debian-bookworm | Downloading certifi-2026.6.17-py3-none-any.whl (133 kB) 2026-07-18 03:05:34.369309 | debian-bookworm | Downloading coverage-7.15.2-cp311-cp311-manylinux1_x86_64.manylinux_2_28_x86_64.manylinux_2_5_x86_64.whl (254 kB) 2026-07-18 03:05:34.395511 | debian-bookworm | Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB) 2026-07-18 03:05:34.416897 | debian-bookworm | Downloading markupsafe-3.0.3-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (22 kB) 2026-07-18 03:05:34.438910 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-07-18 03:05:34.464428 | debian-bookworm | Downloading pygments-2.20.0-py3-none-any.whl (1.2 MB) 2026-07-18 03:05:34.600251 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 7.6 MB/s 0:00:00 2026-07-18 03:05:34.614664 | debian-bookworm | Downloading pyyaml-6.0.3-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB) 2026-07-18 03:05:34.635863 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.6/806.6 kB 35.0 MB/s 0:00:00 2026-07-18 03:05:34.649038 | debian-bookworm | Downloading cryptography-49.0.0-cp311-abi3-manylinux_2_34_x86_64.whl (4.7 MB) 2026-07-18 03:05:34.740302 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.7/4.7 MB 54.2 MB/s 0:00:00 2026-07-18 03:05:34.755012 | debian-bookworm | Downloading cffi-2.1.0-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (217 kB) 2026-07-18 03:05:34.781980 | debian-bookworm | Downloading pycparser-3.0-py3-none-any.whl (48 kB) 2026-07-18 03:05:34.910024 | debian-bookworm | Installing collected packages: urllib3, resolvelib, PyYAML, pygments, pycparser, pluggy, packaging, netaddr, MarkupSafe, loguru, iniconfig, idna, dynaconf, coverage, charset_normalizer, certifi, requests, pytest, Jinja2, cffi, pytest-mock, pytest-cov, pynetbox, cryptography, ansible-core 2026-07-18 03:05:39.160678 | debian-bookworm | 2026-07-18 03:05:39.163106 | debian-bookworm | Successfully installed Jinja2-3.1.6 MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.19.3 certifi-2026.6.17 cffi-2.1.0 charset_normalizer-3.4.9 coverage-7.15.2 cryptography-49.0.0 dynaconf-3.3.2 idna-3.18 iniconfig-2.3.0 loguru-0.7.3 netaddr-1.3.0 packaging-26.2 pluggy-1.6.0 pycparser-3.0 pygments-2.20.0 pynetbox-7.8.0 pytest-9.1.1 pytest-cov-7.1.0 pytest-mock-3.15.1 requests-2.34.2 resolvelib-1.2.1 urllib3-2.7.0 2026-07-18 03:05:39.684324 | debian-bookworm | ok: Runtime: 0:00:15.590818 2026-07-18 03:05:39.690583 | 2026-07-18 03:05:39.690695 | TASK [Run pytest] 2026-07-18 03:05:40.055798 | debian-bookworm | + /tmp/test-unit-venv/bin/pytest tests/unit 2026-07-18 03:05:41.320058 | debian-bookworm | ============================= test session starts ============================== 2026-07-18 03:05:41.320217 | debian-bookworm | platform linux -- Python 3.11.2, pytest-9.1.1, pluggy-1.6.0 2026-07-18 03:05:41.320245 | debian-bookworm | rootdir: /home/zuul/src/github.com/osism/container-image-inventory-reconciler 2026-07-18 03:05:41.320264 | debian-bookworm | configfile: pyproject.toml 2026-07-18 03:05:41.320278 | debian-bookworm | plugins: cov-7.1.0, mock-3.15.1 2026-07-18 03:05:41.320297 | debian-bookworm | collected 606 items 2026-07-18 03:05:41.320348 | debian-bookworm | 2026-07-18 03:05:41.326690 | debian-bookworm | tests/unit/netbox/test_base_extractor.py ...... [ 0%] 2026-07-18 03:05:41.375759 | debian-bookworm | tests/unit/netbox/test_bulk_loader.py ........................... [ 5%] 2026-07-18 03:05:41.388646 | debian-bookworm | tests/unit/netbox/test_cache.py ............. [ 7%] 2026-07-18 03:05:41.417676 | debian-bookworm | tests/unit/netbox/test_config.py .......................... [ 11%] 2026-07-18 03:05:41.424672 | debian-bookworm | tests/unit/netbox/test_config_context_extractor.py ......... [ 13%] 2026-07-18 03:05:41.456612 | debian-bookworm | tests/unit/netbox/test_connection.py ............. [ 15%] 2026-07-18 03:05:41.467402 | debian-bookworm | tests/unit/netbox/test_custom_field_extractor.py ............. [ 17%] 2026-07-18 03:05:41.484184 | debian-bookworm | tests/unit/netbox/test_device_mapping.py .................. [ 20%] 2026-07-18 03:05:41.497138 | debian-bookworm | tests/unit/netbox/test_dnsmasq_base.py ..... [ 21%] 2026-07-18 03:05:41.534538 | debian-bookworm | tests/unit/netbox/test_dnsmasq_dhcp_config.py ..................... [ 24%] 2026-07-18 03:05:41.547034 | debian-bookworm | tests/unit/netbox/test_dnsmasq_interface_handler.py ......... [ 26%] 2026-07-18 03:05:41.571047 | debian-bookworm | tests/unit/netbox/test_dnsmasq_manager.py ........ [ 27%] 2026-07-18 03:05:41.598728 | debian-bookworm | tests/unit/netbox/test_dnsmasq_manager_mode.py ........... [ 29%] 2026-07-18 03:05:41.635872 | debian-bookworm | tests/unit/netbox/test_dnsmasq_metalbox_mode.py ........................ [ 33%] 2026-07-18 03:05:41.746981 | debian-bookworm | ........................................ [ 40%] 2026-07-18 03:05:41.753434 | debian-bookworm | tests/unit/netbox/test_exceptions.py ........ [ 41%] 2026-07-18 03:05:41.778170 | debian-bookworm | tests/unit/netbox/test_filters.py ...................................... [ 47%] 2026-07-18 03:05:41.784382 | debian-bookworm | ......... [ 49%] 2026-07-18 03:05:41.808545 | debian-bookworm | tests/unit/netbox/test_frr_extractor.py ................................ [ 54%] 2026-07-18 03:05:41.883355 | debian-bookworm | ............................................................... [ 64%] 2026-07-18 03:05:41.908065 | debian-bookworm | tests/unit/netbox/test_gnmic_extractor.py ........................... [ 69%] 2026-07-18 03:05:41.942376 | debian-bookworm | tests/unit/netbox/test_interfaces.py ........................... [ 73%] 2026-07-18 03:05:41.965593 | debian-bookworm | tests/unit/netbox/test_netplan_extractor.py ............................ [ 78%] 2026-07-18 03:05:42.019699 | debian-bookworm | .................................................. [ 86%] 2026-07-18 03:05:42.047344 | debian-bookworm | tests/unit/netbox/test_parallel_processor.py ................... [ 89%] 2026-07-18 03:05:42.052558 | debian-bookworm | tests/unit/netbox/test_primary_ip_extractor.py ....... [ 90%] 2026-07-18 03:05:42.071155 | debian-bookworm | tests/unit/netbox/test_retry_utils.py .................... [ 94%] 2026-07-18 03:05:42.081095 | debian-bookworm | tests/unit/netbox/test_secrets_extractor.py ............. [ 96%] 2026-07-18 03:05:42.083221 | debian-bookworm | tests/unit/netbox/test_smoke.py ... [ 96%] 2026-07-18 03:05:42.162554 | debian-bookworm | tests/unit/netbox/test_utils.py ................... [100%] 2026-07-18 03:05:42.162661 | debian-bookworm | 2026-07-18 03:05:42.162678 | debian-bookworm | ============================= 606 passed in 1.78s ============================== 2026-07-18 03:05:42.746534 | debian-bookworm | ok: Runtime: 0:00:02.242573 2026-07-18 03:05:42.750584 | 2026-07-18 03:05:42.750898 | PLAY RECAP 2026-07-18 03:05:42.750995 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-18 03:05:42.751026 | 2026-07-18 03:05:43.036461 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-inventory-reconciler/playbooks/test-unit.yml@main] 2026-07-18 03:05:43.037487 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-18 03:05:44.922762 | 2026-07-18 03:05:44.923289 | PLAY [Base post-fetch] 2026-07-18 03:05:44.938701 | 2026-07-18 03:05:44.938972 | TASK [diagnose-network : Ensure log directory exists] 2026-07-18 03:05:45.588405 | debian-bookworm | ok 2026-07-18 03:05:45.595831 | 2026-07-18 03:05:45.595943 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-07-18 03:05:57.857541 | debian-bookworm | ok: Runtime: 0:00:05.860792 2026-07-18 03:05:57.872416 | 2026-07-18 03:05:57.872639 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-07-18 03:06:03.953031 | debian-bookworm | ok: Runtime: 0:00:00.009454 2026-07-18 03:06:03.960215 | 2026-07-18 03:06:03.960383 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-07-18 03:06:10.039470 | debian-bookworm | ok: Runtime: 0:00:00.006385 2026-07-18 03:06:10.061779 | 2026-07-18 03:06:10.061950 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-18 03:06:10.159661 | debian-bookworm | skipping: Conditional result was False 2026-07-18 03:06:10.166676 | 2026-07-18 03:06:10.166849 | TASK [fetch-output : Set log path for single node] 2026-07-18 03:06:10.231686 | debian-bookworm | ok 2026-07-18 03:06:10.238534 | 2026-07-18 03:06:10.238763 | LOOP [fetch-output : Ensure local output dirs] 2026-07-18 03:06:10.591146 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/cd1525241af14a79bb4bfb0cdcd7837d/work/logs" 2026-07-18 03:06:10.936051 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/cd1525241af14a79bb4bfb0cdcd7837d/work/artifacts" 2026-07-18 03:06:11.255626 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/cd1525241af14a79bb4bfb0cdcd7837d/work/docs" 2026-07-18 03:06:11.287877 | 2026-07-18 03:06:11.288052 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-18 03:06:12.521987 | debian-bookworm | changed: 2026-07-18 03:06:12.522289 | debian-bookworm | .d..t...... ./ 2026-07-18 03:06:12.522358 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-18 03:06:12.522385 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-18 03:06:12.522407 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-18 03:06:12.522434 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-18 03:06:12.522463 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-18 03:06:12.522526 | debian-bookworm | changed: All items complete 2026-07-18 03:06:12.522553 | 2026-07-18 03:06:13.456695 | debian-bookworm | changed: .d..t...... ./ 2026-07-18 03:06:14.624370 | debian-bookworm | changed: .d..t...... ./ 2026-07-18 03:06:14.645634 | 2026-07-18 03:06:14.645798 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-18 03:06:14.701742 | debian-bookworm | skipping: Conditional result was False 2026-07-18 03:06:14.707477 | debian-bookworm | skipping: Conditional result was False 2026-07-18 03:06:14.724260 | 2026-07-18 03:06:14.724415 | PLAY RECAP 2026-07-18 03:06:14.724478 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-07-18 03:06:14.724503 | 2026-07-18 03:06:14.948433 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-18 03:06:14.951677 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-18 03:06:16.002264 | 2026-07-18 03:06:16.002485 | PLAY [Base post] 2026-07-18 03:06:16.018058 | 2026-07-18 03:06:16.018237 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-18 03:06:16.760120 | debian-bookworm | changed 2026-07-18 03:06:16.775434 | 2026-07-18 03:06:16.775617 | PLAY RECAP 2026-07-18 03:06:16.775689 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-18 03:06:16.775756 | 2026-07-18 03:06:17.239429 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-18 03:06:17.240471 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-18 03:06:18.324941 | 2026-07-18 03:06:18.325153 | PLAY [Base post-logs] 2026-07-18 03:06:18.337170 | 2026-07-18 03:06:18.337388 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-18 03:06:18.917252 | localhost | changed 2026-07-18 03:06:18.931529 | 2026-07-18 03:06:18.931739 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-18 03:06:18.990819 | localhost | ok 2026-07-18 03:06:18.995614 | 2026-07-18 03:06:18.995768 | TASK [Set zuul-log-path fact] 2026-07-18 03:06:19.021560 | localhost | ok 2026-07-18 03:06:19.031676 | 2026-07-18 03:06:19.031831 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-18 03:06:19.075943 | localhost | ok 2026-07-18 03:06:19.080286 | 2026-07-18 03:06:19.080467 | TASK [upload-logs : Create log directories] 2026-07-18 03:06:19.917140 | localhost | changed 2026-07-18 03:06:19.920408 | 2026-07-18 03:06:19.920545 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-18 03:06:20.649968 | localhost -> localhost | ok: Runtime: 0:00:00.010676 2026-07-18 03:06:20.654216 | 2026-07-18 03:06:20.654357 | TASK [upload-logs : Upload logs to log server] 2026-07-18 03:06:21.319182 | localhost | Output suppressed because no_log was given 2026-07-18 03:06:21.324618 | 2026-07-18 03:06:21.324730 | LOOP [upload-logs : Compress console log and json output] 2026-07-18 03:06:21.423532 | localhost | skipping: Conditional result was False 2026-07-18 03:06:21.452424 | localhost | skipping: Conditional result was False 2026-07-18 03:06:21.462644 | 2026-07-18 03:06:21.462769 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-18 03:06:21.544845 | localhost | skipping: Conditional result was False 2026-07-18 03:06:21.545063 | 2026-07-18 03:06:21.551538 | localhost | skipping: Conditional result was False 2026-07-18 03:06:21.559026 | 2026-07-18 03:06:21.559130 | LOOP [upload-logs : Upload console log and json output]