2026-04-13 18:41:38.205441 | Job console starting
2026-04-13 18:41:38.218761 | Updating git repos
2026-04-13 18:41:38.241820 | Cloning repos into workspace
2026-04-13 18:41:38.298214 | Restoring repo states
2026-04-13 18:41:38.303807 | Merging changes
2026-04-13 18:41:38.582617 | Checking out repos
2026-04-13 18:41:38.616863 | Preparing playbooks
2026-04-13 18:41:39.736189 | Running Ansible setup
2026-04-13 18:41:44.163881 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 18:41:44.802113 | 
2026-04-13 18:41:45.197385 | PLAY [localhost]
2026-04-13 18:41:45.219377 | 
2026-04-13 18:41:45.219560 | TASK [Gathering Facts]
2026-04-13 18:41:46.151480 | localhost | ok
2026-04-13 18:41:46.162438 | 
2026-04-13 18:41:46.162571 | TASK [Setup log path fact]
2026-04-13 18:41:46.182266 | localhost | ok
2026-04-13 18:41:46.197413 | 
2026-04-13 18:41:46.197529 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 18:41:46.227292 | localhost | ok
2026-04-13 18:41:46.237791 | 
2026-04-13 18:41:46.237934 | TASK [emit-job-header : Print job information]
2026-04-13 18:41:46.278590 | # Job Information
2026-04-13 18:41:46.278806 | Ansible Version: 2.16.16
2026-04-13 18:41:46.278848 | Job: pre-commit
2026-04-13 18:41:46.278873 | Pipeline: check
2026-04-13 18:41:46.278894 | Executor: 0a8996d2b663
2026-04-13 18:41:46.278915 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/108
2026-04-13 18:41:46.278938 | Event ID: 4a670cf0-3768-11f1-97e0-7c8334f2b834
2026-04-13 18:41:46.282099 | 
2026-04-13 18:41:46.282184 | LOOP [emit-job-header : Print node information]
2026-04-13 18:41:46.379661 | localhost | ok:
2026-04-13 18:41:46.380386 | localhost | # Node Information
2026-04-13 18:41:46.380461 | localhost | Inventory Hostname: ubuntu-noble
2026-04-13 18:41:46.380513 | localhost | Hostname: np0000165651
2026-04-13 18:41:46.380557 | localhost | Username: zuul
2026-04-13 18:41:46.380607 | localhost | Distro: Ubuntu 24.04
2026-04-13 18:41:46.380651 | localhost | Provider: yul1
2026-04-13 18:41:46.380693 | localhost | Region: ca-ymq-1
2026-04-13 18:41:46.380735 | localhost | Label: ubuntu-noble
2026-04-13 18:41:46.380776 | localhost | Product Name: OpenStack Nova
2026-04-13 18:41:46.380816 | localhost | Interface IP: 199.204.45.95
2026-04-13 18:41:46.407391 | 
2026-04-13 18:41:46.407562 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-13 18:41:46.894856 | localhost -> localhost | changed
2026-04-13 18:41:46.903886 | 
2026-04-13 18:41:46.904001 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-13 18:41:48.076200 | localhost -> localhost | changed
2026-04-13 18:41:48.082381 | 
2026-04-13 18:41:48.082479 | PLAY [all]
2026-04-13 18:41:48.095058 | 
2026-04-13 18:41:48.095245 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-13 18:41:48.435394 | ubuntu-noble -> localhost | ok
2026-04-13 18:41:48.446817 | 
2026-04-13 18:41:48.446896 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-13 18:41:48.480781 | ubuntu-noble | ok
2026-04-13 18:41:48.502245 | ubuntu-noble | included: /var/lib/zuul/builds/d6a9dc4f4f4545458ef79d4dc099d4bc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-13 18:41:48.508640 | 
2026-04-13 18:41:48.508745 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-13 18:41:49.531648 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-04-13 18:41:49.531889 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/d6a9dc4f4f4545458ef79d4dc099d4bc/work/d6a9dc4f4f4545458ef79d4dc099d4bc_id_rsa
2026-04-13 18:41:49.531935 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/d6a9dc4f4f4545458ef79d4dc099d4bc/work/d6a9dc4f4f4545458ef79d4dc099d4bc_id_rsa.pub
2026-04-13 18:41:49.531967 | ubuntu-noble -> localhost | The key fingerprint is:
2026-04-13 18:41:49.531997 | ubuntu-noble -> localhost | SHA256:ceRbV4PM4pkXdi5i/iXX5uszJUdOMKs0AxnVr6xT+F8 zuul-build-sshkey
2026-04-13 18:41:49.532044 | ubuntu-noble -> localhost | The key's randomart image is:
2026-04-13 18:41:49.532071 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-04-13 18:41:49.532107 | ubuntu-noble -> localhost | |          o++....|
2026-04-13 18:41:49.532139 | ubuntu-noble -> localhost | |         oo. *+o.|
2026-04-13 18:41:49.532167 | ubuntu-noble -> localhost | |        . +.* == |
2026-04-13 18:41:49.532196 | ubuntu-noble -> localhost | |         o O++..+|
2026-04-13 18:41:49.532224 | ubuntu-noble -> localhost | |        S +.o*.=.|
2026-04-13 18:41:49.532251 | ubuntu-noble -> localhost | |           .o.=o*|
2026-04-13 18:41:49.532278 | ubuntu-noble -> localhost | |            .++=E|
2026-04-13 18:41:49.532305 | ubuntu-noble -> localhost | |            o..oo|
2026-04-13 18:41:49.532337 | ubuntu-noble -> localhost | |             ..+=|
2026-04-13 18:41:49.532366 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-04-13 18:41:49.532435 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.480827
2026-04-13 18:41:49.540041 | 
2026-04-13 18:41:49.579100 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-13 18:41:49.615889 | ubuntu-noble | ok
2026-04-13 18:41:49.625035 | ubuntu-noble | included: /var/lib/zuul/builds/d6a9dc4f4f4545458ef79d4dc099d4bc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-13 18:41:49.632887 | 
2026-04-13 18:41:49.632955 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-13 18:41:49.657083 | ubuntu-noble | skipping: Conditional result was False
2026-04-13 18:41:49.667163 | 
2026-04-13 18:41:49.667258 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-13 18:41:50.248441 | ubuntu-noble | changed
2026-04-13 18:41:50.256549 | 
2026-04-13 18:41:50.256637 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-13 18:41:50.502347 | ubuntu-noble | ok
2026-04-13 18:41:50.510321 | 
2026-04-13 18:41:50.510523 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-13 18:41:51.326641 | ubuntu-noble | changed
2026-04-13 18:41:51.332308 | 
2026-04-13 18:41:51.332394 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-13 18:41:51.869650 | ubuntu-noble | changed
2026-04-13 18:41:51.876114 | 
2026-04-13 18:41:51.876184 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-13 18:41:51.922247 | ubuntu-noble | skipping: Conditional result was False
2026-04-13 18:41:51.938098 | 
2026-04-13 18:41:51.938231 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-13 18:41:52.462484 | ubuntu-noble -> localhost | changed
2026-04-13 18:41:52.478817 | 
2026-04-13 18:41:52.478930 | TASK [add-build-sshkey : Add back temp key]
2026-04-13 18:41:52.785453 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/d6a9dc4f4f4545458ef79d4dc099d4bc/work/d6a9dc4f4f4545458ef79d4dc099d4bc_id_rsa (zuul-build-sshkey)
2026-04-13 18:41:52.785730 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013262
2026-04-13 18:41:52.792383 | 
2026-04-13 18:41:52.792453 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-13 18:41:53.167458 | ubuntu-noble | ok
2026-04-13 18:41:53.172707 | 
2026-04-13 18:41:53.172780 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-13 18:41:53.200664 | ubuntu-noble | skipping: Conditional result was False
2026-04-13 18:41:53.217808 | 
2026-04-13 18:41:53.218088 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-13 18:41:53.664690 | ubuntu-noble | ok
2026-04-13 18:41:53.670672 | 
2026-04-13 18:41:53.670750 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-13 18:41:54.555653 | ubuntu-noble | Output suppressed because no_log was given
2026-04-13 18:41:54.575164 | 
2026-04-13 18:41:54.575412 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-13 18:41:54.841976 | ubuntu-noble | ok: "logs"
2026-04-13 18:41:54.842464 | ubuntu-noble | ok: All items complete
2026-04-13 18:41:54.842511 | 
2026-04-13 18:41:55.001364 | ubuntu-noble | ok: "artifacts"
2026-04-13 18:41:55.240022 | ubuntu-noble | ok: "docs"
2026-04-13 18:41:55.257061 | 
2026-04-13 18:41:55.257226 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-13 18:41:55.521825 | ubuntu-noble | changed: "logs"
2026-04-13 18:41:55.732919 | ubuntu-noble | changed: "artifacts"
2026-04-13 18:41:55.936696 | ubuntu-noble | changed: "docs"
2026-04-13 18:41:55.946695 | 
2026-04-13 18:41:55.946834 | PLAY RECAP
2026-04-13 18:41:55.946884 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 18:41:55.946913 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-13 18:41:55.946935 | 
2026-04-13 18:41:56.112344 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-13 18:41:56.113841 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-13 18:41:56.722309 | 
2026-04-13 18:41:56.722465 | PLAY [all]
2026-04-13 18:41:56.736110 | 
2026-04-13 18:41:56.736260 | TASK [setup-uv : Extract archive]
2026-04-13 18:41:59.411635 | ubuntu-noble | changed
2026-04-13 18:41:59.422198 | 
2026-04-13 18:41:59.422389 | TASK [setup-uv : Print version]
2026-04-13 18:41:58.896597 | ubuntu-noble | uv 0.8.13
2026-04-13 18:41:59.960985 | ubuntu-noble | ok: Runtime: 0:00:00.015672
2026-04-13 18:41:59.977132 | 
2026-04-13 18:41:59.977209 | PLAY RECAP
2026-04-13 18:41:59.977260 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 18:41:59.977285 | 
2026-04-13 18:42:00.097299 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-13 18:42:00.098674 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-13 18:42:00.676414 | 
2026-04-13 18:42:00.676583 | PLAY [all]
2026-04-13 18:42:00.688900 | 
2026-04-13 18:42:00.688999 | TASK [Run "pre-commit"]
2026-04-13 18:42:00.317565 | ubuntu-noble | Downloading virtualenv (5.6MiB)
2026-04-13 18:42:00.390132 | ubuntu-noble |  Downloading virtualenv
2026-04-13 18:42:00.404799 | ubuntu-noble | Installed 10 packages in 13ms
2026-04-13 18:42:00.813026 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-13 18:42:01.301630 | 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-13 18:42:01.306917 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-04-13 18:42:01.849819 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-04-13 18:42:02.267374 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-04-13 18:42:02.703610 | 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-13 18:42:02.705642 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-04-13 18:42:03.292653 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-04-13 18:42:04.992272 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-13 18:42:04.992356 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-13 18:42:04.992377 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-13 18:42:09.817217 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-04-13 18:42:09.817253 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-13 18:42:09.817261 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-13 18:42:13.867646 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-04-13 18:42:13.867773 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-13 18:42:13.867789 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-13 18:42:17.224129 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-04-13 18:42:17.224181 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-13 18:42:17.224192 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-13 18:42:19.817426 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-04-13 18:42:19.817483 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-13 18:42:19.817491 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-13 18:42:32.097775 | ubuntu-noble | fix end of files.........................................................Passed
2026-04-13 18:42:32.151235 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-04-13 18:42:32.411097 | ubuntu-noble | black....................................................................Passed
2026-04-13 18:42:32.602802 | ubuntu-noble | flake8...................................................................Passed
2026-04-13 18:42:32.738157 | ubuntu-noble | isort....................................................................Passed
2026-04-13 18:42:48.436966 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-04-13 18:42:48.801833 | ubuntu-noble | ok: Runtime: 0:00:48.336813
2026-04-13 18:42:48.804509 | 
2026-04-13 18:42:48.804558 | PLAY RECAP
2026-04-13 18:42:48.804603 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-13 18:42:48.804625 | 
2026-04-13 18:42:48.918713 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-13 18:42:48.921036 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 18:42:49.487985 | 
2026-04-13 18:42:49.488115 | PLAY [all]
2026-04-13 18:42:49.499665 | 
2026-04-13 18:42:49.499747 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-13 18:42:49.545013 | ubuntu-noble | skipping: Conditional result was False
2026-04-13 18:42:49.555090 | 
2026-04-13 18:42:49.555175 | TASK [fetch-output : Set log path for single node]
2026-04-13 18:42:49.600379 | ubuntu-noble | ok
2026-04-13 18:42:49.605346 | 
2026-04-13 18:42:49.605443 | LOOP [fetch-output : Ensure local output dirs]
2026-04-13 18:42:50.002393 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/d6a9dc4f4f4545458ef79d4dc099d4bc/work/logs"
2026-04-13 18:42:50.207204 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/d6a9dc4f4f4545458ef79d4dc099d4bc/work/artifacts"
2026-04-13 18:42:50.420662 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/d6a9dc4f4f4545458ef79d4dc099d4bc/work/docs"
2026-04-13 18:42:50.439032 | 
2026-04-13 18:42:50.439211 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-13 18:42:51.078475 | ubuntu-noble | changed: .d..t...... ./
2026-04-13 18:42:51.078703 | ubuntu-noble | changed: All items complete
2026-04-13 18:42:51.078730 | 
2026-04-13 18:42:51.581145 | ubuntu-noble | changed: .d..t...... ./
2026-04-13 18:42:52.054970 | ubuntu-noble | changed: .d..t...... ./
2026-04-13 18:42:52.074999 | 
2026-04-13 18:42:52.075173 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-13 18:42:52.490174 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.008664
2026-04-13 18:42:52.717024 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.008403
2026-04-13 18:42:52.737421 | 
2026-04-13 18:42:52.737999 | PLAY [all]
2026-04-13 18:42:52.744450 | 
2026-04-13 18:42:52.744518 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-13 18:42:53.234142 | ubuntu-noble | changed
2026-04-13 18:42:53.240947 | 
2026-04-13 18:42:53.241038 | PLAY RECAP
2026-04-13 18:42:53.241098 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-13 18:42:53.241122 | 
2026-04-13 18:42:53.382963 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-13 18:42:53.385092 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-13 18:42:53.944184 | 
2026-04-13 18:42:53.944332 | PLAY [localhost]
2026-04-13 18:42:53.954454 | 
2026-04-13 18:42:53.954535 | TASK [Generate Zuul manifest]
2026-04-13 18:42:53.976449 | localhost | ok
2026-04-13 18:42:53.992556 | 
2026-04-13 18:42:53.992644 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-13 18:42:54.307751 | localhost | changed
2026-04-13 18:42:54.321038 | 
2026-04-13 18:42:54.321122 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-13 18:42:54.353121 | localhost | ok
2026-04-13 18:42:54.360066 | 
2026-04-13 18:42:54.360154 | TASK [Upload logs]
2026-04-13 18:42:54.380449 | localhost | ok
2026-04-13 18:42:54.440742 | 
2026-04-13 18:42:54.440904 | TASK [Set zuul-log-path fact]
2026-04-13 18:42:54.464773 | localhost | ok
2026-04-13 18:42:54.478795 | 
2026-04-13 18:42:54.478876 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-13 18:42:54.509354 | localhost | ok
2026-04-13 18:42:54.518586 | 
2026-04-13 18:42:54.518659 | TASK [upload-logs : Create log directories]
2026-04-13 18:42:54.905003 | localhost | changed
2026-04-13 18:42:54.911196 | 
2026-04-13 18:42:54.911266 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-13 18:42:55.320945 | localhost -> localhost | ok: Runtime: 0:00:00.005114
2026-04-13 18:42:55.330775 | 
2026-04-13 18:42:55.331052 | TASK [upload-logs : Upload logs to log server]
2026-04-13 18:42:55.792816 | localhost | Output suppressed because no_log was given
2026-04-13 18:42:55.799045 | 
2026-04-13 18:42:55.799137 | LOOP [upload-logs : Compress console log and json output]
2026-04-13 18:42:55.848381 | localhost | skipping: Conditional result was False
2026-04-13 18:42:55.855471 | localhost | skipping: Conditional result was False
2026-04-13 18:42:55.865219 | 
2026-04-13 18:42:55.865411 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-13 18:42:55.910757 | localhost | skipping: Conditional result was False
2026-04-13 18:42:55.911404 | 
2026-04-13 18:42:55.914649 | localhost | skipping: Conditional result was False
2026-04-13 18:42:55.929400 | 
2026-04-13 18:42:55.929548 | LOOP [upload-logs : Upload console log and json output]