2026-04-16 01:00:31.387079 | Job console starting
2026-04-16 01:00:31.411464 | Updating git repos
2026-04-16 01:00:31.494789 | Cloning repos into workspace
2026-04-16 01:00:31.751735 | Restoring repo states
2026-04-16 01:00:31.758006 | Merging changes
2026-04-16 01:00:32.697382 | Checking out repos
2026-04-16 01:00:33.147215 | Preparing playbooks
2026-04-16 01:00:36.737696 | Running Ansible setup
2026-04-16 01:00:40.407767 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-16 01:00:41.027942 | 
2026-04-16 01:00:41.028103 | PLAY [localhost]
2026-04-16 01:00:41.037020 | 
2026-04-16 01:00:41.037166 | TASK [Gathering Facts]
2026-04-16 01:00:41.978692 | localhost | ok
2026-04-16 01:00:41.986274 | 
2026-04-16 01:00:41.986356 | TASK [Setup log path fact]
2026-04-16 01:00:42.013950 | localhost | ok
2026-04-16 01:00:42.034891 | 
2026-04-16 01:00:42.035059 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-16 01:00:42.063873 | localhost | ok
2026-04-16 01:00:42.073504 | 
2026-04-16 01:00:42.073580 | TASK [emit-job-header : Print job information]
2026-04-16 01:00:42.112435 | # Job Information
2026-04-16 01:00:42.112644 | Ansible Version: 2.16.16
2026-04-16 01:00:42.112690 | Job: pre-commit
2026-04-16 01:00:42.112723 | Pipeline: check
2026-04-16 01:00:42.112753 | Executor: 0a8996d2b663
2026-04-16 01:00:42.112783 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109
2026-04-16 01:00:42.112816 | Event ID: 8a718e60-392f-11f1-8507-97aae718a6d9
2026-04-16 01:00:42.116907 | 
2026-04-16 01:00:42.117098 | LOOP [emit-job-header : Print node information]
2026-04-16 01:00:42.235579 | localhost | ok:
2026-04-16 01:00:42.235746 | localhost | # Node Information
2026-04-16 01:00:42.235775 | localhost | Inventory Hostname: ubuntu-noble
2026-04-16 01:00:42.235798 | localhost | Hostname: np0000166540
2026-04-16 01:00:42.235819 | localhost | Username: zuul
2026-04-16 01:00:42.235843 | localhost | Distro: Ubuntu 24.04
2026-04-16 01:00:42.235863 | localhost | Provider: yul1
2026-04-16 01:00:42.235883 | localhost | Region: ca-ymq-1
2026-04-16 01:00:42.235902 | localhost | Label: ubuntu-noble
2026-04-16 01:00:42.235920 | localhost | Product Name: OpenStack Nova
2026-04-16 01:00:42.235939 | localhost | Interface IP: 199.204.45.246
2026-04-16 01:00:42.251264 | 
2026-04-16 01:00:42.251397 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-16 01:00:42.773794 | localhost -> localhost | changed
2026-04-16 01:00:42.778732 | 
2026-04-16 01:00:42.778800 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-16 01:00:43.704517 | localhost -> localhost | changed
2026-04-16 01:00:43.710393 | 
2026-04-16 01:00:43.710445 | PLAY [all]
2026-04-16 01:00:43.718684 | 
2026-04-16 01:00:43.718747 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-16 01:00:44.038235 | ubuntu-noble -> localhost | ok
2026-04-16 01:00:44.045791 | 
2026-04-16 01:00:44.045856 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-16 01:00:44.080068 | ubuntu-noble | ok
2026-04-16 01:00:44.109294 | ubuntu-noble | included: /var/lib/zuul/builds/91a7168d93ff43a9a561456eecacefb6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-16 01:00:44.122925 | 
2026-04-16 01:00:44.123055 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-16 01:00:45.327147 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-04-16 01:00:45.327376 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/91a7168d93ff43a9a561456eecacefb6/work/91a7168d93ff43a9a561456eecacefb6_id_rsa
2026-04-16 01:00:45.327419 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/91a7168d93ff43a9a561456eecacefb6/work/91a7168d93ff43a9a561456eecacefb6_id_rsa.pub
2026-04-16 01:00:45.327453 | ubuntu-noble -> localhost | The key fingerprint is:
2026-04-16 01:00:45.327486 | ubuntu-noble -> localhost | SHA256:BYbYs31VdTK3QKrSVGU9D/wDhRAh3f1pxY3WpQW8Dpw zuul-build-sshkey
2026-04-16 01:00:45.327536 | ubuntu-noble -> localhost | The key's randomart image is:
2026-04-16 01:00:45.327567 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-04-16 01:00:45.327607 | ubuntu-noble -> localhost | |     o .o..=BOXBO|
2026-04-16 01:00:45.327638 | ubuntu-noble -> localhost | |    . +. .o.++BXB|
2026-04-16 01:00:45.327667 | ubuntu-noble -> localhost | |       + ..+ oo=*|
2026-04-16 01:00:45.327696 | ubuntu-noble -> localhost | |      . +.o E .++|
2026-04-16 01:00:45.327725 | ubuntu-noble -> localhost | |       .S+   o. .|
2026-04-16 01:00:45.327753 | ubuntu-noble -> localhost | |        .     .  |
2026-04-16 01:00:45.327782 | ubuntu-noble -> localhost | |                 |
2026-04-16 01:00:45.328002 | ubuntu-noble -> localhost | |                 |
2026-04-16 01:00:45.328101 | ubuntu-noble -> localhost | |                 |
2026-04-16 01:00:45.328135 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-04-16 01:00:45.328252 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.638646
2026-04-16 01:00:45.335957 | 
2026-04-16 01:00:45.336037 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-16 01:00:45.371418 | ubuntu-noble | ok
2026-04-16 01:00:45.382599 | ubuntu-noble | included: /var/lib/zuul/builds/91a7168d93ff43a9a561456eecacefb6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-16 01:00:45.390751 | 
2026-04-16 01:00:45.390815 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-16 01:00:45.415912 | ubuntu-noble | skipping: Conditional result was False
2026-04-16 01:00:45.422491 | 
2026-04-16 01:00:45.422564 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-16 01:00:45.999838 | ubuntu-noble | changed
2026-04-16 01:00:46.006507 | 
2026-04-16 01:00:46.006576 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-16 01:00:46.218634 | ubuntu-noble | ok
2026-04-16 01:00:46.226474 | 
2026-04-16 01:00:46.226560 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-16 01:00:46.775135 | ubuntu-noble | changed
2026-04-16 01:00:46.783222 | 
2026-04-16 01:00:46.783295 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-16 01:00:47.337134 | ubuntu-noble | changed
2026-04-16 01:00:47.342649 | 
2026-04-16 01:00:47.342722 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-16 01:00:47.371647 | ubuntu-noble | skipping: Conditional result was False
2026-04-16 01:00:47.382781 | 
2026-04-16 01:00:47.382978 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-16 01:00:47.768227 | ubuntu-noble -> localhost | changed
2026-04-16 01:00:47.784985 | 
2026-04-16 01:00:47.785087 | TASK [add-build-sshkey : Add back temp key]
2026-04-16 01:00:48.052252 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/91a7168d93ff43a9a561456eecacefb6/work/91a7168d93ff43a9a561456eecacefb6_id_rsa (zuul-build-sshkey)
2026-04-16 01:00:48.052894 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013883
2026-04-16 01:00:48.058575 | 
2026-04-16 01:00:48.058652 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-16 01:00:48.412825 | ubuntu-noble | ok
2026-04-16 01:00:48.420531 | 
2026-04-16 01:00:48.420601 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-16 01:00:48.446397 | ubuntu-noble | skipping: Conditional result was False
2026-04-16 01:00:48.462316 | 
2026-04-16 01:00:48.462407 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-16 01:00:48.873391 | ubuntu-noble | ok
2026-04-16 01:00:48.882927 | 
2026-04-16 01:00:48.883067 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-16 01:00:50.836830 | ubuntu-noble | Output suppressed because no_log was given
2026-04-16 01:00:50.847694 | 
2026-04-16 01:00:50.847760 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-16 01:00:51.142384 | ubuntu-noble | ok: "logs"
2026-04-16 01:00:51.142598 | ubuntu-noble | ok: All items complete
2026-04-16 01:00:51.143079 | 
2026-04-16 01:00:51.277434 | ubuntu-noble | ok: "artifacts"
2026-04-16 01:00:51.518492 | ubuntu-noble | ok: "docs"
2026-04-16 01:00:51.539723 | 
2026-04-16 01:00:51.539920 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-16 01:00:51.767985 | ubuntu-noble | changed: "logs"
2026-04-16 01:00:51.964214 | ubuntu-noble | changed: "artifacts"
2026-04-16 01:00:52.158311 | ubuntu-noble | changed: "docs"
2026-04-16 01:00:52.176773 | 
2026-04-16 01:00:52.176912 | PLAY RECAP
2026-04-16 01:00:52.176978 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-16 01:00:52.177015 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-16 01:00:52.177039 | 
2026-04-16 01:00:52.321624 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-16 01:00:52.324096 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-16 01:00:52.963720 | 
2026-04-16 01:00:52.963878 | PLAY [all]
2026-04-16 01:00:52.977387 | 
2026-04-16 01:00:52.977482 | TASK [setup-uv : Extract archive]
2026-04-16 01:00:55.572201 | ubuntu-noble | changed
2026-04-16 01:00:55.578412 | 
2026-04-16 01:00:55.578488 | TASK [setup-uv : Print version]
2026-04-16 01:00:55.474066 | ubuntu-noble | uv 0.8.13
2026-04-16 01:00:56.121072 | ubuntu-noble | ok: Runtime: 0:00:00.014661
2026-04-16 01:00:56.139667 | 
2026-04-16 01:00:56.139758 | PLAY RECAP
2026-04-16 01:00:56.139808 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-16 01:00:56.139832 | 
2026-04-16 01:00:56.266777 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-16 01:00:56.268035 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-16 01:00:56.846140 | 
2026-04-16 01:00:56.846740 | PLAY [all]
2026-04-16 01:00:56.858614 | 
2026-04-16 01:00:56.858685 | TASK [Run "pre-commit"]
2026-04-16 01:00:57.487111 | ubuntu-noble | Downloading virtualenv (5.6MiB)
2026-04-16 01:00:57.631169 | ubuntu-noble |  Downloading virtualenv
2026-04-16 01:00:57.644828 | ubuntu-noble | Installed 10 packages in 12ms
2026-04-16 01:00:58.012546 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-16 01:00:58.536870 | 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-16 01:00:58.541960 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-04-16 01:00:59.054473 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-04-16 01:00:59.456659 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-04-16 01:00:59.883059 | 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-16 01:00:59.885234 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-04-16 01:01:00.456383 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-04-16 01:01:00.996646 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-16 01:01:00.996744 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-16 01:01:00.996755 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-16 01:01:05.065595 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-04-16 01:01:05.065682 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-16 01:01:05.065692 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-16 01:01:09.493666 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-04-16 01:01:09.493792 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-16 01:01:09.493807 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-16 01:01:12.816548 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-04-16 01:01:12.816645 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-16 01:01:12.816656 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-16 01:01:15.400569 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-04-16 01:01:15.400651 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-16 01:01:15.400662 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-16 01:01:26.269232 | ubuntu-noble | fix end of files.........................................................Passed
2026-04-16 01:01:26.326684 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-04-16 01:01:26.603432 | ubuntu-noble | black....................................................................Passed
2026-04-16 01:01:26.835487 | ubuntu-noble | flake8...................................................................Passed
2026-04-16 01:01:26.972983 | ubuntu-noble | isort....................................................................Passed
2026-04-16 01:01:51.509486 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-04-16 01:01:51.974749 | ubuntu-noble | ok: Runtime: 0:00:54.297664
2026-04-16 01:01:51.976863 | 
2026-04-16 01:01:51.976925 | PLAY RECAP
2026-04-16 01:01:51.977000 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-16 01:01:51.977034 | 
2026-04-16 01:01:52.081500 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-16 01:01:52.083709 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-16 01:01:52.642733 | 
2026-04-16 01:01:52.642847 | PLAY [all]
2026-04-16 01:01:52.654479 | 
2026-04-16 01:01:52.654558 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-16 01:01:52.698431 | ubuntu-noble | skipping: Conditional result was False
2026-04-16 01:01:52.704240 | 
2026-04-16 01:01:52.704333 | TASK [fetch-output : Set log path for single node]
2026-04-16 01:01:52.744407 | ubuntu-noble | ok
2026-04-16 01:01:52.749843 | 
2026-04-16 01:01:52.749927 | LOOP [fetch-output : Ensure local output dirs]
2026-04-16 01:01:53.140279 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/91a7168d93ff43a9a561456eecacefb6/work/logs"
2026-04-16 01:01:53.348790 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/91a7168d93ff43a9a561456eecacefb6/work/artifacts"
2026-04-16 01:01:53.569064 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/91a7168d93ff43a9a561456eecacefb6/work/docs"
2026-04-16 01:01:53.587682 | 
2026-04-16 01:01:53.587809 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-16 01:01:54.237395 | ubuntu-noble | changed: .d..t...... ./
2026-04-16 01:01:54.237609 | ubuntu-noble | changed: All items complete
2026-04-16 01:01:54.237636 | 
2026-04-16 01:01:54.718035 | ubuntu-noble | changed: .d..t...... ./
2026-04-16 01:01:55.207054 | ubuntu-noble | changed: .d..t...... ./
2026-04-16 01:01:55.228846 | 
2026-04-16 01:01:55.229026 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-16 01:01:55.639675 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007434
2026-04-16 01:01:55.877301 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.008868
2026-04-16 01:01:55.900201 | 
2026-04-16 01:01:55.900355 | PLAY [all]
2026-04-16 01:01:55.907278 | 
2026-04-16 01:01:55.907348 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-16 01:01:56.394959 | ubuntu-noble | changed
2026-04-16 01:01:56.401325 | 
2026-04-16 01:01:56.401375 | PLAY RECAP
2026-04-16 01:01:56.401425 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-16 01:01:56.401447 | 
2026-04-16 01:01:56.555249 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-16 01:01:56.556917 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-16 01:01:57.183768 | 
2026-04-16 01:01:57.183896 | PLAY [localhost]
2026-04-16 01:01:57.193414 | 
2026-04-16 01:01:57.193485 | TASK [Generate Zuul manifest]
2026-04-16 01:01:57.211572 | localhost | ok
2026-04-16 01:01:57.227574 | 
2026-04-16 01:01:57.227650 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-16 01:01:57.553696 | localhost | changed
2026-04-16 01:01:57.565352 | 
2026-04-16 01:01:57.565426 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-16 01:01:57.699874 | localhost | ok
2026-04-16 01:01:57.708374 | 
2026-04-16 01:01:57.708449 | TASK [Upload logs]
2026-04-16 01:01:57.730784 | localhost | ok
2026-04-16 01:01:57.787698 | 
2026-04-16 01:01:57.787842 | TASK [Set zuul-log-path fact]
2026-04-16 01:01:57.808493 | localhost | ok
2026-04-16 01:01:57.821108 | 
2026-04-16 01:01:57.821173 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-16 01:01:57.850665 | localhost | ok
2026-04-16 01:01:57.859549 | 
2026-04-16 01:01:57.859628 | TASK [upload-logs : Create log directories]
2026-04-16 01:01:58.275735 | localhost | changed
2026-04-16 01:01:58.281963 | 
2026-04-16 01:01:58.282085 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-16 01:01:58.718278 | localhost -> localhost | ok: Runtime: 0:00:00.006483
2026-04-16 01:01:58.725011 | 
2026-04-16 01:01:58.725121 | TASK [upload-logs : Upload logs to log server]
2026-04-16 01:01:59.242486 | localhost | Output suppressed because no_log was given
2026-04-16 01:01:59.247298 | 
2026-04-16 01:01:59.247368 | LOOP [upload-logs : Compress console log and json output]
2026-04-16 01:01:59.295313 | localhost | skipping: Conditional result was False
2026-04-16 01:01:59.302253 | localhost | skipping: Conditional result was False
2026-04-16 01:01:59.313429 | 
2026-04-16 01:01:59.313611 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-16 01:01:59.360272 | localhost | skipping: Conditional result was False
2026-04-16 01:01:59.360825 | 
2026-04-16 01:01:59.365673 | localhost | skipping: Conditional result was False
2026-04-16 01:01:59.377892 | 
2026-04-16 01:01:59.378072 | LOOP [upload-logs : Upload console log and json output]