2026-10-06 03:20:52.902254 | Job console starting 2026-10-06 03:20:52.913382 | Updating git repos 2026-10-06 03:20:52.941189 | Cloning repos into workspace 2026-10-06 03:20:52.973700 | Restoring repo states 2026-10-06 03:20:52.997795 | Merging changes 2026-10-06 03:20:52.997810 | Checking out repos 2026-10-06 03:20:53.065460 | Preparing playbooks 2026-10-06 03:20:53.781221 | Running Ansible setup 2026-10-06 03:20:58.310090 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-10-06 03:20:59.204007 | 2026-10-06 03:20:59.204168 | PLAY [Base pre] 2026-10-06 03:20:59.230402 | 2026-10-06 03:20:59.230565 | TASK [Setup log path fact] 2026-10-06 03:20:59.260786 | debian-bookworm | ok 2026-10-06 03:20:59.276947 | 2026-10-06 03:20:59.277095 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-06 03:20:59.306215 | debian-bookworm | ok 2026-10-06 03:20:59.316753 | 2026-10-06 03:20:59.316881 | TASK [emit-job-header : Print job information] 2026-10-06 03:20:59.356664 | # Job Information 2026-10-06 03:20:59.356849 | Ansible Version: 2.16.14 2026-10-06 03:20:59.356884 | Job: container-image-inventory-reconciler-unit-tests 2026-10-06 03:20:59.356917 | Pipeline: periodic-daily 2026-10-06 03:20:59.356940 | Executor: 521e9411259a 2026-10-06 03:20:59.356960 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2026-10-06 03:20:59.356982 | Event ID: 8edcedc2dc3e4518be3c68a303f826b2 2026-10-06 03:20:59.362146 | 2026-10-06 03:20:59.362260 | LOOP [emit-job-header : Print node information] 2026-10-06 03:20:59.498643 | debian-bookworm | ok: 2026-10-06 03:20:59.498862 | debian-bookworm | # Node Information 2026-10-06 03:20:59.498903 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-10-06 03:20:59.498936 | debian-bookworm | Hostname: debian 2026-10-06 03:20:59.498964 | debian-bookworm | Username: zuul 2026-10-06 03:20:59.498991 | debian-bookworm | Distro: Debian 12.15 2026-10-06 03:20:59.499022 | debian-bookworm | Provider: regiocloud-a 2026-10-06 03:20:59.499048 | debian-bookworm | Region: 2026-10-06 03:20:59.499074 | debian-bookworm | Label: debian-bookworm 2026-10-06 03:20:59.499099 | debian-bookworm | Product Name: OpenStack Nova 2026-10-06 03:20:59.499124 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed1:f5a1 2026-10-06 03:20:59.516048 | 2026-10-06 03:20:59.516191 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-06 03:21:00.157638 | debian-bookworm -> localhost | changed 2026-10-06 03:21:00.165390 | 2026-10-06 03:21:00.165530 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-06 03:21:01.695985 | debian-bookworm -> localhost | changed 2026-10-06 03:21:01.727128 | 2026-10-06 03:21:01.727340 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-06 03:21:02.036394 | debian-bookworm -> localhost | ok 2026-10-06 03:21:02.043069 | 2026-10-06 03:21:02.043210 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-06 03:21:02.082739 | debian-bookworm | ok 2026-10-06 03:21:02.102768 | debian-bookworm | included: /var/lib/zuul/builds/dbb252a9ef3f4834b631fc2ebbe1275f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-06 03:21:02.108905 | 2026-10-06 03:21:02.109004 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-06 03:21:04.864306 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-10-06 03:21:04.864539 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/dbb252a9ef3f4834b631fc2ebbe1275f/work/dbb252a9ef3f4834b631fc2ebbe1275f_id_rsa 2026-10-06 03:21:04.864586 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/dbb252a9ef3f4834b631fc2ebbe1275f/work/dbb252a9ef3f4834b631fc2ebbe1275f_id_rsa.pub 2026-10-06 03:21:04.864615 | debian-bookworm -> localhost | The key fingerprint is: 2026-10-06 03:21:04.864640 | debian-bookworm -> localhost | SHA256:5GukYPnud5VFVO8+lHEki3D4EWi81785q7BahzSyfz0 zuul-build-sshkey 2026-10-06 03:21:04.864672 | debian-bookworm -> localhost | The key's randomart image is: 2026-10-06 03:21:04.864695 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-10-06 03:21:04.864717 | debian-bookworm -> localhost | | ..oooo.o| 2026-10-06 03:21:04.864738 | debian-bookworm -> localhost | | =o...+.| 2026-10-06 03:21:04.864759 | debian-bookworm -> localhost | | .. o.+..o| 2026-10-06 03:21:04.864779 | debian-bookworm -> localhost | | . o . o o.+| 2026-10-06 03:21:04.864799 | debian-bookworm -> localhost | | + S. + o +.| 2026-10-06 03:21:04.864825 | debian-bookworm -> localhost | | . o o .+ = ...| 2026-10-06 03:21:04.864847 | debian-bookworm -> localhost | | o o. = ...+| 2026-10-06 03:21:04.864867 | debian-bookworm -> localhost | | . .. + +. E.| 2026-10-06 03:21:04.864887 | debian-bookworm -> localhost | | .o. o.o....+| 2026-10-06 03:21:04.864907 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-10-06 03:21:04.864958 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.174677 2026-10-06 03:21:04.885680 | 2026-10-06 03:21:04.885864 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-06 03:21:04.927152 | debian-bookworm | ok 2026-10-06 03:21:04.944637 | debian-bookworm | included: /var/lib/zuul/builds/dbb252a9ef3f4834b631fc2ebbe1275f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-06 03:21:04.961054 | 2026-10-06 03:21:04.961157 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-06 03:21:04.975940 | debian-bookworm | skipping: Conditional result was False 2026-10-06 03:21:04.982572 | 2026-10-06 03:21:04.982683 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-06 03:21:05.729940 | debian-bookworm | changed 2026-10-06 03:21:05.734801 | 2026-10-06 03:21:05.734879 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-06 03:21:05.963682 | debian-bookworm | ok 2026-10-06 03:21:05.972889 | 2026-10-06 03:21:05.972989 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-06 03:21:06.547614 | debian-bookworm | changed 2026-10-06 03:21:06.555121 | 2026-10-06 03:21:06.555227 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-06 03:21:07.154289 | debian-bookworm | changed 2026-10-06 03:21:07.158909 | 2026-10-06 03:21:07.158981 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-06 03:21:07.182623 | debian-bookworm | skipping: Conditional result was False 2026-10-06 03:21:07.187348 | 2026-10-06 03:21:07.187424 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-06 03:21:07.543212 | debian-bookworm -> localhost | changed 2026-10-06 03:21:07.552690 | 2026-10-06 03:21:07.552773 | TASK [add-build-sshkey : Add back temp key] 2026-10-06 03:21:07.894746 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/dbb252a9ef3f4834b631fc2ebbe1275f/work/dbb252a9ef3f4834b631fc2ebbe1275f_id_rsa (zuul-build-sshkey) 2026-10-06 03:21:07.894951 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008472 2026-10-06 03:21:07.902846 | 2026-10-06 03:21:07.902951 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-06 03:21:08.276886 | debian-bookworm | ok 2026-10-06 03:21:08.283629 | 2026-10-06 03:21:08.283723 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-06 03:21:08.306868 | debian-bookworm | skipping: Conditional result was False 2026-10-06 03:21:08.317016 | 2026-10-06 03:21:08.317112 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-10-06 03:21:08.719044 | debian-bookworm | ok 2026-10-06 03:21:08.730539 | 2026-10-06 03:21:08.730625 | TASK [validate-host : Define zuul_info_dir fact] 2026-10-06 03:21:08.769175 | debian-bookworm | ok 2026-10-06 03:21:08.778618 | 2026-10-06 03:21:08.778705 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-10-06 03:21:09.095408 | debian-bookworm -> localhost | ok 2026-10-06 03:21:09.101730 | 2026-10-06 03:21:09.101841 | TASK [validate-host : Collect information about the host] 2026-10-06 03:21:10.175467 | debian-bookworm | ok 2026-10-06 03:21:10.201835 | 2026-10-06 03:21:10.201934 | TASK [validate-host : Sanitize hostname] 2026-10-06 03:21:10.362212 | debian-bookworm | ok 2026-10-06 03:21:10.366472 | 2026-10-06 03:21:10.366585 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-10-06 03:21:11.114905 | debian-bookworm -> localhost | changed 2026-10-06 03:21:11.120303 | 2026-10-06 03:21:11.120388 | TASK [validate-host : Collect information about zuul worker] 2026-10-06 03:21:11.507491 | debian-bookworm | ok 2026-10-06 03:21:11.511547 | 2026-10-06 03:21:11.511633 | TASK [validate-host : Write out all zuul information for each host] 2026-10-06 03:21:12.178264 | debian-bookworm -> localhost | changed 2026-10-06 03:21:12.186343 | 2026-10-06 03:21:12.186423 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-10-06 03:21:12.423357 | debian-bookworm | ok 2026-10-06 03:21:12.427749 | 2026-10-06 03:21:12.427827 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-10-06 03:21:13.229614 | debian-bookworm | changed: 2026-10-06 03:21:13.229780 | debian-bookworm | cd+++++++++ src/ 2026-10-06 03:21:13.229814 | debian-bookworm | cd+++++++++ src/github.com/ 2026-10-06 03:21:13.229838 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-10-06 03:21:13.229859 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2026-10-06 03:21:13.229880 | debian-bookworm | Containerfile 2026-10-06 03:21:13.230030 | debian-bookworm | =2.0 (from Jinja2==3.1.6->-r files/requirements.txt (line 1)) 2026-10-06 03:21:38.235364 | debian-bookworm | Downloading markupsafe-3.0.4-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2026-10-06 03:21:38.309373 | debian-bookworm | Collecting PyYAML>=5.1 (from ansible-core==2.19.11->-r files/requirements.txt (line 2)) 2026-10-06 03:21:38.320386 | 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-10-06 03:21:38.562859 | debian-bookworm | Collecting cryptography (from ansible-core==2.19.11->-r files/requirements.txt (line 2)) 2026-10-06 03:21:38.573652 | debian-bookworm | Downloading cryptography-50.0.2-cp311-abi3-manylinux_2_34_x86_64.whl.metadata (4.4 kB) 2026-10-06 03:21:38.607951 | debian-bookworm | Collecting packaging (from ansible-core==2.19.11->-r files/requirements.txt (line 2)) 2026-10-06 03:21:38.618670 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl.metadata (3.5 kB) 2026-10-06 03:21:38.649095 | debian-bookworm | Collecting resolvelib<2.0.0,>=0.5.3 (from ansible-core==2.19.11->-r files/requirements.txt (line 2)) 2026-10-06 03:21:38.659658 | debian-bookworm | Downloading resolvelib-1.2.1-py3-none-any.whl.metadata (3.7 kB) 2026-10-06 03:21:38.704592 | debian-bookworm | Collecting requests<3.0,>=2.20.0 (from pynetbox==7.8.0->-r files/requirements.txt (line 5)) 2026-10-06 03:21:38.715276 | debian-bookworm | Downloading requests-2.34.2-py3-none-any.whl.metadata (4.8 kB) 2026-10-06 03:21:38.745147 | debian-bookworm | Collecting iniconfig>=1.0.1 (from pytest==9.1.1->-r files/test-requirements.txt (line 2)) 2026-10-06 03:21:38.756152 | debian-bookworm | Downloading iniconfig-2.3.0-py3-none-any.whl.metadata (2.5 kB) 2026-10-06 03:21:38.787210 | debian-bookworm | Collecting pluggy<2,>=1.5 (from pytest==9.1.1->-r files/test-requirements.txt (line 2)) 2026-10-06 03:21:38.797969 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl.metadata (4.8 kB) 2026-10-06 03:21:38.854884 | debian-bookworm | Collecting pygments>=2.7.2 (from pytest==9.1.1->-r files/test-requirements.txt (line 2)) 2026-10-06 03:21:38.867796 | debian-bookworm | Downloading pygments-2.21.0-py3-none-any.whl.metadata (2.5 kB) 2026-10-06 03:21:39.410359 | 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-10-06 03:21:39.421484 | debian-bookworm | Downloading coverage-7.16.2-cp311-cp311-manylinux1_x86_64.manylinux_2_28_x86_64.manylinux_2_5_x86_64.whl.metadata (8.2 kB) 2026-10-06 03:21:39.621676 | 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-10-06 03:21:39.634378 | debian-bookworm | Downloading charset_normalizer-3.5.2-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (46 kB) 2026-10-06 03:21:39.678408 | 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-10-06 03:21:39.690242 | debian-bookworm | Downloading idna-3.20-py3-none-any.whl.metadata (7.2 kB) 2026-10-06 03:21:39.760068 | 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-10-06 03:21:39.771597 | debian-bookworm | Downloading urllib3-2.8.0-py3-none-any.whl.metadata (7.4 kB) 2026-10-06 03:21:39.817148 | 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-10-06 03:21:39.828202 | debian-bookworm | Downloading certifi-2026.7.22-py3-none-any.whl.metadata (2.5 kB) 2026-10-06 03:21:39.983073 | debian-bookworm | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.19.11->-r files/requirements.txt (line 2)) 2026-10-06 03:21:39.994326 | debian-bookworm | Downloading cffi-2.1.1-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.5 kB) 2026-10-06 03:21:40.028063 | debian-bookworm | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.19.11->-r files/requirements.txt (line 2)) 2026-10-06 03:21:40.039659 | debian-bookworm | Downloading pycparser-3.0-py3-none-any.whl.metadata (8.2 kB) 2026-10-06 03:21:40.063892 | debian-bookworm | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-10-06 03:21:40.097166 | debian-bookworm | Downloading ansible_core-2.19.11-py3-none-any.whl (2.4 MB) 2026-10-06 03:21:40.151703 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 2.4/2.4 MB 48.7 MB/s 0:00:00 2026-10-06 03:21:40.163627 | debian-bookworm | Downloading dynaconf-3.3.5-py3-none-any.whl (271 kB) 2026-10-06 03:21:40.189744 | debian-bookworm | Downloading netaddr-1.3.0-py3-none-any.whl (2.3 MB) 2026-10-06 03:21:40.247994 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 2.3/2.3 MB 40.8 MB/s 0:00:00 2026-10-06 03:21:40.261301 | debian-bookworm | Downloading pynetbox-7.8.0-py3-none-any.whl (54 kB) 2026-10-06 03:21:40.284416 | debian-bookworm | Downloading pytest-9.1.1-py3-none-any.whl (386 kB) 2026-10-06 03:21:40.312966 | debian-bookworm | Downloading pytest_cov-7.1.0-py3-none-any.whl (22 kB) 2026-10-06 03:21:40.334519 | debian-bookworm | Downloading pytest_mock-3.16.0-py3-none-any.whl (10 kB) 2026-10-06 03:21:40.357148 | debian-bookworm | Downloading loguru-0.7.3-py3-none-any.whl (61 kB) 2026-10-06 03:21:40.378769 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-10-06 03:21:40.398219 | debian-bookworm | Downloading requests-2.34.2-py3-none-any.whl (73 kB) 2026-10-06 03:21:40.419748 | debian-bookworm | Downloading charset_normalizer-3.5.2-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (269 kB) 2026-10-06 03:21:40.446919 | debian-bookworm | Downloading idna-3.20-py3-none-any.whl (69 kB) 2026-10-06 03:21:40.472223 | debian-bookworm | Downloading resolvelib-1.2.1-py3-none-any.whl (18 kB) 2026-10-06 03:21:40.495842 | debian-bookworm | Downloading urllib3-2.8.0-py3-none-any.whl (135 kB) 2026-10-06 03:21:40.520634 | debian-bookworm | Downloading certifi-2026.7.22-py3-none-any.whl (136 kB) 2026-10-06 03:21:40.546212 | debian-bookworm | Downloading coverage-7.16.2-cp311-cp311-manylinux1_x86_64.manylinux_2_28_x86_64.manylinux_2_5_x86_64.whl (256 kB) 2026-10-06 03:21:40.577366 | debian-bookworm | Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB) 2026-10-06 03:21:40.601395 | debian-bookworm | Downloading markupsafe-3.0.4-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (22 kB) 2026-10-06 03:21:40.623236 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-10-06 03:21:40.647778 | debian-bookworm | Downloading pygments-2.21.0-py3-none-any.whl (1.3 MB) 2026-10-06 03:21:40.776265 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.3/1.3 MB 8.1 MB/s 0:00:00 2026-10-06 03:21:40.788845 | 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-10-06 03:21:40.809467 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.6/806.6 kB 33.7 MB/s 0:00:00 2026-10-06 03:21:40.821187 | debian-bookworm | Downloading cryptography-50.0.2-cp311-abi3-manylinux_2_34_x86_64.whl (4.8 MB) 2026-10-06 03:21:40.908540 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.8/4.8 MB 54.6 MB/s 0:00:00 2026-10-06 03:21:40.920950 | debian-bookworm | Downloading cffi-2.1.1-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (217 kB) 2026-10-06 03:21:40.943103 | debian-bookworm | Downloading pycparser-3.0-py3-none-any.whl (48 kB) 2026-10-06 03:21:41.127969 | 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-10-06 03:21:45.185789 | debian-bookworm | 2026-10-06 03:21:45.191178 | debian-bookworm | Successfully installed Jinja2-3.1.6 MarkupSafe-3.0.4 PyYAML-6.0.3 ansible-core-2.19.11 certifi-2026.7.22 cffi-2.1.1 charset_normalizer-3.5.2 coverage-7.16.2 cryptography-50.0.2 dynaconf-3.3.5 idna-3.20 iniconfig-2.3.0 loguru-0.7.3 netaddr-1.3.0 packaging-26.3 pluggy-1.6.0 pycparser-3.0 pygments-2.21.0 pynetbox-7.8.0 pytest-9.1.1 pytest-cov-7.1.0 pytest-mock-3.16.0 requests-2.34.2 resolvelib-1.2.1 urllib3-2.8.0 2026-10-06 03:21:45.499372 | debian-bookworm | ok: Runtime: 0:00:14.988926 2026-10-06 03:21:45.504220 | 2026-10-06 03:21:45.504295 | TASK [Run pytest] 2026-10-06 03:21:45.763383 | debian-bookworm | + /tmp/test-unit-venv/bin/pytest tests/unit 2026-10-06 03:21:46.982871 | debian-bookworm | ============================= test session starts ============================== 2026-10-06 03:21:46.982936 | debian-bookworm | platform linux -- Python 3.11.2, pytest-9.1.1, pluggy-1.6.0 2026-10-06 03:21:46.982951 | debian-bookworm | rootdir: /home/zuul/src/github.com/osism/container-image-inventory-reconciler 2026-10-06 03:21:46.982963 | debian-bookworm | configfile: pyproject.toml 2026-10-06 03:21:46.982969 | debian-bookworm | plugins: cov-7.1.0, mock-3.16.0 2026-10-06 03:21:46.982977 | debian-bookworm | collected 626 items 2026-10-06 03:21:46.982996 | debian-bookworm | 2026-10-06 03:21:46.988861 | debian-bookworm | tests/unit/netbox/test_base_extractor.py ...... [ 0%] 2026-10-06 03:21:47.033614 | debian-bookworm | tests/unit/netbox/test_bulk_loader.py ........................... [ 5%] 2026-10-06 03:21:47.045185 | debian-bookworm | tests/unit/netbox/test_cache.py ............. [ 7%] 2026-10-06 03:21:47.059838 | debian-bookworm | tests/unit/netbox/test_ceph_extractor.py .................... [ 10%] 2026-10-06 03:21:47.086240 | debian-bookworm | tests/unit/netbox/test_config.py .......................... [ 14%] 2026-10-06 03:21:47.092625 | debian-bookworm | tests/unit/netbox/test_config_context_extractor.py ......... [ 16%] 2026-10-06 03:21:47.121307 | debian-bookworm | tests/unit/netbox/test_connection.py ............. [ 18%] 2026-10-06 03:21:47.130813 | debian-bookworm | tests/unit/netbox/test_custom_field_extractor.py ............. [ 20%] 2026-10-06 03:21:47.146297 | debian-bookworm | tests/unit/netbox/test_device_mapping.py .................. [ 23%] 2026-10-06 03:21:47.157469 | debian-bookworm | tests/unit/netbox/test_dnsmasq_base.py ..... [ 23%] 2026-10-06 03:21:47.192865 | debian-bookworm | tests/unit/netbox/test_dnsmasq_dhcp_config.py ..................... [ 27%] 2026-10-06 03:21:47.204063 | debian-bookworm | tests/unit/netbox/test_dnsmasq_interface_handler.py ......... [ 28%] 2026-10-06 03:21:47.224738 | debian-bookworm | tests/unit/netbox/test_dnsmasq_manager.py ........ [ 30%] 2026-10-06 03:21:47.250586 | debian-bookworm | tests/unit/netbox/test_dnsmasq_manager_mode.py ........... [ 31%] 2026-10-06 03:21:47.282961 | debian-bookworm | tests/unit/netbox/test_dnsmasq_metalbox_mode.py ........................ [ 35%] 2026-10-06 03:21:47.383105 | debian-bookworm | ........................................ [ 42%] 2026-10-06 03:21:47.389036 | debian-bookworm | tests/unit/netbox/test_exceptions.py ........ [ 43%] 2026-10-06 03:21:47.412467 | debian-bookworm | tests/unit/netbox/test_filters.py ...................................... [ 49%] 2026-10-06 03:21:47.418395 | debian-bookworm | ......... [ 50%] 2026-10-06 03:21:47.442963 | debian-bookworm | tests/unit/netbox/test_frr_extractor.py ................................ [ 55%] 2026-10-06 03:21:47.512068 | debian-bookworm | ............................................................... [ 65%] 2026-10-06 03:21:47.535458 | debian-bookworm | tests/unit/netbox/test_gnmic_extractor.py ........................... [ 70%] 2026-10-06 03:21:47.565246 | debian-bookworm | tests/unit/netbox/test_interfaces.py ........................... [ 74%] 2026-10-06 03:21:47.586768 | debian-bookworm | tests/unit/netbox/test_netplan_extractor.py ............................ [ 79%] 2026-10-06 03:21:47.637557 | debian-bookworm | .................................................. [ 87%] 2026-10-06 03:21:47.661219 | debian-bookworm | tests/unit/netbox/test_parallel_processor.py ................... [ 90%] 2026-10-06 03:21:47.665892 | debian-bookworm | tests/unit/netbox/test_primary_ip_extractor.py ....... [ 91%] 2026-10-06 03:21:47.683235 | debian-bookworm | tests/unit/netbox/test_retry_utils.py .................... [ 94%] 2026-10-06 03:21:47.692307 | debian-bookworm | tests/unit/netbox/test_secrets_extractor.py ............. [ 96%] 2026-10-06 03:21:47.694331 | debian-bookworm | tests/unit/netbox/test_smoke.py ... [ 96%] 2026-10-06 03:21:47.760536 | debian-bookworm | tests/unit/netbox/test_utils.py ................... [100%] 2026-10-06 03:21:47.760610 | debian-bookworm | 2026-10-06 03:21:47.760626 | debian-bookworm | ============================= 626 passed in 1.70s ============================== 2026-10-06 03:21:48.027945 | debian-bookworm | ok: Runtime: 0:00:02.090658 2026-10-06 03:21:48.029504 | 2026-10-06 03:21:48.029589 | PLAY RECAP 2026-10-06 03:21:48.029644 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-06 03:21:48.029669 | 2026-10-06 03:21:48.120900 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-image-inventory-reconciler/playbooks/test-unit.yml@main] 2026-10-06 03:21:48.123216 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-10-06 03:21:48.845597 | 2026-10-06 03:21:48.845720 | PLAY [Base post-fetch] 2026-10-06 03:21:48.870833 | 2026-10-06 03:21:48.870962 | TASK [diagnose-network : Ensure log directory exists] 2026-10-06 03:21:49.242987 | debian-bookworm | ok 2026-10-06 03:21:49.254423 | 2026-10-06 03:21:49.254760 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-10-06 03:22:00.867020 | debian-bookworm | ok: Runtime: 0:00:06.042831 2026-10-06 03:22:00.876783 | 2026-10-06 03:22:00.876908 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-10-06 03:22:06.809829 | debian-bookworm | ok: Runtime: 0:00:00.007251 2026-10-06 03:22:06.815324 | 2026-10-06 03:22:06.815449 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-10-06 03:22:12.789800 | debian-bookworm | ok: Runtime: 0:00:00.008555 2026-10-06 03:22:12.795954 | 2026-10-06 03:22:12.796053 | TASK [diagnose-network : Evaluate node isolation verdict (pre-run gate)] 2026-10-06 03:22:13.424643 | debian-bookworm | skipping: Conditional result was False 2026-10-06 03:22:13.431120 | 2026-10-06 03:22:13.431226 | TASK [diagnose-network : Abort the pre-run on a fully isolated node] 2026-10-06 03:22:13.507282 | debian-bookworm | skipping: Conditional result was False 2026-10-06 03:22:13.524848 | 2026-10-06 03:22:13.524956 | TASK [fetch-output : Set log path for multiple nodes] 2026-10-06 03:22:13.599965 | debian-bookworm | skipping: Conditional result was False 2026-10-06 03:22:13.605708 | 2026-10-06 03:22:13.605818 | TASK [fetch-output : Set log path for single node] 2026-10-06 03:22:13.664616 | debian-bookworm | ok 2026-10-06 03:22:13.674618 | 2026-10-06 03:22:13.674721 | LOOP [fetch-output : Ensure local output dirs] 2026-10-06 03:22:14.266793 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/dbb252a9ef3f4834b631fc2ebbe1275f/work/logs" 2026-10-06 03:22:14.730234 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/dbb252a9ef3f4834b631fc2ebbe1275f/work/artifacts" 2026-10-06 03:22:14.920409 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/dbb252a9ef3f4834b631fc2ebbe1275f/work/docs" 2026-10-06 03:22:14.929740 | 2026-10-06 03:22:14.929843 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-06 03:22:15.750410 | debian-bookworm | changed: 2026-10-06 03:22:15.750624 | debian-bookworm | .d..t...... ./ 2026-10-06 03:22:15.750662 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-10-06 03:22:15.750686 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-10-06 03:22:15.750709 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-10-06 03:22:15.750731 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-10-06 03:22:15.750751 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-10-06 03:22:15.750785 | debian-bookworm | changed: All items complete 2026-10-06 03:22:15.750807 | 2026-10-06 03:22:16.336595 | debian-bookworm | changed: .d..t...... ./ 2026-10-06 03:22:16.955001 | debian-bookworm | changed: .d..t...... ./ 2026-10-06 03:22:16.977783 | 2026-10-06 03:22:16.977885 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-06 03:22:17.037804 | debian-bookworm | skipping: Conditional result was False 2026-10-06 03:22:17.044166 | debian-bookworm | skipping: Conditional result was False 2026-10-06 03:22:17.051784 | 2026-10-06 03:22:17.051858 | PLAY RECAP 2026-10-06 03:22:17.051905 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-10-06 03:22:17.051928 | 2026-10-06 03:22:17.179254 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-10-06 03:22:17.179906 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-06 03:22:17.928468 | 2026-10-06 03:22:17.928601 | PLAY [Base post] 2026-10-06 03:22:17.940471 | 2026-10-06 03:22:17.940605 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-06 03:22:18.465566 | debian-bookworm | changed 2026-10-06 03:22:18.470784 | 2026-10-06 03:22:18.470865 | PLAY RECAP 2026-10-06 03:22:18.470914 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-06 03:22:18.470964 | 2026-10-06 03:22:18.549516 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-06 03:22:18.550329 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-06 03:22:19.524057 | 2026-10-06 03:22:19.524741 | PLAY [Base post-logs] 2026-10-06 03:22:19.541496 | 2026-10-06 03:22:19.541668 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-06 03:22:20.054237 | localhost | changed 2026-10-06 03:22:20.064497 | 2026-10-06 03:22:20.064625 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-06 03:22:20.089134 | localhost | ok 2026-10-06 03:22:20.092290 | 2026-10-06 03:22:20.092364 | TASK [Set zuul-log-path fact] 2026-10-06 03:22:20.107379 | localhost | ok 2026-10-06 03:22:20.115986 | 2026-10-06 03:22:20.116075 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-06 03:22:20.140978 | localhost | ok 2026-10-06 03:22:20.143366 | 2026-10-06 03:22:20.143473 | TASK [upload-logs : Create log directories] 2026-10-06 03:22:20.584153 | localhost | changed 2026-10-06 03:22:20.587107 | 2026-10-06 03:22:20.587195 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-06 03:22:21.200097 | localhost -> localhost | ok: Runtime: 0:00:00.026741 2026-10-06 03:22:21.205713 | 2026-10-06 03:22:21.205817 | TASK [upload-logs : Upload logs to log server] 2026-10-06 03:22:21.769727 | localhost | Output suppressed because no_log was given 2026-10-06 03:22:21.771311 | 2026-10-06 03:22:21.771394 | LOOP [upload-logs : Compress console log and json output] 2026-10-06 03:22:21.811809 | localhost | skipping: Conditional result was False 2026-10-06 03:22:21.817481 | localhost | skipping: Conditional result was False 2026-10-06 03:22:21.827630 | 2026-10-06 03:22:21.827718 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-06 03:22:21.861283 | localhost | skipping: Conditional result was False 2026-10-06 03:22:21.861521 | 2026-10-06 03:22:21.866885 | localhost | skipping: Conditional result was False 2026-10-06 03:22:21.873774 | 2026-10-06 03:22:21.873869 | LOOP [upload-logs : Upload console log and json output]