2026-04-18 16:33:28.829604 | Job console starting
2026-04-18 16:33:28.837743 | Updating git repos
2026-04-18 16:33:28.866697 | Cloning repos into workspace
2026-04-18 16:33:29.799030 | Restoring repo states
2026-04-18 16:33:29.807441 | Merging changes
2026-04-18 16:33:30.073516 | Checking out repos
2026-04-18 16:33:30.243248 | Preparing playbooks
2026-04-18 16:33:43.681750 | Running Ansible setup
2026-04-18 16:33:49.156057 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-18 16:33:49.718190 | 
2026-04-18 16:33:49.718302 | PLAY [localhost]
2026-04-18 16:33:49.725482 | 
2026-04-18 16:33:49.725611 | TASK [Gathering Facts]
2026-04-18 16:33:51.211549 | localhost | ok
2026-04-18 16:33:51.220778 | 
2026-04-18 16:33:51.238485 | TASK [Setup log path fact]
2026-04-18 16:33:51.261836 | localhost | ok
2026-04-18 16:33:51.276429 | 
2026-04-18 16:33:51.276571 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-18 16:33:51.306201 | localhost | ok
2026-04-18 16:33:51.315273 | 
2026-04-18 16:33:51.315429 | TASK [emit-job-header : Print job information]
2026-04-18 16:33:51.354365 | # Job Information
2026-04-18 16:33:51.354570 | Ansible Version: 2.16.16
2026-04-18 16:33:51.354604 | Job: pre-commit
2026-04-18 16:33:51.354626 | Pipeline: check
2026-04-18 16:33:51.354646 | Executor: 0a8996d2b663
2026-04-18 16:33:51.354665 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/110
2026-04-18 16:33:51.354687 | Event ID: 34c38600-3b44-11f1-92f3-e4509f68404d
2026-04-18 16:33:51.358302 | 
2026-04-18 16:33:51.369753 | LOOP [emit-job-header : Print node information]
2026-04-18 16:33:51.475172 | localhost | ok:
2026-04-18 16:33:52.131102 | localhost | # Node Information
2026-04-18 16:33:52.131274 | localhost | Inventory Hostname: ubuntu-noble
2026-04-18 16:33:52.131469 | localhost | Hostname: np0000167335
2026-04-18 16:33:52.131579 | localhost | Username: zuul
2026-04-18 16:33:52.131673 | localhost | Distro: Ubuntu 24.04
2026-04-18 16:33:52.132022 | localhost | Provider: yul1
2026-04-18 16:33:52.132095 | localhost | Region: ca-ymq-1
2026-04-18 16:33:52.132144 | localhost | Label: ubuntu-noble
2026-04-18 16:33:52.132190 | localhost | Product Name: OpenStack Nova
2026-04-18 16:33:52.132235 | localhost | Interface IP: 199.204.45.205
2026-04-18 16:33:52.148615 | 
2026-04-18 16:33:52.148739 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-18 16:33:52.789750 | localhost -> localhost | changed
2026-04-18 16:33:52.796207 | 
2026-04-18 16:33:52.796289 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-18 16:33:53.795392 | localhost -> localhost | changed
2026-04-18 16:33:53.806940 | 
2026-04-18 16:33:53.806993 | PLAY [all]
2026-04-18 16:33:53.814843 | 
2026-04-18 16:33:53.814905 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-18 16:33:54.041120 | ubuntu-noble -> localhost | ok
2026-04-18 16:33:54.052077 | 
2026-04-18 16:33:54.052200 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-18 16:33:54.086460 | ubuntu-noble | ok
2026-04-18 16:33:54.100087 | ubuntu-noble | included: /var/lib/zuul/builds/d110d011a3264a8790e6ebac2fd4474a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-18 16:33:54.105987 | 
2026-04-18 16:33:54.106047 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-18 16:33:55.190243 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-04-18 16:33:55.190436 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/d110d011a3264a8790e6ebac2fd4474a/work/d110d011a3264a8790e6ebac2fd4474a_id_rsa
2026-04-18 16:33:55.190484 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/d110d011a3264a8790e6ebac2fd4474a/work/d110d011a3264a8790e6ebac2fd4474a_id_rsa.pub
2026-04-18 16:33:55.190517 | ubuntu-noble -> localhost | The key fingerprint is:
2026-04-18 16:33:55.190549 | ubuntu-noble -> localhost | SHA256:y/nUdNyrhB7nrmshhufBgMySN2cdEf/TQkOL4ahD4ow zuul-build-sshkey
2026-04-18 16:33:55.190599 | ubuntu-noble -> localhost | The key's randomart image is:
2026-04-18 16:33:55.190647 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-04-18 16:33:55.190693 | ubuntu-noble -> localhost | |         oo. .   |
2026-04-18 16:33:55.190737 | ubuntu-noble -> localhost | |         .+ + .  |
2026-04-18 16:33:55.190781 | ubuntu-noble -> localhost | |    +......+ +   |
2026-04-18 16:33:55.190818 | ubuntu-noble -> localhost | |   o+*o+..  o.o. |
2026-04-18 16:33:55.190848 | ubuntu-noble -> localhost | |   Eoo+oS   .+o..|
2026-04-18 16:33:55.190876 | ubuntu-noble -> localhost | |       o.O +..o .|
2026-04-18 16:33:55.190906 | ubuntu-noble -> localhost | |        B +ooo . |
2026-04-18 16:33:55.190936 | ubuntu-noble -> localhost | |         +..= .  |
2026-04-18 16:33:55.190969 | ubuntu-noble -> localhost | |          o+++   |
2026-04-18 16:33:55.191000 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-04-18 16:33:55.191068 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.468464
2026-04-18 16:33:55.198551 | 
2026-04-18 16:33:55.198626 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-18 16:33:55.223638 | ubuntu-noble | ok
2026-04-18 16:33:55.232805 | ubuntu-noble | included: /var/lib/zuul/builds/d110d011a3264a8790e6ebac2fd4474a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-18 16:33:55.240625 | 
2026-04-18 16:33:55.307172 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-18 16:33:55.334271 | ubuntu-noble | skipping: Conditional result was False
2026-04-18 16:33:55.343240 | 
2026-04-18 16:33:55.343331 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-18 16:33:55.872863 | ubuntu-noble | changed
2026-04-18 16:33:56.340360 | 
2026-04-18 16:33:56.340479 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-18 16:33:56.559993 | ubuntu-noble | ok
2026-04-18 16:33:56.565699 | 
2026-04-18 16:33:56.565771 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-18 16:33:57.139536 | ubuntu-noble | changed
2026-04-18 16:33:57.242563 | 
2026-04-18 16:33:57.242690 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-18 16:33:57.805292 | ubuntu-noble | changed
2026-04-18 16:33:57.811049 | 
2026-04-18 16:33:57.811115 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-18 16:33:57.836390 | ubuntu-noble | skipping: Conditional result was False
2026-04-18 16:33:57.843109 | 
2026-04-18 16:33:57.843189 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-18 16:33:58.241660 | ubuntu-noble -> localhost | changed
2026-04-18 16:33:58.255278 | 
2026-04-18 16:33:58.255442 | TASK [add-build-sshkey : Add back temp key]
2026-04-18 16:33:58.579683 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/d110d011a3264a8790e6ebac2fd4474a/work/d110d011a3264a8790e6ebac2fd4474a_id_rsa (zuul-build-sshkey)
2026-04-18 16:33:58.689452 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.019642
2026-04-18 16:33:58.710596 | 
2026-04-18 16:33:58.710754 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-18 16:33:59.060405 | ubuntu-noble | ok
2026-04-18 16:33:59.068787 | 
2026-04-18 16:33:59.069088 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-18 16:33:59.108098 | ubuntu-noble | skipping: Conditional result was False
2026-04-18 16:33:59.133134 | 
2026-04-18 16:33:59.133276 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-18 16:33:59.511677 | ubuntu-noble | ok
2026-04-18 16:33:59.729964 | 
2026-04-18 16:33:59.730090 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-18 16:34:00.578149 | ubuntu-noble | Output suppressed because no_log was given
2026-04-18 16:34:00.590630 | 
2026-04-18 16:34:00.886029 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-18 16:34:01.161290 | ubuntu-noble | ok: "logs"
2026-04-18 16:34:01.161533 | ubuntu-noble | ok: All items complete
2026-04-18 16:34:01.161584 | 
2026-04-18 16:34:01.305773 | ubuntu-noble | ok: "artifacts"
2026-04-18 16:34:01.520001 | ubuntu-noble | ok: "docs"
2026-04-18 16:34:01.877032 | 
2026-04-18 16:34:01.877159 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-18 16:34:02.086422 | ubuntu-noble | changed: "logs"
2026-04-18 16:34:02.274073 | ubuntu-noble | changed: "artifacts"
2026-04-18 16:34:02.468177 | ubuntu-noble | changed: "docs"
2026-04-18 16:34:02.478567 | 
2026-04-18 16:34:02.478677 | PLAY RECAP
2026-04-18 16:34:02.478737 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-18 16:34:02.478779 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-18 16:34:02.478808 | 
2026-04-18 16:34:02.599289 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-18 16:34:02.601341 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-18 16:34:03.246674 | 
2026-04-18 16:34:03.247238 | PLAY [all]
2026-04-18 16:34:03.260697 | 
2026-04-18 16:34:03.260775 | TASK [setup-uv : Extract archive]
2026-04-18 16:34:05.715810 | ubuntu-noble | changed
2026-04-18 16:34:05.840095 | 
2026-04-18 16:34:05.840289 | TASK [setup-uv : Print version]
2026-04-18 16:34:06.220599 | ubuntu-noble | uv 0.8.13
2026-04-18 16:34:06.391771 | ubuntu-noble | ok: Runtime: 0:00:00.016150
2026-04-18 16:34:06.409044 | 
2026-04-18 16:34:06.409122 | PLAY RECAP
2026-04-18 16:34:06.409169 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-18 16:34:06.409194 | 
2026-04-18 16:34:06.544842 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/pre.yaml@main]
2026-04-18 16:34:06.545867 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-18 16:34:07.110032 | 
2026-04-18 16:34:07.110139 | PLAY [all]
2026-04-18 16:34:07.121987 | 
2026-04-18 16:34:07.122064 | TASK [Run "pre-commit"]
2026-04-18 16:34:07.717977 | ubuntu-noble | Downloading virtualenv (5.6MiB)
2026-04-18 16:34:07.794677 | ubuntu-noble |  Downloading virtualenv
2026-04-18 16:34:07.808008 | ubuntu-noble | Installed 10 packages in 12ms
2026-04-18 16:34:08.185972 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-18 16:34:08.826271 | 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-18 16:34:08.831657 | ubuntu-noble | [INFO] Initializing environment for https://github.com/psf/black.
2026-04-18 16:34:09.364116 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/flake8.
2026-04-18 16:34:09.761373 | ubuntu-noble | [INFO] Initializing environment for https://github.com/pycqa/isort.
2026-04-18 16:34:10.215732 | 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-18 16:34:10.217645 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git.
2026-04-18 16:34:10.750081 | ubuntu-noble | [INFO] Initializing environment for https://github.com/ansible/ansible-lint.git:ansible-core>=2.16.0.
2026-04-18 16:34:11.308310 | ubuntu-noble | [INFO] Installing environment for https://github.com/pre-commit/pre-commit-hooks.
2026-04-18 16:34:11.308388 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-18 16:34:11.308407 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-18 16:34:15.314458 | ubuntu-noble | [INFO] Installing environment for https://github.com/psf/black.
2026-04-18 16:34:15.314541 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-18 16:34:15.314567 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-18 16:34:19.689533 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/flake8.
2026-04-18 16:34:19.689632 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-18 16:34:19.689644 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-18 16:34:23.115907 | ubuntu-noble | [INFO] Installing environment for https://github.com/pycqa/isort.
2026-04-18 16:34:23.116007 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-18 16:34:23.116028 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-18 16:34:25.775639 | ubuntu-noble | [INFO] Installing environment for https://github.com/ansible/ansible-lint.git.
2026-04-18 16:34:25.775741 | ubuntu-noble | [INFO] Once installed this environment will be reused.
2026-04-18 16:34:25.775752 | ubuntu-noble | [INFO] This may take a few minutes...
2026-04-18 16:34:36.242143 | ubuntu-noble | fix end of files.........................................................Passed
2026-04-18 16:34:36.307182 | ubuntu-noble | trim trailing whitespace.................................................Passed
2026-04-18 16:34:36.594458 | ubuntu-noble | black....................................................................Passed
2026-04-18 16:34:36.808345 | ubuntu-noble | flake8...................................................................Passed
2026-04-18 16:34:36.939351 | ubuntu-noble | isort....................................................................Passed
2026-04-18 16:34:53.565201 | ubuntu-noble | Ansible-lint.............................................................Passed
2026-04-18 16:34:53.724572 | ubuntu-noble | ok: Runtime: 0:00:46.102829
2026-04-18 16:34:53.726801 | 
2026-04-18 16:34:53.726891 | PLAY RECAP
2026-04-18 16:34:53.726951 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-18 16:34:53.727052 | 
2026-04-18 16:34:53.844404 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/pre-commit/run.yaml@main]
2026-04-18 16:34:53.848531 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-18 16:34:54.451460 | 
2026-04-18 16:34:54.451614 | PLAY [all]
2026-04-18 16:34:54.463294 | 
2026-04-18 16:34:54.463479 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-18 16:34:54.508370 | ubuntu-noble | skipping: Conditional result was False
2026-04-18 16:34:54.514514 | 
2026-04-18 16:34:54.514610 | TASK [fetch-output : Set log path for single node]
2026-04-18 16:34:54.545269 | ubuntu-noble | ok
2026-04-18 16:34:54.550235 | 
2026-04-18 16:34:54.550311 | LOOP [fetch-output : Ensure local output dirs]
2026-04-18 16:34:55.019674 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/d110d011a3264a8790e6ebac2fd4474a/work/logs"
2026-04-18 16:34:55.246608 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/d110d011a3264a8790e6ebac2fd4474a/work/artifacts"
2026-04-18 16:34:55.483442 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/d110d011a3264a8790e6ebac2fd4474a/work/docs"
2026-04-18 16:34:55.504559 | 
2026-04-18 16:34:55.504664 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-18 16:34:56.168925 | ubuntu-noble | ok
2026-04-18 16:34:56.169127 | ubuntu-noble | changed: All items complete
2026-04-18 16:34:56.169156 | 
2026-04-18 16:34:56.651406 | ubuntu-noble | changed: .d..t...... ./
2026-04-18 16:34:57.178687 | ubuntu-noble | changed: .d..t...... ./
2026-04-18 16:34:57.208353 | 
2026-04-18 16:34:57.208525 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-18 16:34:58.020424 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007418
2026-04-18 16:34:58.260702 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.007707
2026-04-18 16:34:58.282553 | 
2026-04-18 16:34:58.282731 | PLAY [all]
2026-04-18 16:34:58.292991 | 
2026-04-18 16:34:58.293182 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-18 16:34:58.811477 | ubuntu-noble | changed
2026-04-18 16:34:58.817899 | 
2026-04-18 16:34:58.817953 | PLAY RECAP
2026-04-18 16:34:58.818000 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-18 16:34:58.818023 | 
2026-04-18 16:34:59.069750 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-18 16:34:59.071343 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-18 16:34:59.779317 | 
2026-04-18 16:34:59.779541 | PLAY [localhost]
2026-04-18 16:34:59.791877 | 
2026-04-18 16:34:59.792035 | TASK [Generate Zuul manifest]
2026-04-18 16:34:59.815072 | localhost | ok
2026-04-18 16:34:59.833414 | 
2026-04-18 16:34:59.833551 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-18 16:35:00.186719 | localhost | changed
2026-04-18 16:35:00.198769 | 
2026-04-18 16:35:00.227657 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-18 16:35:00.269593 | localhost | ok
2026-04-18 16:35:00.279687 | 
2026-04-18 16:35:00.279762 | TASK [Upload logs]
2026-04-18 16:35:00.311719 | localhost | ok
2026-04-18 16:35:00.375661 | 
2026-04-18 16:35:00.375812 | TASK [Set zuul-log-path fact]
2026-04-18 16:35:00.395349 | localhost | ok
2026-04-18 16:35:00.406639 | 
2026-04-18 16:35:00.406712 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-18 16:35:00.450784 | localhost | ok
2026-04-18 16:35:00.459146 | 
2026-04-18 16:35:00.459248 | TASK [upload-logs : Create log directories]
2026-04-18 16:35:00.985243 | localhost | changed
2026-04-18 16:35:00.990565 | 
2026-04-18 16:35:00.990635 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-18 16:35:01.491932 | localhost -> localhost | ok: Runtime: 0:00:00.005246
2026-04-18 16:35:01.509459 | 
2026-04-18 16:35:01.509639 | TASK [upload-logs : Upload logs to log server]
2026-04-18 16:35:02.050394 | localhost | Output suppressed because no_log was given
2026-04-18 16:35:02.055569 | 
2026-04-18 16:35:02.055658 | LOOP [upload-logs : Compress console log and json output]
2026-04-18 16:35:02.117297 | localhost | skipping: Conditional result was False
2026-04-18 16:35:02.126366 | localhost | skipping: Conditional result was False
2026-04-18 16:35:02.139973 | 
2026-04-18 16:35:02.140543 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-18 16:35:02.197325 | localhost | skipping: Conditional result was False
2026-04-18 16:35:02.197715 | 
2026-04-18 16:35:02.203783 | localhost | skipping: Conditional result was False
2026-04-18 16:35:02.211265 | 
2026-04-18 16:35:02.211460 | LOOP [upload-logs : Upload console log and json output]