2025-09-10 06:31:10.308285 | Job console starting 2025-09-10 06:31:10.319412 | Updating git repos 2025-09-10 06:31:10.353210 | Cloning repos into workspace 2025-09-10 06:31:10.386748 | Restoring repo states 2025-09-10 06:31:10.398018 | Merging changes 2025-09-10 06:31:10.914031 | Checking out repos 2025-09-10 06:31:10.998564 | Preparing playbooks 2025-09-10 06:31:11.849032 | Running Ansible setup 2025-09-10 06:31:17.163396 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-10 06:31:17.990806 | 2025-09-10 06:31:17.991010 | PLAY [Base pre] 2025-09-10 06:31:18.019135 | 2025-09-10 06:31:18.019276 | TASK [Setup log path fact] 2025-09-10 06:31:18.059530 | debian-bookworm | ok 2025-09-10 06:31:18.076181 | 2025-09-10 06:31:18.076331 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-10 06:31:18.105772 | debian-bookworm | ok 2025-09-10 06:31:18.115494 | 2025-09-10 06:31:18.115610 | TASK [emit-job-header : Print job information] 2025-09-10 06:31:18.155742 | # Job Information 2025-09-10 06:31:18.155936 | Ansible Version: 2.16.14 2025-09-10 06:31:18.155972 | Job: container-image-inventory-reconciler-build 2025-09-10 06:31:18.156006 | Pipeline: check 2025-09-10 06:31:18.156029 | Executor: 521e9411259a 2025-09-10 06:31:18.156050 | Triggered by: https://github.com/osism/container-image-inventory-reconciler/pull/426 2025-09-10 06:31:18.156072 | Event ID: b8016c40-8e0f-11f0-81de-87737be3d1b5 2025-09-10 06:31:18.161183 | 2025-09-10 06:31:18.161293 | LOOP [emit-job-header : Print node information] 2025-09-10 06:31:18.271400 | debian-bookworm | ok: 2025-09-10 06:31:18.271598 | debian-bookworm | # Node Information 2025-09-10 06:31:18.271633 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-10 06:31:18.271659 | debian-bookworm | Hostname: debian 2025-09-10 06:31:18.271682 | debian-bookworm | Username: zuul 2025-09-10 06:31:18.271703 | debian-bookworm | Distro: Debian 12.12 2025-09-10 06:31:18.271727 | debian-bookworm | Provider: regiocloud-a 2025-09-10 06:31:18.271748 | debian-bookworm | Region: 2025-09-10 06:31:18.271769 | debian-bookworm | Label: debian-bookworm 2025-09-10 06:31:18.271789 | debian-bookworm | Product Name: OpenStack Nova 2025-09-10 06:31:18.271808 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:febc:26a9 2025-09-10 06:31:18.294606 | 2025-09-10 06:31:18.294743 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-10 06:31:18.856173 | debian-bookworm -> localhost | changed 2025-09-10 06:31:18.872464 | 2025-09-10 06:31:18.872667 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-10 06:31:20.140641 | debian-bookworm -> localhost | changed 2025-09-10 06:31:20.163077 | 2025-09-10 06:31:20.163217 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-10 06:31:20.731517 | debian-bookworm -> localhost | ok 2025-09-10 06:31:20.739262 | 2025-09-10 06:31:20.739389 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-10 06:31:20.778409 | debian-bookworm | ok 2025-09-10 06:31:20.797796 | debian-bookworm | included: /var/lib/zuul/builds/1512bb774b3346e2aa7b4bb856e6d3c7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-10 06:31:20.804469 | 2025-09-10 06:31:20.804571 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-10 06:31:22.967317 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-10 06:31:22.967589 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1512bb774b3346e2aa7b4bb856e6d3c7/work/1512bb774b3346e2aa7b4bb856e6d3c7_id_rsa 2025-09-10 06:31:22.967633 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1512bb774b3346e2aa7b4bb856e6d3c7/work/1512bb774b3346e2aa7b4bb856e6d3c7_id_rsa.pub 2025-09-10 06:31:22.967661 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-10 06:31:22.967686 | debian-bookworm -> localhost | SHA256:Y8c6RKcNPaQcFXpOfZNBdWVYFUYZ/pJXaCda8MHwr6U zuul-build-sshkey 2025-09-10 06:31:22.967720 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-10 06:31:22.967743 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-10 06:31:22.967764 | debian-bookworm -> localhost | | ..+.o+=O&| 2025-09-10 06:31:22.967785 | debian-bookworm -> localhost | | . * . +=B.| 2025-09-10 06:31:22.967805 | debian-bookworm -> localhost | | * * . @.o| 2025-09-10 06:31:22.967825 | debian-bookworm -> localhost | | . X . = *o| 2025-09-10 06:31:22.967845 | debian-bookworm -> localhost | | S = . o =| 2025-09-10 06:31:22.967873 | debian-bookworm -> localhost | | o + * | 2025-09-10 06:31:22.967912 | debian-bookworm -> localhost | | o E | 2025-09-10 06:31:22.967934 | debian-bookworm -> localhost | | . | 2025-09-10 06:31:22.967955 | debian-bookworm -> localhost | | | 2025-09-10 06:31:22.967975 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-10 06:31:22.968030 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.450092 2025-09-10 06:31:22.974517 | 2025-09-10 06:31:22.974637 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-10 06:31:23.008212 | debian-bookworm | ok 2025-09-10 06:31:23.021694 | debian-bookworm | included: /var/lib/zuul/builds/1512bb774b3346e2aa7b4bb856e6d3c7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-10 06:31:23.032218 | 2025-09-10 06:31:23.032327 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-10 06:31:23.055900 | debian-bookworm | skipping: Conditional result was False 2025-09-10 06:31:23.061971 | 2025-09-10 06:31:23.062073 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-10 06:31:23.715284 | debian-bookworm | changed 2025-09-10 06:31:23.723293 | 2025-09-10 06:31:23.723419 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-10 06:31:24.002101 | debian-bookworm | ok 2025-09-10 06:31:24.009024 | 2025-09-10 06:31:24.009159 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-10 06:31:24.694382 | debian-bookworm | changed 2025-09-10 06:31:24.701388 | 2025-09-10 06:31:24.701501 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-10 06:31:25.361753 | debian-bookworm | changed 2025-09-10 06:31:25.373563 | 2025-09-10 06:31:25.373698 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-10 06:31:25.398153 | debian-bookworm | skipping: Conditional result was False 2025-09-10 06:31:25.404868 | 2025-09-10 06:31:25.405052 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-10 06:31:25.872664 | debian-bookworm -> localhost | changed 2025-09-10 06:31:25.888120 | 2025-09-10 06:31:25.888251 | TASK [add-build-sshkey : Add back temp key] 2025-09-10 06:31:26.371077 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1512bb774b3346e2aa7b4bb856e6d3c7/work/1512bb774b3346e2aa7b4bb856e6d3c7_id_rsa (zuul-build-sshkey) 2025-09-10 06:31:26.371838 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012604 2025-09-10 06:31:26.385851 | 2025-09-10 06:31:26.386006 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-10 06:31:26.959132 | debian-bookworm | ok 2025-09-10 06:31:26.967782 | 2025-09-10 06:31:26.968258 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-10 06:31:27.004394 | debian-bookworm | skipping: Conditional result was False 2025-09-10 06:31:27.022326 | 2025-09-10 06:31:27.022578 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-10 06:31:27.515819 | debian-bookworm | ok 2025-09-10 06:31:27.531290 | 2025-09-10 06:31:27.531418 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-10 06:31:27.573517 | debian-bookworm | ok 2025-09-10 06:31:27.580102 | 2025-09-10 06:31:27.580224 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-10 06:31:27.925327 | debian-bookworm -> localhost | ok 2025-09-10 06:31:27.933336 | 2025-09-10 06:31:27.933472 | TASK [validate-host : Collect information about the host] 2025-09-10 06:31:29.249287 | debian-bookworm | ok 2025-09-10 06:31:29.264456 | 2025-09-10 06:31:29.264596 | TASK [validate-host : Sanitize hostname] 2025-09-10 06:31:29.375832 | debian-bookworm | ok 2025-09-10 06:31:29.414953 | 2025-09-10 06:31:29.415087 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-10 06:31:30.026405 | debian-bookworm -> localhost | changed 2025-09-10 06:31:30.032496 | 2025-09-10 06:31:30.032613 | TASK [validate-host : Collect information about zuul worker] 2025-09-10 06:31:30.452491 | debian-bookworm | ok 2025-09-10 06:31:30.466197 | 2025-09-10 06:31:30.466456 | TASK [validate-host : Write out all zuul information for each host] 2025-09-10 06:31:31.119210 | debian-bookworm -> localhost | changed 2025-09-10 06:31:31.129224 | 2025-09-10 06:31:31.129342 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-10 06:31:31.407380 | debian-bookworm | ok 2025-09-10 06:31:31.415119 | 2025-09-10 06:31:31.415249 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-10 06:31:32.298171 | debian-bookworm | changed: 2025-09-10 06:31:32.298415 | debian-bookworm | cd+++++++++ src/ 2025-09-10 06:31:32.298462 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-10 06:31:32.298494 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-10 06:31:32.298523 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-10 06:31:32.298549 | debian-bookworm | Containerfile 2025-09-10 06:31:32.298777 | debian-bookworm | 3.5.2-r0) 2025-09-10 06:32:33.698474 | debian-bookworm | #9 5.673 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-10 06:32:33.939767 | debian-bookworm | #9 5.697 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-10 06:32:33.939859 | debian-bookworm | #9 5.736 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-10 06:32:33.939867 | debian-bookworm | #9 5.763 (28/34) Installing python3 (3.12.11-r0) 2025-09-10 06:32:34.087852 | debian-bookworm | #9 6.062 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-10 06:32:34.253829 | debian-bookworm | #9 6.229 (30/34) Installing pyc (3.12.11-r0) 2025-09-10 06:32:34.404967 | debian-bookworm | #9 6.229 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-10 06:32:34.405052 | debian-bookworm | #9 6.229 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-10 06:32:34.693514 | debian-bookworm | #9 6.668 (33/34) Installing yq-go (4.46.1-r2) 2025-09-10 06:32:34.910108 | debian-bookworm | #9 6.885 (34/34) Installing .build-deps (20250910.063230) 2025-09-10 06:32:35.125389 | debian-bookworm | #9 6.887 Executing busybox-1.37.0-r18.trigger 2025-09-10 06:32:35.125577 | debian-bookworm | #9 6.893 Executing ca-certificates-20250619-r0.trigger 2025-09-10 06:32:35.125600 | debian-bookworm | #9 6.949 OK: 359 MiB in 82 packages 2025-09-10 06:32:35.432079 | debian-bookworm | #9 DONE 7.4s 2025-09-10 06:32:35.533585 | debian-bookworm | 2025-09-10 06:32:35.533775 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-10 06:32:35.533867 | debian-bookworm | #10 DONE 0.1s 2025-09-10 06:32:35.533909 | debian-bookworm | 2025-09-10 06:32:35.533943 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-10 06:32:35.668436 | debian-bookworm | #11 DONE 0.1s 2025-09-10 06:32:35.671587 | debian-bookworm | 2025-09-10 06:32:35.671631 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-10 06:32:35.671644 | debian-bookworm | #12 DONE 0.1s 2025-09-10 06:32:35.823521 | debian-bookworm | 2025-09-10 06:32:35.823601 | 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-10 06:32:36.462664 | debian-bookworm | #13 0.790 Using Python 3.13.7 environment at: /usr/local 2025-09-10 06:32:36.815543 | debian-bookworm | #13 1.143 Resolved 17 packages in 350ms 2025-09-10 06:32:37.022600 | debian-bookworm | #13 1.195 Downloading ansible-core (2.3MiB) 2025-09-10 06:32:37.022688 | debian-bookworm | #13 1.198 Downloading netaddr (2.2MiB) 2025-09-10 06:32:37.022739 | debian-bookworm | #13 1.199 Downloading cryptography (4.4MiB) 2025-09-10 06:32:37.389887 | debian-bookworm | #13 1.545 Downloading netaddr 2025-09-10 06:32:37.389995 | debian-bookworm | #13 1.641 Downloading cryptography 2025-09-10 06:32:37.390012 | debian-bookworm | #13 1.717 Downloading ansible-core 2025-09-10 06:32:37.603281 | debian-bookworm | #13 1.719 Prepared 17 packages in 574ms 2025-09-10 06:32:37.603391 | debian-bookworm | #13 1.776 Installed 17 packages in 55ms 2025-09-10 06:32:37.603406 | debian-bookworm | #13 1.776 + ansible-core==2.19.2 2025-09-10 06:32:37.603432 | debian-bookworm | #13 1.776 + certifi==2025.8.3 2025-09-10 06:32:37.603445 | debian-bookworm | #13 1.776 + cffi==2.0.0 2025-09-10 06:32:37.603455 | debian-bookworm | #13 1.776 + charset-normalizer==3.4.3 2025-09-10 06:32:37.603465 | debian-bookworm | #13 1.777 + cryptography==45.0.7 2025-09-10 06:32:37.603475 | debian-bookworm | #13 1.778 + dynaconf==3.2.11 2025-09-10 06:32:37.603484 | debian-bookworm | #13 1.779 + idna==3.10 2025-09-10 06:32:37.603494 | debian-bookworm | #13 1.779 + jinja2==3.1.6 2025-09-10 06:32:37.603503 | debian-bookworm | #13 1.779 + markupsafe==3.0.2 2025-09-10 06:32:37.603511 | debian-bookworm | #13 1.780 + netaddr==1.3.0 2025-09-10 06:32:37.603520 | debian-bookworm | #13 1.780 + packaging==25.0 2025-09-10 06:32:37.603529 | debian-bookworm | #13 1.780 + pycparser==2.23 2025-09-10 06:32:37.603563 | debian-bookworm | #13 1.780 + pynetbox==7.5.0 2025-09-10 06:32:37.603573 | debian-bookworm | #13 1.780 + pyyaml==6.0.2 2025-09-10 06:32:37.603583 | debian-bookworm | #13 1.780 + requests==2.32.5 2025-09-10 06:32:37.603591 | debian-bookworm | #13 1.780 + resolvelib==1.2.0 2025-09-10 06:32:37.603600 | debian-bookworm | #13 1.780 + urllib3==2.5.0 2025-09-10 06:32:37.616729 | debian-bookworm | #13 DONE 1.9s 2025-09-10 06:32:37.775764 | debian-bookworm | 2025-09-10 06:32:37.775837 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.006721 2025-09-10 06:33:07.125967 | 2025-09-10 06:33:07.126185 | TASK [upload-logs : Upload logs to log server] 2025-09-10 06:33:07.697464 | localhost | Output suppressed because no_log was given 2025-09-10 06:33:07.699492 | 2025-09-10 06:33:07.699606 | LOOP [upload-logs : Compress console log and json output] 2025-09-10 06:33:07.745661 | localhost | skipping: Conditional result was False 2025-09-10 06:33:07.752535 | localhost | skipping: Conditional result was False 2025-09-10 06:33:07.758386 | 2025-09-10 06:33:07.758580 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-10 06:33:07.804887 | localhost | skipping: Conditional result was False 2025-09-10 06:33:07.805436 | 2025-09-10 06:33:07.808980 | localhost | skipping: Conditional result was False 2025-09-10 06:33:07.822195 | 2025-09-10 06:33:07.822425 | LOOP [upload-logs : Upload console log and json output]