2026-08-12 01:00:28.535285 | Job console starting
2026-08-12 01:00:28.546818 | Updating git repos
2026-08-12 01:00:28.577007 | Cloning repos into workspace
2026-08-12 01:00:28.700527 | Restoring repo states
2026-08-12 01:00:28.709159 | Merging changes
2026-08-12 01:00:29.060521 | Checking out repos
2026-08-12 01:00:29.091572 | Preparing playbooks
2026-08-12 01:00:30.003909 | Running Ansible setup
2026-08-12 01:00:33.904214 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-12 01:00:34.527571 | 
2026-08-12 01:00:34.527687 | PLAY [localhost]
2026-08-12 01:00:34.535199 | 
2026-08-12 01:00:34.535277 | TASK [Gathering Facts]
2026-08-12 01:00:35.415067 | localhost | ok
2026-08-12 01:00:35.426697 | 
2026-08-12 01:00:35.426784 | TASK [Setup log path fact]
2026-08-12 01:00:35.450764 | localhost | ok
2026-08-12 01:00:35.471297 | 
2026-08-12 01:00:35.471474 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-12 01:00:35.502006 | localhost | ok
2026-08-12 01:00:35.509958 | 
2026-08-12 01:00:35.510030 | TASK [emit-job-header : Print job information]
2026-08-12 01:00:35.550700 | # Job Information
2026-08-12 01:00:35.550859 | Ansible Version: 2.16.18
2026-08-12 01:00:35.550905 | Job: pre-commit
2026-08-12 01:00:35.550936 | Pipeline: check
2026-08-12 01:00:35.550964 | Executor: 2d72f0692154
2026-08-12 01:00:35.550992 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/141
2026-08-12 01:00:35.551024 | Event ID: 9d5b1ec0-95e5-11f1-83a5-16991d845851
2026-08-12 01:00:35.554788 | 
2026-08-12 01:00:35.554878 | LOOP [emit-job-header : Print node information]
2026-08-12 01:00:35.652181 | localhost | ok:
2026-08-12 01:00:35.652410 | localhost | # Node Information
2026-08-12 01:00:35.652455 | localhost | Inventory Hostname: ubuntu-noble
2026-08-12 01:00:35.652486 | localhost | Hostname: np0000188836
2026-08-12 01:00:35.652510 | localhost | Username: zuul
2026-08-12 01:00:35.652538 | localhost | Distro: Ubuntu 24.04
2026-08-12 01:00:35.652565 | localhost | Provider: yul1
2026-08-12 01:00:35.652590 | localhost | Region: ca-ymq-1
2026-08-12 01:00:35.652614 | localhost | Label: ubuntu-noble
2026-08-12 01:00:35.652638 | localhost | Product Name: OpenStack Nova
2026-08-12 01:00:35.652661 | localhost | Interface IP: 199.19.213.123
2026-08-12 01:00:35.663033 | 
2026-08-12 01:00:35.663195 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-12 01:00:36.063672 | localhost -> localhost | changed
2026-08-12 01:00:36.070790 | 
2026-08-12 01:00:36.070876 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-12 01:00:36.954562 | localhost -> localhost | changed
2026-08-12 01:00:36.963893 | 
2026-08-12 01:00:36.964103 | PLAY [all]
2026-08-12 01:00:36.975359 | 
2026-08-12 01:00:36.975510 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-12 01:00:37.244204 | ubuntu-noble -> localhost | ok
2026-08-12 01:00:37.254209 | 
2026-08-12 01:00:37.254350 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-12 01:00:37.290497 | ubuntu-noble | ok
2026-08-12 01:00:37.309431 | ubuntu-noble | included: /var/lib/zuul/builds/a7e8cd40fdd54e38a8cece72127d934d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-12 01:00:37.316324 | 
2026-08-12 01:00:37.316411 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-12 01:00:38.273840 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-08-12 01:00:38.274051 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/a7e8cd40fdd54e38a8cece72127d934d/work/a7e8cd40fdd54e38a8cece72127d934d_id_rsa
2026-08-12 01:00:38.274107 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/a7e8cd40fdd54e38a8cece72127d934d/work/a7e8cd40fdd54e38a8cece72127d934d_id_rsa.pub
2026-08-12 01:00:38.274145 | ubuntu-noble -> localhost | The key fingerprint is:
2026-08-12 01:00:38.274190 | ubuntu-noble -> localhost | SHA256:DapL3OkAa4sS1fEOA6wSjhb04U1PXU5KKNp+kiGOtHQ zuul-build-sshkey
2026-08-12 01:00:38.274249 | ubuntu-noble -> localhost | The key's randomart image is:
2026-08-12 01:00:38.274295 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-08-12 01:00:38.274339 | ubuntu-noble -> localhost | |.o . . .o..o     |
2026-08-12 01:00:38.274373 | ubuntu-noble -> localhost | |..= =.o...+      |
2026-08-12 01:00:38.274407 | ubuntu-noble -> localhost | |oo.=o+.... .     |
2026-08-12 01:00:38.274438 | ubuntu-noble -> localhost | |+=.E+o.. o       |
2026-08-12 01:00:38.274472 | ubuntu-noble -> localhost | |=.* o++ S .      |
2026-08-12 01:00:38.274502 | ubuntu-noble -> localhost | |.o = *.o         |
2026-08-12 01:00:38.274532 | ubuntu-noble -> localhost | | .o = =          |
2026-08-12 01:00:38.274566 | ubuntu-noble -> localhost | |.o o +           |
2026-08-12 01:00:38.274614 | ubuntu-noble -> localhost | |o . . .          |
2026-08-12 01:00:38.274659 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-08-12 01:00:38.274740 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.455440
2026-08-12 01:00:38.281454 | 
2026-08-12 01:00:38.281526 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-12 01:00:38.318350 | ubuntu-noble | ok
2026-08-12 01:00:38.330219 | ubuntu-noble | included: /var/lib/zuul/builds/a7e8cd40fdd54e38a8cece72127d934d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-12 01:00:38.339526 | 
2026-08-12 01:00:38.339646 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-12 01:00:38.364292 | ubuntu-noble | skipping: Conditional result was False
2026-08-12 01:00:38.375616 | 
2026-08-12 01:00:38.375721 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-12 01:00:38.917585 | ubuntu-noble | changed
2026-08-12 01:00:38.925935 | 
2026-08-12 01:00:38.926046 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-12 01:00:39.157937 | ubuntu-noble | ok
2026-08-12 01:00:39.235859 | 
2026-08-12 01:00:39.235977 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-12 01:00:39.838232 | ubuntu-noble | changed
2026-08-12 01:00:39.846520 | 
2026-08-12 01:00:39.846627 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-12 01:00:40.458911 | ubuntu-noble | changed
2026-08-12 01:00:40.473036 | 
2026-08-12 01:00:40.473127 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-12 01:00:40.497892 | ubuntu-noble | skipping: Conditional result was False
2026-08-12 01:00:40.511227 | 
2026-08-12 01:00:40.511412 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-12 01:00:40.869790 | ubuntu-noble -> localhost | changed
2026-08-12 01:00:40.880447 | 
2026-08-12 01:00:40.880513 | TASK [add-build-sshkey : Add back temp key]
2026-08-12 01:00:41.177320 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/a7e8cd40fdd54e38a8cece72127d934d/work/a7e8cd40fdd54e38a8cece72127d934d_id_rsa (zuul-build-sshkey)
2026-08-12 01:00:41.177542 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014780
2026-08-12 01:00:41.185079 | 
2026-08-12 01:00:41.185169 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-12 01:00:41.534493 | ubuntu-noble | ok
2026-08-12 01:00:41.542963 | 
2026-08-12 01:00:41.543063 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-12 01:00:41.568356 | ubuntu-noble | skipping: Conditional result was False
2026-08-12 01:00:41.584523 | 
2026-08-12 01:00:41.584617 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-12 01:00:41.932157 | ubuntu-noble | ok
2026-08-12 01:00:41.977239 | 
2026-08-12 01:00:41.977356 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-12 01:00:42.732658 | ubuntu-noble | Output suppressed because no_log was given
2026-08-12 01:00:42.745886 | 
2026-08-12 01:00:42.745956 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-12 01:00:42.980058 | ubuntu-noble | ok: "logs"
2026-08-12 01:00:42.980756 | ubuntu-noble | ok: All items complete
2026-08-12 01:00:42.980850 | 
2026-08-12 01:00:43.180791 | ubuntu-noble | ok: "artifacts"
2026-08-12 01:00:43.383229 | ubuntu-noble | ok: "docs"
2026-08-12 01:00:43.402440 | 
2026-08-12 01:00:43.402578 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-12 01:00:43.624065 | ubuntu-noble | changed: "logs"
2026-08-12 01:00:43.842579 | ubuntu-noble | changed: "artifacts"
2026-08-12 01:00:44.049207 | ubuntu-noble | changed: "docs"
2026-08-12 01:00:44.072875 | 
2026-08-12 01:00:44.072992 | PLAY RECAP
2026-08-12 01:00:44.073073 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 01:00:44.073108 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-12 01:00:44.073135 | 
2026-08-12 01:00:44.196117 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-12 01:00:44.198480 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-08-12 01:00:44.776884 | 
2026-08-12 01:00:44.777040 | PLAY [all]
2026-08-12 01:00:44.789496 | 
2026-08-12 01:00:44.789616 | TASK [setup-uv : Extract archive]
2026-08-12 01:00:47.531020 | ubuntu-noble | changed
2026-08-12 01:00:47.538047 | 
2026-08-12 01:00:47.538127 | TASK [setup-uv : Print version]
2026-08-12 01:00:47.919836 | ubuntu-noble | uv 0.8.13
2026-08-12 01:00:48.080040 | ubuntu-noble | ok: Runtime: 0:00:00.016260
2026-08-12 01:00:48.087869 | 
2026-08-12 01:00:48.087944 | PLAY RECAP
2026-08-12 01:00:48.087998 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 01:00:48.088029 | 
2026-08-12 01:00:48.195213 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-08-12 01:00:48.197685 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-08-12 01:00:48.795953 | 
2026-08-12 01:00:48.796062 | PLAY [all]
2026-08-12 01:00:48.808173 | 
2026-08-12 01:00:48.808256 | TASK [Run "pre-commit"]
2026-08-12 01:00:49.479088 | ubuntu-noble | Downloading virtualenv (5.1MiB)
2026-08-12 01:00:49.576101 | ubuntu-noble |  Downloading virtualenv
2026-08-12 01:00:49.589837 | ubuntu-noble | Installed 10 packages in 12ms
2026-08-12 01:00:50.012858 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-08-12 01:00:50.765340 | 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-08-12 01:00:50.770623 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-08-12 01:00:52.212666 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-08-12 01:00:52.911148 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-08-12 01:00:53.485195 | 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-08-12 01:00:53.487214 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-08-12 01:00:54.246865 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-08-12 01:00:55.190268 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-08-12 01:00:55.190373 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-08-12 01:00:55.190397 | ubuntu-noble | [INFO] This may take a few minutes...
2026-08-12 01:01:00.243857 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-08-12 01:01:00.243918 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-08-12 01:01:00.243923 | ubuntu-noble | [INFO] This may take a few minutes...
2026-08-12 01:01:06.430637 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-08-12 01:01:06.430688 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-08-12 01:01:06.430706 | ubuntu-noble | [INFO] This may take a few minutes...
2026-08-12 01:01:09.782486 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-08-12 01:01:09.782593 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-08-12 01:01:09.782612 | ubuntu-noble | [INFO] This may take a few minutes...
2026-08-12 01:01:12.761333 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-08-12 01:01:12.761376 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-08-12 01:01:12.761381 | ubuntu-noble | [INFO] This may take a few minutes...
2026-08-12 01:01:26.634724 | ubuntu-noble | fix end of files.........................................................Passed
2026-08-12 01:01:26.706413 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-08-12 01:01:26.984077 | ubuntu-noble | black....................................................................Passed
2026-08-12 01:01:27.222649 | ubuntu-noble | flake8...................................................................Passed
2026-08-12 01:01:27.362444 | ubuntu-noble | isort....................................................................Passed
2026-08-12 01:02:34.943166 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-08-12 01:02:35.417714 | ubuntu-noble | ok: Runtime: 0:01:45.776735
2026-08-12 01:02:35.419287 | 
2026-08-12 01:02:35.419336 | PLAY RECAP
2026-08-12 01:02:35.419378 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 01:02:35.419400 | 
2026-08-12 01:02:35.547442 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-08-12 01:02:35.549502 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-12 01:02:36.352886 | 
2026-08-12 01:02:36.353221 | PLAY [all]
2026-08-12 01:02:36.365426 | 
2026-08-12 01:02:36.365506 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-12 01:02:36.420196 | ubuntu-noble | skipping: Conditional result was False
2026-08-12 01:02:36.430343 | 
2026-08-12 01:02:36.430468 | TASK [fetch-output : Set log path for single node]
2026-08-12 01:02:36.474823 | ubuntu-noble | ok
2026-08-12 01:02:36.490861 | 
2026-08-12 01:02:36.490954 | LOOP [fetch-output : Ensure local output dirs]
2026-08-12 01:02:37.143077 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/a7e8cd40fdd54e38a8cece72127d934d/work/logs"
2026-08-12 01:02:37.376241 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/a7e8cd40fdd54e38a8cece72127d934d/work/artifacts"
2026-08-12 01:02:37.613598 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/a7e8cd40fdd54e38a8cece72127d934d/work/docs"
2026-08-12 01:02:37.634516 | 
2026-08-12 01:02:37.634666 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-12 01:02:38.369384 | ubuntu-noble | changed: .d..t...... ./
2026-08-12 01:02:38.369654 | ubuntu-noble | changed: All items complete
2026-08-12 01:02:38.369699 | 
2026-08-12 01:02:38.872316 | ubuntu-noble | changed: .d..t...... ./
2026-08-12 01:02:39.362888 | ubuntu-noble | changed: .d..t...... ./
2026-08-12 01:02:39.389107 | 
2026-08-12 01:02:39.389215 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-12 01:02:39.893561 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.099175
2026-08-12 01:02:40.142429 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.006660
2026-08-12 01:02:40.160530 | 
2026-08-12 01:02:40.160635 | PLAY [all]
2026-08-12 01:02:40.166882 | 
2026-08-12 01:02:40.167105 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-12 01:02:40.658983 | ubuntu-noble | changed
2026-08-12 01:02:40.926096 | 
2026-08-12 01:02:40.928468 | PLAY RECAP
2026-08-12 01:02:40.929953 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-12 01:02:40.930021 | 
2026-08-12 01:02:41.123367 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-12 01:02:41.125733 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-12 01:02:41.803860 | 
2026-08-12 01:02:41.928550 | PLAY [localhost]
2026-08-12 01:02:41.947763 | 
2026-08-12 01:02:41.947888 | TASK [Generate Zuul manifest]
2026-08-12 01:02:41.967423 | localhost | ok
2026-08-12 01:02:42.064178 | 
2026-08-12 01:02:42.064346 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-12 01:02:42.582582 | localhost | changed
2026-08-12 01:02:42.598105 | 
2026-08-12 01:02:42.598205 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-12 01:02:42.812854 | localhost | ok
2026-08-12 01:02:42.822812 | 
2026-08-12 01:02:42.822935 | TASK [Upload logs]
2026-08-12 01:02:42.843661 | localhost | ok
2026-08-12 01:02:43.154099 | 
2026-08-12 01:02:43.156405 | TASK [Set zuul-log-path fact]
2026-08-12 01:02:43.178726 | localhost | ok
2026-08-12 01:02:43.194508 | 
2026-08-12 01:02:43.194655 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-12 01:02:43.226528 | localhost | ok
2026-08-12 01:02:43.237362 | 
2026-08-12 01:02:43.237544 | TASK [upload-logs : Create log directories]
2026-08-12 01:02:43.664670 | localhost | changed
2026-08-12 01:02:43.672771 | 
2026-08-12 01:02:43.672940 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-12 01:02:44.114345 | localhost -> localhost | ok: Runtime: 0:00:00.007603
2026-08-12 01:02:44.121088 | 
2026-08-12 01:02:44.121185 | TASK [upload-logs : Upload logs to log server]
2026-08-12 01:02:44.630517 | localhost | Output suppressed because no_log was given
2026-08-12 01:02:44.635630 | 
2026-08-12 01:02:44.635743 | LOOP [upload-logs : Compress console log and json output]
2026-08-12 01:02:44.687908 | localhost | skipping: Conditional result was False
2026-08-12 01:02:44.696405 | localhost | skipping: Conditional result was False
2026-08-12 01:02:44.710381 | 
2026-08-12 01:02:44.710570 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-12 01:02:44.755204 | localhost | skipping: Conditional result was False
2026-08-12 01:02:44.755494 | 
2026-08-12 01:02:44.762037 | localhost | skipping: Conditional result was False
2026-08-12 01:02:44.779930 | 
2026-08-12 01:02:44.780072 | LOOP [upload-logs : Upload console log and json output]