2025-08-21 22:36:23.704048 | Job console starting 2025-08-21 22:36:23.715899 | Updating git repos 2025-08-21 22:36:23.748047 | Cloning repos into workspace 2025-08-21 22:36:23.813777 | Restoring repo states 2025-08-21 22:36:23.819260 | Merging changes 2025-08-21 22:36:24.377324 | Checking out repos 2025-08-21 22:36:24.439223 | Preparing playbooks 2025-08-21 22:36:25.615807 | Running Ansible setup 2025-08-21 22:36:30.006520 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-21 22:36:30.746660 | 2025-08-21 22:36:30.746813 | PLAY [Base pre] 2025-08-21 22:36:30.762666 | 2025-08-21 22:36:30.762813 | TASK [Setup log path fact] 2025-08-21 22:36:30.792159 | debian-bookworm | ok 2025-08-21 22:36:30.807498 | 2025-08-21 22:36:30.807659 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-21 22:36:30.847874 | debian-bookworm | ok 2025-08-21 22:36:30.858095 | 2025-08-21 22:36:30.858254 | TASK [emit-job-header : Print job information] 2025-08-21 22:36:30.897442 | # Job Information 2025-08-21 22:36:30.897626 | Ansible Version: 2.16.14 2025-08-21 22:36:30.897661 | Job: container-image-inventory-reconciler-build 2025-08-21 22:36:30.897694 | Pipeline: check 2025-08-21 22:36:30.897717 | Executor: 521e9411259a 2025-08-21 22:36:30.897738 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/414 2025-08-21 22:36:30.897760 | Event ID: 3f5dbd00-7edf-11f0-9a6c-abdc8555ceaf 2025-08-21 22:36:30.903994 | 2025-08-21 22:36:30.904128 | LOOP [emit-job-header : Print node information] 2025-08-21 22:36:31.010239 | debian-bookworm | ok: 2025-08-21 22:36:31.010548 | debian-bookworm | # Node Information 2025-08-21 22:36:31.010588 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-21 22:36:31.010614 | debian-bookworm | Hostname: debian 2025-08-21 22:36:31.010636 | debian-bookworm | Username: zuul 2025-08-21 22:36:31.010657 | debian-bookworm | Distro: Debian 12.11 2025-08-21 22:36:31.010681 | debian-bookworm | Provider: regiocloud-a 2025-08-21 22:36:31.010704 | debian-bookworm | Region: 2025-08-21 22:36:31.010724 | debian-bookworm | Label: debian-bookworm 2025-08-21 22:36:31.010743 | debian-bookworm | Product Name: OpenStack Nova 2025-08-21 22:36:31.010763 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe73:4c8b 2025-08-21 22:36:31.031247 | 2025-08-21 22:36:31.031382 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-21 22:36:31.540607 | debian-bookworm -> localhost | changed 2025-08-21 22:36:31.548464 | 2025-08-21 22:36:31.548585 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-21 22:36:32.686449 | debian-bookworm -> localhost | changed 2025-08-21 22:36:32.697512 | 2025-08-21 22:36:32.697623 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-21 22:36:32.984952 | debian-bookworm -> localhost | ok 2025-08-21 22:36:32.991292 | 2025-08-21 22:36:32.991412 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-21 22:36:33.020363 | debian-bookworm | ok 2025-08-21 22:36:33.037614 | debian-bookworm | included: /var/lib/zuul/builds/ea58219dc34c4b5ea3b8111cacf9e65a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-21 22:36:33.044393 | 2025-08-21 22:36:33.044501 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-21 22:36:35.467464 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-21 22:36:35.467987 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ea58219dc34c4b5ea3b8111cacf9e65a/work/ea58219dc34c4b5ea3b8111cacf9e65a_id_rsa 2025-08-21 22:36:35.468095 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ea58219dc34c4b5ea3b8111cacf9e65a/work/ea58219dc34c4b5ea3b8111cacf9e65a_id_rsa.pub 2025-08-21 22:36:35.468196 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-21 22:36:35.468265 | debian-bookworm -> localhost | SHA256:7nKezs6inx2LKDpzDIFKMMrjLFD5PJ7iu4U/ffNcnwk zuul-build-sshkey 2025-08-21 22:36:35.468349 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-21 22:36:35.468411 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-21 22:36:35.468471 | debian-bookworm -> localhost | | . | 2025-08-21 22:36:35.468530 | debian-bookworm -> localhost | |o o | 2025-08-21 22:36:35.468587 | debian-bookworm -> localhost | |=o o | 2025-08-21 22:36:35.468643 | debian-bookworm -> localhost | |=+ + | 2025-08-21 22:36:35.468698 | debian-bookworm -> localhost | |*... o S | 2025-08-21 22:36:35.468763 | debian-bookworm -> localhost | |+o..o . | 2025-08-21 22:36:35.468823 | debian-bookworm -> localhost | |.+.... o E | 2025-08-21 22:36:35.468882 | debian-bookworm -> localhost | |o =o..+B+= . o o | 2025-08-21 22:36:35.468941 | debian-bookworm -> localhost | |.=o++++O@oo + | 2025-08-21 22:36:35.468999 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-21 22:36:35.469167 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.768458 2025-08-21 22:36:35.483014 | 2025-08-21 22:36:35.483191 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-21 22:36:35.519124 | debian-bookworm | ok 2025-08-21 22:36:35.531274 | debian-bookworm | included: /var/lib/zuul/builds/ea58219dc34c4b5ea3b8111cacf9e65a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-21 22:36:35.541043 | 2025-08-21 22:36:35.541190 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-21 22:36:35.564727 | debian-bookworm | skipping: Conditional result was False 2025-08-21 22:36:35.570991 | 2025-08-21 22:36:35.571144 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-21 22:36:36.244546 | debian-bookworm | changed 2025-08-21 22:36:36.252271 | 2025-08-21 22:36:36.252419 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-21 22:36:36.532545 | debian-bookworm | ok 2025-08-21 22:36:36.540983 | 2025-08-21 22:36:36.541162 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-21 22:36:37.235579 | debian-bookworm | changed 2025-08-21 22:36:37.243018 | 2025-08-21 22:36:37.243186 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-21 22:36:37.882428 | debian-bookworm | changed 2025-08-21 22:36:37.891096 | 2025-08-21 22:36:37.891248 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-21 22:36:37.915562 | debian-bookworm | skipping: Conditional result was False 2025-08-21 22:36:37.921709 | 2025-08-21 22:36:37.921832 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-21 22:36:38.366729 | debian-bookworm -> localhost | changed 2025-08-21 22:36:38.388338 | 2025-08-21 22:36:38.388520 | TASK [add-build-sshkey : Add back temp key] 2025-08-21 22:36:38.718583 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ea58219dc34c4b5ea3b8111cacf9e65a/work/ea58219dc34c4b5ea3b8111cacf9e65a_id_rsa (zuul-build-sshkey) 2025-08-21 22:36:38.718905 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013742 2025-08-21 22:36:38.727540 | 2025-08-21 22:36:38.727723 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-21 22:36:39.122727 | debian-bookworm | ok 2025-08-21 22:36:39.128950 | 2025-08-21 22:36:39.129133 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-21 22:36:39.154469 | debian-bookworm | skipping: Conditional result was False 2025-08-21 22:36:39.175374 | 2025-08-21 22:36:39.175538 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-21 22:36:39.623691 | debian-bookworm | ok 2025-08-21 22:36:39.637809 | 2025-08-21 22:36:39.637934 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-21 22:36:39.682294 | debian-bookworm | ok 2025-08-21 22:36:39.689455 | 2025-08-21 22:36:39.689590 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-21 22:36:40.009787 | debian-bookworm -> localhost | ok 2025-08-21 22:36:40.022556 | 2025-08-21 22:36:40.022746 | TASK [validate-host : Collect information about the host] 2025-08-21 22:36:41.208005 | debian-bookworm | ok 2025-08-21 22:36:41.222428 | 2025-08-21 22:36:41.222556 | TASK [validate-host : Sanitize hostname] 2025-08-21 22:36:41.332841 | debian-bookworm | ok 2025-08-21 22:36:41.380404 | 2025-08-21 22:36:41.380539 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-21 22:36:41.934402 | debian-bookworm -> localhost | changed 2025-08-21 22:36:41.941219 | 2025-08-21 22:36:41.941336 | TASK [validate-host : Collect information about zuul worker] 2025-08-21 22:36:42.343004 | debian-bookworm | ok 2025-08-21 22:36:42.347931 | 2025-08-21 22:36:42.348047 | TASK [validate-host : Write out all zuul information for each host] 2025-08-21 22:36:42.912881 | debian-bookworm -> localhost | changed 2025-08-21 22:36:42.923061 | 2025-08-21 22:36:42.923194 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-21 22:36:43.200823 | debian-bookworm | ok 2025-08-21 22:36:43.206560 | 2025-08-21 22:36:43.206680 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-21 22:36:44.066726 | debian-bookworm | changed: 2025-08-21 22:36:44.066990 | debian-bookworm | cd+++++++++ src/ 2025-08-21 22:36:44.067028 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-21 22:36:44.067053 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-21 22:36:44.067076 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-08-21 22:36:44.067097 | debian-bookworm | Containerfile 2025-08-21 22:36:44.067261 | debian-bookworm | 3.5.2-r0) 2025-08-21 22:37:44.778927 | debian-bookworm | #9 5.856 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-08-21 22:37:44.879202 | debian-bookworm | #9 5.887 (26/34) Installing openssl-dev (3.5.2-r0) 2025-08-21 22:37:44.879266 | debian-bookworm | #9 5.934 (27/34) Installing mpdecimal (4.0.1-r0) 2025-08-21 22:37:44.879273 | debian-bookworm | #9 5.957 (28/34) Installing python3 (3.12.11-r0) 2025-08-21 22:37:45.170763 | debian-bookworm | #9 6.248 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-08-21 22:37:45.338605 | debian-bookworm | #9 6.416 (30/34) Installing pyc (3.12.11-r0) 2025-08-21 22:37:45.338658 | debian-bookworm | #9 6.416 (31/34) Installing python3-pyc (3.12.11-r0) 2025-08-21 22:37:45.338664 | debian-bookworm | #9 6.416 (32/34) Installing python3-dev (3.12.11-r0) 2025-08-21 22:37:45.791769 | debian-bookworm | #9 6.869 (33/34) Installing yq-go (4.46.1-r1) 2025-08-21 22:37:45.932255 | debian-bookworm | #9 7.010 (34/34) Installing .build-deps (20250821.223741) 2025-08-21 22:37:46.148226 | debian-bookworm | #9 7.011 Executing busybox-1.37.0-r18.trigger 2025-08-21 22:37:46.148308 | debian-bookworm | #9 7.018 Executing ca-certificates-20250619-r0.trigger 2025-08-21 22:37:46.148316 | debian-bookworm | #9 7.074 OK: 359 MiB in 82 packages 2025-08-21 22:37:46.534132 | debian-bookworm | #9 DONE 7.6s 2025-08-21 22:37:46.636655 | debian-bookworm | 2025-08-21 22:37:46.636791 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-08-21 22:37:46.636904 | debian-bookworm | #10 DONE 0.1s 2025-08-21 22:37:46.636978 | debian-bookworm | 2025-08-21 22:37:46.637023 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-08-21 22:37:46.766244 | debian-bookworm | #11 DONE 0.1s 2025-08-21 22:37:46.766454 | debian-bookworm | 2025-08-21 22:37:46.766508 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-08-21 22:37:46.766549 | debian-bookworm | #12 DONE 0.1s 2025-08-21 22:37:46.921746 | debian-bookworm | 2025-08-21 22:37:46.921937 | debian-bookworm | #13 [builder 7/22] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2025-08-21 22:37:47.536796 | debian-bookworm | #13 0.766 Using Python 3.13.7 environment at: /usr/local 2025-08-21 22:37:47.891805 | debian-bookworm | #13 1.121 Resolved 17 packages in 352ms 2025-08-21 22:37:48.084390 | debian-bookworm | #13 1.161 Downloading netaddr (2.2MiB) 2025-08-21 22:37:48.084530 | debian-bookworm | #13 1.162 Downloading cryptography (4.4MiB) 2025-08-21 22:37:48.084577 | debian-bookworm | #13 1.163 Downloading ansible-core (2.3MiB) 2025-08-21 22:37:48.389160 | debian-bookworm | #13 1.483 Downloading netaddr 2025-08-21 22:37:48.389219 | debian-bookworm | #13 1.541 Downloading cryptography 2025-08-21 22:37:48.389227 | debian-bookworm | #13 1.619 Downloading ansible-core 2025-08-21 22:37:48.599185 | debian-bookworm | #13 1.620 Prepared 17 packages in 497ms 2025-08-21 22:37:48.599256 | debian-bookworm | #13 1.675 Installed 17 packages in 54ms 2025-08-21 22:37:48.599266 | debian-bookworm | #13 1.675 + ansible-core==2.19.0 2025-08-21 22:37:48.599275 | debian-bookworm | #13 1.675 + certifi==2025.8.3 2025-08-21 22:37:48.599295 | debian-bookworm | #13 1.675 + cffi==1.17.1 2025-08-21 22:37:48.599304 | debian-bookworm | #13 1.676 + charset-normalizer==3.4.3 2025-08-21 22:37:48.599311 | debian-bookworm | #13 1.676 + cryptography==45.0.6 2025-08-21 22:37:48.599334 | debian-bookworm | #13 1.676 + dynaconf==3.2.11 2025-08-21 22:37:48.599343 | debian-bookworm | #13 1.676 + idna==3.10 2025-08-21 22:37:48.599366 | debian-bookworm | #13 1.677 + jinja2==3.1.6 2025-08-21 22:37:48.599375 | debian-bookworm | #13 1.677 + markupsafe==3.0.2 2025-08-21 22:37:48.599382 | debian-bookworm | #13 1.677 + netaddr==1.3.0 2025-08-21 22:37:48.599389 | debian-bookworm | #13 1.677 + packaging==25.0 2025-08-21 22:37:48.599396 | debian-bookworm | #13 1.677 + pycparser==2.22 2025-08-21 22:37:48.599404 | debian-bookworm | #13 1.677 + pynetbox==7.5.0 2025-08-21 22:37:48.599411 | debian-bookworm | #13 1.678 + pyyaml==6.0.2 2025-08-21 22:37:48.599418 | debian-bookworm | #13 1.678 + requests==2.32.5 2025-08-21 22:37:48.599425 | debian-bookworm | #13 1.678 + resolvelib==1.2.0 2025-08-21 22:37:48.599436 | debian-bookworm | #13 1.678 + urllib3==2.5.0 2025-08-21 22:37:48.606754 | debian-bookworm | #13 DONE 1.8s 2025-08-21 22:37:48.764039 | debian-bookworm | 2025-08-21 22:37:48.764106 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.006893 2025-08-21 22:38:18.533513 | 2025-08-21 22:38:18.533696 | TASK [upload-logs : Upload logs to log server] 2025-08-21 22:38:19.081612 | localhost | Output suppressed because no_log was given 2025-08-21 22:38:19.084817 | 2025-08-21 22:38:19.084950 | LOOP [upload-logs : Compress console log and json output] 2025-08-21 22:38:19.139294 | localhost | skipping: Conditional result was False 2025-08-21 22:38:19.153835 | localhost | skipping: Conditional result was False 2025-08-21 22:38:19.166584 | 2025-08-21 22:38:19.166891 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-21 22:38:19.215186 | localhost | skipping: Conditional result was False 2025-08-21 22:38:19.215448 | 2025-08-21 22:38:19.229194 | localhost | skipping: Conditional result was False 2025-08-21 22:38:19.236601 | 2025-08-21 22:38:19.236746 | LOOP [upload-logs : Upload console log and json output]