2025-08-23 13:32:53.258101 | Job console starting 2025-08-23 13:32:53.291625 | Updating git repos 2025-08-23 13:32:53.325588 | Cloning repos into workspace 2025-08-23 13:32:53.379371 | Restoring repo states 2025-08-23 13:32:53.385947 | Merging changes 2025-08-23 13:32:53.900326 | Checking out repos 2025-08-23 13:32:53.954592 | Preparing playbooks 2025-08-23 13:32:54.893781 | Running Ansible setup 2025-08-23 13:32:59.846605 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-23 13:33:00.836583 | 2025-08-23 13:33:00.836755 | PLAY [Base pre] 2025-08-23 13:33:00.855143 | 2025-08-23 13:33:00.855309 | TASK [Setup log path fact] 2025-08-23 13:33:00.885096 | debian-bookworm | ok 2025-08-23 13:33:00.916326 | 2025-08-23 13:33:00.916540 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-23 13:33:00.987314 | debian-bookworm | ok 2025-08-23 13:33:01.007075 | 2025-08-23 13:33:01.007228 | TASK [emit-job-header : Print job information] 2025-08-23 13:33:01.075125 | # Job Information 2025-08-23 13:33:01.075324 | Ansible Version: 2.16.14 2025-08-23 13:33:01.075359 | Job: container-image-inventory-reconciler-build 2025-08-23 13:33:01.075392 | Pipeline: check 2025-08-23 13:33:01.075415 | Executor: 521e9411259a 2025-08-23 13:33:01.075436 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/419 2025-08-23 13:33:01.075458 | Event ID: a6aee370-8025-11f0-8fd7-8a5ceea0d66d 2025-08-23 13:33:01.080528 | 2025-08-23 13:33:01.080646 | LOOP [emit-job-header : Print node information] 2025-08-23 13:33:01.191724 | debian-bookworm | ok: 2025-08-23 13:33:01.191991 | debian-bookworm | # Node Information 2025-08-23 13:33:01.192027 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-23 13:33:01.192052 | debian-bookworm | Hostname: debian 2025-08-23 13:33:01.192074 | debian-bookworm | Username: zuul 2025-08-23 13:33:01.192159 | debian-bookworm | Distro: Debian 12.11 2025-08-23 13:33:01.192220 | debian-bookworm | Provider: regiocloud-a 2025-08-23 13:33:01.192246 | debian-bookworm | Region: 2025-08-23 13:33:01.192269 | debian-bookworm | Label: debian-bookworm 2025-08-23 13:33:01.192290 | debian-bookworm | Product Name: OpenStack Nova 2025-08-23 13:33:01.192310 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe34:d196 2025-08-23 13:33:01.213066 | 2025-08-23 13:33:01.213216 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-23 13:33:01.856519 | debian-bookworm -> localhost | changed 2025-08-23 13:33:01.864107 | 2025-08-23 13:33:01.864255 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-23 13:33:03.171348 | debian-bookworm -> localhost | changed 2025-08-23 13:33:03.182077 | 2025-08-23 13:33:03.182184 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-23 13:33:03.410124 | debian-bookworm -> localhost | ok 2025-08-23 13:33:03.416519 | 2025-08-23 13:33:03.416626 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-23 13:33:03.443875 | debian-bookworm | ok 2025-08-23 13:33:03.459764 | debian-bookworm | included: /var/lib/zuul/builds/72135afccc2d4a23a8df5d30ff16ee08/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-23 13:33:03.466540 | 2025-08-23 13:33:03.466632 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-23 13:33:04.931459 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-23 13:33:04.931671 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/72135afccc2d4a23a8df5d30ff16ee08/work/72135afccc2d4a23a8df5d30ff16ee08_id_rsa 2025-08-23 13:33:04.931710 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/72135afccc2d4a23a8df5d30ff16ee08/work/72135afccc2d4a23a8df5d30ff16ee08_id_rsa.pub 2025-08-23 13:33:04.931736 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-23 13:33:04.931761 | debian-bookworm -> localhost | SHA256:ZifPXG0T2LB+aUfJfdUOYobtDFI59SQnt5b4M55VXwI zuul-build-sshkey 2025-08-23 13:33:04.931793 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-23 13:33:04.931816 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-23 13:33:04.931837 | debian-bookworm -> localhost | | ..=E + o| 2025-08-23 13:33:04.931858 | debian-bookworm -> localhost | | . = =#oo=| 2025-08-23 13:33:04.931878 | debian-bookworm -> localhost | | . B+.O=*| 2025-08-23 13:33:04.931897 | debian-bookworm -> localhost | | .o+ =*| 2025-08-23 13:33:04.931917 | debian-bookworm -> localhost | | S . o @ +| 2025-08-23 13:33:04.931943 | debian-bookworm -> localhost | | o * . = B | 2025-08-23 13:33:04.931963 | debian-bookworm -> localhost | | + o | 2025-08-23 13:33:04.931983 | debian-bookworm -> localhost | | | 2025-08-23 13:33:04.932003 | debian-bookworm -> localhost | | | 2025-08-23 13:33:04.932023 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-23 13:33:04.932077 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.798116 2025-08-23 13:33:04.938402 | 2025-08-23 13:33:04.938515 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-23 13:33:04.966173 | debian-bookworm | ok 2025-08-23 13:33:04.977474 | debian-bookworm | included: /var/lib/zuul/builds/72135afccc2d4a23a8df5d30ff16ee08/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-23 13:33:04.986203 | 2025-08-23 13:33:04.986284 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-23 13:33:05.009231 | debian-bookworm | skipping: Conditional result was False 2025-08-23 13:33:05.014719 | 2025-08-23 13:33:05.014801 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-23 13:33:05.639349 | debian-bookworm | changed 2025-08-23 13:33:05.646935 | 2025-08-23 13:33:05.647036 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-23 13:33:05.915174 | debian-bookworm | ok 2025-08-23 13:33:05.923828 | 2025-08-23 13:33:05.923965 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-23 13:33:06.547558 | debian-bookworm | changed 2025-08-23 13:33:06.556329 | 2025-08-23 13:33:06.556439 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-23 13:33:07.136566 | debian-bookworm | changed 2025-08-23 13:33:07.142818 | 2025-08-23 13:33:07.143011 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-23 13:33:07.155778 | debian-bookworm | skipping: Conditional result was False 2025-08-23 13:33:07.161393 | 2025-08-23 13:33:07.161480 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-23 13:33:07.566580 | debian-bookworm -> localhost | changed 2025-08-23 13:33:07.576959 | 2025-08-23 13:33:07.577058 | TASK [add-build-sshkey : Add back temp key] 2025-08-23 13:33:07.873940 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/72135afccc2d4a23a8df5d30ff16ee08/work/72135afccc2d4a23a8df5d30ff16ee08_id_rsa (zuul-build-sshkey) 2025-08-23 13:33:07.874137 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013130 2025-08-23 13:33:07.881432 | 2025-08-23 13:33:07.881548 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-23 13:33:08.269436 | debian-bookworm | ok 2025-08-23 13:33:08.279025 | 2025-08-23 13:33:08.279142 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-23 13:33:08.302300 | debian-bookworm | skipping: Conditional result was False 2025-08-23 13:33:08.312878 | 2025-08-23 13:33:08.312967 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-23 13:33:08.710644 | debian-bookworm | ok 2025-08-23 13:33:08.724834 | 2025-08-23 13:33:08.724937 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-23 13:33:08.768768 | debian-bookworm | ok 2025-08-23 13:33:08.775970 | 2025-08-23 13:33:08.776070 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-23 13:33:09.043040 | debian-bookworm -> localhost | ok 2025-08-23 13:33:09.049200 | 2025-08-23 13:33:09.049296 | TASK [validate-host : Collect information about the host] 2025-08-23 13:33:10.074547 | debian-bookworm | ok 2025-08-23 13:33:10.095223 | 2025-08-23 13:33:10.095353 | TASK [validate-host : Sanitize hostname] 2025-08-23 13:33:10.206564 | debian-bookworm | ok 2025-08-23 13:33:10.270350 | 2025-08-23 13:33:10.270532 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-23 13:33:10.846767 | debian-bookworm -> localhost | changed 2025-08-23 13:33:10.858696 | 2025-08-23 13:33:10.858869 | TASK [validate-host : Collect information about zuul worker] 2025-08-23 13:33:11.280558 | debian-bookworm | ok 2025-08-23 13:33:11.285879 | 2025-08-23 13:33:11.294716 | TASK [validate-host : Write out all zuul information for each host] 2025-08-23 13:33:11.885159 | debian-bookworm -> localhost | changed 2025-08-23 13:33:11.895232 | 2025-08-23 13:33:11.895397 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-23 13:33:12.171996 | debian-bookworm | ok 2025-08-23 13:33:12.179972 | 2025-08-23 13:33:12.180099 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-23 13:33:13.109149 | debian-bookworm | changed: 2025-08-23 13:33:13.109372 | debian-bookworm | cd+++++++++ src/ 2025-08-23 13:33:13.109414 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-23 13:33:13.109449 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-23 13:33:13.109481 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-08-23 13:33:13.109528 | debian-bookworm | Containerfile 2025-08-23 13:33:13.109662 | debian-bookworm | 3.5.2-r0) 2025-08-23 13:34:17.991717 | debian-bookworm | #9 7.701 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-08-23 13:34:18.112658 | debian-bookworm | #9 7.743 (26/34) Installing openssl-dev (3.5.2-r0) 2025-08-23 13:34:18.112782 | debian-bookworm | #9 7.800 (27/34) Installing mpdecimal (4.0.1-r0) 2025-08-23 13:34:18.112871 | debian-bookworm | #9 7.823 (28/34) Installing python3 (3.12.11-r0) 2025-08-23 13:34:18.484145 | debian-bookworm | #9 8.194 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-08-23 13:34:18.687453 | debian-bookworm | #9 8.398 (30/34) Installing pyc (3.12.11-r0) 2025-08-23 13:34:18.839112 | debian-bookworm | #9 8.399 (31/34) Installing python3-pyc (3.12.11-r0) 2025-08-23 13:34:18.839272 | debian-bookworm | #9 8.399 (32/34) Installing python3-dev (3.12.11-r0) 2025-08-23 13:34:19.330783 | debian-bookworm | #9 9.040 (33/34) Installing yq-go (4.46.1-r1) 2025-08-23 13:34:19.524283 | debian-bookworm | #9 9.234 (34/34) Installing .build-deps (20250823.133413) 2025-08-23 13:34:19.739491 | debian-bookworm | #9 9.236 Executing busybox-1.37.0-r18.trigger 2025-08-23 13:34:19.739596 | debian-bookworm | #9 9.244 Executing ca-certificates-20250619-r0.trigger 2025-08-23 13:34:19.739623 | debian-bookworm | #9 9.297 OK: 359 MiB in 82 packages 2025-08-23 13:34:20.020688 | debian-bookworm | #9 DONE 9.7s 2025-08-23 13:34:20.129137 | debian-bookworm | 2025-08-23 13:34:20.129228 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-08-23 13:34:20.129256 | debian-bookworm | #10 DONE 0.1s 2025-08-23 13:34:20.269641 | debian-bookworm | 2025-08-23 13:34:20.269718 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-08-23 13:34:20.269729 | debian-bookworm | #11 DONE 0.1s 2025-08-23 13:34:20.269737 | debian-bookworm | 2025-08-23 13:34:20.269745 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-08-23 13:34:20.269762 | debian-bookworm | #12 DONE 0.1s 2025-08-23 13:34:20.423522 | debian-bookworm | 2025-08-23 13:34:20.423617 | 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-23 13:34:21.002746 | debian-bookworm | #13 0.730 Using Python 3.13.7 environment at: /usr/local 2025-08-23 13:34:21.483997 | debian-bookworm | #13 1.021 Resolved 17 packages in 288ms 2025-08-23 13:34:21.484050 | debian-bookworm | #13 1.059 Downloading cryptography (4.4MiB) 2025-08-23 13:34:21.484066 | debian-bookworm | #13 1.060 Downloading netaddr (2.2MiB) 2025-08-23 13:34:21.484070 | debian-bookworm | #13 1.060 Downloading ansible-core (2.3MiB) 2025-08-23 13:34:21.643388 | debian-bookworm | #13 1.370 Downloading netaddr 2025-08-23 13:34:21.798632 | debian-bookworm | #13 1.459 Downloading cryptography 2025-08-23 13:34:21.976183 | debian-bookworm | #13 1.526 Downloading ansible-core 2025-08-23 13:34:21.976266 | debian-bookworm | #13 1.528 Prepared 17 packages in 505ms 2025-08-23 13:34:21.976298 | debian-bookworm | #13 1.575 Installed 17 packages in 46ms 2025-08-23 13:34:21.976310 | debian-bookworm | #13 1.576 + ansible-core==2.19.0 2025-08-23 13:34:21.976323 | debian-bookworm | #13 1.576 + certifi==2025.8.3 2025-08-23 13:34:21.976335 | debian-bookworm | #13 1.576 + cffi==1.17.1 2025-08-23 13:34:21.976347 | debian-bookworm | #13 1.576 + charset-normalizer==3.4.3 2025-08-23 13:34:21.976358 | debian-bookworm | #13 1.576 + cryptography==45.0.6 2025-08-23 13:34:21.976369 | debian-bookworm | #13 1.576 + dynaconf==3.2.11 2025-08-23 13:34:21.976381 | debian-bookworm | #13 1.576 + idna==3.10 2025-08-23 13:34:21.976392 | debian-bookworm | #13 1.576 + jinja2==3.1.6 2025-08-23 13:34:21.976404 | debian-bookworm | #13 1.576 + markupsafe==3.0.2 2025-08-23 13:34:21.976415 | debian-bookworm | #13 1.576 + netaddr==1.3.0 2025-08-23 13:34:21.976426 | debian-bookworm | #13 1.576 + packaging==25.0 2025-08-23 13:34:21.976436 | debian-bookworm | #13 1.576 + pycparser==2.22 2025-08-23 13:34:21.976463 | debian-bookworm | #13 1.576 + pynetbox==7.5.0 2025-08-23 13:34:21.976475 | debian-bookworm | #13 1.576 + pyyaml==6.0.2 2025-08-23 13:34:21.976486 | debian-bookworm | #13 1.576 + requests==2.32.5 2025-08-23 13:34:21.976497 | debian-bookworm | #13 1.576 + resolvelib==1.2.0 2025-08-23 13:34:21.976507 | debian-bookworm | #13 1.576 + urllib3==2.5.0 2025-08-23 13:34:21.976518 | debian-bookworm | #13 DONE 1.7s 2025-08-23 13:34:22.140432 | debian-bookworm | 2025-08-23 13:34:22.140539 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007113 2025-08-23 13:34:51.264416 | 2025-08-23 13:34:51.264575 | TASK [upload-logs : Upload logs to log server] 2025-08-23 13:34:51.843061 | localhost | Output suppressed because no_log was given 2025-08-23 13:34:51.847405 | 2025-08-23 13:34:51.847698 | LOOP [upload-logs : Compress console log and json output] 2025-08-23 13:34:51.908505 | localhost | skipping: Conditional result was False 2025-08-23 13:34:51.926375 | localhost | skipping: Conditional result was False 2025-08-23 13:34:51.937637 | 2025-08-23 13:34:51.937804 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-23 13:34:51.984667 | localhost | skipping: Conditional result was False 2025-08-23 13:34:51.984926 | 2025-08-23 13:34:51.991329 | localhost | skipping: Conditional result was False 2025-08-23 13:34:51.998518 | 2025-08-23 13:34:51.998765 | LOOP [upload-logs : Upload console log and json output]