2026-09-05 00:56:36.986369 | Job console starting
2026-09-05 00:56:37.005225 | Updating git repos
2026-09-05 00:56:37.033903 | Cloning repos into workspace
2026-09-05 00:56:37.124695 | Restoring repo states
2026-09-05 00:56:37.131537 | Merging changes
2026-09-05 00:56:37.994774 | Checking out repos
2026-09-05 00:56:38.161209 | Preparing playbooks
2026-09-05 00:56:39.832692 | Running Ansible setup
2026-09-05 00:56:43.676417 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-05 00:56:44.233114 | 
2026-09-05 00:56:44.233245 | PLAY [localhost]
2026-09-05 00:56:44.244371 | 
2026-09-05 00:56:44.244509 | TASK [Gathering Facts]
2026-09-05 00:56:45.337079 | localhost | ok
2026-09-05 00:56:45.349745 | 
2026-09-05 00:56:45.349891 | TASK [Setup log path fact]
2026-09-05 00:56:45.371393 | localhost | ok
2026-09-05 00:56:45.390037 | 
2026-09-05 00:56:45.390159 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-05 00:56:45.430810 | localhost | ok
2026-09-05 00:56:45.442146 | 
2026-09-05 00:56:45.442285 | TASK [emit-job-header : Print job information]
2026-09-05 00:56:45.482972 | # Job Information
2026-09-05 00:56:45.483178 | Ansible Version: 2.16.18
2026-09-05 00:56:45.483208 | Job: ansible-test
2026-09-05 00:56:45.483227 | Pipeline: check
2026-09-05 00:56:45.483244 | Executor: 2d72f0692154
2026-09-05 00:56:45.483261 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/149
2026-09-05 00:56:45.483281 | Event ID: b7f4aa30-a8c3-11f1-9178-a13e202f19f2
2026-09-05 00:56:45.486116 | 
2026-09-05 00:56:45.486186 | LOOP [emit-job-header : Print node information]
2026-09-05 00:56:45.621280 | localhost | ok:
2026-09-05 00:56:45.621464 | localhost | # Node Information
2026-09-05 00:56:45.621491 | localhost | Inventory Hostname: ubuntu-noble
2026-09-05 00:56:45.621511 | localhost | Hostname: np0000191953
2026-09-05 00:56:45.621529 | localhost | Username: zuul
2026-09-05 00:56:45.621549 | localhost | Distro: Ubuntu 24.04
2026-09-05 00:56:45.621566 | localhost | Provider: yul1
2026-09-05 00:56:45.621583 | localhost | Region: ca-ymq-1
2026-09-05 00:56:45.621600 | localhost | Label: ubuntu-noble
2026-09-05 00:56:45.621616 | localhost | Product Name: OpenStack Nova
2026-09-05 00:56:45.621633 | localhost | Interface IP: 162.253.55.210
2026-09-05 00:56:45.636750 | 
2026-09-05 00:56:45.636983 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-05 00:56:46.113049 | localhost -> localhost | changed
2026-09-05 00:56:46.118250 | 
2026-09-05 00:56:46.118339 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-05 00:56:47.010396 | localhost -> localhost | changed
2026-09-05 00:56:47.024256 | 
2026-09-05 00:56:47.024483 | PLAY [all]
2026-09-05 00:56:47.039286 | 
2026-09-05 00:56:47.039399 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-05 00:56:47.275017 | ubuntu-noble -> localhost | ok
2026-09-05 00:56:47.282112 | 
2026-09-05 00:56:47.282177 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-05 00:56:47.306457 | ubuntu-noble | ok
2026-09-05 00:56:47.325197 | ubuntu-noble | included: /var/lib/zuul/builds/e63d42e7271e4062b032d88004b4cf05/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-05 00:56:47.332329 | 
2026-09-05 00:56:47.332399 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-05 00:56:48.683833 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-09-05 00:56:48.683970 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/e63d42e7271e4062b032d88004b4cf05/work/e63d42e7271e4062b032d88004b4cf05_id_rsa
2026-09-05 00:56:48.683998 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/e63d42e7271e4062b032d88004b4cf05/work/e63d42e7271e4062b032d88004b4cf05_id_rsa.pub
2026-09-05 00:56:48.684018 | ubuntu-noble -> localhost | The key fingerprint is:
2026-09-05 00:56:48.684037 | ubuntu-noble -> localhost | SHA256:eAtj8U7bdvkVVUxcMOi3X6CihG9lZk6H68QUenm+wZk zuul-build-sshkey
2026-09-05 00:56:48.684066 | ubuntu-noble -> localhost | The key's randomart image is:
2026-09-05 00:56:48.684088 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-09-05 00:56:48.684113 | ubuntu-noble -> localhost | |             .o==|
2026-09-05 00:56:48.684132 | ubuntu-noble -> localhost | |            .  .+|
2026-09-05 00:56:48.684150 | ubuntu-noble -> localhost | |      .   ..    .|
2026-09-05 00:56:48.684167 | ubuntu-noble -> localhost | |       + . o. o .|
2026-09-05 00:56:48.684185 | ubuntu-noble -> localhost | |      =.S +..o + |
2026-09-05 00:56:48.684201 | ubuntu-noble -> localhost | |     ..*.BO+o+. o|
2026-09-05 00:56:48.684217 | ubuntu-noble -> localhost | |       o+O=+E  .o|
2026-09-05 00:56:48.684234 | ubuntu-noble -> localhost | |        +oo. + ..|
2026-09-05 00:56:48.684253 | ubuntu-noble -> localhost | |       . .. . .  |
2026-09-05 00:56:48.684270 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-09-05 00:56:48.684311 | ubuntu-noble -> localhost | ok: Runtime: 0:00:01.003522
2026-09-05 00:56:48.692426 | 
2026-09-05 00:56:48.692554 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-05 00:56:48.731529 | ubuntu-noble | ok
2026-09-05 00:56:48.740916 | ubuntu-noble | included: /var/lib/zuul/builds/e63d42e7271e4062b032d88004b4cf05/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-05 00:56:48.748293 | 
2026-09-05 00:56:48.748373 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-05 00:56:48.771625 | ubuntu-noble | skipping: Conditional result was False
2026-09-05 00:56:48.777525 | 
2026-09-05 00:56:48.777597 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-05 00:56:49.303570 | ubuntu-noble | changed
2026-09-05 00:56:49.325577 | 
2026-09-05 00:56:49.325731 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-05 00:56:49.576276 | ubuntu-noble | ok
2026-09-05 00:56:49.582007 | 
2026-09-05 00:56:49.582091 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-05 00:56:50.159531 | ubuntu-noble | changed
2026-09-05 00:56:50.172355 | 
2026-09-05 00:56:50.172420 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-05 00:56:50.784402 | ubuntu-noble | changed
2026-09-05 00:56:50.791071 | 
2026-09-05 00:56:50.791134 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-05 00:56:50.806649 | ubuntu-noble | skipping: Conditional result was False
2026-09-05 00:56:50.823107 | 
2026-09-05 00:56:50.823329 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-05 00:56:51.245619 | ubuntu-noble -> localhost | changed
2026-09-05 00:56:51.263751 | 
2026-09-05 00:56:51.263870 | TASK [add-build-sshkey : Add back temp key]
2026-09-05 00:56:51.575125 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/e63d42e7271e4062b032d88004b4cf05/work/e63d42e7271e4062b032d88004b4cf05_id_rsa (zuul-build-sshkey)
2026-09-05 00:56:51.575295 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014180
2026-09-05 00:56:51.579979 | 
2026-09-05 00:56:51.580034 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-05 00:56:51.970605 | ubuntu-noble | ok
2026-09-05 00:56:51.982182 | 
2026-09-05 00:56:51.982528 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-05 00:56:52.006781 | ubuntu-noble | skipping: Conditional result was False
2026-09-05 00:56:52.021049 | 
2026-09-05 00:56:52.021182 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-05 00:56:52.383072 | ubuntu-noble | ok
2026-09-05 00:56:52.423067 | 
2026-09-05 00:56:52.423182 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-05 00:56:53.263644 | ubuntu-noble | Output suppressed because no_log was given
2026-09-05 00:56:53.281684 | 
2026-09-05 00:56:53.281842 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-05 00:56:53.529164 | ubuntu-noble | ok: "logs"
2026-09-05 00:56:53.529505 | ubuntu-noble | ok: All items complete
2026-09-05 00:56:53.529582 | 
2026-09-05 00:56:53.738276 | ubuntu-noble | ok: "artifacts"
2026-09-05 00:56:53.936347 | ubuntu-noble | ok: "docs"
2026-09-05 00:56:53.954893 | 
2026-09-05 00:56:53.955029 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-05 00:56:54.187933 | ubuntu-noble | changed: "logs"
2026-09-05 00:56:54.368621 | ubuntu-noble | changed: "artifacts"
2026-09-05 00:56:54.551311 | ubuntu-noble | changed: "docs"
2026-09-05 00:56:54.568895 | 
2026-09-05 00:56:54.569075 | PLAY RECAP
2026-09-05 00:56:54.569200 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-05 00:56:54.569292 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-05 00:56:54.569364 | 
2026-09-05 00:56:55.504875 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-05 00:56:55.506301 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/ansible-test/pre.yaml@main]
2026-09-05 00:56:56.127359 | 
2026-09-05 00:56:56.127484 | PLAY [all]
2026-09-05 00:56:56.141162 | 
2026-09-05 00:56:56.141286 | TASK [setup-uv : Extract archive]
2026-09-05 00:56:58.640219 | ubuntu-noble | changed
2026-09-05 00:56:58.645788 | 
2026-09-05 00:56:58.645862 | TASK [setup-uv : Print version]
2026-09-05 00:56:59.069710 | ubuntu-noble | uv 0.8.13
2026-09-05 00:56:59.182555 | ubuntu-noble | ok: Runtime: 0:00:00.021239
2026-09-05 00:56:59.204034 | 
2026-09-05 00:56:59.204300 | TASK [Install Galaxy collection]
2026-09-05 00:56:59.595637 | ubuntu-noble | Using CPython 3.12.3 interpreter at: /usr/bin/python3
2026-09-05 00:56:59.595866 | ubuntu-noble | Creating virtual environment at: .venv
2026-09-05 00:57:00.525922 | ubuntu-noble | error: Failed to fetch: `https://pypi.org/simple/rich/`
2026-09-05 00:57:00.526018 | ubuntu-noble |   Caused by: request or response body error
2026-09-05 00:57:00.526051 | ubuntu-noble |   Caused by: error reading a body from connection
2026-09-05 00:57:00.526066 | ubuntu-noble |   Caused by: connection reset
2026-09-05 00:57:00.751941 | ubuntu-noble | ERROR
2026-09-05 00:57:00.752252 | ubuntu-noble | {
2026-09-05 00:57:00.752319 | ubuntu-noble |   "delta": "0:00:01.048331",
2026-09-05 00:57:00.752379 | ubuntu-noble |   "end": "2026-09-05 00:57:00.533349",
2026-09-05 00:57:00.752461 | ubuntu-noble |   "msg": "non-zero return code",
2026-09-05 00:57:00.752563 | ubuntu-noble |   "rc": 2,
2026-09-05 00:57:00.752611 | ubuntu-noble |   "start": "2026-09-05 00:56:59.485018"
2026-09-05 00:57:00.752650 | ubuntu-noble | }
failure
2026-09-05 00:57:00.754717 | 
2026-09-05 00:57:00.754801 | PLAY RECAP
2026-09-05 00:57:00.754889 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-05 00:57:00.754933 | 
2026-09-05 00:57:00.865786 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/ansible-test/pre.yaml@main]
2026-09-05 00:57:00.868184 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-05 00:57:01.439107 | 
2026-09-05 00:57:01.439254 | PLAY [all]
2026-09-05 00:57:01.450345 | 
2026-09-05 00:57:01.450420 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-05 00:57:01.504293 | ubuntu-noble | skipping: Conditional result was False
2026-09-05 00:57:01.511117 | 
2026-09-05 00:57:01.511217 | TASK [fetch-output : Set log path for single node]
2026-09-05 00:57:01.565287 | ubuntu-noble | ok
2026-09-05 00:57:01.575296 | 
2026-09-05 00:57:01.575457 | LOOP [fetch-output : Ensure local output dirs]
2026-09-05 00:57:01.987480 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/e63d42e7271e4062b032d88004b4cf05/work/logs"
2026-09-05 00:57:02.176591 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/e63d42e7271e4062b032d88004b4cf05/work/artifacts"
2026-09-05 00:57:02.440691 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/e63d42e7271e4062b032d88004b4cf05/work/docs"
2026-09-05 00:57:02.465236 | 
2026-09-05 00:57:02.465547 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-05 00:57:03.158795 | ubuntu-noble | ok
2026-09-05 00:57:03.159093 | ubuntu-noble | changed: All items complete
2026-09-05 00:57:03.159149 | 
2026-09-05 00:57:03.697307 | ubuntu-noble | changed: .d..t...... ./
2026-09-05 00:57:04.200592 | ubuntu-noble | changed: .d..t...... ./
2026-09-05 00:57:04.225239 | 
2026-09-05 00:57:04.225450 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-05 00:57:04.647546 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.006278
2026-09-05 00:57:04.869640 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.007447
2026-09-05 00:57:04.893389 | 
2026-09-05 00:57:04.893655 | PLAY [all]
2026-09-05 00:57:04.907494 | 
2026-09-05 00:57:04.907625 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-05 00:57:05.379355 | ubuntu-noble | changed
2026-09-05 00:57:05.390067 | 
2026-09-05 00:57:05.390158 | PLAY RECAP
2026-09-05 00:57:05.390236 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-05 00:57:05.390277 | 
2026-09-05 00:57:05.505655 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-05 00:57:05.506638 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-05 00:57:06.104741 | 
2026-09-05 00:57:06.104857 | PLAY [localhost]
2026-09-05 00:57:06.114715 | 
2026-09-05 00:57:06.114797 | TASK [Generate Zuul manifest]
2026-09-05 00:57:06.131566 | localhost | ok
2026-09-05 00:57:06.144602 | 
2026-09-05 00:57:06.144683 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-05 00:57:06.565362 | localhost | changed
2026-09-05 00:57:06.574589 | 
2026-09-05 00:57:06.574662 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-05 00:57:06.601324 | localhost | ok
2026-09-05 00:57:06.608378 | 
2026-09-05 00:57:06.608459 | TASK [Upload logs]
2026-09-05 00:57:06.625533 | localhost | ok
2026-09-05 00:57:06.677849 | 
2026-09-05 00:57:06.677979 | TASK [Set zuul-log-path fact]
2026-09-05 00:57:06.705296 | localhost | ok
2026-09-05 00:57:06.715945 | 
2026-09-05 00:57:06.716023 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-05 00:57:06.744897 | localhost | ok
2026-09-05 00:57:06.753350 | 
2026-09-05 00:57:06.756306 | TASK [upload-logs : Create log directories]
2026-09-05 00:57:07.228352 | localhost | changed
2026-09-05 00:57:07.238494 | 
2026-09-05 00:57:07.238643 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-05 00:57:07.774145 | localhost -> localhost | ok: Runtime: 0:00:00.005421
2026-09-05 00:57:07.783543 | 
2026-09-05 00:57:07.783674 | TASK [upload-logs : Upload logs to log server]
2026-09-05 00:57:08.330713 | localhost | Output suppressed because no_log was given
2026-09-05 00:57:08.339488 | 
2026-09-05 00:57:08.339685 | LOOP [upload-logs : Compress console log and json output]
2026-09-05 00:57:08.400829 | localhost | skipping: Conditional result was False
2026-09-05 00:57:08.410786 | localhost | skipping: Conditional result was False
2026-09-05 00:57:08.424036 | 
2026-09-05 00:57:08.424198 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-05 00:57:08.474232 | localhost | skipping: Conditional result was False
2026-09-05 00:57:08.474564 | 
2026-09-05 00:57:08.480470 | localhost | skipping: Conditional result was False
2026-09-05 00:57:08.496555 | 
2026-09-05 00:57:08.496701 | LOOP [upload-logs : Upload console log and json output]