2025-08-21 18:14:45.625374 | Job console starting 2025-08-21 18:14:45.635446 | Updating git repos 2025-08-21 18:14:45.661653 | Cloning repos into workspace 2025-08-21 18:14:45.708263 | Restoring repo states 2025-08-21 18:14:45.721858 | Merging changes 2025-08-21 18:14:45.722655 | Checking out repos 2025-08-21 18:14:45.786327 | Preparing playbooks 2025-08-21 18:14:46.460694 | Running Ansible setup 2025-08-21 18:14:50.594772 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-21 18:14:51.366806 | 2025-08-21 18:14:51.367040 | PLAY [Base pre] 2025-08-21 18:14:51.384675 | 2025-08-21 18:14:51.384809 | TASK [Setup log path fact] 2025-08-21 18:14:51.423911 | debian-bookworm | ok 2025-08-21 18:14:51.443208 | 2025-08-21 18:14:51.443357 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-21 18:14:51.484293 | debian-bookworm | ok 2025-08-21 18:14:51.495668 | 2025-08-21 18:14:51.495787 | TASK [emit-job-header : Print job information] 2025-08-21 18:14:51.542169 | # Job Information 2025-08-21 18:14:51.542546 | Ansible Version: 2.16.14 2025-08-21 18:14:51.542619 | Job: container-image-inventory-reconciler-push 2025-08-21 18:14:51.542670 | Pipeline: post 2025-08-21 18:14:51.542705 | Executor: 521e9411259a 2025-08-21 18:14:51.542737 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/commit/542e3df22b392d4c5978cad09b98ae4e703bd114 2025-08-21 18:14:51.542769 | Event ID: b42cb110-7eba-11f0-8198-0333748446a3 2025-08-21 18:14:51.551733 | 2025-08-21 18:14:51.552432 | LOOP [emit-job-header : Print node information] 2025-08-21 18:14:51.704821 | debian-bookworm | ok: 2025-08-21 18:14:51.705112 | debian-bookworm | # Node Information 2025-08-21 18:14:51.705169 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-21 18:14:51.705212 | debian-bookworm | Hostname: debian 2025-08-21 18:14:51.705249 | debian-bookworm | Username: zuul 2025-08-21 18:14:51.705284 | debian-bookworm | Distro: Debian 12.11 2025-08-21 18:14:51.705326 | debian-bookworm | Provider: regiocloud-a 2025-08-21 18:14:51.705361 | debian-bookworm | Region: 2025-08-21 18:14:51.705394 | debian-bookworm | Label: debian-bookworm 2025-08-21 18:14:51.705427 | debian-bookworm | Product Name: OpenStack Nova 2025-08-21 18:14:51.705461 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe64:2341 2025-08-21 18:14:51.727022 | 2025-08-21 18:14:51.727168 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-21 18:14:52.211563 | debian-bookworm -> localhost | changed 2025-08-21 18:14:52.221835 | 2025-08-21 18:14:52.222048 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-21 18:14:53.303923 | debian-bookworm -> localhost | changed 2025-08-21 18:14:53.315273 | 2025-08-21 18:14:53.315390 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-21 18:14:53.663921 | debian-bookworm -> localhost | ok 2025-08-21 18:14:53.673277 | 2025-08-21 18:14:53.673525 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-21 18:14:53.712283 | debian-bookworm | ok 2025-08-21 18:14:53.737434 | debian-bookworm | included: /var/lib/zuul/builds/eddf1763c30347118e004c5bea333ee0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-21 18:14:53.743895 | 2025-08-21 18:14:53.744038 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-21 18:14:55.735236 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-21 18:14:55.735633 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/eddf1763c30347118e004c5bea333ee0/work/eddf1763c30347118e004c5bea333ee0_id_rsa 2025-08-21 18:14:55.735712 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/eddf1763c30347118e004c5bea333ee0/work/eddf1763c30347118e004c5bea333ee0_id_rsa.pub 2025-08-21 18:14:55.735768 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-21 18:14:55.735817 | debian-bookworm -> localhost | SHA256:tpA4gbEUnfshIXWh0hDwn1ZK77dKTEvet/+fE53NDIs zuul-build-sshkey 2025-08-21 18:14:55.735885 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-21 18:14:55.735933 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-21 18:14:55.735995 | debian-bookworm -> localhost | |..*=..o. | 2025-08-21 18:14:55.736042 | debian-bookworm -> localhost | | o.*+o | 2025-08-21 18:14:55.736085 | debian-bookworm -> localhost | | =.=o. | 2025-08-21 18:14:55.736128 | debian-bookworm -> localhost | | +oB.. . | 2025-08-21 18:14:55.736171 | debian-bookworm -> localhost | | Bo*.S . =+| 2025-08-21 18:14:55.736223 | debian-bookworm -> localhost | | . B.= . E .o=| 2025-08-21 18:14:55.736270 | debian-bookworm -> localhost | | * + . .| 2025-08-21 18:14:55.736313 | debian-bookworm -> localhost | | . . o . ..| 2025-08-21 18:14:55.736358 | debian-bookworm -> localhost | | ... .....oo| 2025-08-21 18:14:55.736400 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-21 18:14:55.736506 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.475264 2025-08-21 18:14:55.748034 | 2025-08-21 18:14:55.748191 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-21 18:14:55.779543 | debian-bookworm | ok 2025-08-21 18:14:55.790597 | debian-bookworm | included: /var/lib/zuul/builds/eddf1763c30347118e004c5bea333ee0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-21 18:14:55.800157 | 2025-08-21 18:14:55.800257 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-21 18:14:55.823501 | debian-bookworm | skipping: Conditional result was False 2025-08-21 18:14:55.829208 | 2025-08-21 18:14:55.829311 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-21 18:14:56.480816 | debian-bookworm | changed 2025-08-21 18:14:56.489000 | 2025-08-21 18:14:56.489124 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-21 18:14:56.748552 | debian-bookworm | ok 2025-08-21 18:14:56.758229 | 2025-08-21 18:14:56.758380 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-21 18:14:57.420863 | debian-bookworm | changed 2025-08-21 18:14:57.428476 | 2025-08-21 18:14:57.428602 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-21 18:14:58.089157 | debian-bookworm | changed 2025-08-21 18:14:58.094989 | 2025-08-21 18:14:58.095106 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-21 18:14:58.128887 | debian-bookworm | skipping: Conditional result was False 2025-08-21 18:14:58.135672 | 2025-08-21 18:14:58.135796 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-21 18:14:58.586667 | debian-bookworm -> localhost | changed 2025-08-21 18:14:58.597801 | 2025-08-21 18:14:58.597929 | TASK [add-build-sshkey : Add back temp key] 2025-08-21 18:14:59.004061 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/eddf1763c30347118e004c5bea333ee0/work/eddf1763c30347118e004c5bea333ee0_id_rsa (zuul-build-sshkey) 2025-08-21 18:14:59.004596 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017176 2025-08-21 18:14:59.032378 | 2025-08-21 18:14:59.032517 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-21 18:14:59.453124 | debian-bookworm | ok 2025-08-21 18:14:59.463883 | 2025-08-21 18:14:59.464020 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-21 18:14:59.498722 | debian-bookworm | skipping: Conditional result was False 2025-08-21 18:14:59.529846 | 2025-08-21 18:14:59.530162 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-21 18:15:00.049317 | debian-bookworm | ok 2025-08-21 18:15:00.080103 | 2025-08-21 18:15:00.080248 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-21 18:15:00.128346 | debian-bookworm | ok 2025-08-21 18:15:00.137357 | 2025-08-21 18:15:00.137664 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-21 18:15:00.480867 | debian-bookworm -> localhost | ok 2025-08-21 18:15:00.507239 | 2025-08-21 18:15:00.507455 | TASK [validate-host : Collect information about the host] 2025-08-21 18:15:01.762694 | debian-bookworm | ok 2025-08-21 18:15:01.776760 | 2025-08-21 18:15:01.776927 | TASK [validate-host : Sanitize hostname] 2025-08-21 18:15:01.904865 | debian-bookworm | ok 2025-08-21 18:15:01.960180 | 2025-08-21 18:15:01.960328 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-21 18:15:02.626073 | debian-bookworm -> localhost | changed 2025-08-21 18:15:02.655400 | 2025-08-21 18:15:02.656257 | TASK [validate-host : Collect information about zuul worker] 2025-08-21 18:15:03.115141 | debian-bookworm | ok 2025-08-21 18:15:03.120051 | 2025-08-21 18:15:03.120177 | TASK [validate-host : Write out all zuul information for each host] 2025-08-21 18:15:03.671751 | debian-bookworm -> localhost | changed 2025-08-21 18:15:03.683567 | 2025-08-21 18:15:03.683692 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-21 18:15:03.961342 | debian-bookworm | ok 2025-08-21 18:15:03.970304 | 2025-08-21 18:15:03.970433 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-21 18:15:04.791374 | debian-bookworm | changed: 2025-08-21 18:15:04.791605 | debian-bookworm | cd+++++++++ src/ 2025-08-21 18:15:04.791642 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-21 18:15:04.792198 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-21 18:15:04.792251 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-08-21 18:15:04.792277 | debian-bookworm | Containerfile 2025-08-21 18:15:04.792415 | debian-bookworm | 3.5.2-r0) 2025-08-21 18:16:08.030730 | debian-bookworm | #9 6.838 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-08-21 18:16:08.149628 | debian-bookworm | #9 6.878 (26/34) Installing openssl-dev (3.5.2-r0) 2025-08-21 18:16:08.149742 | debian-bookworm | #9 6.957 (27/34) Installing mpdecimal (4.0.1-r0) 2025-08-21 18:16:08.359101 | debian-bookworm | #9 7.016 (28/34) Installing python3 (3.12.11-r0) 2025-08-21 18:16:08.549046 | debian-bookworm | #9 7.356 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-08-21 18:16:08.827278 | debian-bookworm | #9 7.635 (30/34) Installing pyc (3.12.11-r0) 2025-08-21 18:16:08.978827 | debian-bookworm | #9 7.635 (31/34) Installing python3-pyc (3.12.11-r0) 2025-08-21 18:16:08.979014 | debian-bookworm | #9 7.635 (32/34) Installing python3-dev (3.12.11-r0) 2025-08-21 18:16:09.698970 | debian-bookworm | #9 8.506 (33/34) Installing yq-go (4.46.1-r1) 2025-08-21 18:16:09.873195 | debian-bookworm | #9 8.680 (34/34) Installing .build-deps (20250821.181604) 2025-08-21 18:16:10.098981 | debian-bookworm | #9 8.682 Executing busybox-1.37.0-r18.trigger 2025-08-21 18:16:10.099058 | debian-bookworm | #9 8.691 Executing ca-certificates-20250619-r0.trigger 2025-08-21 18:16:10.099078 | debian-bookworm | #9 8.756 OK: 359 MiB in 82 packages 2025-08-21 18:16:10.477429 | debian-bookworm | #9 DONE 9.3s 2025-08-21 18:16:10.632897 | debian-bookworm | 2025-08-21 18:16:10.633046 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-08-21 18:16:10.633100 | debian-bookworm | #10 DONE 0.1s 2025-08-21 18:16:10.633131 | debian-bookworm | 2025-08-21 18:16:10.633159 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-08-21 18:16:10.633191 | debian-bookworm | #11 DONE 0.1s 2025-08-21 18:16:10.857146 | debian-bookworm | 2025-08-21 18:16:10.857250 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-08-21 18:16:10.857257 | debian-bookworm | #12 DONE 0.1s 2025-08-21 18:16:10.857262 | debian-bookworm | 2025-08-21 18:16:10.857267 | 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 18:16:11.587589 | debian-bookworm | #13 0.730 Using Python 3.13.7 environment at: /usr/local 2025-08-21 18:16:11.670553 | debian-bookworm | #13 0.964 Resolved 17 packages in 231ms 2025-08-21 18:16:11.874290 | debian-bookworm | #13 1.008 Downloading ansible-core (2.3MiB) 2025-08-21 18:16:11.874363 | debian-bookworm | #13 1.011 Downloading netaddr (2.2MiB) 2025-08-21 18:16:11.874369 | debian-bookworm | #13 1.016 Downloading cryptography (4.4MiB) 2025-08-21 18:16:12.043553 | debian-bookworm | #13 1.336 Downloading netaddr 2025-08-21 18:16:12.143584 | debian-bookworm | #13 1.436 Downloading cryptography 2025-08-21 18:16:12.245721 | debian-bookworm | #13 1.482 Downloading ansible-core 2025-08-21 18:16:12.245794 | debian-bookworm | #13 1.484 Prepared 17 packages in 518ms 2025-08-21 18:16:12.403605 | debian-bookworm | #13 1.539 Installed 17 packages in 54ms 2025-08-21 18:16:12.403778 | debian-bookworm | #13 1.541 + ansible-core==2.19.0 2025-08-21 18:16:12.403791 | debian-bookworm | #13 1.542 + certifi==2025.8.3 2025-08-21 18:16:12.403800 | debian-bookworm | #13 1.542 + cffi==1.17.1 2025-08-21 18:16:12.403809 | debian-bookworm | #13 1.542 + charset-normalizer==3.4.3 2025-08-21 18:16:12.403817 | debian-bookworm | #13 1.543 + cryptography==45.0.6 2025-08-21 18:16:12.403825 | debian-bookworm | #13 1.543 + dynaconf==3.2.11 2025-08-21 18:16:12.403834 | debian-bookworm | #13 1.544 + idna==3.10 2025-08-21 18:16:12.403842 | debian-bookworm | #13 1.544 + jinja2==3.1.6 2025-08-21 18:16:12.403851 | debian-bookworm | #13 1.544 + markupsafe==3.0.2 2025-08-21 18:16:12.403860 | debian-bookworm | #13 1.545 + netaddr==1.3.0 2025-08-21 18:16:12.403868 | debian-bookworm | #13 1.545 + packaging==25.0 2025-08-21 18:16:12.403875 | debian-bookworm | #13 1.545 + pycparser==2.22 2025-08-21 18:16:12.403883 | debian-bookworm | #13 1.545 + pynetbox==7.5.0 2025-08-21 18:16:12.403891 | debian-bookworm | #13 1.546 + pyyaml==6.0.2 2025-08-21 18:16:12.403899 | debian-bookworm | #13 1.546 + requests==2.32.5 2025-08-21 18:16:12.403907 | debian-bookworm | #13 1.546 + resolvelib==1.2.0 2025-08-21 18:16:12.403915 | debian-bookworm | #13 1.546 + urllib3==2.5.0 2025-08-21 18:16:12.415597 | debian-bookworm | #13 DONE 1.7s 2025-08-21 18:16:12.585284 | debian-bookworm | 2025-08-21 18:16:12.585405 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.004245 2025-08-21 18:17:48.781152 | 2025-08-21 18:17:48.781315 | TASK [upload-logs : Upload logs to log server] 2025-08-21 18:17:49.338531 | localhost | Output suppressed because no_log was given 2025-08-21 18:17:49.341704 | 2025-08-21 18:17:49.341867 | LOOP [upload-logs : Compress console log and json output] 2025-08-21 18:17:49.389740 | localhost | skipping: Conditional result was False 2025-08-21 18:17:49.395719 | localhost | skipping: Conditional result was False 2025-08-21 18:17:49.399393 | 2025-08-21 18:17:49.399521 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-21 18:17:49.455684 | localhost | skipping: Conditional result was False 2025-08-21 18:17:49.456420 | 2025-08-21 18:17:49.459634 | localhost | skipping: Conditional result was False 2025-08-21 18:17:49.473400 | 2025-08-21 18:17:49.473637 | LOOP [upload-logs : Upload console log and json output]