2026-03-11 00:44:18.179720 | Job console starting
2026-03-11 00:44:18.197795 | Updating git repos
2026-03-11 00:44:18.225378 | Cloning repos into workspace
2026-03-11 00:44:18.286726 | Restoring repo states
2026-03-11 00:44:18.293034 | Merging changes
2026-03-11 00:44:18.557518 | Checking out repos
2026-03-11 00:44:18.597231 | Preparing playbooks
2026-03-11 00:44:20.033597 | Running Ansible setup
2026-03-11 00:44:23.914879 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-11 00:44:24.540472 | 
2026-03-11 00:44:24.540631 | PLAY [localhost]
2026-03-11 00:44:24.548759 | 
2026-03-11 00:44:24.548829 | TASK [Gathering Facts]
2026-03-11 00:44:25.428062 | localhost | ok
2026-03-11 00:44:25.435267 | 
2026-03-11 00:44:25.435348 | TASK [Setup log path fact]
2026-03-11 00:44:25.453984 | localhost | ok
2026-03-11 00:44:25.468473 | 
2026-03-11 00:44:25.468541 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-11 00:44:25.509451 | localhost | ok
2026-03-11 00:44:25.517913 | 
2026-03-11 00:44:25.518005 | TASK [emit-job-header : Print job information]
2026-03-11 00:44:25.559422 | # Job Information
2026-03-11 00:44:25.559636 | Ansible Version: 2.16.16
2026-03-11 00:44:25.559707 | Job: pre-commit
2026-03-11 00:44:25.559756 | Pipeline: check
2026-03-11 00:44:25.559802 | Executor: 0a8996d2b663
2026-03-11 00:44:25.559849 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/99
2026-03-11 00:44:25.559902 | Event ID: 53b91b30-1ce3-11f1-884c-c2e8535f96e3
2026-03-11 00:44:25.564540 | 
2026-03-11 00:44:25.564633 | LOOP [emit-job-header : Print node information]
2026-03-11 00:44:25.666596 | localhost | ok:
2026-03-11 00:44:25.666860 | localhost | # Node Information
2026-03-11 00:44:25.666900 | localhost | Inventory Hostname: ubuntu-noble
2026-03-11 00:44:25.666931 | localhost | Hostname: np0000158309
2026-03-11 00:44:25.666959 | localhost | Username: zuul
2026-03-11 00:44:25.666992 | localhost | Distro: Ubuntu 24.04
2026-03-11 00:44:25.667019 | localhost | Provider: yul1
2026-03-11 00:44:25.667046 | localhost | Region: ca-ymq-1
2026-03-11 00:44:25.667072 | localhost | Label: ubuntu-noble
2026-03-11 00:44:25.667097 | localhost | Product Name: OpenStack Nova
2026-03-11 00:44:25.667123 | localhost | Interface IP: 199.204.45.78
2026-03-11 00:44:25.683648 | 
2026-03-11 00:44:25.683808 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-11 00:44:26.237220 | localhost -> localhost | changed
2026-03-11 00:44:26.247347 | 
2026-03-11 00:44:26.247448 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-11 00:44:27.255106 | localhost -> localhost | changed
2026-03-11 00:44:27.263975 | 
2026-03-11 00:44:27.264052 | PLAY [all]
2026-03-11 00:44:27.274178 | 
2026-03-11 00:44:27.274263 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-11 00:44:27.542929 | ubuntu-noble -> localhost | ok
2026-03-11 00:44:27.554038 | 
2026-03-11 00:44:27.554148 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-11 00:44:27.591788 | ubuntu-noble | ok
2026-03-11 00:44:27.608620 | ubuntu-noble | included: /var/lib/zuul/builds/594fd27510e745efaeaee87ea9b30487/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-11 00:44:27.614843 | 
2026-03-11 00:44:27.614904 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-11 00:44:29.091245 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-03-11 00:44:29.091470 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/594fd27510e745efaeaee87ea9b30487/work/594fd27510e745efaeaee87ea9b30487_id_rsa
2026-03-11 00:44:29.091802 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/594fd27510e745efaeaee87ea9b30487/work/594fd27510e745efaeaee87ea9b30487_id_rsa.pub
2026-03-11 00:44:29.091839 | ubuntu-noble -> localhost | The key fingerprint is:
2026-03-11 00:44:29.091871 | ubuntu-noble -> localhost | SHA256:gjYMx4CzK0OH4pld9rvtZjAP77WVCjdgM67kphewsjE zuul-build-sshkey
2026-03-11 00:44:29.091913 | ubuntu-noble -> localhost | The key's randomart image is:
2026-03-11 00:44:29.091943 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-03-11 00:44:29.091975 | ubuntu-noble -> localhost | | ..              |
2026-03-11 00:44:29.092005 | ubuntu-noble -> localhost | |o  o             |
2026-03-11 00:44:29.092034 | ubuntu-noble -> localhost | | oo o            |
2026-03-11 00:44:29.092064 | ubuntu-noble -> localhost | |oo = =           |
2026-03-11 00:44:29.092092 | ubuntu-noble -> localhost | |+.= B = S=       |
2026-03-11 00:44:29.092121 | ubuntu-noble -> localhost | |++ E o Bo +   .  |
2026-03-11 00:44:29.092149 | ubuntu-noble -> localhost | |..  =  .Oo + o   |
2026-03-11 00:44:29.092176 | ubuntu-noble -> localhost | |   .  o+o=+ =    |
2026-03-11 00:44:29.092209 | ubuntu-noble -> localhost | |     .++*+ o     |
2026-03-11 00:44:29.092238 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-03-11 00:44:29.092304 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.934963
2026-03-11 00:44:29.100858 | 
2026-03-11 00:44:29.100922 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-11 00:44:29.138347 | ubuntu-noble | ok
2026-03-11 00:44:29.394640 | ubuntu-noble | included: /var/lib/zuul/builds/594fd27510e745efaeaee87ea9b30487/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-11 00:44:29.409457 | 
2026-03-11 00:44:29.409543 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-11 00:44:29.436605 | ubuntu-noble | skipping: Conditional result was False
2026-03-11 00:44:29.446740 | 
2026-03-11 00:44:29.447080 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-11 00:44:29.995250 | ubuntu-noble | changed
2026-03-11 00:44:30.001485 | 
2026-03-11 00:44:30.001549 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-11 00:44:30.218505 | ubuntu-noble | ok
2026-03-11 00:44:30.360559 | 
2026-03-11 00:44:30.360695 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-11 00:44:30.971468 | ubuntu-noble | changed
2026-03-11 00:44:30.977885 | 
2026-03-11 00:44:30.977950 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-11 00:44:31.580272 | ubuntu-noble | changed
2026-03-11 00:44:31.585509 | 
2026-03-11 00:44:31.585570 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-11 00:44:31.614505 | ubuntu-noble | skipping: Conditional result was False
2026-03-11 00:44:31.623633 | 
2026-03-11 00:44:31.623775 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-11 00:44:31.983090 | ubuntu-noble -> localhost | changed
2026-03-11 00:44:31.995884 | 
2026-03-11 00:44:31.995979 | TASK [add-build-sshkey : Add back temp key]
2026-03-11 00:44:32.286221 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/594fd27510e745efaeaee87ea9b30487/work/594fd27510e745efaeaee87ea9b30487_id_rsa (zuul-build-sshkey)
2026-03-11 00:44:32.286405 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010030
2026-03-11 00:44:32.291806 | 
2026-03-11 00:44:32.291867 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-11 00:44:32.635069 | ubuntu-noble | ok
2026-03-11 00:44:32.640294 | 
2026-03-11 00:44:32.640356 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-11 00:44:32.669400 | ubuntu-noble | skipping: Conditional result was False
2026-03-11 00:44:32.684891 | 
2026-03-11 00:44:32.684994 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-11 00:44:33.041416 | ubuntu-noble | ok
2026-03-11 00:44:33.124387 | 
2026-03-11 00:44:33.124555 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-11 00:44:33.970625 | ubuntu-noble | Output suppressed because no_log was given
2026-03-11 00:44:33.979882 | 
2026-03-11 00:44:33.979942 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-11 00:44:34.245259 | ubuntu-noble | ok: "logs"
2026-03-11 00:44:34.245476 | ubuntu-noble | ok: All items complete
2026-03-11 00:44:34.245524 | 
2026-03-11 00:44:34.392513 | ubuntu-noble | ok: "artifacts"
2026-03-11 00:44:34.590520 | ubuntu-noble | ok: "docs"
2026-03-11 00:44:34.621220 | 
2026-03-11 00:44:34.621383 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-11 00:44:34.849608 | ubuntu-noble | changed: "logs"
2026-03-11 00:44:35.036671 | ubuntu-noble | changed: "artifacts"
2026-03-11 00:44:35.240651 | ubuntu-noble | changed: "docs"
2026-03-11 00:44:35.256655 | 
2026-03-11 00:44:35.256807 | PLAY RECAP
2026-03-11 00:44:35.256876 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-11 00:44:35.256918 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-03-11 00:44:35.256973 | 
2026-03-11 00:44:35.420161 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-11 00:44:35.442129 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-03-11 00:44:36.018035 | 
2026-03-11 00:44:36.018149 | PLAY [all]
2026-03-11 00:44:36.030184 | 
2026-03-11 00:44:36.030261 | TASK [setup-uv : Extract archive]
2026-03-11 00:44:38.459863 | ubuntu-noble | changed
2026-03-11 00:44:38.537771 | 
2026-03-11 00:44:38.538034 | TASK [setup-uv : Print version]
2026-03-11 00:44:37.490961 | ubuntu-noble | uv 0.8.13
2026-03-11 00:44:39.077760 | ubuntu-noble | ok: Runtime: 0:00:00.018075
2026-03-11 00:44:39.086779 | 
2026-03-11 00:44:39.086828 | PLAY RECAP
2026-03-11 00:44:39.086876 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-11 00:44:39.086900 | 
2026-03-11 00:44:39.191427 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-03-11 00:44:39.201793 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-03-11 00:44:39.830835 | 
2026-03-11 00:44:39.830988 | PLAY [all]
2026-03-11 00:44:39.842638 | 
2026-03-11 00:44:39.842709 | TASK [Run "pre-commit"]
2026-03-11 00:44:39.079397 | ubuntu-noble | Downloading virtualenv (5.6MiB)
2026-03-11 00:44:39.160039 | ubuntu-noble |  Downloading virtualenv
2026-03-11 00:44:39.172331 | ubuntu-noble | Installed 10 packages in 11ms
2026-03-11 00:44:39.580694 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-03-11 00:44:40.122372 | 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-03-11 00:44:40.128787 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-03-11 00:44:40.639316 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-03-11 00:44:41.023579 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-03-11 00:44:41.479547 | 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-03-11 00:44:41.480541 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-03-11 00:44:42.035689 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-03-11 00:44:42.557444 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-03-11 00:44:42.557512 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-03-11 00:44:42.557523 | ubuntu-noble | [INFO] This may take a few minutes...
2026-03-11 00:44:47.839901 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-03-11 00:44:47.839996 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-03-11 00:44:47.840007 | ubuntu-noble | [INFO] This may take a few minutes...
2026-03-11 00:44:52.277491 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-03-11 00:44:52.277572 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-03-11 00:44:52.277584 | ubuntu-noble | [INFO] This may take a few minutes...
2026-03-11 00:44:55.730725 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-03-11 00:44:55.730806 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-03-11 00:44:55.730815 | ubuntu-noble | [INFO] This may take a few minutes...
2026-03-11 00:44:58.384948 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-03-11 00:44:58.385034 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-03-11 00:44:58.385045 | ubuntu-noble | [INFO] This may take a few minutes...
2026-03-11 00:45:09.303128 | ubuntu-noble | fix end of files.........................................................Passed
2026-03-11 00:45:09.363731 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-03-11 00:45:09.626192 | ubuntu-noble | black....................................................................Passed
2026-03-11 00:45:09.839920 | ubuntu-noble | flake8...................................................................Passed
2026-03-11 00:45:09.960984 | ubuntu-noble | isort....................................................................Passed
2026-03-11 00:45:28.215637 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-03-11 00:45:28.411637 | ubuntu-noble | ok: Runtime: 0:00:49.440772
2026-03-11 00:45:28.414727 | 
2026-03-11 00:45:28.414824 | PLAY RECAP
2026-03-11 00:45:28.414895 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-11 00:45:28.414921 | 
2026-03-11 00:45:28.509563 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-03-11 00:45:28.511577 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-11 00:45:29.063367 | 
2026-03-11 00:45:29.063508 | PLAY [all]
2026-03-11 00:45:29.075162 | 
2026-03-11 00:45:29.075243 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-11 00:45:29.121176 | ubuntu-noble | skipping: Conditional result was False
2026-03-11 00:45:29.129869 | 
2026-03-11 00:45:29.130050 | TASK [fetch-output : Set log path for single node]
2026-03-11 00:45:29.178352 | ubuntu-noble | ok
2026-03-11 00:45:29.185671 | 
2026-03-11 00:45:29.185769 | LOOP [fetch-output : Ensure local output dirs]
2026-03-11 00:45:29.588686 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/594fd27510e745efaeaee87ea9b30487/work/logs"
2026-03-11 00:45:29.830531 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/594fd27510e745efaeaee87ea9b30487/work/artifacts"
2026-03-11 00:45:30.031219 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/594fd27510e745efaeaee87ea9b30487/work/docs"
2026-03-11 00:45:30.046480 | 
2026-03-11 00:45:30.046661 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-11 00:45:30.736406 | ubuntu-noble | changed: .d..t...... ./
2026-03-11 00:45:30.736671 | ubuntu-noble | changed: All items complete
2026-03-11 00:45:30.736698 | 
2026-03-11 00:45:31.206939 | ubuntu-noble | changed: .d..t...... ./
2026-03-11 00:45:31.679594 | ubuntu-noble | changed: .d..t...... ./
2026-03-11 00:45:31.707297 | 
2026-03-11 00:45:31.707462 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-11 00:45:32.130880 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007718
2026-03-11 00:45:32.351053 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.007408
2026-03-11 00:45:32.377710 | 
2026-03-11 00:45:32.377887 | PLAY [all]
2026-03-11 00:45:32.385258 | 
2026-03-11 00:45:32.385325 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-11 00:45:32.857750 | ubuntu-noble | changed
2026-03-11 00:45:32.864198 | 
2026-03-11 00:45:32.864254 | PLAY RECAP
2026-03-11 00:45:32.864306 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-11 00:45:32.864328 | 
2026-03-11 00:45:33.032633 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-11 00:45:33.034626 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-03-11 00:45:33.616793 | 
2026-03-11 00:45:33.617016 | PLAY [localhost]
2026-03-11 00:45:33.628282 | 
2026-03-11 00:45:33.628355 | TASK [Generate Zuul manifest]
2026-03-11 00:45:33.648265 | localhost | ok
2026-03-11 00:45:33.665670 | 
2026-03-11 00:45:33.665751 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-11 00:45:33.971280 | localhost | changed
2026-03-11 00:45:33.985839 | 
2026-03-11 00:45:33.985914 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-11 00:45:34.016396 | localhost | ok
2026-03-11 00:45:34.025162 | 
2026-03-11 00:45:34.025228 | TASK [Upload logs]
2026-03-11 00:45:34.046844 | localhost | ok
2026-03-11 00:45:34.107631 | 
2026-03-11 00:45:34.107763 | TASK [Set zuul-log-path fact]
2026-03-11 00:45:34.130658 | localhost | ok
2026-03-11 00:45:34.142308 | 
2026-03-11 00:45:34.142405 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-11 00:45:34.176472 | localhost | ok
2026-03-11 00:45:34.186303 | 
2026-03-11 00:45:34.186395 | TASK [upload-logs : Create log directories]
2026-03-11 00:45:34.581597 | localhost | changed
2026-03-11 00:45:34.588523 | 
2026-03-11 00:45:34.588610 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-11 00:45:34.976069 | localhost -> localhost | ok: Runtime: 0:00:00.005447
2026-03-11 00:45:34.983167 | 
2026-03-11 00:45:34.983233 | TASK [upload-logs : Upload logs to log server]
2026-03-11 00:45:35.440133 | localhost | Output suppressed because no_log was given
2026-03-11 00:45:35.445074 | 
2026-03-11 00:45:35.445159 | LOOP [upload-logs : Compress console log and json output]
2026-03-11 00:45:35.492407 | localhost | skipping: Conditional result was False
2026-03-11 00:45:35.499179 | localhost | skipping: Conditional result was False
2026-03-11 00:45:35.511756 | 
2026-03-11 00:45:35.511853 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-11 00:45:35.555523 | localhost | skipping: Conditional result was False
2026-03-11 00:45:35.555864 | 
2026-03-11 00:45:35.559624 | localhost | skipping: Conditional result was False
2026-03-11 00:45:35.574089 | 
2026-03-11 00:45:35.574180 | LOOP [upload-logs : Upload console log and json output]