2026-05-21 02:32:04.660085 | Job console starting
2026-05-21 02:32:04.675144 | Updating git repos
2026-05-21 02:32:04.717604 | Cloning repos into workspace
2026-05-21 02:32:05.413081 | Restoring repo states
2026-05-21 02:32:05.430570 | Merging changes
2026-05-21 02:32:06.024591 | Checking out repos
2026-05-21 02:32:06.186500 | Preparing playbooks
2026-05-21 02:32:10.277424 | Running Ansible setup
2026-05-21 02:32:14.453230 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 02:32:15.041936 | 
2026-05-21 02:32:15.042051 | PLAY [localhost]
2026-05-21 02:32:15.049117 | 
2026-05-21 02:32:15.049185 | TASK [Gathering Facts]
2026-05-21 02:32:15.915655 | localhost | ok
2026-05-21 02:32:15.924003 | 
2026-05-21 02:32:15.924087 | TASK [Setup log path fact]
2026-05-21 02:32:15.943813 | localhost | ok
2026-05-21 02:32:15.957423 | 
2026-05-21 02:32:15.957490 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 02:32:15.992406 | localhost | ok
2026-05-21 02:32:15.999506 | 
2026-05-21 02:32:15.999573 | TASK [emit-job-header : Print job information]
2026-05-21 02:32:16.041303 | # Job Information
2026-05-21 02:32:16.041449 | Ansible Version: 2.16.18
2026-05-21 02:32:16.041491 | Job: pre-commit
2026-05-21 02:32:16.041521 | Pipeline: check
2026-05-21 02:32:16.041548 | Executor: 2d72f0692154
2026-05-21 02:32:16.041574 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/126
2026-05-21 02:32:16.041605 | Event ID: fae3e460-54bc-11f1-8133-32d0a9e16fea
2026-05-21 02:32:16.045627 | 
2026-05-21 02:32:16.045723 | LOOP [emit-job-header : Print node information]
2026-05-21 02:32:16.162279 | localhost | ok:
2026-05-21 02:32:16.162742 | localhost | # Node Information
2026-05-21 02:32:16.162785 | localhost | Inventory Hostname: ubuntu-noble
2026-05-21 02:32:16.162808 | localhost | Hostname: np0000175708
2026-05-21 02:32:16.162828 | localhost | Username: zuul
2026-05-21 02:32:16.162850 | localhost | Distro: Ubuntu 24.04
2026-05-21 02:32:16.162870 | localhost | Provider: yul1
2026-05-21 02:32:16.162889 | localhost | Region: ca-ymq-1
2026-05-21 02:32:16.162906 | localhost | Label: ubuntu-noble
2026-05-21 02:32:16.162924 | localhost | Product Name: OpenStack Nova
2026-05-21 02:32:16.162942 | localhost | Interface IP: 199.19.213.96
2026-05-21 02:32:16.181314 | 
2026-05-21 02:32:16.181431 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-21 02:32:16.636265 | localhost -> localhost | changed
2026-05-21 02:32:16.644545 | 
2026-05-21 02:32:16.644677 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-21 02:32:17.522028 | localhost -> localhost | changed
2026-05-21 02:32:17.528713 | 
2026-05-21 02:32:17.528774 | PLAY [all]
2026-05-21 02:32:17.538827 | 
2026-05-21 02:32:17.538910 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-21 02:32:17.818106 | ubuntu-noble -> localhost | ok
2026-05-21 02:32:17.827575 | 
2026-05-21 02:32:17.827653 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-21 02:32:17.861195 | ubuntu-noble | ok
2026-05-21 02:32:17.882083 | ubuntu-noble | included: /var/lib/zuul/builds/48d9036284a34865b1b754e65201ef02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-21 02:32:18.279966 | 
2026-05-21 02:32:18.280786 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-21 02:32:19.348924 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-05-21 02:32:19.847384 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/48d9036284a34865b1b754e65201ef02/work/48d9036284a34865b1b754e65201ef02_id_rsa
2026-05-21 02:32:19.847525 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/48d9036284a34865b1b754e65201ef02/work/48d9036284a34865b1b754e65201ef02_id_rsa.pub
2026-05-21 02:32:19.847590 | ubuntu-noble -> localhost | The key fingerprint is:
2026-05-21 02:32:19.847637 | ubuntu-noble -> localhost | SHA256:bbMzI/CDj5NlaeouWeelRgFU85n1empMXQlCRNvUrjw zuul-build-sshkey
2026-05-21 02:32:19.847705 | ubuntu-noble -> localhost | The key's randomart image is:
2026-05-21 02:32:19.847754 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-05-21 02:32:19.847803 | ubuntu-noble -> localhost | |     ...o +=.o.  |
2026-05-21 02:32:19.847850 | ubuntu-noble -> localhost | |      .  o +=....|
2026-05-21 02:32:19.847895 | ubuntu-noble -> localhost | |       .  +. .o..|
2026-05-21 02:32:19.847939 | ubuntu-noble -> localhost | |        ..   o o |
2026-05-21 02:32:19.847985 | ubuntu-noble -> localhost | |      . So+ + +  |
2026-05-21 02:32:19.848034 | ubuntu-noble -> localhost | |      .+B..= E   |
2026-05-21 02:32:19.848083 | ubuntu-noble -> localhost | |     o.X+o= + .  |
2026-05-21 02:32:19.848130 | ubuntu-noble -> localhost | |    o +o+o =     |
2026-05-21 02:32:19.848183 | ubuntu-noble -> localhost | |     ++o.        |
2026-05-21 02:32:19.848225 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-05-21 02:32:19.848367 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.628570
2026-05-21 02:32:19.863521 | 
2026-05-21 02:32:19.863609 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-21 02:32:19.892691 | ubuntu-noble | ok
2026-05-21 02:32:19.904243 | ubuntu-noble | included: /var/lib/zuul/builds/48d9036284a34865b1b754e65201ef02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-21 02:32:19.913655 | 
2026-05-21 02:32:19.913715 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-21 02:32:19.949051 | ubuntu-noble | skipping: Conditional result was False
2026-05-21 02:32:19.960040 | 
2026-05-21 02:32:19.960136 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-21 02:32:20.518581 | ubuntu-noble | changed
2026-05-21 02:32:20.524495 | 
2026-05-21 02:32:20.524566 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-21 02:32:20.756359 | ubuntu-noble | ok
2026-05-21 02:32:20.764495 | 
2026-05-21 02:32:20.764746 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-21 02:32:21.467115 | ubuntu-noble | changed
2026-05-21 02:32:21.508188 | 
2026-05-21 02:32:21.508303 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-21 02:32:22.098917 | ubuntu-noble | changed
2026-05-21 02:32:22.105913 | 
2026-05-21 02:32:22.105977 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-21 02:32:22.131844 | ubuntu-noble | skipping: Conditional result was False
2026-05-21 02:32:22.143817 | 
2026-05-21 02:32:22.143908 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-21 02:32:22.555120 | ubuntu-noble -> localhost | changed
2026-05-21 02:32:22.569729 | 
2026-05-21 02:32:22.569812 | TASK [add-build-sshkey : Add back temp key]
2026-05-21 02:32:22.976574 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/48d9036284a34865b1b754e65201ef02/work/48d9036284a34865b1b754e65201ef02_id_rsa (zuul-build-sshkey)
2026-05-21 02:32:22.976822 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013798
2026-05-21 02:32:22.993173 | 
2026-05-21 02:32:22.993279 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-21 02:32:23.370804 | ubuntu-noble | ok
2026-05-21 02:32:23.375893 | 
2026-05-21 02:32:23.375968 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-21 02:32:23.406796 | ubuntu-noble | skipping: Conditional result was False
2026-05-21 02:32:23.429408 | 
2026-05-21 02:32:23.429582 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-21 02:32:23.824185 | ubuntu-noble | ok
2026-05-21 02:32:23.914900 | 
2026-05-21 02:32:23.915070 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-21 02:32:24.812364 | ubuntu-noble | Output suppressed because no_log was given
2026-05-21 02:32:24.822520 | 
2026-05-21 02:32:24.822672 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-21 02:32:25.067159 | ubuntu-noble | ok: "logs"
2026-05-21 02:32:25.067389 | ubuntu-noble | ok: All items complete
2026-05-21 02:32:25.067432 | 
2026-05-21 02:32:25.275138 | ubuntu-noble | ok: "artifacts"
2026-05-21 02:32:25.478113 | ubuntu-noble | ok: "docs"
2026-05-21 02:32:25.497748 | 
2026-05-21 02:32:25.497878 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-21 02:32:25.720713 | ubuntu-noble | changed: "logs"
2026-05-21 02:32:25.919202 | ubuntu-noble | changed: "artifacts"
2026-05-21 02:32:26.116156 | ubuntu-noble | changed: "docs"
2026-05-21 02:32:26.131384 | 
2026-05-21 02:32:26.131486 | PLAY RECAP
2026-05-21 02:32:26.131531 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:32:26.131561 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-21 02:32:26.131585 | 
2026-05-21 02:32:26.310211 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 02:32:26.313926 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-05-21 02:32:26.923972 | 
2026-05-21 02:32:26.924103 | PLAY [all]
2026-05-21 02:32:26.936881 | 
2026-05-21 02:32:26.936996 | TASK [setup-uv : Extract archive]
2026-05-21 02:32:29.403787 | ubuntu-noble | changed
2026-05-21 02:32:29.415037 | 
2026-05-21 02:32:29.415140 | TASK [setup-uv : Print version]
2026-05-21 02:32:29.880559 | ubuntu-noble | uv 0.8.13
2026-05-21 02:32:29.979275 | ubuntu-noble | ok: Runtime: 0:00:00.014022
2026-05-21 02:32:29.990632 | 
2026-05-21 02:32:29.990892 | PLAY RECAP
2026-05-21 02:32:29.990940 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:32:29.990966 | 
2026-05-21 02:32:30.110037 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-05-21 02:32:30.111162 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-05-21 02:32:30.691216 | 
2026-05-21 02:32:30.691344 | PLAY [all]
2026-05-21 02:32:30.703057 | 
2026-05-21 02:32:30.703142 | TASK [Run "pre-commit"]
2026-05-21 02:32:31.350504 | ubuntu-noble | Downloading virtualenv (7.2MiB)
2026-05-21 02:32:31.471945 | ubuntu-noble |  Downloading virtualenv
2026-05-21 02:32:31.484326 | ubuntu-noble | Installed 10 packages in 10ms
2026-05-21 02:32:31.901876 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-05-21 02:32:32.412559 | 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-21 02:32:32.418461 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-05-21 02:32:32.946322 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-05-21 02:32:33.327736 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-05-21 02:32:33.776723 | 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-21 02:32:33.777879 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-05-21 02:32:34.302484 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-05-21 02:32:34.826721 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-05-21 02:32:34.826824 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-21 02:32:34.826835 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-21 02:32:38.753181 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-05-21 02:32:38.753313 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-21 02:32:38.753327 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-21 02:32:42.830202 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-05-21 02:32:42.830365 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-21 02:32:42.830379 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-21 02:32:46.023925 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-05-21 02:32:46.024047 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-21 02:32:46.024059 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-21 02:32:48.714907 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-05-21 02:32:48.715010 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-21 02:32:48.715021 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-21 02:32:59.595907 | ubuntu-noble | fix end of files.........................................................Passed
2026-05-21 02:32:59.654209 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-05-21 02:32:59.924876 | ubuntu-noble | black....................................................................Passed
2026-05-21 02:33:00.125990 | ubuntu-noble | flake8...................................................................Passed
2026-05-21 02:33:00.255316 | ubuntu-noble | isort....................................................................Passed
2026-05-21 02:33:18.499192 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-05-21 02:33:19.938528 | ubuntu-noble | ok: Runtime: 0:00:47.389266
2026-05-21 02:33:19.942405 | 
2026-05-21 02:33:19.942510 | PLAY RECAP
2026-05-21 02:33:19.942572 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:33:19.942605 | 
2026-05-21 02:33:20.074325 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-05-21 02:33:20.075512 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 02:33:20.808594 | 
2026-05-21 02:33:20.809076 | PLAY [all]
2026-05-21 02:33:20.826625 | 
2026-05-21 02:33:20.826797 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-21 02:33:20.872347 | ubuntu-noble | skipping: Conditional result was False
2026-05-21 02:33:20.879775 | 
2026-05-21 02:33:20.879904 | TASK [fetch-output : Set log path for single node]
2026-05-21 02:33:20.921462 | ubuntu-noble | ok
2026-05-21 02:33:20.928285 | 
2026-05-21 02:33:20.928354 | LOOP [fetch-output : Ensure local output dirs]
2026-05-21 02:33:21.364120 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/48d9036284a34865b1b754e65201ef02/work/logs"
2026-05-21 02:33:21.641727 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/48d9036284a34865b1b754e65201ef02/work/artifacts"
2026-05-21 02:33:22.033221 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/48d9036284a34865b1b754e65201ef02/work/docs"
2026-05-21 02:33:22.054919 | 
2026-05-21 02:33:22.055105 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-21 02:33:22.747711 | ubuntu-noble | changed: .d..t...... ./
2026-05-21 02:33:22.747924 | ubuntu-noble | changed: All items complete
2026-05-21 02:33:22.747952 | 
2026-05-21 02:33:23.519631 | ubuntu-noble | changed: .d..t...... ./
2026-05-21 02:33:24.015061 | ubuntu-noble | changed: .d..t...... ./
2026-05-21 02:33:24.029762 | 
2026-05-21 02:33:24.029882 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-21 02:33:24.581597 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.005581
2026-05-21 02:33:24.943545 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.082030
2026-05-21 02:33:24.960501 | 
2026-05-21 02:33:24.960660 | PLAY [all]
2026-05-21 02:33:24.974151 | 
2026-05-21 02:33:24.974297 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-21 02:33:25.519204 | ubuntu-noble | changed
2026-05-21 02:33:25.800879 | 
2026-05-21 02:33:25.801015 | PLAY RECAP
2026-05-21 02:33:25.801070 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-21 02:33:25.801098 | 
2026-05-21 02:33:25.944758 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 02:33:25.946190 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-21 02:33:26.678092 | 
2026-05-21 02:33:26.678848 | PLAY [localhost]
2026-05-21 02:33:26.690898 | 
2026-05-21 02:33:26.690985 | TASK [Generate Zuul manifest]
2026-05-21 02:33:26.722011 | localhost | ok
2026-05-21 02:33:26.749113 | 
2026-05-21 02:33:26.749277 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-21 02:33:27.253181 | localhost | changed
2026-05-21 02:33:27.265633 | 
2026-05-21 02:33:27.265740 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-21 02:33:27.295079 | localhost | ok
2026-05-21 02:33:27.304699 | 
2026-05-21 02:33:27.304768 | TASK [Upload logs]
2026-05-21 02:33:27.322398 | localhost | ok
2026-05-21 02:33:27.384177 | 
2026-05-21 02:33:27.384358 | TASK [Set zuul-log-path fact]
2026-05-21 02:33:27.403965 | localhost | ok
2026-05-21 02:33:27.417365 | 
2026-05-21 02:33:27.417498 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 02:33:27.449712 | localhost | ok
2026-05-21 02:33:27.456663 | 
2026-05-21 02:33:27.456744 | TASK [upload-logs : Create log directories]
2026-05-21 02:33:27.869075 | localhost | changed
2026-05-21 02:33:27.873690 | 
2026-05-21 02:33:27.873756 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-21 02:33:28.333793 | localhost -> localhost | ok: Runtime: 0:00:00.005518
2026-05-21 02:33:28.338729 | 
2026-05-21 02:33:28.338812 | TASK [upload-logs : Upload logs to log server]
2026-05-21 02:33:28.833104 | localhost | Output suppressed because no_log was given
2026-05-21 02:33:28.836682 | 
2026-05-21 02:33:28.836751 | LOOP [upload-logs : Compress console log and json output]
2026-05-21 02:33:28.879671 | localhost | skipping: Conditional result was False
2026-05-21 02:33:28.887776 | localhost | skipping: Conditional result was False
2026-05-21 02:33:28.896588 | 
2026-05-21 02:33:28.896702 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-21 02:33:28.965358 | localhost | skipping: Conditional result was False
2026-05-21 02:33:28.965776 | 
2026-05-21 02:33:28.978073 | localhost | skipping: Conditional result was False
2026-05-21 02:33:28.987760 | 
2026-05-21 02:33:28.987903 | LOOP [upload-logs : Upload console log and json output]