2026-05-09 12:58:11.614757 | Job console starting
2026-05-09 12:58:11.628597 | Updating git repos
2026-05-09 12:58:11.664440 | Cloning repos into workspace
2026-05-09 12:58:11.809636 | Restoring repo states
2026-05-09 12:58:11.817748 | Merging changes
2026-05-09 12:58:12.405159 | Checking out repos
2026-05-09 12:58:12.449482 | Preparing playbooks
2026-05-09 12:58:15.606856 | Running Ansible setup
2026-05-09 12:58:20.456173 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-09 12:58:21.094356 | 
2026-05-09 12:58:21.094493 | PLAY [localhost]
2026-05-09 12:58:21.102318 | 
2026-05-09 12:58:21.102396 | TASK [Gathering Facts]
2026-05-09 12:58:22.551167 | localhost | ok
2026-05-09 12:58:22.563440 | 
2026-05-09 12:58:22.563588 | TASK [Setup log path fact]
2026-05-09 12:58:22.583419 | localhost | ok
2026-05-09 12:58:22.597427 | 
2026-05-09 12:58:22.597521 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-09 12:58:22.627281 | localhost | ok
2026-05-09 12:58:22.637223 | 
2026-05-09 12:58:22.637315 | TASK [emit-job-header : Print job information]
2026-05-09 12:58:22.680330 | # Job Information
2026-05-09 12:58:22.691024 | Ansible Version: 2.16.18
2026-05-09 12:58:22.691219 | Job: pre-commit
2026-05-09 12:58:22.691280 | Pipeline: check
2026-05-09 12:58:22.691327 | Executor: 2d72f0692154
2026-05-09 12:58:22.691373 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/124
2026-05-09 12:58:22.691424 | Event ID: 13987950-4ba6-11f1-9dc4-27c1f9d27b57
2026-05-09 12:58:22.699585 | 
2026-05-09 12:58:22.699801 | LOOP [emit-job-header : Print node information]
2026-05-09 12:58:22.808846 | localhost | ok:
2026-05-09 12:58:22.809387 | localhost | # Node Information
2026-05-09 12:58:22.809618 | localhost | Inventory Hostname: ubuntu-noble
2026-05-09 12:58:22.809690 | localhost | Hostname: np0000173131
2026-05-09 12:58:22.809795 | localhost | Username: zuul
2026-05-09 12:58:22.809851 | localhost | Distro: Ubuntu 24.04
2026-05-09 12:58:22.809898 | localhost | Provider: yul1
2026-05-09 12:58:22.809943 | localhost | Region: ca-ymq-1
2026-05-09 12:58:22.809987 | localhost | Label: ubuntu-noble
2026-05-09 12:58:22.810030 | localhost | Product Name: OpenStack Nova
2026-05-09 12:58:22.810074 | localhost | Interface IP: 199.19.213.112
2026-05-09 12:58:22.825819 | 
2026-05-09 12:58:22.825953 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-09 12:58:23.235471 | localhost -> localhost | changed
2026-05-09 12:58:23.245722 | 
2026-05-09 12:58:23.343151 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-09 12:58:24.282092 | localhost -> localhost | changed
2026-05-09 12:58:24.289397 | 
2026-05-09 12:58:24.289462 | PLAY [all]
2026-05-09 12:58:24.299281 | 
2026-05-09 12:58:24.299378 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-09 12:58:24.559826 | ubuntu-noble -> localhost | ok
2026-05-09 12:58:24.566540 | 
2026-05-09 12:58:24.566610 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-09 12:58:24.598777 | ubuntu-noble | ok
2026-05-09 12:58:24.617526 | ubuntu-noble | included: /var/lib/zuul/builds/814f0c4eda92428c8c37a1c9dd664ff2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-09 12:58:24.623488 | 
2026-05-09 12:58:24.623549 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-09 12:58:26.021012 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-05-09 12:58:26.021209 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/814f0c4eda92428c8c37a1c9dd664ff2/work/814f0c4eda92428c8c37a1c9dd664ff2_id_rsa
2026-05-09 12:58:26.021252 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/814f0c4eda92428c8c37a1c9dd664ff2/work/814f0c4eda92428c8c37a1c9dd664ff2_id_rsa.pub
2026-05-09 12:58:26.021284 | ubuntu-noble -> localhost | The key fingerprint is:
2026-05-09 12:58:26.021314 | ubuntu-noble -> localhost | SHA256:XGKnF7wGZ1cHUcDPwd+K6nWYiLdaOvKeinD+fcAe9eQ zuul-build-sshkey
2026-05-09 12:58:26.021359 | ubuntu-noble -> localhost | The key's randomart image is:
2026-05-09 12:58:26.021390 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-05-09 12:58:26.021427 | ubuntu-noble -> localhost | |             .*=o|
2026-05-09 12:58:26.021458 | ubuntu-noble -> localhost | |         .   ..+ |
2026-05-09 12:58:26.021488 | ubuntu-noble -> localhost | |        + B .  o+|
2026-05-09 12:58:26.021516 | ubuntu-noble -> localhost | |       o O.+.   =|
2026-05-09 12:58:26.021544 | ubuntu-noble -> localhost | |       .S.++ . . |
2026-05-09 12:58:26.021573 | ubuntu-noble -> localhost | |        ++ .Eo.  |
2026-05-09 12:58:26.021600 | ubuntu-noble -> localhost | |  . .  ..o+.+ .  |
2026-05-09 12:58:26.021628 | ubuntu-noble -> localhost | |   + ...o=oo .   |
2026-05-09 12:58:26.021659 | ubuntu-noble -> localhost | |    o.o=B=o      |
2026-05-09 12:58:26.021688 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-05-09 12:58:26.021750 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.906910
2026-05-09 12:58:26.029456 | 
2026-05-09 12:58:26.029521 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-09 12:58:26.063192 | ubuntu-noble | ok
2026-05-09 12:58:26.075515 | ubuntu-noble | included: /var/lib/zuul/builds/814f0c4eda92428c8c37a1c9dd664ff2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-09 12:58:26.084572 | 
2026-05-09 12:58:26.084672 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-09 12:58:26.109242 | ubuntu-noble | skipping: Conditional result was False
2026-05-09 12:58:26.120513 | 
2026-05-09 12:58:26.120665 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-09 12:58:26.651188 | ubuntu-noble | changed
2026-05-09 12:58:26.657815 | 
2026-05-09 12:58:26.657888 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-09 12:58:26.870193 | ubuntu-noble | ok
2026-05-09 12:58:26.875835 | 
2026-05-09 12:58:26.875907 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-09 12:58:27.445351 | ubuntu-noble | changed
2026-05-09 12:58:27.451511 | 
2026-05-09 12:58:27.451574 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-09 12:58:28.062514 | ubuntu-noble | changed
2026-05-09 12:58:28.074716 | 
2026-05-09 12:58:28.074798 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-09 12:58:28.098113 | ubuntu-noble | skipping: Conditional result was False
2026-05-09 12:58:28.109684 | 
2026-05-09 12:58:28.109749 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-09 12:58:28.517735 | ubuntu-noble -> localhost | changed
2026-05-09 12:58:28.534503 | 
2026-05-09 12:58:28.534620 | TASK [add-build-sshkey : Add back temp key]
2026-05-09 12:58:28.843694 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/814f0c4eda92428c8c37a1c9dd664ff2/work/814f0c4eda92428c8c37a1c9dd664ff2_id_rsa (zuul-build-sshkey)
2026-05-09 12:58:28.843877 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014798
2026-05-09 12:58:28.849248 | 
2026-05-09 12:58:28.849310 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-09 12:58:29.193764 | ubuntu-noble | ok
2026-05-09 12:58:29.199454 | 
2026-05-09 12:58:29.199536 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-09 12:58:29.223898 | ubuntu-noble | skipping: Conditional result was False
2026-05-09 12:58:29.239854 | 
2026-05-09 12:58:29.239943 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-09 12:58:29.610723 | ubuntu-noble | ok
2026-05-09 12:58:29.789486 | 
2026-05-09 12:58:29.789631 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-09 12:58:30.702135 | ubuntu-noble | Output suppressed because no_log was given
2026-05-09 12:58:30.714667 | 
2026-05-09 12:58:30.714771 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-09 12:58:30.964306 | ubuntu-noble | ok: "logs"
2026-05-09 12:58:30.964623 | ubuntu-noble | ok: All items complete
2026-05-09 12:58:30.964676 | 
2026-05-09 12:58:31.199069 | ubuntu-noble | ok: "artifacts"
2026-05-09 12:58:31.409564 | ubuntu-noble | ok: "docs"
2026-05-09 12:58:31.425718 | 
2026-05-09 12:58:31.425906 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-09 12:58:31.657587 | ubuntu-noble | changed: "logs"
2026-05-09 12:58:31.861387 | ubuntu-noble | changed: "artifacts"
2026-05-09 12:58:32.068893 | ubuntu-noble | changed: "docs"
2026-05-09 12:58:32.091182 | 
2026-05-09 12:58:32.091288 | PLAY RECAP
2026-05-09 12:58:32.091333 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-09 12:58:32.091363 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-09 12:58:32.091387 | 
2026-05-09 12:58:32.297066 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-09 12:58:32.299921 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-05-09 12:58:32.925290 | 
2026-05-09 12:58:33.610450 | PLAY [all]
2026-05-09 12:58:33.630109 | 
2026-05-09 12:58:33.630222 | TASK [setup-uv : Extract archive]
2026-05-09 12:58:36.077163 | ubuntu-noble | changed
2026-05-09 12:58:36.153706 | 
2026-05-09 12:58:36.153938 | TASK [setup-uv : Print version]
2026-05-09 12:58:36.557637 | ubuntu-noble | uv 0.8.13
2026-05-09 12:58:36.701434 | ubuntu-noble | ok: Runtime: 0:00:00.012344
2026-05-09 12:58:36.710741 | 
2026-05-09 12:58:36.710813 | PLAY RECAP
2026-05-09 12:58:36.710856 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-09 12:58:36.710880 | 
2026-05-09 12:58:36.840763 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-05-09 12:58:36.842341 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-05-09 12:58:37.467112 | 
2026-05-09 12:58:37.467248 | PLAY [all]
2026-05-09 12:58:37.480599 | 
2026-05-09 12:58:37.480726 | TASK [Run "pre-commit"]
2026-05-09 12:58:38.129604 | ubuntu-noble | Downloading virtualenv (7.2MiB)
2026-05-09 12:58:38.225907 | ubuntu-noble |  Downloading virtualenv
2026-05-09 12:58:38.240502 | ubuntu-noble | Installed 10 packages in 14ms
2026-05-09 12:58:38.621891 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-05-09 12:58:39.192829 | 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-09 12:58:39.198754 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-05-09 12:58:39.729003 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-05-09 12:58:40.136599 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-05-09 12:58:40.741479 | 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-09 12:58:40.743286 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-05-09 12:58:41.282666 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-05-09 12:58:41.816064 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-05-09 12:58:41.816145 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-09 12:58:41.816160 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-09 12:58:45.765320 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-05-09 12:58:45.765464 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-09 12:58:45.765476 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-09 12:58:50.403538 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-05-09 12:58:50.403662 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-09 12:58:50.403675 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-09 12:58:53.781324 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-05-09 12:58:53.781400 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-09 12:58:53.781412 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-09 12:58:56.466938 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-05-09 12:58:56.467012 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-05-09 12:58:56.467022 | ubuntu-noble | [INFO] This may take a few minutes...
2026-05-09 12:59:06.487766 | ubuntu-noble | fix end of files.........................................................Passed
2026-05-09 12:59:06.549856 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-05-09 12:59:06.810054 | ubuntu-noble | black....................................................................Passed
2026-05-09 12:59:06.995459 | ubuntu-noble | flake8...................................................................Passed
2026-05-09 12:59:07.112953 | ubuntu-noble | isort....................................................................Passed
2026-05-09 12:59:23.338201 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-05-09 12:59:23.570810 | ubuntu-noble | ok: Runtime: 0:00:45.456601
2026-05-09 12:59:23.572860 | 
2026-05-09 12:59:23.572909 | PLAY RECAP
2026-05-09 12:59:23.572977 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-09 12:59:23.573004 | 
2026-05-09 12:59:23.686306 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-05-09 12:59:23.688735 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-09 12:59:24.339154 | 
2026-05-09 12:59:24.339276 | PLAY [all]
2026-05-09 12:59:24.351193 | 
2026-05-09 12:59:24.351278 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-09 12:59:24.396062 | ubuntu-noble | skipping: Conditional result was False
2026-05-09 12:59:24.407562 | 
2026-05-09 12:59:24.407649 | TASK [fetch-output : Set log path for single node]
2026-05-09 12:59:24.452452 | ubuntu-noble | ok
2026-05-09 12:59:24.459526 | 
2026-05-09 12:59:24.459593 | LOOP [fetch-output : Ensure local output dirs]
2026-05-09 12:59:24.831773 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/814f0c4eda92428c8c37a1c9dd664ff2/work/logs"
2026-05-09 12:59:25.047087 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/814f0c4eda92428c8c37a1c9dd664ff2/work/artifacts"
2026-05-09 12:59:25.277239 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/814f0c4eda92428c8c37a1c9dd664ff2/work/docs"
2026-05-09 12:59:25.294009 | 
2026-05-09 12:59:25.294121 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-09 12:59:25.960836 | ubuntu-noble | changed: .d..t...... ./
2026-05-09 12:59:25.961093 | ubuntu-noble | changed: All items complete
2026-05-09 12:59:25.961135 | 
2026-05-09 12:59:26.453911 | ubuntu-noble | changed: .d..t...... ./
2026-05-09 12:59:26.929627 | ubuntu-noble | changed: .d..t...... ./
2026-05-09 12:59:26.953958 | 
2026-05-09 12:59:26.954092 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-09 12:59:27.427992 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.006985
2026-05-09 12:59:27.646137 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.006420
2026-05-09 12:59:27.669288 | 
2026-05-09 12:59:27.670198 | PLAY [all]
2026-05-09 12:59:27.679455 | 
2026-05-09 12:59:27.679591 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-09 12:59:28.144811 | ubuntu-noble | changed
2026-05-09 12:59:28.150873 | 
2026-05-09 12:59:28.150946 | PLAY RECAP
2026-05-09 12:59:28.150988 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-09 12:59:28.151011 | 
2026-05-09 12:59:28.272940 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-09 12:59:28.275546 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-09 12:59:28.917704 | 
2026-05-09 12:59:28.917857 | PLAY [localhost]
2026-05-09 12:59:28.933355 | 
2026-05-09 12:59:28.933484 | TASK [Generate Zuul manifest]
2026-05-09 12:59:28.954798 | localhost | ok
2026-05-09 12:59:28.972441 | 
2026-05-09 12:59:28.972613 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-09 12:59:29.414614 | localhost | changed
2026-05-09 12:59:29.427585 | 
2026-05-09 12:59:29.427719 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-09 12:59:29.462964 | localhost | ok
2026-05-09 12:59:29.472308 | 
2026-05-09 12:59:29.472392 | TASK [Upload logs]
2026-05-09 12:59:29.497999 | localhost | ok
2026-05-09 12:59:29.571255 | 
2026-05-09 12:59:29.571461 | TASK [Set zuul-log-path fact]
2026-05-09 12:59:29.594733 | localhost | ok
2026-05-09 12:59:29.606932 | 
2026-05-09 12:59:29.607220 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-09 12:59:29.658611 | localhost | ok
2026-05-09 12:59:29.665795 | 
2026-05-09 12:59:29.665863 | TASK [upload-logs : Create log directories]
2026-05-09 12:59:30.136218 | localhost | changed
2026-05-09 12:59:30.141671 | 
2026-05-09 12:59:30.141761 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-09 12:59:30.567996 | localhost -> localhost | ok: Runtime: 0:00:00.005445
2026-05-09 12:59:30.573472 | 
2026-05-09 12:59:30.573539 | TASK [upload-logs : Upload logs to log server]
2026-05-09 12:59:31.060120 | localhost | Output suppressed because no_log was given
2026-05-09 12:59:31.066745 | 
2026-05-09 12:59:31.066820 | LOOP [upload-logs : Compress console log and json output]
2026-05-09 12:59:31.119591 | localhost | skipping: Conditional result was False
2026-05-09 12:59:31.127204 | localhost | skipping: Conditional result was False
2026-05-09 12:59:31.143639 | 
2026-05-09 12:59:31.143823 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-09 12:59:31.189343 | localhost | skipping: Conditional result was False
2026-05-09 12:59:31.190097 | 
2026-05-09 12:59:31.194419 | localhost | skipping: Conditional result was False
2026-05-09 12:59:31.211146 | 
2026-05-09 12:59:31.211331 | LOOP [upload-logs : Upload console log and json output]