2026-04-26 11:22:35.849956 | Job console starting
2026-04-26 11:22:35.860188 | Updating git repos
2026-04-26 11:22:35.894012 | Cloning repos into workspace
2026-04-26 11:22:36.783751 | Restoring repo states
2026-04-26 11:22:36.791573 | Merging changes
2026-04-26 11:22:37.049321 | Checking out repos
2026-04-26 11:22:37.150972 | Preparing playbooks
2026-04-26 11:22:42.739183 | Running Ansible setup
2026-04-26 11:22:46.685331 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-26 11:22:47.276742 | 
2026-04-26 11:22:47.276943 | PLAY [localhost]
2026-04-26 11:22:47.284235 | 
2026-04-26 11:22:47.284303 | TASK [Gathering Facts]
2026-04-26 11:22:48.156677 | localhost | ok
2026-04-26 11:22:48.171114 | 
2026-04-26 11:22:48.171316 | TASK [Setup log path fact]
2026-04-26 11:22:48.197626 | localhost | ok
2026-04-26 11:22:48.213692 | 
2026-04-26 11:22:48.213776 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-26 11:22:48.248777 | localhost | ok
2026-04-26 11:22:48.258405 | 
2026-04-26 11:22:48.258556 | TASK [emit-job-header : Print job information]
2026-04-26 11:22:48.300215 | # Job Information
2026-04-26 11:22:48.300401 | Ansible Version: 2.16.16
2026-04-26 11:22:48.300449 | Job: pre-commit
2026-04-26 11:22:48.300479 | Pipeline: check
2026-04-26 11:22:48.300507 | Executor: 0a8996d2b663
2026-04-26 11:22:48.300535 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/122
2026-04-26 11:22:48.300567 | Event ID: 21689ae0-4162-11f1-9bf5-ceff5f6c5044
2026-04-26 11:22:48.304591 | 
2026-04-26 11:22:48.304690 | LOOP [emit-job-header : Print node information]
2026-04-26 11:22:49.159142 | localhost | ok:
2026-04-26 11:22:49.159356 | localhost | # Node Information
2026-04-26 11:22:49.159396 | localhost | Inventory Hostname: ubuntu-noble
2026-04-26 11:22:49.159426 | localhost | Hostname: np0000170285
2026-04-26 11:22:49.159455 | localhost | Username: zuul
2026-04-26 11:22:49.159486 | localhost | Distro: Ubuntu 24.04
2026-04-26 11:22:49.159514 | localhost | Provider: yul1
2026-04-26 11:22:49.159542 | localhost | Region: ca-ymq-1
2026-04-26 11:22:49.159569 | localhost | Label: ubuntu-noble
2026-04-26 11:22:49.159596 | localhost | Product Name: OpenStack Nova
2026-04-26 11:22:49.159623 | localhost | Interface IP: 199.204.45.30
2026-04-26 11:22:49.171403 | 
2026-04-26 11:22:49.171544 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-26 11:22:49.613961 | localhost -> localhost | changed
2026-04-26 11:22:49.621949 | 
2026-04-26 11:22:49.622083 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-26 11:22:51.863498 | localhost -> localhost | changed
2026-04-26 11:22:51.872527 | 
2026-04-26 11:22:51.872660 | PLAY [all]
2026-04-26 11:22:51.885272 | 
2026-04-26 11:22:51.885377 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-26 11:22:52.112190 | ubuntu-noble -> localhost | ok
2026-04-26 11:22:52.123075 | 
2026-04-26 11:22:52.123160 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-26 11:22:52.159660 | ubuntu-noble | ok
2026-04-26 11:22:52.175246 | ubuntu-noble | included: /var/lib/zuul/builds/8b9919edc95d47988ee9ec83e3a5b1fb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-26 11:22:52.181818 | 
2026-04-26 11:22:52.181901 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-26 11:22:53.225443 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-04-26 11:22:53.225710 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/8b9919edc95d47988ee9ec83e3a5b1fb/work/8b9919edc95d47988ee9ec83e3a5b1fb_id_rsa
2026-04-26 11:22:53.225768 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/8b9919edc95d47988ee9ec83e3a5b1fb/work/8b9919edc95d47988ee9ec83e3a5b1fb_id_rsa.pub
2026-04-26 11:22:53.225814 | ubuntu-noble -> localhost | The key fingerprint is:
2026-04-26 11:22:53.225851 | ubuntu-noble -> localhost | SHA256:aT98j2SxAMwaichtwHJ1zCuVEDMxzsTqeU3kdlSRVxY zuul-build-sshkey
2026-04-26 11:22:53.225910 | ubuntu-noble -> localhost | The key's randomart image is:
2026-04-26 11:22:53.225947 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-04-26 11:22:53.225988 | ubuntu-noble -> localhost | | ..oX*.. .oo .E. |
2026-04-26 11:22:53.226025 | ubuntu-noble -> localhost | |..oB.**+. . ..   |
2026-04-26 11:22:53.226064 | ubuntu-noble -> localhost | | oo.*+oo+  .     |
2026-04-26 11:22:53.226104 | ubuntu-noble -> localhost | |  ... =o.o       |
2026-04-26 11:22:53.226145 | ubuntu-noble -> localhost | | . . =..S . .    |
2026-04-26 11:22:53.226177 | ubuntu-noble -> localhost | |  o . .. o . o   |
2026-04-26 11:22:53.226204 | ubuntu-noble -> localhost | |   .      + =    |
2026-04-26 11:22:53.226245 | ubuntu-noble -> localhost | |           = o   |
2026-04-26 11:22:53.226284 | ubuntu-noble -> localhost | |            . .  |
2026-04-26 11:22:53.226325 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-04-26 11:22:53.226467 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.637465
2026-04-26 11:22:53.235659 | 
2026-04-26 11:22:53.235772 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-26 11:22:53.269271 | ubuntu-noble | ok
2026-04-26 11:22:53.279977 | ubuntu-noble | included: /var/lib/zuul/builds/8b9919edc95d47988ee9ec83e3a5b1fb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-26 11:22:53.288456 | 
2026-04-26 11:22:53.288522 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-26 11:22:53.303801 | ubuntu-noble | skipping: Conditional result was False
2026-04-26 11:22:53.314133 | 
2026-04-26 11:22:53.314206 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-26 11:22:53.843349 | ubuntu-noble | changed
2026-04-26 11:22:53.851175 | 
2026-04-26 11:22:53.851243 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-26 11:22:54.064946 | ubuntu-noble | ok
2026-04-26 11:22:54.071117 | 
2026-04-26 11:22:54.071204 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-26 11:22:54.642117 | ubuntu-noble | changed
2026-04-26 11:22:54.647633 | 
2026-04-26 11:22:54.647698 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-26 11:22:55.195759 | ubuntu-noble | changed
2026-04-26 11:22:55.202923 | 
2026-04-26 11:22:55.203052 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-26 11:22:56.120355 | ubuntu-noble | skipping: Conditional result was False
2026-04-26 11:22:56.127309 | 
2026-04-26 11:22:56.127410 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-26 11:22:56.505715 | ubuntu-noble -> localhost | changed
2026-04-26 11:22:56.522953 | 
2026-04-26 11:22:56.523020 | TASK [add-build-sshkey : Add back temp key]
2026-04-26 11:22:56.814517 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/8b9919edc95d47988ee9ec83e3a5b1fb/work/8b9919edc95d47988ee9ec83e3a5b1fb_id_rsa (zuul-build-sshkey)
2026-04-26 11:22:56.814754 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013868
2026-04-26 11:22:56.820649 | 
2026-04-26 11:22:56.820739 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-26 11:22:57.177322 | ubuntu-noble | ok
2026-04-26 11:22:57.191240 | 
2026-04-26 11:22:57.191328 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-26 11:22:57.218496 | ubuntu-noble | skipping: Conditional result was False
2026-04-26 11:22:57.236923 | 
2026-04-26 11:22:57.237015 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-26 11:22:57.646434 | ubuntu-noble | ok
2026-04-26 11:22:57.652778 | 
2026-04-26 11:22:57.652849 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-26 11:23:02.255502 | ubuntu-noble | Output suppressed because no_log was given
2026-04-26 11:23:02.273667 | 
2026-04-26 11:23:02.273764 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-26 11:23:02.562090 | ubuntu-noble | ok: "logs"
2026-04-26 11:23:02.562282 | ubuntu-noble | ok: All items complete
2026-04-26 11:23:02.562323 | 
2026-04-26 11:23:02.725951 | ubuntu-noble | ok: "artifacts"
2026-04-26 11:23:02.945324 | ubuntu-noble | ok: "docs"
2026-04-26 11:23:02.963763 | 
2026-04-26 11:23:02.963915 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-26 11:23:03.195597 | ubuntu-noble | changed: "logs"
2026-04-26 11:23:03.416342 | ubuntu-noble | changed: "artifacts"
2026-04-26 11:23:03.621384 | ubuntu-noble | changed: "docs"
2026-04-26 11:23:03.637670 | 
2026-04-26 11:23:03.637791 | PLAY RECAP
2026-04-26 11:23:03.637844 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-26 11:23:03.637877 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-26 11:23:03.637898 | 
2026-04-26 11:23:03.783511 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-26 11:23:03.785833 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-26 11:23:04.426250 | 
2026-04-26 11:23:04.426454 | PLAY [all]
2026-04-26 11:23:04.443625 | 
2026-04-26 11:23:04.443797 | TASK [setup-uv : Extract archive]
2026-04-26 11:23:06.804507 | ubuntu-noble | changed
2026-04-26 11:23:06.813971 | 
2026-04-26 11:23:06.814067 | TASK [setup-uv : Print version]
2026-04-26 11:23:07.216363 | ubuntu-noble | uv 0.8.13
2026-04-26 11:23:07.363159 | ubuntu-noble | ok: Runtime: 0:00:00.013363
2026-04-26 11:23:07.370461 | 
2026-04-26 11:23:07.370517 | PLAY RECAP
2026-04-26 11:23:07.370567 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-26 11:23:07.370592 | 
2026-04-26 11:23:07.527527 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-26 11:23:07.530057 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-26 11:23:08.173616 | 
2026-04-26 11:23:08.173775 | PLAY [all]
2026-04-26 11:23:08.188898 | 
2026-04-26 11:23:08.189080 | TASK [Run "pre-commit"]
2026-04-26 11:23:08.816780 | ubuntu-noble | Downloading virtualenv (5.6MiB)
2026-04-26 11:23:08.900093 | ubuntu-noble |  Downloading virtualenv
2026-04-26 11:23:08.913417 | ubuntu-noble | Installed 10 packages in 12ms
2026-04-26 11:23:09.283711 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-26 11:23:09.790587 | 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-26 11:23:09.796464 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-04-26 11:23:10.307352 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-04-26 11:23:10.675341 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-04-26 11:23:11.127294 | 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-26 11:23:11.129342 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-04-26 11:23:11.863402 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-04-26 11:23:12.368866 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-26 11:23:12.368938 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-26 11:23:12.368951 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-26 11:23:16.224467 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-04-26 11:23:16.224640 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-26 11:23:16.224653 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-26 11:23:20.390475 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-04-26 11:23:20.390554 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-26 11:23:20.390573 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-26 11:23:23.513936 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-04-26 11:23:23.514014 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-26 11:23:23.514025 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-26 11:23:26.112155 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-04-26 11:23:26.112226 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-26 11:23:26.112237 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-26 11:23:36.058938 | ubuntu-noble | fix end of files.........................................................Passed
2026-04-26 11:23:36.111585 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-04-26 11:23:36.360139 | ubuntu-noble | black....................................................................Passed
2026-04-26 11:23:36.550372 | ubuntu-noble | flake8...................................................................Passed
2026-04-26 11:23:36.670183 | ubuntu-noble | isort....................................................................Passed
2026-04-26 11:23:53.924201 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-04-26 11:23:54.349561 | ubuntu-noble | ok: Runtime: 0:00:45.347261
2026-04-26 11:23:54.353292 | 
2026-04-26 11:23:54.353362 | PLAY RECAP
2026-04-26 11:23:54.353424 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-26 11:23:54.353456 | 
2026-04-26 11:23:54.468055 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-26 11:23:54.470369 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-26 11:23:55.118228 | 
2026-04-26 11:23:55.118371 | PLAY [all]
2026-04-26 11:23:55.130442 | 
2026-04-26 11:23:55.130552 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-26 11:23:55.175650 | ubuntu-noble | skipping: Conditional result was False
2026-04-26 11:23:55.186132 | 
2026-04-26 11:23:55.186270 | TASK [fetch-output : Set log path for single node]
2026-04-26 11:23:55.230544 | ubuntu-noble | ok
2026-04-26 11:23:55.238105 | 
2026-04-26 11:23:55.238211 | LOOP [fetch-output : Ensure local output dirs]
2026-04-26 11:23:55.644620 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/8b9919edc95d47988ee9ec83e3a5b1fb/work/logs"
2026-04-26 11:23:55.877109 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/8b9919edc95d47988ee9ec83e3a5b1fb/work/artifacts"
2026-04-26 11:23:56.083157 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/8b9919edc95d47988ee9ec83e3a5b1fb/work/docs"
2026-04-26 11:23:56.100393 | 
2026-04-26 11:23:56.100731 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-26 11:23:56.753493 | ubuntu-noble | ok
2026-04-26 11:23:56.753727 | ubuntu-noble | changed: All items complete
2026-04-26 11:23:56.753754 | 
2026-04-26 11:23:57.244466 | ubuntu-noble | changed: .d..t...... ./
2026-04-26 11:23:57.722241 | ubuntu-noble | changed: .d..t...... ./
2026-04-26 11:23:57.753252 | 
2026-04-26 11:23:57.753389 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-26 11:23:58.206589 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007962
2026-04-26 11:23:58.443948 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.008057
2026-04-26 11:23:58.465808 | 
2026-04-26 11:23:58.465925 | PLAY [all]
2026-04-26 11:23:58.473061 | 
2026-04-26 11:23:58.473168 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-26 11:23:58.932126 | ubuntu-noble | changed
2026-04-26 11:23:58.941218 | 
2026-04-26 11:23:58.941267 | PLAY RECAP
2026-04-26 11:23:58.941315 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-26 11:23:58.941338 | 
2026-04-26 11:23:59.057464 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-26 11:24:00.108007 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-26 11:24:00.730000 | 
2026-04-26 11:24:00.730174 | PLAY [localhost]
2026-04-26 11:24:00.741516 | 
2026-04-26 11:24:00.741591 | TASK [Generate Zuul manifest]
2026-04-26 11:24:00.764344 | localhost | ok
2026-04-26 11:24:00.786230 | 
2026-04-26 11:24:00.786458 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-26 11:24:01.124222 | localhost | changed
2026-04-26 11:24:01.135721 | 
2026-04-26 11:24:01.135807 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-26 11:24:01.471241 | localhost | ok
2026-04-26 11:24:01.478295 | 
2026-04-26 11:24:01.478365 | TASK [Upload logs]
2026-04-26 11:24:01.499113 | localhost | ok
2026-04-26 11:24:01.558996 | 
2026-04-26 11:24:01.559144 | TASK [Set zuul-log-path fact]
2026-04-26 11:24:01.579011 | localhost | ok
2026-04-26 11:24:01.594088 | 
2026-04-26 11:24:01.594191 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-26 11:24:01.625701 | localhost | ok
2026-04-26 11:24:01.636587 | 
2026-04-26 11:24:01.636659 | TASK [upload-logs : Create log directories]
2026-04-26 11:24:02.114141 | localhost | changed
2026-04-26 11:24:02.120737 | 
2026-04-26 11:24:02.120832 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-26 11:24:02.517147 | localhost -> localhost | ok: Runtime: 0:00:00.004660
2026-04-26 11:24:02.522356 | 
2026-04-26 11:24:02.522426 | TASK [upload-logs : Upload logs to log server]
2026-04-26 11:24:05.171152 | localhost | Output suppressed because no_log was given
2026-04-26 11:24:05.176122 | 
2026-04-26 11:24:05.176209 | LOOP [upload-logs : Compress console log and json output]
2026-04-26 11:24:05.239389 | localhost | skipping: Conditional result was False
2026-04-26 11:24:05.249099 | localhost | skipping: Conditional result was False
2026-04-26 11:24:05.261735 | 
2026-04-26 11:24:05.261926 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-26 11:24:05.326076 | localhost | skipping: Conditional result was False
2026-04-26 11:24:05.326514 | 
2026-04-26 11:24:05.330582 | localhost | skipping: Conditional result was False
2026-04-26 11:24:05.344466 | 
2026-04-26 11:24:05.344547 | LOOP [upload-logs : Upload console log and json output]