2026-05-01 01:09:59.913575 | Job console starting
2026-05-01 01:09:59.923381 | Updating git repos
2026-05-01 01:09:59.961491 | Cloning repos into workspace
2026-05-01 01:10:00.158930 | Restoring repo states
2026-05-01 01:10:00.164727 | Merging changes
2026-05-01 01:10:00.517802 | Checking out repos
2026-05-01 01:10:00.565540 | Preparing playbooks
2026-05-01 01:10:01.947729 | Running Ansible setup
2026-05-01 01:10:05.843920 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-01 01:10:06.526237 | 
2026-05-01 01:10:06.526391 | PLAY [localhost]
2026-05-01 01:10:06.535689 | 
2026-05-01 01:10:06.535804 | TASK [Gathering Facts]
2026-05-01 01:10:07.440486 | localhost | ok
2026-05-01 01:10:07.447122 | 
2026-05-01 01:10:07.447237 | TASK [Setup log path fact]
2026-05-01 01:10:07.475008 | localhost | ok
2026-05-01 01:10:07.486893 | 
2026-05-01 01:10:07.486985 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-01 01:10:07.526446 | localhost | ok
2026-05-01 01:10:07.534630 | 
2026-05-01 01:10:07.534723 | TASK [emit-job-header : Print job information]
2026-05-01 01:10:07.572168 | # Job Information
2026-05-01 01:10:07.572336 | Ansible Version: 2.16.18
2026-05-01 01:10:07.572372 | Job: pre-commit
2026-05-01 01:10:07.572394 | Pipeline: check
2026-05-01 01:10:07.572414 | Executor: 2d72f0692154
2026-05-01 01:10:07.572434 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/127
2026-05-01 01:10:07.572458 | Event ID: 5d95fee0-44fa-11f1-996a-0310ba4962fe
2026-05-01 01:10:07.575566 | 
2026-05-01 01:10:07.575658 | LOOP [emit-job-header : Print node information]
2026-05-01 01:10:07.678277 | localhost | ok:
2026-05-01 01:10:07.678424 | localhost | # Node Information
2026-05-01 01:10:07.678452 | localhost | Inventory Hostname: ubuntu-noble
2026-05-01 01:10:07.678474 | localhost | Hostname: np0000171432
2026-05-01 01:10:07.678494 | localhost | Username: zuul
2026-05-01 01:10:07.678516 | localhost | Distro: Ubuntu 24.04
2026-05-01 01:10:07.678536 | localhost | Provider: yul1
2026-05-01 01:10:07.678556 | localhost | Region: ca-ymq-1
2026-05-01 01:10:07.678575 | localhost | Label: ubuntu-noble
2026-05-01 01:10:07.678594 | localhost | Product Name: OpenStack Nova
2026-05-01 01:10:07.678612 | localhost | Interface IP: 199.204.45.138
2026-05-01 01:10:07.696718 | 
2026-05-01 01:10:07.696785 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-01 01:10:08.326788 | localhost -> localhost | changed
2026-05-01 01:10:08.533830 | 
2026-05-01 01:10:08.533942 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-01 01:10:09.466253 | localhost -> localhost | changed
2026-05-01 01:10:09.474491 | 
2026-05-01 01:10:09.474758 | PLAY [all]
2026-05-01 01:10:09.490862 | 
2026-05-01 01:10:09.490930 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-01 01:10:10.066640 | ubuntu-noble -> localhost | ok
2026-05-01 01:10:10.075789 | 
2026-05-01 01:10:10.075914 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-01 01:10:10.122560 | ubuntu-noble | ok
2026-05-01 01:10:10.145305 | ubuntu-noble | included: /var/lib/zuul/builds/177440a17798446d809d11a8300a8e47/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-01 01:10:10.154525 | 
2026-05-01 01:10:10.154650 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-01 01:10:11.687831 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-05-01 01:10:11.688011 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/177440a17798446d809d11a8300a8e47/work/177440a17798446d809d11a8300a8e47_id_rsa
2026-05-01 01:10:11.688058 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/177440a17798446d809d11a8300a8e47/work/177440a17798446d809d11a8300a8e47_id_rsa.pub
2026-05-01 01:10:11.688092 | ubuntu-noble -> localhost | The key fingerprint is:
2026-05-01 01:10:11.688346 | ubuntu-noble -> localhost | SHA256:Y33R8+AOZRMUtnr+kwojSA/UGxdme3QD+Q9j9luqqck zuul-build-sshkey
2026-05-01 01:10:11.688413 | ubuntu-noble -> localhost | The key's randomart image is:
2026-05-01 01:10:11.688449 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-05-01 01:10:11.688492 | ubuntu-noble -> localhost | |           + +B+ |
2026-05-01 01:10:11.688525 | ubuntu-noble -> localhost | |        . o ++.o.|
2026-05-01 01:10:11.688555 | ubuntu-noble -> localhost | |       . o o..X  |
2026-05-01 01:10:11.688585 | ubuntu-noble -> localhost | |      .  .+ .*=* |
2026-05-01 01:10:11.688615 | ubuntu-noble -> localhost | |       oS.. +oo+o|
2026-05-01 01:10:11.688645 | ubuntu-noble -> localhost | |      ..+. . =  +|
2026-05-01 01:10:11.688674 | ubuntu-noble -> localhost | |       . o o  o.+|
2026-05-01 01:10:11.688703 | ubuntu-noble -> localhost | |         ...o..= |
2026-05-01 01:10:11.688736 | ubuntu-noble -> localhost | |          E.oo. o|
2026-05-01 01:10:11.688766 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-05-01 01:10:11.688848 | ubuntu-noble -> localhost | ok: Runtime: 0:00:01.090266
2026-05-01 01:10:11.695615 | 
2026-05-01 01:10:11.695695 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-01 01:10:11.735744 | ubuntu-noble | ok
2026-05-01 01:10:11.747512 | ubuntu-noble | included: /var/lib/zuul/builds/177440a17798446d809d11a8300a8e47/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-01 01:10:11.755793 | 
2026-05-01 01:10:11.755871 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-01 01:10:11.783291 | ubuntu-noble | skipping: Conditional result was False
2026-05-01 01:10:12.001612 | 
2026-05-01 01:10:12.001712 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-01 01:10:12.493457 | ubuntu-noble | changed
2026-05-01 01:10:12.698935 | 
2026-05-01 01:10:12.699035 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-01 01:10:12.914900 | ubuntu-noble | ok
2026-05-01 01:10:12.924170 | 
2026-05-01 01:10:12.924339 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-01 01:10:13.519412 | ubuntu-noble | changed
2026-05-01 01:10:13.530387 | 
2026-05-01 01:10:13.530733 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-01 01:10:14.085540 | ubuntu-noble | changed
2026-05-01 01:10:14.189402 | 
2026-05-01 01:10:14.189535 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-01 01:10:14.214093 | ubuntu-noble | skipping: Conditional result was False
2026-05-01 01:10:14.227454 | 
2026-05-01 01:10:14.227576 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-01 01:10:14.558978 | ubuntu-noble -> localhost | changed
2026-05-01 01:10:14.570845 | 
2026-05-01 01:10:14.570944 | TASK [add-build-sshkey : Add back temp key]
2026-05-01 01:10:14.971887 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/177440a17798446d809d11a8300a8e47/work/177440a17798446d809d11a8300a8e47_id_rsa (zuul-build-sshkey)
2026-05-01 01:10:14.972272 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014712
2026-05-01 01:10:14.980179 | 
2026-05-01 01:10:15.094628 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-01 01:10:15.446913 | ubuntu-noble | ok
2026-05-01 01:10:15.452540 | 
2026-05-01 01:10:15.452601 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-01 01:10:15.482134 | ubuntu-noble | skipping: Conditional result was False
2026-05-01 01:10:15.496458 | 
2026-05-01 01:10:15.496527 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-01 01:10:15.906136 | ubuntu-noble | ok
2026-05-01 01:10:16.051618 | 
2026-05-01 01:10:16.051720 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-01 01:10:16.910634 | ubuntu-noble | Output suppressed because no_log was given
2026-05-01 01:10:16.921522 | 
2026-05-01 01:10:16.921593 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-01 01:10:17.139271 | ubuntu-noble | ok: "logs"
2026-05-01 01:10:17.139476 | ubuntu-noble | ok: All items complete
2026-05-01 01:10:17.139517 | 
2026-05-01 01:10:17.352233 | ubuntu-noble | ok: "artifacts"
2026-05-01 01:10:17.578952 | ubuntu-noble | ok: "docs"
2026-05-01 01:10:17.600694 | 
2026-05-01 01:10:17.607944 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-01 01:10:17.849169 | ubuntu-noble | changed: "logs"
2026-05-01 01:10:18.073276 | ubuntu-noble | changed: "artifacts"
2026-05-01 01:10:18.263720 | ubuntu-noble | changed: "docs"
2026-05-01 01:10:18.283299 | 
2026-05-01 01:10:18.283460 | PLAY RECAP
2026-05-01 01:10:18.283530 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-01 01:10:18.283576 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-01 01:10:18.283610 | 
2026-05-01 01:10:19.004927 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-01 01:10:19.293797 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-05-01 01:10:19.969895 | 
2026-05-01 01:10:19.970043 | PLAY [all]
2026-05-01 01:10:19.994145 | 
2026-05-01 01:10:19.994258 | TASK [setup-uv : Extract archive]
2026-05-01 01:10:22.486620 | ubuntu-noble | changed
2026-05-01 01:10:22.492616 | 
2026-05-01 01:10:22.492704 | TASK [setup-uv : Print version]
2026-05-01 01:10:22.590862 | ubuntu-noble | uv 0.8.13
2026-05-01 01:10:23.028211 | ubuntu-noble | ok: Runtime: 0:00:00.017444
2026-05-01 01:10:23.036504 | 
2026-05-01 01:10:23.036581 | PLAY RECAP
2026-05-01 01:10:23.036637 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-01 01:10:23.036672 | 
2026-05-01 01:10:23.129759 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-05-01 01:10:23.130858 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-05-01 01:10:23.676615 | 
2026-05-01 01:10:23.676748 | PLAY [all]
2026-05-01 01:10:23.689903 | 
2026-05-01 01:10:23.690025 | TASK [Run "pre-commit"]
2026-05-01 01:10:24.002282 | ubuntu-noble | Downloading virtualenv (7.2MiB)
2026-05-01 01:10:24.099337 | ubuntu-noble |  Downloading virtualenv
2026-05-01 01:10:24.111332 | ubuntu-noble | Installed 10 packages in 11ms
2026-05-01 01:10:24.476542 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-05-01 01:10:25.004990 | 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-05-01 01:10:25.011219 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-05-01 01:10:25.968203 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-05-01 01:10:26.351788 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-05-01 01:10:26.808560 | 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-05-01 01:10:26.810667 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-05-01 01:10:27.389460 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-05-01 01:10:27.899912 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-05-01 01:10:27.899985 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-01 01:10:27.899999 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-01 01:10:32.059111 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-05-01 01:10:32.059234 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-01 01:10:32.059254 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-01 01:10:36.993351 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-05-01 01:10:36.993500 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-01 01:10:36.993509 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-01 01:10:40.447354 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-05-01 01:10:40.447421 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-01 01:10:40.447429 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-01 01:10:43.333695 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-05-01 01:10:43.333779 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-01 01:10:43.333805 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-01 01:10:53.356008 | ubuntu-noble | fix end of files.........................................................Passed
2026-05-01 01:10:53.413436 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-05-01 01:10:53.687736 | ubuntu-noble | black....................................................................Passed
2026-05-01 01:10:53.879718 | ubuntu-noble | flake8...................................................................Passed
2026-05-01 01:10:54.002395 | ubuntu-noble | isort....................................................................Passed
2026-05-01 01:11:11.519702 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-05-01 01:11:12.018417 | ubuntu-noble | ok: Runtime: 0:00:47.792337
2026-05-01 01:11:12.021211 | 
2026-05-01 01:11:12.021282 | PLAY RECAP
2026-05-01 01:11:12.021343 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-01 01:11:12.021375 | 
2026-05-01 01:11:12.183064 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-05-01 01:11:12.184428 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-01 01:11:12.785077 | 
2026-05-01 01:11:12.785505 | PLAY [all]
2026-05-01 01:11:12.797189 | 
2026-05-01 01:11:12.797270 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-01 01:11:12.842304 | ubuntu-noble | skipping: Conditional result was False
2026-05-01 01:11:12.852406 | 
2026-05-01 01:11:12.852514 | TASK [fetch-output : Set log path for single node]
2026-05-01 01:11:12.899310 | ubuntu-noble | ok
2026-05-01 01:11:12.905644 | 
2026-05-01 01:11:12.905713 | LOOP [fetch-output : Ensure local output dirs]
2026-05-01 01:11:13.349881 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/177440a17798446d809d11a8300a8e47/work/logs"
2026-05-01 01:11:13.567475 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/177440a17798446d809d11a8300a8e47/work/artifacts"
2026-05-01 01:11:13.773183 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/177440a17798446d809d11a8300a8e47/work/docs"
2026-05-01 01:11:13.796688 | 
2026-05-01 01:11:13.796840 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-01 01:11:14.416010 | ubuntu-noble | changed: .d..t...... ./
2026-05-01 01:11:14.416242 | ubuntu-noble | changed: All items complete
2026-05-01 01:11:14.416282 | 
2026-05-01 01:11:14.860253 | ubuntu-noble | changed: .d..t...... ./
2026-05-01 01:11:15.330562 | ubuntu-noble | changed: .d..t...... ./
2026-05-01 01:11:15.355910 | 
2026-05-01 01:11:15.356088 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-01 01:11:15.752233 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.008352
2026-05-01 01:11:15.999164 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.007944
2026-05-01 01:11:16.019329 | 
2026-05-01 01:11:16.019468 | PLAY [all]
2026-05-01 01:11:16.026142 | 
2026-05-01 01:11:16.026212 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-01 01:11:16.488175 | ubuntu-noble | changed
2026-05-01 01:11:16.496210 | 
2026-05-01 01:11:16.496264 | PLAY RECAP
2026-05-01 01:11:16.496312 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-01 01:11:16.496334 | 
2026-05-01 01:11:16.668579 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-01 01:11:16.671762 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-01 01:11:17.219821 | 
2026-05-01 01:11:17.219925 | PLAY [localhost]
2026-05-01 01:11:17.229958 | 
2026-05-01 01:11:17.230029 | TASK [Generate Zuul manifest]
2026-05-01 01:11:17.249787 | localhost | ok
2026-05-01 01:11:17.269158 | 
2026-05-01 01:11:17.269234 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-01 01:11:17.646564 | localhost | changed
2026-05-01 01:11:17.659671 | 
2026-05-01 01:11:17.659748 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-01 01:11:17.692057 | localhost | ok
2026-05-01 01:11:17.700812 | 
2026-05-01 01:11:17.700876 | TASK [Upload logs]
2026-05-01 01:11:17.722819 | localhost | ok
2026-05-01 01:11:17.780108 | 
2026-05-01 01:11:17.780219 | TASK [Set zuul-log-path fact]
2026-05-01 01:11:17.801933 | localhost | ok
2026-05-01 01:11:17.815861 | 
2026-05-01 01:11:17.815927 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-01 01:11:17.846845 | localhost | ok
2026-05-01 01:11:17.857753 | 
2026-05-01 01:11:17.857819 | TASK [upload-logs : Create log directories]
2026-05-01 01:11:18.252675 | localhost | changed
2026-05-01 01:11:18.259171 | 
2026-05-01 01:11:18.259269 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-01 01:11:18.656485 | localhost -> localhost | ok: Runtime: 0:00:00.005822
2026-05-01 01:11:18.663128 | 
2026-05-01 01:11:18.663219 | TASK [upload-logs : Upload logs to log server]
2026-05-01 01:11:19.150727 | localhost | Output suppressed because no_log was given
2026-05-01 01:11:19.156405 | 
2026-05-01 01:11:19.156469 | LOOP [upload-logs : Compress console log and json output]
2026-05-01 01:11:19.203466 | localhost | skipping: Conditional result was False
2026-05-01 01:11:19.210458 | localhost | skipping: Conditional result was False
2026-05-01 01:11:19.219647 | 
2026-05-01 01:11:19.219790 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-01 01:11:19.260194 | localhost | skipping: Conditional result was False
2026-05-01 01:11:19.260618 | 
2026-05-01 01:11:19.264109 | localhost | skipping: Conditional result was False
2026-05-01 01:11:19.273992 | 
2026-05-01 01:11:19.274181 | LOOP [upload-logs : Upload console log and json output]