2025-09-20 09:49:46.018353 | Job console starting 2025-09-20 09:49:46.034545 | Updating git repos 2025-09-20 09:49:46.094510 | Cloning repos into workspace 2025-09-20 09:49:46.155780 | Restoring repo states 2025-09-20 09:49:46.173230 | Merging changes 2025-09-20 09:49:46.173254 | Checking out repos 2025-09-20 09:49:46.236548 | Preparing playbooks 2025-09-20 09:49:46.878141 | Running Ansible setup 2025-09-20 09:49:51.133988 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-20 09:49:51.871789 | 2025-09-20 09:49:51.871953 | PLAY [Base pre] 2025-09-20 09:49:51.894815 | 2025-09-20 09:49:51.895002 | TASK [Setup log path fact] 2025-09-20 09:49:51.914928 | debian-bookworm | ok 2025-09-20 09:49:51.930593 | 2025-09-20 09:49:51.930742 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-20 09:49:51.960367 | debian-bookworm | ok 2025-09-20 09:49:51.970510 | 2025-09-20 09:49:51.970629 | TASK [emit-job-header : Print job information] 2025-09-20 09:49:52.010689 | # Job Information 2025-09-20 09:49:52.010886 | Ansible Version: 2.16.14 2025-09-20 09:49:52.010927 | Job: cfg-generics-container-image-inventory-reconciler-push 2025-09-20 09:49:52.010966 | Pipeline: post 2025-09-20 09:49:52.010992 | Executor: 521e9411259a 2025-09-20 09:49:52.011015 | Triggered by: https://github.com/osism/cfg-generics/commit/3762369d2c2b0028840d71cfc3e6e7b1cd2216ae 2025-09-20 09:49:52.011040 | Event ID: 1f4a4008-9607-11f0-9426-496add31ed47 2025-09-20 09:49:52.016753 | 2025-09-20 09:49:52.016866 | LOOP [emit-job-header : Print node information] 2025-09-20 09:49:52.124896 | debian-bookworm | ok: 2025-09-20 09:49:52.125076 | debian-bookworm | # Node Information 2025-09-20 09:49:52.125110 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-20 09:49:52.125135 | debian-bookworm | Hostname: debian 2025-09-20 09:49:52.125157 | debian-bookworm | Username: zuul 2025-09-20 09:49:52.125275 | debian-bookworm | Distro: Debian 12.12 2025-09-20 09:49:52.125312 | debian-bookworm | Provider: regiocloud-a 2025-09-20 09:49:52.125337 | debian-bookworm | Region: 2025-09-20 09:49:52.125360 | debian-bookworm | Label: debian-bookworm 2025-09-20 09:49:52.125381 | debian-bookworm | Product Name: OpenStack Nova 2025-09-20 09:49:52.125402 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fecc:782 2025-09-20 09:49:52.149693 | 2025-09-20 09:49:52.149842 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-20 09:49:52.588804 | debian-bookworm -> localhost | changed 2025-09-20 09:49:52.596385 | 2025-09-20 09:49:52.596508 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-20 09:49:53.636302 | debian-bookworm -> localhost | changed 2025-09-20 09:49:53.647231 | 2025-09-20 09:49:53.647349 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-20 09:49:53.954826 | debian-bookworm -> localhost | ok 2025-09-20 09:49:53.961231 | 2025-09-20 09:49:53.961355 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-20 09:49:53.994382 | debian-bookworm | ok 2025-09-20 09:49:54.018595 | debian-bookworm | included: /var/lib/zuul/builds/b095be48b67848a081cd07fff19ba1e4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-20 09:49:54.034623 | 2025-09-20 09:49:54.034915 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-20 09:49:55.373466 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-20 09:49:55.373957 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b095be48b67848a081cd07fff19ba1e4/work/b095be48b67848a081cd07fff19ba1e4_id_rsa 2025-09-20 09:49:55.374065 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b095be48b67848a081cd07fff19ba1e4/work/b095be48b67848a081cd07fff19ba1e4_id_rsa.pub 2025-09-20 09:49:55.374144 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-20 09:49:55.374248 | debian-bookworm -> localhost | SHA256:hdTguSeAcx2b7+Gyy7B9WPQbn6NduhRx4mzv/79ZQhI zuul-build-sshkey 2025-09-20 09:49:55.374336 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-20 09:49:55.374403 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-20 09:49:55.374464 | debian-bookworm -> localhost | | +o | 2025-09-20 09:49:55.374527 | debian-bookworm -> localhost | | . + *. | 2025-09-20 09:49:55.374585 | debian-bookworm -> localhost | | o o B . E o .| 2025-09-20 09:49:55.374644 | debian-bookworm -> localhost | | o . +. + + | 2025-09-20 09:49:55.374702 | debian-bookworm -> localhost | | S.+.. * | 2025-09-20 09:49:55.374774 | debian-bookworm -> localhost | | =..o+ o | 2025-09-20 09:49:55.374930 | debian-bookworm -> localhost | | . .oo +o.+| 2025-09-20 09:49:55.375000 | debian-bookworm -> localhost | | =.o. .o+=o| 2025-09-20 09:49:55.375062 | debian-bookworm -> localhost | | . =o ..+=X| 2025-09-20 09:49:55.375119 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-20 09:49:55.375296 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.808782 2025-09-20 09:49:55.389005 | 2025-09-20 09:49:55.389214 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-20 09:49:55.425984 | debian-bookworm | ok 2025-09-20 09:49:55.442858 | debian-bookworm | included: /var/lib/zuul/builds/b095be48b67848a081cd07fff19ba1e4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-20 09:49:55.452889 | 2025-09-20 09:49:55.452991 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-20 09:49:55.476563 | debian-bookworm | skipping: Conditional result was False 2025-09-20 09:49:55.484256 | 2025-09-20 09:49:55.484364 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-20 09:49:56.163195 | debian-bookworm | changed 2025-09-20 09:49:56.170100 | 2025-09-20 09:49:56.170242 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-20 09:49:56.494280 | debian-bookworm | ok 2025-09-20 09:49:56.502576 | 2025-09-20 09:49:56.502717 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-20 09:49:57.162935 | debian-bookworm | changed 2025-09-20 09:49:57.179905 | 2025-09-20 09:49:57.180042 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-20 09:49:57.840019 | debian-bookworm | changed 2025-09-20 09:49:57.845720 | 2025-09-20 09:49:57.845826 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-20 09:49:57.879349 | debian-bookworm | skipping: Conditional result was False 2025-09-20 09:49:57.888133 | 2025-09-20 09:49:57.888279 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-20 09:49:58.344015 | debian-bookworm -> localhost | changed 2025-09-20 09:49:58.363069 | 2025-09-20 09:49:58.363226 | TASK [add-build-sshkey : Add back temp key] 2025-09-20 09:49:58.691582 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b095be48b67848a081cd07fff19ba1e4/work/b095be48b67848a081cd07fff19ba1e4_id_rsa (zuul-build-sshkey) 2025-09-20 09:49:58.692064 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016567 2025-09-20 09:49:58.707158 | 2025-09-20 09:49:58.707319 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-20 09:49:59.099777 | debian-bookworm | ok 2025-09-20 09:49:59.107804 | 2025-09-20 09:49:59.107963 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-20 09:49:59.132392 | debian-bookworm | skipping: Conditional result was False 2025-09-20 09:49:59.152360 | 2025-09-20 09:49:59.152504 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-20 09:49:59.583242 | debian-bookworm | ok 2025-09-20 09:49:59.599450 | 2025-09-20 09:49:59.599589 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-20 09:49:59.646329 | debian-bookworm | ok 2025-09-20 09:49:59.654686 | 2025-09-20 09:49:59.654822 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-20 09:49:59.963389 | debian-bookworm -> localhost | ok 2025-09-20 09:49:59.976633 | 2025-09-20 09:49:59.976781 | TASK [validate-host : Collect information about the host] 2025-09-20 09:50:01.135421 | debian-bookworm | ok 2025-09-20 09:50:01.149829 | 2025-09-20 09:50:01.149961 | TASK [validate-host : Sanitize hostname] 2025-09-20 09:50:01.268594 | debian-bookworm | ok 2025-09-20 09:50:01.308372 | 2025-09-20 09:50:01.308499 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-20 09:50:01.864005 | debian-bookworm -> localhost | changed 2025-09-20 09:50:01.874780 | 2025-09-20 09:50:01.874975 | TASK [validate-host : Collect information about zuul worker] 2025-09-20 09:50:02.284154 | debian-bookworm | ok 2025-09-20 09:50:02.289979 | 2025-09-20 09:50:02.290096 | TASK [validate-host : Write out all zuul information for each host] 2025-09-20 09:50:02.820993 | debian-bookworm -> localhost | changed 2025-09-20 09:50:02.832370 | 2025-09-20 09:50:02.832486 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-20 09:50:03.109531 | debian-bookworm | ok 2025-09-20 09:50:03.117974 | 2025-09-20 09:50:03.118115 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-20 09:50:03.945832 | debian-bookworm | changed: 2025-09-20 09:50:03.946151 | debian-bookworm | cd+++++++++ src/ 2025-09-20 09:50:03.946291 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-20 09:50:03.946342 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-20 09:50:03.946387 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-generics/ 2025-09-20 09:50:03.946436 | debian-bookworm | 3.5.2-r0) 2025-09-20 09:51:08.104881 | debian-bookworm | #9 5.570 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-20 09:51:08.329019 | debian-bookworm | #9 5.592 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-20 09:51:08.329123 | debian-bookworm | #9 5.629 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-20 09:51:08.329140 | debian-bookworm | #9 5.643 (28/34) Installing python3 (3.12.11-r0) 2025-09-20 09:51:08.462134 | debian-bookworm | #9 5.927 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-20 09:51:08.636084 | debian-bookworm | #9 6.101 (30/34) Installing pyc (3.12.11-r0) 2025-09-20 09:51:08.636233 | debian-bookworm | #9 6.101 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-20 09:51:08.636304 | debian-bookworm | #9 6.101 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-20 09:51:09.325306 | debian-bookworm | #9 6.790 (33/34) Installing yq-go (4.47.2-r0) 2025-09-20 09:51:09.562854 | debian-bookworm | #9 7.028 (34/34) Installing .build-deps (20250920.095104) 2025-09-20 09:51:09.788465 | debian-bookworm | #9 7.031 Executing busybox-1.37.0-r18.trigger 2025-09-20 09:51:09.788599 | debian-bookworm | #9 7.039 Executing ca-certificates-20250619-r0.trigger 2025-09-20 09:51:09.788648 | debian-bookworm | #9 7.101 OK: 359 MiB in 82 packages 2025-09-20 09:51:10.167303 | debian-bookworm | #9 DONE 7.6s 2025-09-20 09:51:10.320864 | debian-bookworm | 2025-09-20 09:51:10.320990 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-20 09:51:10.321067 | debian-bookworm | #10 DONE 0.1s 2025-09-20 09:51:10.321104 | debian-bookworm | 2025-09-20 09:51:10.321129 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-20 09:51:10.321163 | debian-bookworm | #11 DONE 0.1s 2025-09-20 09:51:10.568471 | debian-bookworm | 2025-09-20 09:51:10.568568 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-20 09:51:10.568581 | debian-bookworm | #12 DONE 0.1s 2025-09-20 09:51:10.568590 | debian-bookworm | 2025-09-20 09:51:10.568600 | 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-20 09:51:11.329927 | debian-bookworm | #13 0.760 Using Python 3.13.7 environment at: /usr/local 2025-09-20 09:51:11.633862 | debian-bookworm | #13 1.021 Resolved 17 packages in 259ms 2025-09-20 09:51:11.633925 | debian-bookworm | #13 1.064 Downloading cryptography (4.5MiB) 2025-09-20 09:51:11.633932 | debian-bookworm | #13 1.065 Downloading netaddr (2.2MiB) 2025-09-20 09:51:11.633936 | debian-bookworm | #13 1.066 Downloading ansible-core (2.3MiB) 2025-09-20 09:51:11.817237 | debian-bookworm | #13 1.400 Downloading netaddr 2025-09-20 09:51:11.937023 | debian-bookworm | #13 1.519 Downloading cryptography 2025-09-20 09:51:12.146149 | debian-bookworm | #13 1.527 Downloading ansible-core 2025-09-20 09:51:12.146284 | debian-bookworm | #13 1.528 Prepared 17 packages in 503ms 2025-09-20 09:51:12.146321 | debian-bookworm | #13 1.577 Installed 17 packages in 47ms 2025-09-20 09:51:12.146379 | debian-bookworm | #13 1.577 + ansible-core==2.19.2 2025-09-20 09:51:12.146413 | debian-bookworm | #13 1.577 + certifi==2025.8.3 2025-09-20 09:51:12.146454 | debian-bookworm | #13 1.577 + cffi==2.0.0 2025-09-20 09:51:12.146487 | debian-bookworm | #13 1.577 + charset-normalizer==3.4.3 2025-09-20 09:51:12.146516 | debian-bookworm | #13 1.577 + cryptography==46.0.1 2025-09-20 09:51:12.146545 | debian-bookworm | #13 1.577 + dynaconf==3.2.11 2025-09-20 09:51:12.146577 | debian-bookworm | #13 1.577 + idna==3.10 2025-09-20 09:51:12.146606 | debian-bookworm | #13 1.577 + jinja2==3.1.6 2025-09-20 09:51:12.146634 | debian-bookworm | #13 1.577 + markupsafe==3.0.2 2025-09-20 09:51:12.146664 | debian-bookworm | #13 1.577 + netaddr==1.3.0 2025-09-20 09:51:12.146692 | debian-bookworm | #13 1.577 + packaging==25.0 2025-09-20 09:51:12.146720 | debian-bookworm | #13 1.578 + pycparser==2.23 2025-09-20 09:51:12.146754 | debian-bookworm | #13 1.578 + pynetbox==7.5.0 2025-09-20 09:51:12.146782 | debian-bookworm | #13 1.578 + pyyaml==6.0.2 2025-09-20 09:51:12.146810 | debian-bookworm | #13 1.578 + requests==2.32.5 2025-09-20 09:51:12.146844 | debian-bookworm | #13 1.578 + resolvelib==1.2.0 2025-09-20 09:51:12.146871 | debian-bookworm | #13 1.578 + urllib3==2.5.0 2025-09-20 09:51:12.151323 | debian-bookworm | #13 DONE 1.7s 2025-09-20 09:51:12.310476 | debian-bookworm | 2025-09-20 09:51:12.310545 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.006890 2025-09-20 09:52:50.340950 | 2025-09-20 09:52:50.341115 | TASK [upload-logs : Upload logs to log server] 2025-09-20 09:52:50.885988 | localhost | Output suppressed because no_log was given 2025-09-20 09:52:50.888457 | 2025-09-20 09:52:50.888586 | LOOP [upload-logs : Compress console log and json output] 2025-09-20 09:52:50.940881 | localhost | skipping: Conditional result was False 2025-09-20 09:52:50.946909 | localhost | skipping: Conditional result was False 2025-09-20 09:52:50.959406 | 2025-09-20 09:52:50.959622 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-20 09:52:51.018653 | localhost | skipping: Conditional result was False 2025-09-20 09:52:51.019029 | 2025-09-20 09:52:51.024837 | localhost | skipping: Conditional result was False 2025-09-20 09:52:51.031940 | 2025-09-20 09:52:51.032168 | LOOP [upload-logs : Upload console log and json output]