2025-07-14 00:00:14.652059 | Job console starting 2025-07-14 00:00:14.673099 | Updating git repos 2025-07-14 00:00:14.698630 | Cloning repos into workspace 2025-07-14 00:00:14.825317 | Restoring repo states 2025-07-14 00:00:14.839596 | Merging changes 2025-07-14 00:00:14.839613 | Checking out repos 2025-07-14 00:00:15.032344 | Preparing playbooks 2025-07-14 00:00:16.054558 | Running Ansible setup 2025-07-14 00:00:23.292663 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-14 00:00:25.116344 | 2025-07-14 00:00:25.116486 | PLAY [Base pre] 2025-07-14 00:00:25.148714 | 2025-07-14 00:00:25.148824 | TASK [Setup log path fact] 2025-07-14 00:00:25.175927 | debian-bookworm | ok 2025-07-14 00:00:25.191691 | 2025-07-14 00:00:25.191809 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-14 00:00:25.219475 | debian-bookworm | ok 2025-07-14 00:00:25.235234 | 2025-07-14 00:00:25.235335 | TASK [emit-job-header : Print job information] 2025-07-14 00:00:25.297014 | # Job Information 2025-07-14 00:00:25.297785 | Ansible Version: 2.16.14 2025-07-14 00:00:25.297821 | Job: container-image-inventory-reconciler-push 2025-07-14 00:00:25.297850 | Pipeline: periodic-midnight 2025-07-14 00:00:25.297869 | Executor: 521e9411259a 2025-07-14 00:00:25.297887 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-07-14 00:00:25.297905 | Event ID: 77ee728369224c33aba37e46a81831c8 2025-07-14 00:00:25.301904 | 2025-07-14 00:00:25.301987 | LOOP [emit-job-header : Print node information] 2025-07-14 00:00:25.406941 | debian-bookworm | ok: 2025-07-14 00:00:25.407084 | debian-bookworm | # Node Information 2025-07-14 00:00:25.407113 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-07-14 00:00:25.407134 | debian-bookworm | Hostname: debian 2025-07-14 00:00:25.407156 | debian-bookworm | Username: zuul 2025-07-14 00:00:25.407173 | debian-bookworm | Distro: Debian 12.11 2025-07-14 00:00:25.407194 | debian-bookworm | Provider: regiocloud-a 2025-07-14 00:00:25.407211 | debian-bookworm | Region: 2025-07-14 00:00:25.407228 | debian-bookworm | Label: debian-bookworm 2025-07-14 00:00:25.407245 | debian-bookworm | Product Name: OpenStack Nova 2025-07-14 00:00:25.407261 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee8:5a0b 2025-07-14 00:00:25.420084 | 2025-07-14 00:00:25.420196 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-14 00:00:26.147460 | debian-bookworm -> localhost | changed 2025-07-14 00:00:26.169383 | 2025-07-14 00:00:26.169494 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-14 00:00:28.822197 | debian-bookworm -> localhost | changed 2025-07-14 00:00:28.833729 | 2025-07-14 00:00:28.833866 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-14 00:00:29.230030 | debian-bookworm -> localhost | ok 2025-07-14 00:00:29.236614 | 2025-07-14 00:00:29.236723 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-14 00:00:29.274757 | debian-bookworm | ok 2025-07-14 00:00:29.324221 | debian-bookworm | included: /var/lib/zuul/builds/b3d5912896c7453c84bbac9f4419a270/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-14 00:00:29.330268 | 2025-07-14 00:00:29.330380 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-14 00:00:33.706544 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-07-14 00:00:33.707218 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b3d5912896c7453c84bbac9f4419a270/work/b3d5912896c7453c84bbac9f4419a270_id_rsa 2025-07-14 00:00:33.707266 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b3d5912896c7453c84bbac9f4419a270/work/b3d5912896c7453c84bbac9f4419a270_id_rsa.pub 2025-07-14 00:00:33.707294 | debian-bookworm -> localhost | The key fingerprint is: 2025-07-14 00:00:33.707318 | debian-bookworm -> localhost | SHA256:0Y/w70QcIz61EmqJtsVbCb7IRArNs2v5vlxhy6pzyZw zuul-build-sshkey 2025-07-14 00:00:33.707382 | debian-bookworm -> localhost | The key's randomart image is: 2025-07-14 00:00:33.707409 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-07-14 00:00:33.707431 | debian-bookworm -> localhost | | | 2025-07-14 00:00:33.707453 | debian-bookworm -> localhost | | o . | 2025-07-14 00:00:33.707473 | debian-bookworm -> localhost | | . + . + + + | 2025-07-14 00:00:33.707493 | debian-bookworm -> localhost | | . = + O O + | 2025-07-14 00:00:33.707512 | debian-bookworm -> localhost | | o + S O = | 2025-07-14 00:00:33.707536 | debian-bookworm -> localhost | | * B * = | 2025-07-14 00:00:33.707557 | debian-bookworm -> localhost | | +o+o* o | 2025-07-14 00:00:33.707578 | debian-bookworm -> localhost | | ..oEo o | 2025-07-14 00:00:33.707600 | debian-bookworm -> localhost | | .=*. . | 2025-07-14 00:00:33.707621 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-07-14 00:00:33.707675 | debian-bookworm -> localhost | ok: Runtime: 0:00:03.240800 2025-07-14 00:00:33.721203 | 2025-07-14 00:00:33.721316 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-14 00:00:33.769562 | debian-bookworm | ok 2025-07-14 00:00:33.801417 | debian-bookworm | included: /var/lib/zuul/builds/b3d5912896c7453c84bbac9f4419a270/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-14 00:00:33.825317 | 2025-07-14 00:00:33.825436 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-14 00:00:33.877378 | debian-bookworm | skipping: Conditional result was False 2025-07-14 00:00:33.883482 | 2025-07-14 00:00:33.883588 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-14 00:00:34.781533 | debian-bookworm | changed 2025-07-14 00:00:34.785873 | 2025-07-14 00:00:34.785954 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-14 00:00:35.059002 | debian-bookworm | ok 2025-07-14 00:00:35.063591 | 2025-07-14 00:00:35.063669 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-14 00:00:35.679290 | debian-bookworm | changed 2025-07-14 00:00:35.684740 | 2025-07-14 00:00:35.684823 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-14 00:00:36.437909 | debian-bookworm | changed 2025-07-14 00:00:36.442488 | 2025-07-14 00:00:36.442573 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-14 00:00:36.479941 | debian-bookworm | skipping: Conditional result was False 2025-07-14 00:00:36.485187 | 2025-07-14 00:00:36.485276 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-14 00:00:37.007246 | debian-bookworm -> localhost | changed 2025-07-14 00:00:37.017818 | 2025-07-14 00:00:37.017929 | TASK [add-build-sshkey : Add back temp key] 2025-07-14 00:00:37.870038 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b3d5912896c7453c84bbac9f4419a270/work/b3d5912896c7453c84bbac9f4419a270_id_rsa (zuul-build-sshkey) 2025-07-14 00:00:37.870257 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.027108 2025-07-14 00:00:37.882772 | 2025-07-14 00:00:37.882927 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-14 00:00:38.317466 | debian-bookworm | ok 2025-07-14 00:00:38.322695 | 2025-07-14 00:00:38.322802 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-14 00:00:38.339902 | debian-bookworm | skipping: Conditional result was False 2025-07-14 00:00:38.348939 | 2025-07-14 00:00:38.349036 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-14 00:00:38.730144 | debian-bookworm | ok 2025-07-14 00:00:38.742024 | 2025-07-14 00:00:38.742124 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-14 00:00:38.797576 | debian-bookworm | ok 2025-07-14 00:00:38.801758 | 2025-07-14 00:00:38.801844 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-14 00:00:39.330861 | debian-bookworm -> localhost | ok 2025-07-14 00:00:39.336154 | 2025-07-14 00:00:39.336238 | TASK [validate-host : Collect information about the host] 2025-07-14 00:00:41.351371 | debian-bookworm | ok 2025-07-14 00:00:41.369235 | 2025-07-14 00:00:41.369409 | TASK [validate-host : Sanitize hostname] 2025-07-14 00:00:41.459398 | debian-bookworm | ok 2025-07-14 00:00:41.507712 | 2025-07-14 00:00:41.507827 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-14 00:00:42.244146 | debian-bookworm -> localhost | changed 2025-07-14 00:00:42.250056 | 2025-07-14 00:00:42.250158 | TASK [validate-host : Collect information about zuul worker] 2025-07-14 00:00:42.761020 | debian-bookworm | ok 2025-07-14 00:00:42.767134 | 2025-07-14 00:00:42.767251 | TASK [validate-host : Write out all zuul information for each host] 2025-07-14 00:00:43.375913 | debian-bookworm -> localhost | changed 2025-07-14 00:00:43.385242 | 2025-07-14 00:00:43.385354 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-14 00:00:43.640440 | debian-bookworm | ok 2025-07-14 00:00:43.645916 | 2025-07-14 00:00:43.646010 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-14 00:00:44.471042 | debian-bookworm | changed: 2025-07-14 00:00:44.471244 | debian-bookworm | cd+++++++++ src/ 2025-07-14 00:00:44.471279 | debian-bookworm | cd+++++++++ src/github.com/ 2025-07-14 00:00:44.471314 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-07-14 00:00:44.471337 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-07-14 00:00:44.471358 | debian-bookworm | Containerfile 2025-07-14 00:00:44.471492 | debian-bookworm | 3.5.1-r0) 2025-07-14 00:01:47.423736 | debian-bookworm | #9 5.931 (25/34) Upgrading libssl3 (3.5.0-r0 -> 3.5.1-r0) 2025-07-14 00:01:47.526893 | debian-bookworm | #9 5.975 (26/34) Installing openssl-dev (3.5.1-r0) 2025-07-14 00:01:47.526987 | debian-bookworm | #9 6.035 (27/34) Installing mpdecimal (4.0.1-r0) 2025-07-14 00:01:47.701986 | debian-bookworm | #9 6.059 (28/34) Installing python3 (3.12.11-r0) 2025-07-14 00:01:47.853046 | debian-bookworm | #9 6.361 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-07-14 00:01:48.020529 | debian-bookworm | #9 6.528 (30/34) Installing pyc (3.12.11-r0) 2025-07-14 00:01:48.171381 | debian-bookworm | #9 6.528 (31/34) Installing python3-pyc (3.12.11-r0) 2025-07-14 00:01:48.171507 | debian-bookworm | #9 6.529 (32/34) Installing python3-dev (3.12.11-r0) 2025-07-14 00:01:48.546053 | debian-bookworm | #9 7.054 (33/34) Installing yq-go (4.46.1-r0) 2025-07-14 00:01:48.701566 | debian-bookworm | #9 7.209 (34/34) Installing .build-deps (20250714.000143) 2025-07-14 00:01:48.902369 | debian-bookworm | #9 7.210 Executing busybox-1.37.0-r18.trigger 2025-07-14 00:01:48.902409 | debian-bookworm | #9 7.216 Executing ca-certificates-20241121-r2.trigger 2025-07-14 00:01:48.902415 | debian-bookworm | #9 7.260 OK: 359 MiB in 82 packages 2025-07-14 00:01:49.182571 | debian-bookworm | #9 DONE 7.7s 2025-07-14 00:01:49.310841 | debian-bookworm | 2025-07-14 00:01:49.310931 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-07-14 00:01:49.310973 | debian-bookworm | #10 DONE 0.1s 2025-07-14 00:01:49.415269 | debian-bookworm | 2025-07-14 00:01:49.415407 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-07-14 00:01:49.415468 | debian-bookworm | #11 DONE 0.1s 2025-07-14 00:01:49.644203 | debian-bookworm | 2025-07-14 00:01:49.644344 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-07-14 00:01:49.644400 | debian-bookworm | #12 DONE 0.1s 2025-07-14 00:01:49.644443 | debian-bookworm | 2025-07-14 00:01:49.644475 | debian-bookworm | #13 [builder 7/22] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2025-07-14 00:01:50.283102 | debian-bookworm | #13 0.790 Using Python 3.13.5 environment at: /usr/local 2025-07-14 00:01:50.858360 | debian-bookworm | #13 1.175 Resolved 17 packages in 382ms 2025-07-14 00:01:50.858441 | debian-bookworm | #13 1.210 Downloading netaddr (2.2MiB) 2025-07-14 00:01:50.858490 | debian-bookworm | #13 1.211 Downloading ansible-core (2.1MiB) 2025-07-14 00:01:50.858512 | debian-bookworm | #13 1.214 Downloading cryptography (4.4MiB) 2025-07-14 00:01:51.037525 | debian-bookworm | #13 1.544 Downloading netaddr 2025-07-14 00:01:51.159101 | debian-bookworm | #13 1.614 Downloading cryptography 2025-07-14 00:01:51.376613 | debian-bookworm | #13 1.666 Downloading ansible-core 2025-07-14 00:01:51.376993 | debian-bookworm | #13 1.667 Prepared 17 packages in 490ms 2025-07-14 00:01:51.377012 | debian-bookworm | #13 1.724 Installed 17 packages in 55ms 2025-07-14 00:01:51.377024 | debian-bookworm | #13 1.725 + ansible-core==2.18.6 2025-07-14 00:01:51.377037 | debian-bookworm | #13 1.725 + certifi==2025.7.9 2025-07-14 00:01:51.377049 | debian-bookworm | #13 1.726 + cffi==1.17.1 2025-07-14 00:01:51.377060 | debian-bookworm | #13 1.727 + charset-normalizer==3.4.2 2025-07-14 00:01:51.377071 | debian-bookworm | #13 1.728 + cryptography==45.0.5 2025-07-14 00:01:51.377160 | debian-bookworm | #13 1.728 + dynaconf==3.2.11 2025-07-14 00:01:51.377173 | debian-bookworm | #13 1.729 + idna==3.10 2025-07-14 00:01:51.377184 | debian-bookworm | #13 1.729 + jinja2==3.1.6 2025-07-14 00:01:51.377195 | debian-bookworm | #13 1.730 + markupsafe==3.0.2 2025-07-14 00:01:51.377206 | debian-bookworm | #13 1.730 + netaddr==1.3.0 2025-07-14 00:01:51.377217 | debian-bookworm | #13 1.730 + packaging==25.0 2025-07-14 00:01:51.377229 | debian-bookworm | #13 1.731 + pycparser==2.22 2025-07-14 00:01:51.377241 | debian-bookworm | #13 1.731 + pynetbox==7.5.0 2025-07-14 00:01:51.377252 | debian-bookworm | #13 1.731 + pyyaml==6.0.2 2025-07-14 00:01:51.377263 | debian-bookworm | #13 1.731 + requests==2.32.4 2025-07-14 00:01:51.377274 | debian-bookworm | #13 1.732 + resolvelib==1.0.1 2025-07-14 00:01:51.377285 | debian-bookworm | #13 1.733 + urllib3==2.5.0 2025-07-14 00:01:51.395365 | debian-bookworm | #13 DONE 1.9s 2025-07-14 00:01:51.552428 | debian-bookworm | 2025-07-14 00:01:51.552501 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007489 2025-07-14 00:03:23.281494 | 2025-07-14 00:03:23.281824 | TASK [upload-logs : Upload logs to log server] 2025-07-14 00:03:23.892935 | localhost | Output suppressed because no_log was given 2025-07-14 00:03:23.894928 | 2025-07-14 00:03:23.895041 | LOOP [upload-logs : Compress console log and json output] 2025-07-14 00:03:23.947420 | localhost | skipping: Conditional result was False 2025-07-14 00:03:23.954281 | localhost | skipping: Conditional result was False 2025-07-14 00:03:23.968305 | 2025-07-14 00:03:23.968658 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-14 00:03:24.036011 | localhost | skipping: Conditional result was False 2025-07-14 00:03:24.036365 | 2025-07-14 00:03:24.041065 | localhost | skipping: Conditional result was False 2025-07-14 00:03:24.051659 | 2025-07-14 00:03:24.051783 | LOOP [upload-logs : Upload console log and json output]