2025-09-19 20:36:59.864493 | Job console starting 2025-09-19 20:36:59.903682 | Updating git repos 2025-09-19 20:36:59.936996 | Cloning repos into workspace 2025-09-19 20:36:59.986145 | Restoring repo states 2025-09-19 20:36:59.996521 | Merging changes 2025-09-19 20:37:00.529807 | Checking out repos 2025-09-19 20:37:00.616299 | Preparing playbooks 2025-09-19 20:37:01.606867 | Running Ansible setup 2025-09-19 20:37:06.320270 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-19 20:37:07.323631 | 2025-09-19 20:37:07.323799 | PLAY [Base pre] 2025-09-19 20:37:07.340019 | 2025-09-19 20:37:07.340158 | TASK [Setup log path fact] 2025-09-19 20:37:07.369315 | debian-bookworm | ok 2025-09-19 20:37:07.404370 | 2025-09-19 20:37:07.404529 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-19 20:37:07.435940 | debian-bookworm | ok 2025-09-19 20:37:07.445833 | 2025-09-19 20:37:07.445951 | TASK [emit-job-header : Print job information] 2025-09-19 20:37:07.484721 | # Job Information 2025-09-19 20:37:07.484902 | Ansible Version: 2.16.14 2025-09-19 20:37:07.484937 | Job: container-image-inventory-reconciler-build 2025-09-19 20:37:07.484970 | Pipeline: check 2025-09-19 20:37:07.484992 | Executor: 521e9411259a 2025-09-19 20:37:07.485013 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/429 2025-09-19 20:37:07.485035 | Event ID: 5ed99210-9598-11f0-86ff-df0f0a946bb0 2025-09-19 20:37:07.489915 | 2025-09-19 20:37:07.490023 | LOOP [emit-job-header : Print node information] 2025-09-19 20:37:07.596333 | debian-bookworm | ok: 2025-09-19 20:37:07.599445 | debian-bookworm | # Node Information 2025-09-19 20:37:07.599559 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-19 20:37:07.599593 | debian-bookworm | Hostname: debian 2025-09-19 20:37:07.599618 | debian-bookworm | Username: zuul 2025-09-19 20:37:07.599640 | debian-bookworm | Distro: Debian 12.12 2025-09-19 20:37:07.599668 | debian-bookworm | Provider: regiocloud-a 2025-09-19 20:37:07.599690 | debian-bookworm | Region: 2025-09-19 20:37:07.599711 | debian-bookworm | Label: debian-bookworm 2025-09-19 20:37:07.599731 | debian-bookworm | Product Name: OpenStack Nova 2025-09-19 20:37:07.599751 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee9:5ea 2025-09-19 20:37:07.617699 | 2025-09-19 20:37:07.617821 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-19 20:37:08.383450 | debian-bookworm -> localhost | changed 2025-09-19 20:37:08.391008 | 2025-09-19 20:37:08.391147 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-19 20:37:09.706265 | debian-bookworm -> localhost | changed 2025-09-19 20:37:09.727606 | 2025-09-19 20:37:09.727756 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-19 20:37:10.026295 | debian-bookworm -> localhost | ok 2025-09-19 20:37:10.041476 | 2025-09-19 20:37:10.041737 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-19 20:37:10.084889 | debian-bookworm | ok 2025-09-19 20:37:10.115556 | debian-bookworm | included: /var/lib/zuul/builds/714063325dad4633ac7cda5868882988/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-19 20:37:10.130314 | 2025-09-19 20:37:10.130419 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-19 20:37:11.154067 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-19 20:37:11.154297 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/714063325dad4633ac7cda5868882988/work/714063325dad4633ac7cda5868882988_id_rsa 2025-09-19 20:37:11.154338 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/714063325dad4633ac7cda5868882988/work/714063325dad4633ac7cda5868882988_id_rsa.pub 2025-09-19 20:37:11.154365 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-19 20:37:11.154390 | debian-bookworm -> localhost | SHA256:4hFLqE0jjhjwmctjPkDIivXu/EJP7hGSVIELEYH8IHo zuul-build-sshkey 2025-09-19 20:37:11.154427 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-19 20:37:11.154450 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-19 20:37:11.154473 | debian-bookworm -> localhost | |o.++ .o. | 2025-09-19 20:37:11.154496 | debian-bookworm -> localhost | |*+.o.o | 2025-09-19 20:37:11.154518 | debian-bookworm -> localhost | |=oO.=.o | 2025-09-19 20:37:11.154555 | debian-bookworm -> localhost | |**EX.+ o | 2025-09-19 20:37:11.154576 | debian-bookworm -> localhost | |*.B = = S | 2025-09-19 20:37:11.154607 | debian-bookworm -> localhost | | + o.o.+ | 2025-09-19 20:37:11.154629 | debian-bookworm -> localhost | | o..+o | 2025-09-19 20:37:11.154650 | debian-bookworm -> localhost | | +. o. | 2025-09-19 20:37:11.154671 | debian-bookworm -> localhost | | o+o | 2025-09-19 20:37:11.154693 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-19 20:37:11.154749 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.398374 2025-09-19 20:37:11.161379 | 2025-09-19 20:37:11.161489 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-19 20:37:11.179971 | debian-bookworm | ok 2025-09-19 20:37:11.189569 | debian-bookworm | included: /var/lib/zuul/builds/714063325dad4633ac7cda5868882988/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-19 20:37:11.198726 | 2025-09-19 20:37:11.198853 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-19 20:37:11.221943 | debian-bookworm | skipping: Conditional result was False 2025-09-19 20:37:11.227579 | 2025-09-19 20:37:11.227680 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-19 20:37:11.928720 | debian-bookworm | changed 2025-09-19 20:37:11.936959 | 2025-09-19 20:37:11.937077 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-19 20:37:12.205065 | debian-bookworm | ok 2025-09-19 20:37:12.211184 | 2025-09-19 20:37:12.211301 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-19 20:37:12.849391 | debian-bookworm | changed 2025-09-19 20:37:12.859749 | 2025-09-19 20:37:12.859877 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-19 20:37:13.526161 | debian-bookworm | changed 2025-09-19 20:37:13.532115 | 2025-09-19 20:37:13.532229 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-19 20:37:13.556317 | debian-bookworm | skipping: Conditional result was False 2025-09-19 20:37:13.562719 | 2025-09-19 20:37:13.562904 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-19 20:37:14.032584 | debian-bookworm -> localhost | changed 2025-09-19 20:37:14.051433 | 2025-09-19 20:37:14.051599 | TASK [add-build-sshkey : Add back temp key] 2025-09-19 20:37:14.387548 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/714063325dad4633ac7cda5868882988/work/714063325dad4633ac7cda5868882988_id_rsa (zuul-build-sshkey) 2025-09-19 20:37:14.387853 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010327 2025-09-19 20:37:14.396280 | 2025-09-19 20:37:14.396401 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-19 20:37:14.788831 | debian-bookworm | ok 2025-09-19 20:37:14.799400 | 2025-09-19 20:37:14.799568 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-19 20:37:14.827762 | debian-bookworm | skipping: Conditional result was False 2025-09-19 20:37:14.838330 | 2025-09-19 20:37:14.838458 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-19 20:37:15.283083 | debian-bookworm | ok 2025-09-19 20:37:15.302033 | 2025-09-19 20:37:15.302183 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-19 20:37:15.351792 | debian-bookworm | ok 2025-09-19 20:37:15.356849 | 2025-09-19 20:37:15.356955 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-19 20:37:15.660632 | debian-bookworm -> localhost | ok 2025-09-19 20:37:15.673990 | 2025-09-19 20:37:15.674155 | TASK [validate-host : Collect information about the host] 2025-09-19 20:37:16.770575 | debian-bookworm | ok 2025-09-19 20:37:16.784621 | 2025-09-19 20:37:16.784748 | TASK [validate-host : Sanitize hostname] 2025-09-19 20:37:16.896987 | debian-bookworm | ok 2025-09-19 20:37:16.954013 | 2025-09-19 20:37:16.954154 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-19 20:37:17.537252 | debian-bookworm -> localhost | changed 2025-09-19 20:37:17.543140 | 2025-09-19 20:37:17.543247 | TASK [validate-host : Collect information about zuul worker] 2025-09-19 20:37:17.936560 | debian-bookworm | ok 2025-09-19 20:37:17.941340 | 2025-09-19 20:37:17.941452 | TASK [validate-host : Write out all zuul information for each host] 2025-09-19 20:37:18.601127 | debian-bookworm -> localhost | changed 2025-09-19 20:37:18.613061 | 2025-09-19 20:37:18.613185 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-19 20:37:18.934636 | debian-bookworm | ok 2025-09-19 20:37:18.944119 | 2025-09-19 20:37:18.944238 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-19 20:37:19.759546 | debian-bookworm | changed: 2025-09-19 20:37:19.759871 | debian-bookworm | cd+++++++++ src/ 2025-09-19 20:37:19.759912 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-19 20:37:19.759937 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-19 20:37:19.759975 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-19 20:37:19.759997 | debian-bookworm | Containerfile 2025-09-19 20:37:19.760131 | debian-bookworm | 3.5.2-r0) 2025-09-19 20:38:20.889274 | debian-bookworm | #9 6.520 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-19 20:38:21.127473 | debian-bookworm | #9 6.548 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-19 20:38:21.127573 | debian-bookworm | #9 6.592 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-19 20:38:21.127589 | debian-bookworm | #9 6.608 (28/34) Installing python3 (3.12.11-r0) 2025-09-19 20:38:21.264540 | debian-bookworm | #9 6.895 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-19 20:38:21.435707 | debian-bookworm | #9 7.066 (30/34) Installing pyc (3.12.11-r0) 2025-09-19 20:38:21.435795 | debian-bookworm | #9 7.066 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-19 20:38:21.435838 | debian-bookworm | #9 7.066 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-19 20:38:21.902969 | debian-bookworm | #9 7.533 (33/34) Installing yq-go (4.47.2-r0) 2025-09-19 20:38:22.062025 | debian-bookworm | #9 7.693 (34/34) Installing .build-deps (20250919.203817) 2025-09-19 20:38:22.277742 | debian-bookworm | #9 7.694 Executing busybox-1.37.0-r18.trigger 2025-09-19 20:38:22.277832 | debian-bookworm | #9 7.701 Executing ca-certificates-20250619-r0.trigger 2025-09-19 20:38:22.277855 | debian-bookworm | #9 7.758 OK: 359 MiB in 82 packages 2025-09-19 20:38:22.628002 | debian-bookworm | #9 DONE 8.3s 2025-09-19 20:38:22.792773 | debian-bookworm | 2025-09-19 20:38:22.792859 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-19 20:38:22.792883 | debian-bookworm | #10 DONE 0.1s 2025-09-19 20:38:22.792894 | debian-bookworm | 2025-09-19 20:38:22.792904 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-19 20:38:22.792915 | debian-bookworm | #11 DONE 0.1s 2025-09-19 20:38:23.014107 | debian-bookworm | 2025-09-19 20:38:23.014219 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-19 20:38:23.014238 | debian-bookworm | #12 DONE 0.1s 2025-09-19 20:38:23.014250 | debian-bookworm | 2025-09-19 20:38:23.014262 | debian-bookworm | #13 [builder 7/22] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2025-09-19 20:38:23.593268 | debian-bookworm | #13 0.730 Using Python 3.13.7 environment at: /usr/local 2025-09-19 20:38:23.867205 | debian-bookworm | #13 1.004 Resolved 17 packages in 271ms 2025-09-19 20:38:24.067219 | debian-bookworm | #13 1.047 Downloading ansible-core (2.3MiB) 2025-09-19 20:38:24.067272 | debian-bookworm | #13 1.048 Downloading cryptography (4.5MiB) 2025-09-19 20:38:24.067278 | debian-bookworm | #13 1.053 Downloading netaddr (2.2MiB) 2025-09-19 20:38:24.394555 | debian-bookworm | #13 1.351 Downloading netaddr 2025-09-19 20:38:24.394629 | debian-bookworm | #13 1.418 Downloading cryptography 2025-09-19 20:38:24.394642 | debian-bookworm | #13 1.531 Downloading ansible-core 2025-09-19 20:38:24.579041 | debian-bookworm | #13 1.533 Prepared 17 packages in 526ms 2025-09-19 20:38:24.579174 | debian-bookworm | #13 1.581 Installed 17 packages in 47ms 2025-09-19 20:38:24.579194 | debian-bookworm | #13 1.581 + ansible-core==2.19.2 2025-09-19 20:38:24.579208 | debian-bookworm | #13 1.581 + certifi==2025.8.3 2025-09-19 20:38:24.579233 | debian-bookworm | #13 1.581 + cffi==2.0.0 2025-09-19 20:38:24.579245 | debian-bookworm | #13 1.581 + charset-normalizer==3.4.3 2025-09-19 20:38:24.579256 | debian-bookworm | #13 1.581 + cryptography==46.0.1 2025-09-19 20:38:24.579267 | debian-bookworm | #13 1.581 + dynaconf==3.2.11 2025-09-19 20:38:24.579280 | debian-bookworm | #13 1.581 + idna==3.10 2025-09-19 20:38:24.579291 | debian-bookworm | #13 1.581 + jinja2==3.1.6 2025-09-19 20:38:24.579304 | debian-bookworm | #13 1.581 + markupsafe==3.0.2 2025-09-19 20:38:24.579322 | debian-bookworm | #13 1.581 + netaddr==1.3.0 2025-09-19 20:38:24.579334 | debian-bookworm | #13 1.581 + packaging==25.0 2025-09-19 20:38:24.579345 | debian-bookworm | #13 1.581 + pycparser==2.23 2025-09-19 20:38:24.579355 | debian-bookworm | #13 1.581 + pynetbox==7.5.0 2025-09-19 20:38:24.579366 | debian-bookworm | #13 1.581 + pyyaml==6.0.2 2025-09-19 20:38:24.579377 | debian-bookworm | #13 1.581 + requests==2.32.5 2025-09-19 20:38:24.579388 | debian-bookworm | #13 1.581 + resolvelib==1.2.0 2025-09-19 20:38:24.579399 | debian-bookworm | #13 1.581 + urllib3==2.5.0 2025-09-19 20:38:24.579410 | debian-bookworm | #13 DONE 1.7s 2025-09-19 20:38:24.735911 | debian-bookworm | 2025-09-19 20:38:24.736026 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.006770 2025-09-19 20:38:53.587274 | 2025-09-19 20:38:53.587495 | TASK [upload-logs : Upload logs to log server] 2025-09-19 20:38:54.164367 | localhost | Output suppressed because no_log was given 2025-09-19 20:38:54.166466 | 2025-09-19 20:38:54.166578 | LOOP [upload-logs : Compress console log and json output] 2025-09-19 20:38:54.220686 | localhost | skipping: Conditional result was False 2025-09-19 20:38:54.226087 | localhost | skipping: Conditional result was False 2025-09-19 20:38:54.233903 | 2025-09-19 20:38:54.234019 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-19 20:38:54.279620 | localhost | skipping: Conditional result was False 2025-09-19 20:38:54.279996 | 2025-09-19 20:38:54.284030 | localhost | skipping: Conditional result was False 2025-09-19 20:38:54.293482 | 2025-09-19 20:38:54.293712 | LOOP [upload-logs : Upload console log and json output]