2026-04-14 02:50:17.500611 | Job console starting
2026-04-14 02:50:17.524651 | Updating git repos
2026-04-14 02:50:17.553559 | Cloning repos into workspace
2026-04-14 02:50:17.718971 | Restoring repo states
2026-04-14 02:50:17.726155 | Merging changes
2026-04-14 02:50:18.007030 | Checking out repos
2026-04-14 02:50:18.043231 | Preparing playbooks
2026-04-14 02:50:19.266610 | Running Ansible setup
2026-04-14 02:50:24.195250 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 02:50:24.842487 | 
2026-04-14 02:50:24.842657 | PLAY [localhost]
2026-04-14 02:50:24.851650 | 
2026-04-14 02:50:24.851731 | TASK [Gathering Facts]
2026-04-14 02:50:25.794299 | localhost | ok
2026-04-14 02:50:25.801106 | 
2026-04-14 02:50:25.801187 | TASK [Setup log path fact]
2026-04-14 02:50:25.820921 | localhost | ok
2026-04-14 02:50:25.832640 | 
2026-04-14 02:50:25.832767 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 02:50:25.887639 | localhost | ok
2026-04-14 02:50:25.894999 | 
2026-04-14 02:50:25.895097 | TASK [emit-job-header : Print job information]
2026-04-14 02:50:25.936126 | # Job Information
2026-04-14 02:50:26.079113 | Ansible Version: 2.16.16
2026-04-14 02:50:26.079346 | Job: pre-commit
2026-04-14 02:50:26.079395 | Pipeline: check
2026-04-14 02:50:26.079432 | Executor: 0a8996d2b663
2026-04-14 02:50:26.079467 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/112
2026-04-14 02:50:26.079507 | Event ID: 9246c490-37ac-11f1-8fb9-92e03caab9e4
2026-04-14 02:50:26.084837 | 
2026-04-14 02:50:26.085044 | LOOP [emit-job-header : Print node information]
2026-04-14 02:50:26.198910 | localhost | ok:
2026-04-14 02:50:26.199135 | localhost | # Node Information
2026-04-14 02:50:26.199178 | localhost | Inventory Hostname: ubuntu-noble
2026-04-14 02:50:26.199212 | localhost | Hostname: np0000166021
2026-04-14 02:50:26.199242 | localhost | Username: zuul
2026-04-14 02:50:26.199278 | localhost | Distro: Ubuntu 24.04
2026-04-14 02:50:26.199309 | localhost | Provider: yul1
2026-04-14 02:50:26.199339 | localhost | Region: ca-ymq-1
2026-04-14 02:50:26.199367 | localhost | Label: ubuntu-noble
2026-04-14 02:50:26.199394 | localhost | Product Name: OpenStack Nova
2026-04-14 02:50:26.199422 | localhost | Interface IP: 199.19.213.87
2026-04-14 02:50:26.218543 | 
2026-04-14 02:50:26.218805 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-14 02:50:26.833990 | localhost -> localhost | changed
2026-04-14 02:50:26.842879 | 
2026-04-14 02:50:26.843031 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-14 02:50:27.961177 | localhost -> localhost | changed
2026-04-14 02:50:27.966879 | 
2026-04-14 02:50:27.966933 | PLAY [all]
2026-04-14 02:50:27.976120 | 
2026-04-14 02:50:27.976192 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-14 02:50:28.445675 | ubuntu-noble -> localhost | ok
2026-04-14 02:50:28.454653 | 
2026-04-14 02:50:28.454771 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-14 02:50:28.489391 | ubuntu-noble | ok
2026-04-14 02:50:28.517174 | ubuntu-noble | included: /var/lib/zuul/builds/74c1526e2f194316980d8a3680e4ff29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-14 02:50:28.523543 | 
2026-04-14 02:50:28.523608 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-14 02:50:29.392752 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-04-14 02:50:29.392955 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/74c1526e2f194316980d8a3680e4ff29/work/74c1526e2f194316980d8a3680e4ff29_id_rsa
2026-04-14 02:50:29.393012 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/74c1526e2f194316980d8a3680e4ff29/work/74c1526e2f194316980d8a3680e4ff29_id_rsa.pub
2026-04-14 02:50:29.393036 | ubuntu-noble -> localhost | The key fingerprint is:
2026-04-14 02:50:29.393058 | ubuntu-noble -> localhost | SHA256:DKymUkf1QHrrLpywlTxsYV7u8CscGW1+PKgwzdgYTXo zuul-build-sshkey
2026-04-14 02:50:29.393097 | ubuntu-noble -> localhost | The key's randomart image is:
2026-04-14 02:50:29.393119 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-04-14 02:50:29.393147 | ubuntu-noble -> localhost | |     .+          |
2026-04-14 02:50:29.393169 | ubuntu-noble -> localhost | |    .+ o         |
2026-04-14 02:50:29.393189 | ubuntu-noble -> localhost | |   +o.+ .        |
2026-04-14 02:50:29.393210 | ubuntu-noble -> localhost | |  o.Eo++         |
2026-04-14 02:50:29.393230 | ubuntu-noble -> localhost | |  .&+X.oS        |
2026-04-14 02:50:29.393250 | ubuntu-noble -> localhost | | .*+/.+ +        |
2026-04-14 02:50:29.393270 | ubuntu-noble -> localhost | |. .X O.. .       |
2026-04-14 02:50:29.393289 | ubuntu-noble -> localhost | | .. B.o          |
2026-04-14 02:50:29.393311 | ubuntu-noble -> localhost | |     oo.         |
2026-04-14 02:50:29.393331 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-04-14 02:50:29.393380 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.412662
2026-04-14 02:50:29.398964 | 
2026-04-14 02:50:29.399036 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-14 02:50:29.440736 | ubuntu-noble | ok
2026-04-14 02:50:29.449838 | ubuntu-noble | included: /var/lib/zuul/builds/74c1526e2f194316980d8a3680e4ff29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-14 02:50:29.457991 | 
2026-04-14 02:50:29.458084 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-14 02:50:29.482904 | ubuntu-noble | skipping: Conditional result was False
2026-04-14 02:50:29.490961 | 
2026-04-14 02:50:29.491052 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-14 02:50:30.045587 | ubuntu-noble | changed
2026-04-14 02:50:30.095821 | 
2026-04-14 02:50:30.095945 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-14 02:50:30.311178 | ubuntu-noble | ok
2026-04-14 02:50:30.317052 | 
2026-04-14 02:50:30.317158 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-14 02:50:30.873474 | ubuntu-noble | changed
2026-04-14 02:50:31.074323 | 
2026-04-14 02:50:31.074449 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-14 02:50:31.613323 | ubuntu-noble | changed
2026-04-14 02:50:31.743617 | 
2026-04-14 02:50:31.743774 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-14 02:50:31.767949 | ubuntu-noble | skipping: Conditional result was False
2026-04-14 02:50:31.779634 | 
2026-04-14 02:50:31.779706 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-14 02:50:32.369609 | ubuntu-noble -> localhost | changed
2026-04-14 02:50:32.381023 | 
2026-04-14 02:50:32.381097 | TASK [add-build-sshkey : Add back temp key]
2026-04-14 02:50:33.343065 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/74c1526e2f194316980d8a3680e4ff29/work/74c1526e2f194316980d8a3680e4ff29_id_rsa (zuul-build-sshkey)
2026-04-14 02:50:33.343284 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013001
2026-04-14 02:50:33.349671 | 
2026-04-14 02:50:33.349734 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-14 02:50:33.736501 | ubuntu-noble | ok
2026-04-14 02:50:33.745880 | 
2026-04-14 02:50:33.745952 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-14 02:50:33.782201 | ubuntu-noble | skipping: Conditional result was False
2026-04-14 02:50:33.794393 | 
2026-04-14 02:50:33.794466 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-14 02:50:34.154246 | ubuntu-noble | ok
2026-04-14 02:50:34.287543 | 
2026-04-14 02:50:34.287669 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-14 02:50:35.175815 | ubuntu-noble | Output suppressed because no_log was given
2026-04-14 02:50:35.198915 | 
2026-04-14 02:50:35.199089 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-14 02:50:35.490709 | ubuntu-noble | ok: "logs"
2026-04-14 02:50:35.490883 | ubuntu-noble | ok: All items complete
2026-04-14 02:50:35.490919 | 
2026-04-14 02:50:35.633065 | ubuntu-noble | ok: "artifacts"
2026-04-14 02:50:35.883372 | ubuntu-noble | ok: "docs"
2026-04-14 02:50:35.892664 | 
2026-04-14 02:50:35.892838 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-14 02:50:36.120022 | ubuntu-noble | changed: "logs"
2026-04-14 02:50:36.301005 | ubuntu-noble | changed: "artifacts"
2026-04-14 02:50:36.494782 | ubuntu-noble | changed: "docs"
2026-04-14 02:50:36.561598 | 
2026-04-14 02:50:36.561714 | PLAY RECAP
2026-04-14 02:50:36.561792 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 02:50:36.561823 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-14 02:50:36.561845 | 
2026-04-14 02:50:36.761021 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 02:50:36.762851 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-14 02:50:37.417768 | 
2026-04-14 02:50:37.648916 | PLAY [all]
2026-04-14 02:50:37.667473 | 
2026-04-14 02:50:37.667626 | TASK [setup-uv : Extract archive]
2026-04-14 02:50:40.101029 | ubuntu-noble | changed
2026-04-14 02:50:40.108123 | 
2026-04-14 02:50:40.108260 | TASK [setup-uv : Print version]
2026-04-14 02:50:39.747684 | ubuntu-noble | uv 0.8.13
2026-04-14 02:50:40.651305 | ubuntu-noble | ok: Runtime: 0:00:00.015900
2026-04-14 02:50:40.661856 | 
2026-04-14 02:50:40.662033 | PLAY RECAP
2026-04-14 02:50:40.662136 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 02:50:40.662219 | 
2026-04-14 02:50:40.838593 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-14 02:50:40.841560 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-14 02:50:41.446973 | 
2026-04-14 02:50:41.447154 | PLAY [all]
2026-04-14 02:50:41.461435 | 
2026-04-14 02:50:41.461552 | TASK [Run "pre-commit"]
2026-04-14 02:50:41.481863 | ubuntu-noble | Downloading virtualenv (5.6MiB)
2026-04-14 02:50:41.660758 | ubuntu-noble |  Downloading virtualenv
2026-04-14 02:50:41.673422 | ubuntu-noble | Installed 10 packages in 12ms
2026-04-14 02:50:42.038557 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-14 02:50:42.575420 | 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-14 02:50:42.582287 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-04-14 02:50:43.884696 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-04-14 02:50:44.264244 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-04-14 02:50:44.688061 | 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-14 02:50:44.689789 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-04-14 02:50:45.238112 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-04-14 02:50:45.767126 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-14 02:50:45.767198 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-14 02:50:45.767209 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-14 02:50:49.761916 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-04-14 02:50:49.761992 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-14 02:50:49.762003 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-14 02:50:54.856626 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-04-14 02:50:54.856693 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-14 02:50:54.856702 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-14 02:50:58.093793 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-04-14 02:50:58.093846 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-14 02:50:58.093862 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-14 02:51:00.724839 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-04-14 02:51:00.724901 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-14 02:51:00.724912 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-14 02:51:11.634307 | ubuntu-noble | fix end of files.........................................................Passed
2026-04-14 02:51:11.689747 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-04-14 02:51:11.958885 | ubuntu-noble | black....................................................................Passed
2026-04-14 02:51:12.139312 | ubuntu-noble | flake8...................................................................Passed
2026-04-14 02:51:12.256842 | ubuntu-noble | isort....................................................................Passed
2026-04-14 02:51:28.638937 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-04-14 02:51:29.033560 | ubuntu-noble | ok: Runtime: 0:00:47.611257
2026-04-14 02:51:29.036146 | 
2026-04-14 02:51:29.036225 | PLAY RECAP
2026-04-14 02:51:29.036302 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 02:51:29.036341 | 
2026-04-14 02:51:29.218544 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-14 02:51:29.220808 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 02:51:29.822176 | 
2026-04-14 02:51:29.822981 | PLAY [all]
2026-04-14 02:51:29.835880 | 
2026-04-14 02:51:29.836053 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-14 02:51:29.882237 | ubuntu-noble | skipping: Conditional result was False
2026-04-14 02:51:29.892871 | 
2026-04-14 02:51:29.893135 | TASK [fetch-output : Set log path for single node]
2026-04-14 02:51:29.943557 | ubuntu-noble | ok
2026-04-14 02:51:29.949130 | 
2026-04-14 02:51:29.949207 | LOOP [fetch-output : Ensure local output dirs]
2026-04-14 02:51:30.360255 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/74c1526e2f194316980d8a3680e4ff29/work/logs"
2026-04-14 02:51:30.572194 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/74c1526e2f194316980d8a3680e4ff29/work/artifacts"
2026-04-14 02:51:30.811295 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/74c1526e2f194316980d8a3680e4ff29/work/docs"
2026-04-14 02:51:30.828531 | 
2026-04-14 02:51:30.828735 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-14 02:51:31.523214 | ubuntu-noble | changed: .d..t...... ./
2026-04-14 02:51:31.532415 | ubuntu-noble | changed: All items complete
2026-04-14 02:51:31.532549 | 
2026-04-14 02:51:32.019494 | ubuntu-noble | changed: .d..t...... ./
2026-04-14 02:51:32.509119 | ubuntu-noble | changed: .d..t...... ./
2026-04-14 02:51:32.530478 | 
2026-04-14 02:51:32.530573 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-14 02:51:33.007807 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.008192
2026-04-14 02:51:33.237938 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.007224
2026-04-14 02:51:33.255406 | 
2026-04-14 02:51:33.255549 | PLAY [all]
2026-04-14 02:51:33.264238 | 
2026-04-14 02:51:33.264349 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-14 02:51:33.750101 | ubuntu-noble | changed
2026-04-14 02:51:33.758446 | 
2026-04-14 02:51:33.758516 | PLAY RECAP
2026-04-14 02:51:33.758577 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-14 02:51:33.758609 | 
2026-04-14 02:51:33.890704 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 02:51:33.894314 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-14 02:51:34.505918 | 
2026-04-14 02:51:34.506048 | PLAY [localhost]
2026-04-14 02:51:34.517393 | 
2026-04-14 02:51:34.517495 | TASK [Generate Zuul manifest]
2026-04-14 02:51:34.536075 | localhost | ok
2026-04-14 02:51:34.553663 | 
2026-04-14 02:51:34.553775 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-14 02:51:34.876712 | localhost | changed
2026-04-14 02:51:34.893204 | 
2026-04-14 02:51:34.893339 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-14 02:51:34.925731 | localhost | ok
2026-04-14 02:51:34.934690 | 
2026-04-14 02:51:34.934772 | TASK [Upload logs]
2026-04-14 02:51:34.959161 | localhost | ok
2026-04-14 02:51:35.021434 | 
2026-04-14 02:51:35.021576 | TASK [Set zuul-log-path fact]
2026-04-14 02:51:35.053268 | localhost | ok
2026-04-14 02:51:35.066723 | 
2026-04-14 02:51:35.066863 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 02:51:35.099223 | localhost | ok
2026-04-14 02:51:35.108230 | 
2026-04-14 02:51:35.108307 | TASK [upload-logs : Create log directories]
2026-04-14 02:51:35.514769 | localhost | changed
2026-04-14 02:51:35.521017 | 
2026-04-14 02:51:35.521112 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-14 02:51:35.969448 | localhost -> localhost | ok: Runtime: 0:00:00.007474
2026-04-14 02:51:35.976336 | 
2026-04-14 02:51:35.976428 | TASK [upload-logs : Upload logs to log server]
2026-04-14 02:51:36.452356 | localhost | Output suppressed because no_log was given
2026-04-14 02:51:36.457419 | 
2026-04-14 02:51:36.457500 | LOOP [upload-logs : Compress console log and json output]
2026-04-14 02:51:36.510038 | localhost | skipping: Conditional result was False
2026-04-14 02:51:36.520278 | localhost | skipping: Conditional result was False
2026-04-14 02:51:36.530542 | 
2026-04-14 02:51:36.530632 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-14 02:51:36.578108 | localhost | skipping: Conditional result was False
2026-04-14 02:51:36.578519 | 
2026-04-14 02:51:36.583335 | localhost | skipping: Conditional result was False
2026-04-14 02:51:36.593890 | 
2026-04-14 02:51:36.594066 | LOOP [upload-logs : Upload console log and json output]