2026-04-09 16:03:59.718209 | Job console starting
2026-04-09 16:03:59.728243 | Updating git repos
2026-04-09 16:03:59.748739 | Cloning repos into workspace
2026-04-09 16:03:59.795302 | Restoring repo states
2026-04-09 16:03:59.802999 | Merging changes
2026-04-09 16:04:00.061228 | Checking out repos
2026-04-09 16:04:00.086994 | Preparing playbooks
2026-04-09 16:04:00.940811 | Running Ansible setup
2026-04-09 16:04:04.450237 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-09 16:04:05.013303 | 
2026-04-09 16:04:05.013812 | PLAY [localhost]
2026-04-09 16:04:05.020857 | 
2026-04-09 16:04:05.020925 | TASK [Gathering Facts]
2026-04-09 16:04:05.889814 | localhost | ok
2026-04-09 16:04:05.899274 | 
2026-04-09 16:04:05.899356 | TASK [Setup log path fact]
2026-04-09 16:04:05.921309 | localhost | ok
2026-04-09 16:04:05.939232 | 
2026-04-09 16:04:05.939314 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-09 16:04:05.968941 | localhost | ok
2026-04-09 16:04:05.975444 | 
2026-04-09 16:04:05.975506 | TASK [emit-job-header : Print job information]
2026-04-09 16:04:06.015404 | # Job Information
2026-04-09 16:04:06.015605 | Ansible Version: 2.16.16
2026-04-09 16:04:06.015673 | Job: pre-commit
2026-04-09 16:04:06.015720 | Pipeline: check
2026-04-09 16:04:06.015764 | Executor: 0a8996d2b663
2026-04-09 16:04:06.015806 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/104
2026-04-09 16:04:06.015854 | Event ID: 52712c30-342c-11f1-9984-b8d6bab5b522
2026-04-09 16:04:06.021590 | 
2026-04-09 16:04:06.021657 | LOOP [emit-job-header : Print node information]
2026-04-09 16:04:06.115895 | localhost | ok:
2026-04-09 16:04:06.116630 | localhost | # Node Information
2026-04-09 16:04:06.116705 | localhost | Inventory Hostname: ubuntu-noble
2026-04-09 16:04:06.116755 | localhost | Hostname: np0000164566
2026-04-09 16:04:06.116799 | localhost | Username: zuul
2026-04-09 16:04:06.116847 | localhost | Distro: Ubuntu 24.04
2026-04-09 16:04:06.116891 | localhost | Provider: yul1
2026-04-09 16:04:06.116933 | localhost | Region: ca-ymq-1
2026-04-09 16:04:06.117007 | localhost | Label: ubuntu-noble
2026-04-09 16:04:06.117050 | localhost | Product Name: OpenStack Nova
2026-04-09 16:04:06.117091 | localhost | Interface IP: 199.204.45.70
2026-04-09 16:04:06.137381 | 
2026-04-09 16:04:06.137513 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-09 16:04:06.493384 | localhost -> localhost | changed
2026-04-09 16:04:06.503850 | 
2026-04-09 16:04:06.503935 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-09 16:04:07.390367 | localhost -> localhost | changed
2026-04-09 16:04:07.399386 | 
2026-04-09 16:04:07.399467 | PLAY [all]
2026-04-09 16:04:07.408217 | 
2026-04-09 16:04:07.408283 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-09 16:04:07.626787 | ubuntu-noble -> localhost | ok
2026-04-09 16:04:07.637614 | 
2026-04-09 16:04:07.637726 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-09 16:04:07.674108 | ubuntu-noble | ok
2026-04-09 16:04:07.688789 | ubuntu-noble | included: /var/lib/zuul/builds/72523a89cff841d19111ff79b9525c63/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-09 16:04:07.694996 | 
2026-04-09 16:04:07.695056 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-09 16:04:08.318274 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-04-09 16:04:08.318514 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/72523a89cff841d19111ff79b9525c63/work/72523a89cff841d19111ff79b9525c63_id_rsa
2026-04-09 16:04:08.318550 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/72523a89cff841d19111ff79b9525c63/work/72523a89cff841d19111ff79b9525c63_id_rsa.pub
2026-04-09 16:04:08.318575 | ubuntu-noble -> localhost | The key fingerprint is:
2026-04-09 16:04:08.318598 | ubuntu-noble -> localhost | SHA256:MwOpgEiR5c+viknKMgrdlJdHoDVoTO0c7kf2W5YUGZA zuul-build-sshkey
2026-04-09 16:04:08.318638 | ubuntu-noble -> localhost | The key's randomart image is:
2026-04-09 16:04:08.318661 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-04-09 16:04:08.318686 | ubuntu-noble -> localhost | | o++.o+   .o.o   |
2026-04-09 16:04:08.318708 | ubuntu-noble -> localhost | |oo. +oo+  E o    |
2026-04-09 16:04:08.318729 | ubuntu-noble -> localhost | |o .o.+o..    .   |
2026-04-09 16:04:08.318750 | ubuntu-noble -> localhost | |   .oo++o   .    |
2026-04-09 16:04:08.318771 | ubuntu-noble -> localhost | |    ++ooS. . .   |
2026-04-09 16:04:08.318791 | ubuntu-noble -> localhost | | . o .o..+. +    |
2026-04-09 16:04:08.318812 | ubuntu-noble -> localhost | |... .  o   +     |
2026-04-09 16:04:08.318832 | ubuntu-noble -> localhost | |B o   .   .      |
2026-04-09 16:04:08.318855 | ubuntu-noble -> localhost | |== ...           |
2026-04-09 16:04:08.318876 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-04-09 16:04:08.318927 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.229769
2026-04-09 16:04:08.324609 | 
2026-04-09 16:04:08.324677 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-09 16:04:08.371076 | ubuntu-noble | ok
2026-04-09 16:04:08.381378 | ubuntu-noble | included: /var/lib/zuul/builds/72523a89cff841d19111ff79b9525c63/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-09 16:04:08.389728 | 
2026-04-09 16:04:08.389791 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-09 16:04:08.415026 | ubuntu-noble | skipping: Conditional result was False
2026-04-09 16:04:08.421610 | 
2026-04-09 16:04:08.421680 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-09 16:04:08.954395 | ubuntu-noble | changed
2026-04-09 16:04:08.964364 | 
2026-04-09 16:04:08.964455 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-09 16:04:09.180390 | ubuntu-noble | ok
2026-04-09 16:04:09.186529 | 
2026-04-09 16:04:09.186594 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-09 16:04:09.980625 | ubuntu-noble | changed
2026-04-09 16:04:09.987043 | 
2026-04-09 16:04:09.987107 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-09 16:04:10.522438 | ubuntu-noble | changed
2026-04-09 16:04:10.527992 | 
2026-04-09 16:04:10.528060 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-09 16:04:10.550702 | ubuntu-noble | skipping: Conditional result was False
2026-04-09 16:04:10.560559 | 
2026-04-09 16:04:10.560645 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-09 16:04:10.929490 | ubuntu-noble -> localhost | changed
2026-04-09 16:04:10.941427 | 
2026-04-09 16:04:10.941494 | TASK [add-build-sshkey : Add back temp key]
2026-04-09 16:04:11.218405 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/72523a89cff841d19111ff79b9525c63/work/72523a89cff841d19111ff79b9525c63_id_rsa (zuul-build-sshkey)
2026-04-09 16:04:11.218644 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013712
2026-04-09 16:04:11.226928 | 
2026-04-09 16:04:11.227013 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-09 16:04:11.566445 | ubuntu-noble | ok
2026-04-09 16:04:11.571846 | 
2026-04-09 16:04:11.577577 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-09 16:04:11.603718 | ubuntu-noble | skipping: Conditional result was False
2026-04-09 16:04:11.623656 | 
2026-04-09 16:04:11.623746 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-09 16:04:11.963745 | ubuntu-noble | ok
2026-04-09 16:04:11.970597 | 
2026-04-09 16:04:11.970664 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-09 16:04:12.791161 | ubuntu-noble | Output suppressed because no_log was given
2026-04-09 16:04:12.802778 | 
2026-04-09 16:04:12.807787 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-09 16:04:13.092221 | ubuntu-noble | ok: "logs"
2026-04-09 16:04:13.092399 | ubuntu-noble | ok: All items complete
2026-04-09 16:04:13.092437 | 
2026-04-09 16:04:13.236551 | ubuntu-noble | ok: "artifacts"
2026-04-09 16:04:13.449357 | ubuntu-noble | ok: "docs"
2026-04-09 16:04:13.467987 | 
2026-04-09 16:04:13.468119 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-09 16:04:13.682350 | ubuntu-noble | changed: "logs"
2026-04-09 16:04:13.888644 | ubuntu-noble | changed: "artifacts"
2026-04-09 16:04:14.077950 | ubuntu-noble | changed: "docs"
2026-04-09 16:04:14.099131 | 
2026-04-09 16:04:14.099252 | PLAY RECAP
2026-04-09 16:04:14.099298 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:04:14.099326 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-09 16:04:14.099349 | 
2026-04-09 16:04:14.221685 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-09 16:04:14.223726 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-09 16:04:14.816316 | 
2026-04-09 16:04:14.816491 | PLAY [all]
2026-04-09 16:04:14.829640 | 
2026-04-09 16:04:14.829816 | TASK [setup-uv : Extract archive]
2026-04-09 16:04:17.220462 | ubuntu-noble | changed
2026-04-09 16:04:17.227621 | 
2026-04-09 16:04:17.227710 | TASK [setup-uv : Print version]
2026-04-09 16:04:17.192442 | ubuntu-noble | uv 0.8.13
2026-04-09 16:04:17.768518 | ubuntu-noble | ok: Runtime: 0:00:00.014323
2026-04-09 16:04:17.777185 | 
2026-04-09 16:04:17.777237 | PLAY RECAP
2026-04-09 16:04:17.777287 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:04:17.777312 | 
2026-04-09 16:04:17.879704 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-09 16:04:17.881789 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-09 16:04:18.430152 | 
2026-04-09 16:04:18.430645 | PLAY [all]
2026-04-09 16:04:18.441532 | 
2026-04-09 16:04:18.441608 | TASK [Run "pre-commit"]
2026-04-09 16:04:18.658581 | ubuntu-noble | Downloading virtualenv (5.6MiB)
2026-04-09 16:04:18.771905 | ubuntu-noble |  Downloading virtualenv
2026-04-09 16:04:18.781269 | ubuntu-noble | Installed 10 packages in 8ms
2026-04-09 16:04:19.175431 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-09 16:04:19.733317 | 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-04-09 16:04:19.738469 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-04-09 16:04:20.267543 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-04-09 16:04:20.664648 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-04-09 16:04:21.195668 | 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-04-09 16:04:21.196430 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-04-09 16:04:21.759052 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-04-09 16:04:22.352814 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-09 16:04:22.352855 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-09 16:04:22.352870 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-09 16:04:26.218004 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-04-09 16:04:26.218056 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-09 16:04:26.218063 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-09 16:04:30.973095 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-04-09 16:04:30.973135 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-09 16:04:30.973141 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-09 16:04:34.219570 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-04-09 16:04:34.219655 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-09 16:04:34.219662 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-09 16:04:36.827279 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-04-09 16:04:36.827329 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-09 16:04:36.827334 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-09 16:04:47.724597 | ubuntu-noble | fix end of files.........................................................Passed
2026-04-09 16:04:47.779952 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-04-09 16:04:48.025907 | ubuntu-noble | black....................................................................Passed
2026-04-09 16:04:48.200527 | ubuntu-noble | flake8...................................................................Passed
2026-04-09 16:04:48.321540 | ubuntu-noble | isort....................................................................Passed
2026-04-09 16:05:07.384464 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-04-09 16:05:07.511952 | ubuntu-noble | ok: Runtime: 0:00:49.023040
2026-04-09 16:05:07.515063 | 
2026-04-09 16:05:07.515163 | PLAY RECAP
2026-04-09 16:05:07.515247 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:05:07.515295 | 
2026-04-09 16:05:07.605584 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-09 16:05:07.607952 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-09 16:05:08.185821 | 
2026-04-09 16:05:08.185933 | PLAY [all]
2026-04-09 16:05:08.197499 | 
2026-04-09 16:05:08.197591 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-09 16:05:08.241968 | ubuntu-noble | skipping: Conditional result was False
2026-04-09 16:05:08.252873 | 
2026-04-09 16:05:08.252981 | TASK [fetch-output : Set log path for single node]
2026-04-09 16:05:08.299066 | ubuntu-noble | ok
2026-04-09 16:05:08.304134 | 
2026-04-09 16:05:08.304217 | LOOP [fetch-output : Ensure local output dirs]
2026-04-09 16:05:08.676688 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/72523a89cff841d19111ff79b9525c63/work/logs"
2026-04-09 16:05:08.874884 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/72523a89cff841d19111ff79b9525c63/work/artifacts"
2026-04-09 16:05:09.088260 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/72523a89cff841d19111ff79b9525c63/work/docs"
2026-04-09 16:05:09.113486 | 
2026-04-09 16:05:09.113647 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-09 16:05:09.730110 | ubuntu-noble | changed: .d..t...... ./
2026-04-09 16:05:09.730320 | ubuntu-noble | changed: All items complete
2026-04-09 16:05:09.730349 | 
2026-04-09 16:05:10.195890 | ubuntu-noble | changed: .d..t...... ./
2026-04-09 16:05:10.681106 | ubuntu-noble | changed: .d..t...... ./
2026-04-09 16:05:10.704013 | 
2026-04-09 16:05:10.704211 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-09 16:05:11.095514 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007181
2026-04-09 16:05:11.313557 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.007381
2026-04-09 16:05:11.339918 | 
2026-04-09 16:05:11.340083 | PLAY [all]
2026-04-09 16:05:11.347430 | 
2026-04-09 16:05:11.347530 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-09 16:05:11.818351 | ubuntu-noble | changed
2026-04-09 16:05:11.826918 | 
2026-04-09 16:05:11.826995 | PLAY RECAP
2026-04-09 16:05:11.827045 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-09 16:05:11.827067 | 
2026-04-09 16:05:12.014133 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-09 16:05:12.016502 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-09 16:05:12.550927 | 
2026-04-09 16:05:12.551049 | PLAY [localhost]
2026-04-09 16:05:12.560494 | 
2026-04-09 16:05:12.560565 | TASK [Generate Zuul manifest]
2026-04-09 16:05:12.578905 | localhost | ok
2026-04-09 16:05:12.596102 | 
2026-04-09 16:05:12.596180 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-09 16:05:12.900719 | localhost | changed
2026-04-09 16:05:12.912450 | 
2026-04-09 16:05:12.912533 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-09 16:05:12.943150 | localhost | ok
2026-04-09 16:05:12.951994 | 
2026-04-09 16:05:12.952081 | TASK [Upload logs]
2026-04-09 16:05:12.982940 | localhost | ok
2026-04-09 16:05:13.044678 | 
2026-04-09 16:05:13.044811 | TASK [Set zuul-log-path fact]
2026-04-09 16:05:13.064099 | localhost | ok
2026-04-09 16:05:13.079526 | 
2026-04-09 16:05:13.079593 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-09 16:05:13.110296 | localhost | ok
2026-04-09 16:05:13.119691 | 
2026-04-09 16:05:13.119776 | TASK [upload-logs : Create log directories]
2026-04-09 16:05:13.495457 | localhost | changed
2026-04-09 16:05:13.501843 | 
2026-04-09 16:05:13.501940 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-09 16:05:13.898016 | localhost -> localhost | ok: Runtime: 0:00:00.004871
2026-04-09 16:05:13.904635 | 
2026-04-09 16:05:13.904729 | TASK [upload-logs : Upload logs to log server]
2026-04-09 16:05:14.372871 | localhost | Output suppressed because no_log was given
2026-04-09 16:05:14.378701 | 
2026-04-09 16:05:14.378793 | LOOP [upload-logs : Compress console log and json output]
2026-04-09 16:05:14.423571 | localhost | skipping: Conditional result was False
2026-04-09 16:05:14.430286 | localhost | skipping: Conditional result was False
2026-04-09 16:05:14.443937 | 
2026-04-09 16:05:14.444148 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-09 16:05:14.489081 | localhost | skipping: Conditional result was False
2026-04-09 16:05:14.489499 | 
2026-04-09 16:05:14.493077 | localhost | skipping: Conditional result was False
2026-04-09 16:05:14.510288 | 
2026-04-09 16:05:14.510491 | LOOP [upload-logs : Upload console log and json output]