2026-09-26 00:56:06.248893 | Job console starting
2026-09-26 00:56:06.268186 | Updating git repos
2026-09-26 00:56:06.314258 | Cloning repos into workspace
2026-09-26 00:56:06.396089 | Restoring repo states
2026-09-26 00:56:06.404093 | Merging changes
2026-09-26 00:56:06.797466 | Checking out repos
2026-09-26 00:56:06.839459 | Preparing playbooks
2026-09-26 00:56:08.063831 | Running Ansible setup
2026-09-26 00:56:11.604980 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-26 00:56:12.222197 | 
2026-09-26 00:56:12.222379 | PLAY [localhost]
2026-09-26 00:56:12.230013 | 
2026-09-26 00:56:12.230083 | TASK [Gathering Facts]
2026-09-26 00:56:13.086729 | localhost | ok
2026-09-26 00:56:13.099637 | 
2026-09-26 00:56:13.099821 | TASK [Setup log path fact]
2026-09-26 00:56:13.122846 | localhost | ok
2026-09-26 00:56:13.141108 | 
2026-09-26 00:56:13.141198 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-26 00:56:13.171656 | localhost | ok
2026-09-26 00:56:13.182576 | 
2026-09-26 00:56:13.182712 | TASK [emit-job-header : Print job information]
2026-09-26 00:56:13.224118 | # Job Information
2026-09-26 00:56:13.224355 | Ansible Version: 2.16.18
2026-09-26 00:56:13.224424 | Job: pre-commit
2026-09-26 00:56:13.224468 | Pipeline: check
2026-09-26 00:56:13.224541 | Executor: 2d72f0692154
2026-09-26 00:56:13.224583 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/136
2026-09-26 00:56:13.224629 | Event ID: 4fc289a0-b944-11f1-829e-01862b553d14
2026-09-26 00:56:13.230577 | 
2026-09-26 00:56:13.230801 | LOOP [emit-job-header : Print node information]
2026-09-26 00:56:13.324204 | localhost | ok:
2026-09-26 00:56:13.324461 | localhost | # Node Information
2026-09-26 00:56:13.324554 | localhost | Inventory Hostname: ubuntu-noble
2026-09-26 00:56:13.324599 | localhost | Hostname: np0000194035
2026-09-26 00:56:13.324638 | localhost | Username: zuul
2026-09-26 00:56:13.324682 | localhost | Distro: Ubuntu 24.04
2026-09-26 00:56:13.324720 | localhost | Provider: yul1
2026-09-26 00:56:13.324759 | localhost | Region: ca-ymq-1
2026-09-26 00:56:13.324794 | localhost | Label: ubuntu-noble
2026-09-26 00:56:13.324830 | localhost | Product Name: OpenStack Nova
2026-09-26 00:56:13.324866 | localhost | Interface IP: 162.253.55.102
2026-09-26 00:56:13.335921 | 
2026-09-26 00:56:13.336054 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-26 00:56:13.714575 | localhost -> localhost | changed
2026-09-26 00:56:13.724238 | 
2026-09-26 00:56:13.724437 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-26 00:56:14.536956 | localhost -> localhost | changed
2026-09-26 00:56:14.541918 | 
2026-09-26 00:56:14.541965 | PLAY [all]
2026-09-26 00:56:14.548676 | 
2026-09-26 00:56:14.548736 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-26 00:56:14.803536 | ubuntu-noble -> localhost | ok
2026-09-26 00:56:14.809255 | 
2026-09-26 00:56:14.809340 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-26 00:56:14.838396 | ubuntu-noble | ok
2026-09-26 00:56:14.851868 | ubuntu-noble | included: /var/lib/zuul/builds/206e0dd78d6b4afca5ae2bedba16d5b0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-26 00:56:14.857364 | 
2026-09-26 00:56:14.857439 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-26 00:56:16.144245 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-09-26 00:56:16.144451 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/206e0dd78d6b4afca5ae2bedba16d5b0/work/206e0dd78d6b4afca5ae2bedba16d5b0_id_rsa
2026-09-26 00:56:16.144512 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/206e0dd78d6b4afca5ae2bedba16d5b0/work/206e0dd78d6b4afca5ae2bedba16d5b0_id_rsa.pub
2026-09-26 00:56:16.144551 | ubuntu-noble -> localhost | The key fingerprint is:
2026-09-26 00:56:16.144586 | ubuntu-noble -> localhost | SHA256:OAIEAGKpTorT1T7bzJMY44pc+WT7savuzim/wdyV0mo zuul-build-sshkey
2026-09-26 00:56:16.144635 | ubuntu-noble -> localhost | The key's randomart image is:
2026-09-26 00:56:16.144669 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-09-26 00:56:16.144702 | ubuntu-noble -> localhost | |Boo              |
2026-09-26 00:56:16.144735 | ubuntu-noble -> localhost | |oo               |
2026-09-26 00:56:16.144766 | ubuntu-noble -> localhost | |. .  .           |
2026-09-26 00:56:16.144791 | ubuntu-noble -> localhost | |.. .. .. . .     |
2026-09-26 00:56:16.144816 | ubuntu-noble -> localhost | |=. ...o S +      |
2026-09-26 00:56:16.144840 | ubuntu-noble -> localhost | |+..  ==o +       |
2026-09-26 00:56:16.144864 | ubuntu-noble -> localhost | | .  o.*XE.       |
2026-09-26 00:56:16.144889 | ubuntu-noble -> localhost | | . o.=+=*o       |
2026-09-26 00:56:16.144916 | ubuntu-noble -> localhost | |  o .B%++o       |
2026-09-26 00:56:16.144942 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-09-26 00:56:16.145001 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.823880
2026-09-26 00:56:16.151898 | 
2026-09-26 00:56:16.151976 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-26 00:56:16.184688 | ubuntu-noble | ok
2026-09-26 00:56:16.197922 | ubuntu-noble | included: /var/lib/zuul/builds/206e0dd78d6b4afca5ae2bedba16d5b0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-26 00:56:16.207509 | 
2026-09-26 00:56:16.207584 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-26 00:56:16.231096 | ubuntu-noble | skipping: Conditional result was False
2026-09-26 00:56:16.241110 | 
2026-09-26 00:56:16.241236 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-26 00:56:16.753900 | ubuntu-noble | changed
2026-09-26 00:56:16.758655 | 
2026-09-26 00:56:16.758716 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-26 00:56:17.014866 | ubuntu-noble | ok
2026-09-26 00:56:17.032025 | 
2026-09-26 00:56:17.032633 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-26 00:56:17.642426 | ubuntu-noble | changed
2026-09-26 00:56:17.652134 | 
2026-09-26 00:56:17.652256 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-26 00:56:18.228579 | ubuntu-noble | changed
2026-09-26 00:56:18.235649 | 
2026-09-26 00:56:18.235726 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-26 00:56:18.259619 | ubuntu-noble | skipping: Conditional result was False
2026-09-26 00:56:18.266987 | 
2026-09-26 00:56:18.267069 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-26 00:56:18.664896 | ubuntu-noble -> localhost | changed
2026-09-26 00:56:18.684290 | 
2026-09-26 00:56:18.684431 | TASK [add-build-sshkey : Add back temp key]
2026-09-26 00:56:18.998618 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/206e0dd78d6b4afca5ae2bedba16d5b0/work/206e0dd78d6b4afca5ae2bedba16d5b0_id_rsa (zuul-build-sshkey)
2026-09-26 00:56:18.998852 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.015612
2026-09-26 00:56:19.009878 | 
2026-09-26 00:56:19.010076 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-26 00:56:19.395750 | ubuntu-noble | ok
2026-09-26 00:56:19.405278 | 
2026-09-26 00:56:19.405431 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-26 00:56:19.430671 | ubuntu-noble | skipping: Conditional result was False
2026-09-26 00:56:19.445875 | 
2026-09-26 00:56:19.445932 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-26 00:56:19.847022 | ubuntu-noble | ok
2026-09-26 00:56:19.903498 | 
2026-09-26 00:56:19.903611 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-26 00:56:20.760749 | ubuntu-noble | Output suppressed because no_log was given
2026-09-26 00:56:20.769112 | 
2026-09-26 00:56:20.769178 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-26 00:56:21.030951 | ubuntu-noble | ok: "logs"
2026-09-26 00:56:21.031778 | ubuntu-noble | ok: All items complete
2026-09-26 00:56:21.031874 | 
2026-09-26 00:56:21.241525 | ubuntu-noble | ok: "artifacts"
2026-09-26 00:56:21.438221 | ubuntu-noble | ok: "docs"
2026-09-26 00:56:21.447822 | 
2026-09-26 00:56:21.447982 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-26 00:56:21.668539 | ubuntu-noble | changed: "logs"
2026-09-26 00:56:21.869777 | ubuntu-noble | changed: "artifacts"
2026-09-26 00:56:22.054825 | ubuntu-noble | changed: "docs"
2026-09-26 00:56:22.074450 | 
2026-09-26 00:56:22.074611 | PLAY RECAP
2026-09-26 00:56:22.074669 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-26 00:56:22.074707 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-26 00:56:22.074733 | 
2026-09-26 00:56:22.205790 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-26 00:56:22.207142 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-09-26 00:56:22.719102 | 
2026-09-26 00:56:22.719652 | PLAY [all]
2026-09-26 00:56:22.730190 | 
2026-09-26 00:56:22.730259 | TASK [setup-uv : Extract archive]
2026-09-26 00:56:25.258565 | ubuntu-noble | changed
2026-09-26 00:56:25.263871 | 
2026-09-26 00:56:25.263943 | TASK [setup-uv : Print version]
2026-09-26 00:56:25.693237 | ubuntu-noble | uv 0.8.13
2026-09-26 00:56:25.802190 | ubuntu-noble | ok: Runtime: 0:00:00.018991
2026-09-26 00:56:25.814641 | 
2026-09-26 00:56:25.814736 | PLAY RECAP
2026-09-26 00:56:25.814814 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-26 00:56:25.814863 | 
2026-09-26 00:56:25.970293 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-09-26 00:56:25.972222 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-09-26 00:56:26.526598 | 
2026-09-26 00:56:26.526707 | PLAY [all]
2026-09-26 00:56:26.536870 | 
2026-09-26 00:56:26.536943 | TASK [Run "pre-commit"]
2026-09-26 00:56:27.564459 | ubuntu-noble | Downloading virtualenv (5.2MiB)
2026-09-26 00:56:27.688591 | ubuntu-noble |  Downloading virtualenv
2026-09-26 00:56:27.704993 | ubuntu-noble | Installed 10 packages in 15ms
2026-09-26 00:56:28.081601 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-09-26 00:56:28.733906 | ubuntu-noble | [WARNING] repo `https://github.com/pre-commit/pre-commit-hooks` uses deprecated stage names (commit, push) which will be removed in a future version.  Hint: often `pre-commit autoupdate --repo https://github.com/pre-commit/pre-commit-hooks` will fix this.  if it does not -- consider reporting an issue to that repo.
2026-09-26 00:56:28.742229 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-09-26 00:56:29.437254 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-09-26 00:56:29.953322 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-09-26 00:56:30.528458 | ubuntu-noble | [WARNING] repo `https://github.com/pycqa/isort` uses deprecated stage names (commit, merge-commit, push) which will be removed in a future version.  Hint: often `pre-commit autoupdate --repo https://github.com/pycqa/isort` will fix this.  if it does not -- consider reporting an issue to that repo.
2026-09-26 00:56:30.530351 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-09-26 00:56:31.213416 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-09-26 00:56:31.854963 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-09-26 00:56:31.855056 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-09-26 00:56:31.855109 | ubuntu-noble | [INFO] This may take a few minutes...
2026-09-26 00:56:38.049957 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-09-26 00:56:38.050062 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-09-26 00:56:38.050116 | ubuntu-noble | [INFO] This may take a few minutes...
2026-09-26 00:56:46.845423 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-09-26 00:56:46.845485 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-09-26 00:56:46.845491 | ubuntu-noble | [INFO] This may take a few minutes...
2026-09-26 00:56:53.179906 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-09-26 00:56:53.179996 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-09-26 00:56:53.180000 | ubuntu-noble | [INFO] This may take a few minutes...
2026-09-26 00:56:59.053156 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-09-26 00:56:59.053274 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-09-26 00:56:59.053279 | ubuntu-noble | [INFO] This may take a few minutes...
2026-09-26 00:57:10.729819 | ubuntu-noble | fix end of files.........................................................Passed
2026-09-26 00:57:10.792111 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-09-26 00:57:11.088037 | ubuntu-noble | black....................................................................Passed
2026-09-26 00:57:11.307057 | ubuntu-noble | flake8...................................................................Passed
2026-09-26 00:57:11.447051 | ubuntu-noble | isort....................................................................Passed
2026-09-26 00:58:54.654792 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-09-26 00:58:55.143361 | ubuntu-noble | ok: Runtime: 0:02:27.748209
2026-09-26 00:58:55.146182 | 
2026-09-26 00:58:55.146276 | PLAY RECAP
2026-09-26 00:58:55.146356 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-26 00:58:55.146398 | 
2026-09-26 00:58:55.254279 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-09-26 00:58:55.256562 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-26 00:58:55.786646 | 
2026-09-26 00:58:55.786747 | PLAY [all]
2026-09-26 00:58:55.797087 | 
2026-09-26 00:58:55.797173 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-26 00:58:55.860884 | ubuntu-noble | skipping: Conditional result was False
2026-09-26 00:58:55.868156 | 
2026-09-26 00:58:55.868257 | TASK [fetch-output : Set log path for single node]
2026-09-26 00:58:55.912900 | ubuntu-noble | ok
2026-09-26 00:58:55.922205 | 
2026-09-26 00:58:55.922341 | LOOP [fetch-output : Ensure local output dirs]
2026-09-26 00:58:56.331952 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/206e0dd78d6b4afca5ae2bedba16d5b0/work/logs"
2026-09-26 00:58:56.556888 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/206e0dd78d6b4afca5ae2bedba16d5b0/work/artifacts"
2026-09-26 00:58:56.770147 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/206e0dd78d6b4afca5ae2bedba16d5b0/work/docs"
2026-09-26 00:58:56.788072 | 
2026-09-26 00:58:56.788273 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-26 00:58:57.470525 | ubuntu-noble | changed: .d..t...... ./
2026-09-26 00:58:57.470805 | ubuntu-noble | changed: All items complete
2026-09-26 00:58:57.470859 | 
2026-09-26 00:58:57.935634 | ubuntu-noble | changed: .d..t...... ./
2026-09-26 00:58:58.488591 | ubuntu-noble | changed: .d..t...... ./
2026-09-26 00:58:58.521410 | 
2026-09-26 00:58:58.521575 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-26 00:58:58.955287 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.006584
2026-09-26 00:58:59.150607 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.006331
2026-09-26 00:58:59.172754 | 
2026-09-26 00:58:59.172911 | PLAY [all]
2026-09-26 00:58:59.181491 | 
2026-09-26 00:58:59.181578 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-26 00:58:59.674156 | ubuntu-noble | changed
2026-09-26 00:58:59.685663 | 
2026-09-26 00:58:59.685757 | PLAY RECAP
2026-09-26 00:58:59.685842 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-26 00:58:59.685884 | 
2026-09-26 00:58:59.799084 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-26 00:58:59.801363 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-26 00:59:00.313400 | 
2026-09-26 00:59:00.313512 | PLAY [localhost]
2026-09-26 00:59:00.322766 | 
2026-09-26 00:59:00.322835 | TASK [Generate Zuul manifest]
2026-09-26 00:59:00.338653 | localhost | ok
2026-09-26 00:59:00.351211 | 
2026-09-26 00:59:00.351291 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-26 00:59:00.715369 | localhost | changed
2026-09-26 00:59:00.735429 | 
2026-09-26 00:59:00.735583 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-26 00:59:00.767351 | localhost | ok
2026-09-26 00:59:00.782486 | 
2026-09-26 00:59:00.782631 | TASK [Upload logs]
2026-09-26 00:59:00.803623 | localhost | ok
2026-09-26 00:59:00.870240 | 
2026-09-26 00:59:00.870322 | TASK [Set zuul-log-path fact]
2026-09-26 00:59:00.886923 | localhost | ok
2026-09-26 00:59:00.899879 | 
2026-09-26 00:59:00.899966 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-26 00:59:00.927266 | localhost | ok
2026-09-26 00:59:00.935186 | 
2026-09-26 00:59:00.935268 | TASK [upload-logs : Create log directories]
2026-09-26 00:59:01.325920 | localhost | changed
2026-09-26 00:59:01.331309 | 
2026-09-26 00:59:01.331396 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-26 00:59:01.738864 | localhost -> localhost | ok: Runtime: 0:00:00.004592
2026-09-26 00:59:01.747380 | 
2026-09-26 00:59:01.747511 | TASK [upload-logs : Upload logs to log server]
2026-09-26 00:59:02.213815 | localhost | Output suppressed because no_log was given
2026-09-26 00:59:02.220398 | 
2026-09-26 00:59:02.220550 | LOOP [upload-logs : Compress console log and json output]
2026-09-26 00:59:02.270893 | localhost | skipping: Conditional result was False
2026-09-26 00:59:02.277383 | localhost | skipping: Conditional result was False
2026-09-26 00:59:02.292679 | 
2026-09-26 00:59:02.292842 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-26 00:59:02.348731 | localhost | skipping: Conditional result was False
2026-09-26 00:59:02.349024 | 
2026-09-26 00:59:02.354486 | localhost | skipping: Conditional result was False
2026-09-26 00:59:02.364641 | 
2026-09-26 00:59:02.364798 | LOOP [upload-logs : Upload console log and json output]