2026-09-12 00:59:13.095089 | Job console starting
2026-09-12 00:59:13.104807 | Updating git repos
2026-09-12 00:59:13.151887 | Cloning repos into workspace
2026-09-12 00:59:13.280021 | Restoring repo states
2026-09-12 00:59:13.298311 | Merging changes
2026-09-12 00:59:13.941794 | Checking out repos
2026-09-12 00:59:13.986619 | Preparing playbooks
2026-09-12 00:59:15.262457 | Running Ansible setup
2026-09-12 00:59:19.080668 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-12 00:59:19.651339 | 
2026-09-12 00:59:19.652023 | PLAY [localhost]
2026-09-12 00:59:19.659495 | 
2026-09-12 00:59:19.659559 | TASK [Gathering Facts]
2026-09-12 00:59:20.529343 | localhost | ok
2026-09-12 00:59:20.542293 | 
2026-09-12 00:59:20.542442 | TASK [Setup log path fact]
2026-09-12 00:59:20.562676 | localhost | ok
2026-09-12 00:59:20.582210 | 
2026-09-12 00:59:20.582345 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-12 00:59:20.625383 | localhost | ok
2026-09-12 00:59:20.640332 | 
2026-09-12 00:59:20.640545 | TASK [emit-job-header : Print job information]
2026-09-12 00:59:20.692545 | # Job Information
2026-09-12 00:59:20.692762 | Ansible Version: 2.16.18
2026-09-12 00:59:20.692824 | Job: ansible-test
2026-09-12 00:59:20.692866 | Pipeline: check
2026-09-12 00:59:20.692904 | Executor: 2d72f0692154
2026-09-12 00:59:20.692943 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/150
2026-09-12 00:59:20.692987 | Event ID: c02f8e60-ae43-11f1-9f8b-a3c8ebe95043
2026-09-12 00:59:20.698353 | 
2026-09-12 00:59:20.698482 | LOOP [emit-job-header : Print node information]
2026-09-12 00:59:20.808447 | localhost | ok:
2026-09-12 00:59:20.808771 | localhost | # Node Information
2026-09-12 00:59:20.808829 | localhost | Inventory Hostname: ubuntu-noble
2026-09-12 00:59:20.808871 | localhost | Hostname: np0000192665
2026-09-12 00:59:20.808923 | localhost | Username: zuul
2026-09-12 00:59:20.809019 | localhost | Distro: Ubuntu 24.04
2026-09-12 00:59:20.809086 | localhost | Provider: yul1
2026-09-12 00:59:20.809129 | localhost | Region: ca-ymq-1
2026-09-12 00:59:20.809167 | localhost | Label: ubuntu-noble
2026-09-12 00:59:20.809202 | localhost | Product Name: OpenStack Nova
2026-09-12 00:59:20.809238 | localhost | Interface IP: 162.253.55.126
2026-09-12 00:59:20.826259 | 
2026-09-12 00:59:20.826450 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-12 00:59:21.237314 | localhost -> localhost | changed
2026-09-12 00:59:21.248661 | 
2026-09-12 00:59:21.248869 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-12 00:59:22.134796 | localhost -> localhost | changed
2026-09-12 00:59:22.145974 | 
2026-09-12 00:59:22.146092 | PLAY [all]
2026-09-12 00:59:22.161493 | 
2026-09-12 00:59:22.161624 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-12 00:59:22.368640 | ubuntu-noble -> localhost | ok
2026-09-12 00:59:22.380157 | 
2026-09-12 00:59:22.380279 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-12 00:59:22.414778 | ubuntu-noble | ok
2026-09-12 00:59:22.442467 | ubuntu-noble | included: /var/lib/zuul/builds/e364d3475ae748e3b30d972d8824fd0e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-12 00:59:22.450361 | 
2026-09-12 00:59:22.450438 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-12 00:59:23.447135 | ubuntu-noble -> localhost | Generating public/private rsa key pair.
2026-09-12 00:59:23.447409 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/e364d3475ae748e3b30d972d8824fd0e/work/e364d3475ae748e3b30d972d8824fd0e_id_rsa
2026-09-12 00:59:23.447470 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/e364d3475ae748e3b30d972d8824fd0e/work/e364d3475ae748e3b30d972d8824fd0e_id_rsa.pub
2026-09-12 00:59:23.447514 | ubuntu-noble -> localhost | The key fingerprint is:
2026-09-12 00:59:23.447555 | ubuntu-noble -> localhost | SHA256:Io6krU4kFBS7/aUB2pUzO0LAsoyPmG8H4Ow81msi+/w zuul-build-sshkey
2026-09-12 00:59:23.447618 | ubuntu-noble -> localhost | The key's randomart image is:
2026-09-12 00:59:23.447659 | ubuntu-noble -> localhost | +---[RSA 3072]----+
2026-09-12 00:59:23.447728 | ubuntu-noble -> localhost | |o=.              |
2026-09-12 00:59:23.447774 | ubuntu-noble -> localhost | |..+   .          |
2026-09-12 00:59:23.447813 | ubuntu-noble -> localhost | |++ o =           |
2026-09-12 00:59:23.447852 | ubuntu-noble -> localhost | |=.* o +          |
2026-09-12 00:59:23.447890 | ubuntu-noble -> localhost | |=O.+.+..S        |
2026-09-12 00:59:23.447927 | ubuntu-noble -> localhost | |**ooo.=.         |
2026-09-12 00:59:23.447963 | ubuntu-noble -> localhost | |+o+..o           |
2026-09-12 00:59:23.447999 | ubuntu-noble -> localhost | |oOoo.            |
2026-09-12 00:59:23.448041 | ubuntu-noble -> localhost | |*=*+E            |
2026-09-12 00:59:23.448081 | ubuntu-noble -> localhost | +----[SHA256]-----+
2026-09-12 00:59:23.448166 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.583730
2026-09-12 00:59:23.459339 | 
2026-09-12 00:59:23.459478 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-12 00:59:23.494447 | ubuntu-noble | ok
2026-09-12 00:59:23.512647 | ubuntu-noble | included: /var/lib/zuul/builds/e364d3475ae748e3b30d972d8824fd0e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-12 00:59:23.523694 | 
2026-09-12 00:59:23.523783 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-12 00:59:23.547416 | ubuntu-noble | skipping: Conditional result was False
2026-09-12 00:59:23.558311 | 
2026-09-12 00:59:23.558439 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-12 00:59:24.139764 | ubuntu-noble | changed
2026-09-12 00:59:24.150659 | 
2026-09-12 00:59:24.150804 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-12 00:59:24.380448 | ubuntu-noble | ok
2026-09-12 00:59:24.390876 | 
2026-09-12 00:59:24.390997 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-12 00:59:25.014154 | ubuntu-noble | changed
2026-09-12 00:59:25.024835 | 
2026-09-12 00:59:25.024959 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-12 00:59:25.608942 | ubuntu-noble | changed
2026-09-12 00:59:25.621218 | 
2026-09-12 00:59:25.621337 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-12 00:59:25.646348 | ubuntu-noble | skipping: Conditional result was False
2026-09-12 00:59:25.657371 | 
2026-09-12 00:59:25.657502 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-12 00:59:26.016578 | ubuntu-noble -> localhost | changed
2026-09-12 00:59:26.037621 | 
2026-09-12 00:59:26.037792 | TASK [add-build-sshkey : Add back temp key]
2026-09-12 00:59:26.346449 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/e364d3475ae748e3b30d972d8824fd0e/work/e364d3475ae748e3b30d972d8824fd0e_id_rsa (zuul-build-sshkey)
2026-09-12 00:59:26.346772 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014339
2026-09-12 00:59:26.357354 | 
2026-09-12 00:59:26.357473 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-12 00:59:26.750200 | ubuntu-noble | ok
2026-09-12 00:59:26.761051 | 
2026-09-12 00:59:26.761250 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-12 00:59:26.787138 | ubuntu-noble | skipping: Conditional result was False
2026-09-12 00:59:26.808903 | 
2026-09-12 00:59:26.809102 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-12 00:59:27.228050 | ubuntu-noble | ok
2026-09-12 00:59:27.282667 | 
2026-09-12 00:59:27.282823 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-12 00:59:28.097891 | ubuntu-noble | Output suppressed because no_log was given
2026-09-12 00:59:28.116867 | 
2026-09-12 00:59:28.117005 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-12 00:59:28.365213 | ubuntu-noble | ok: "logs"
2026-09-12 00:59:28.365652 | ubuntu-noble | ok: All items complete
2026-09-12 00:59:28.365751 | 
2026-09-12 00:59:28.569925 | ubuntu-noble | ok: "artifacts"
2026-09-12 00:59:28.761133 | ubuntu-noble | ok: "docs"
2026-09-12 00:59:28.780119 | 
2026-09-12 00:59:28.780295 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-12 00:59:29.030645 | ubuntu-noble | changed: "logs"
2026-09-12 00:59:29.257223 | ubuntu-noble | changed: "artifacts"
2026-09-12 00:59:29.480642 | ubuntu-noble | changed: "docs"
2026-09-12 00:59:29.505641 | 
2026-09-12 00:59:29.505815 | PLAY RECAP
2026-09-12 00:59:29.505901 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-12 00:59:29.505958 | ubuntu-noble | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-12 00:59:29.506000 | 
2026-09-12 00:59:29.668427 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-12 00:59:29.669609 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/ansible-test/pre.yaml@main]
2026-09-12 00:59:30.218457 | 
2026-09-12 00:59:30.218586 | PLAY [all]
2026-09-12 00:59:30.230191 | 
2026-09-12 00:59:30.230267 | TASK [setup-uv : Extract archive]
2026-09-12 00:59:33.242085 | ubuntu-noble | changed
2026-09-12 00:59:33.253755 | 
2026-09-12 00:59:33.253908 | TASK [setup-uv : Print version]
2026-09-12 00:59:33.659097 | ubuntu-noble | uv 0.8.13
2026-09-12 00:59:33.800910 | ubuntu-noble | ok: Runtime: 0:00:00.019205
2026-09-12 00:59:33.812696 | 
2026-09-12 00:59:33.812890 | TASK [Install Galaxy collection]
2026-09-12 00:59:34.194371 | ubuntu-noble | Using CPython 3.12.3 interpreter at: /usr/bin/python3
2026-09-12 00:59:34.194544 | ubuntu-noble | Creating virtual environment at: .venv
2026-09-12 00:59:34.490187 | ubuntu-noble | Downloading ansible-core (2.3MiB)
2026-09-12 00:59:34.490435 | ubuntu-noble | Downloading pygments (1.2MiB)
2026-09-12 00:59:34.491735 | ubuntu-noble | Downloading cryptography (4.3MiB)
2026-09-12 00:59:34.660900 | ubuntu-noble |  Downloading cryptography
2026-09-12 00:59:34.715202 | ubuntu-noble |  Downloading pygments
2026-09-12 00:59:34.897688 | ubuntu-noble |  Downloading ansible-core
2026-09-12 00:59:34.953426 | ubuntu-noble | Installed 37 packages in 55ms
2026-09-12 01:00:22.423253 | ubuntu-noble | [WARNING]: Skipping './.venv/lib64' as it is a symbolic link to a directory outside the collection
2026-09-12 01:01:38.688263 | ubuntu-noble | [ERROR]: Failed to download collection tar from 'default' due to the following unforeseen error: The read operation timed out
2026-09-12 01:01:38.688545 | ubuntu-noble | Starting galaxy collection install process
2026-09-12 01:01:38.688580 | ubuntu-noble | Process install dependency map
2026-09-12 01:01:38.688593 | ubuntu-noble | Starting collection install process
2026-09-12 01:01:38.688604 | ubuntu-noble | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-12 01:01:38.688616 | ubuntu-noble | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-12 01:01:38.688694 | ubuntu-noble | vexxhost.containers:1.6.8 was installed successfully
2026-09-12 01:01:38.688740 | ubuntu-noble | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1074nj429e7a/tmprfilsifc/ansible-posix-2.2.2-p53uupw4
2026-09-12 01:01:38.688761 | ubuntu-noble | Installing 'ansible.posix:2.2.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-12 01:01:38.688773 | ubuntu-noble | ansible.posix:2.2.2 was installed successfully
2026-09-12 01:01:38.688784 | ubuntu-noble | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-general-13.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-1074nj429e7a/tmprfilsifc/community-general-13.4.0-mchlt9c8
2026-09-12 01:01:38.915638 | ubuntu-noble | ERROR
2026-09-12 01:01:38.915872 | ubuntu-noble | {
2026-09-12 01:01:38.915933 | ubuntu-noble |   "delta": "0:02:04.751155",
2026-09-12 01:01:38.915977 | ubuntu-noble |   "end": "2026-09-12 01:01:38.837083",
2026-09-12 01:01:38.916015 | ubuntu-noble |   "msg": "non-zero return code",
2026-09-12 01:01:38.916051 | ubuntu-noble |   "rc": 1,
2026-09-12 01:01:38.916090 | ubuntu-noble |   "start": "2026-09-12 00:59:34.085928"
2026-09-12 01:01:38.916125 | ubuntu-noble | }
failure
2026-09-12 01:01:38.918521 | 
2026-09-12 01:01:38.918608 | PLAY RECAP
2026-09-12 01:01:38.918685 | ubuntu-noble | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-12 01:01:38.918725 | 
2026-09-12 01:01:39.035371 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/ansible-test/pre.yaml@main]
2026-09-12 01:01:39.037498 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-12 01:01:39.591396 | 
2026-09-12 01:01:39.591511 | PLAY [all]
2026-09-12 01:01:39.602695 | 
2026-09-12 01:01:39.602767 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-12 01:01:39.646565 | ubuntu-noble | skipping: Conditional result was False
2026-09-12 01:01:39.652590 | 
2026-09-12 01:01:39.652670 | TASK [fetch-output : Set log path for single node]
2026-09-12 01:01:39.692380 | ubuntu-noble | ok
2026-09-12 01:01:39.698873 | 
2026-09-12 01:01:39.698971 | LOOP [fetch-output : Ensure local output dirs]
2026-09-12 01:01:40.112361 | ubuntu-noble -> localhost | ok: "/var/lib/zuul/builds/e364d3475ae748e3b30d972d8824fd0e/work/logs"
2026-09-12 01:01:40.334652 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/e364d3475ae748e3b30d972d8824fd0e/work/artifacts"
2026-09-12 01:01:40.544453 | ubuntu-noble -> localhost | changed: "/var/lib/zuul/builds/e364d3475ae748e3b30d972d8824fd0e/work/docs"
2026-09-12 01:01:40.568898 | 
2026-09-12 01:01:40.569093 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-12 01:01:41.272284 | ubuntu-noble | changed: .d..t...... ./
2026-09-12 01:01:41.272702 | ubuntu-noble | changed: All items complete
2026-09-12 01:01:41.272788 | 
2026-09-12 01:01:41.818314 | ubuntu-noble | changed: .d..t...... ./
2026-09-12 01:01:42.337757 | ubuntu-noble | changed: .d..t...... ./
2026-09-12 01:01:42.365248 | 
2026-09-12 01:01:42.365496 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-12 01:01:42.830597 | ubuntu-noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.006552
2026-09-12 01:01:43.063834 | ubuntu-noble -> localhost | ok: Item: docs Runtime: 0:00:00.006864
2026-09-12 01:01:43.089562 | 
2026-09-12 01:01:43.089926 | PLAY [all]
2026-09-12 01:01:43.101933 | 
2026-09-12 01:01:43.102062 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-12 01:01:43.635286 | ubuntu-noble | changed
2026-09-12 01:01:43.646398 | 
2026-09-12 01:01:43.646498 | PLAY RECAP
2026-09-12 01:01:43.646579 | ubuntu-noble | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-12 01:01:43.646620 | 
2026-09-12 01:01:43.766292 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-12 01:01:43.768189 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-12 01:01:44.301084 | 
2026-09-12 01:01:44.301223 | PLAY [localhost]
2026-09-12 01:01:44.311488 | 
2026-09-12 01:01:44.311566 | TASK [Generate Zuul manifest]
2026-09-12 01:01:44.331504 | localhost | ok
2026-09-12 01:01:44.344643 | 
2026-09-12 01:01:44.344720 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-12 01:01:44.730071 | localhost | changed
2026-09-12 01:01:44.750161 | 
2026-09-12 01:01:44.750333 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-12 01:01:44.792033 | localhost | ok
2026-09-12 01:01:44.806865 | 
2026-09-12 01:01:44.806996 | TASK [Upload logs]
2026-09-12 01:01:44.829237 | localhost | ok
2026-09-12 01:01:44.896471 | 
2026-09-12 01:01:44.896617 | TASK [Set zuul-log-path fact]
2026-09-12 01:01:44.915273 | localhost | ok
2026-09-12 01:01:44.928565 | 
2026-09-12 01:01:44.928628 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-12 01:01:44.956743 | localhost | ok
2026-09-12 01:01:44.965089 | 
2026-09-12 01:01:44.965172 | TASK [upload-logs : Create log directories]
2026-09-12 01:01:45.414597 | localhost | changed
2026-09-12 01:01:45.427772 | 
2026-09-12 01:01:45.427914 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-12 01:01:45.866634 | localhost -> localhost | ok: Runtime: 0:00:00.006118
2026-09-12 01:01:45.875569 | 
2026-09-12 01:01:45.875695 | TASK [upload-logs : Upload logs to log server]
2026-09-12 01:01:46.363615 | localhost | Output suppressed because no_log was given
2026-09-12 01:01:46.370922 | 
2026-09-12 01:01:46.371063 | LOOP [upload-logs : Compress console log and json output]
2026-09-12 01:01:46.432970 | localhost | skipping: Conditional result was False
2026-09-12 01:01:46.441328 | localhost | skipping: Conditional result was False
2026-09-12 01:01:46.454927 | 
2026-09-12 01:01:46.455144 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-12 01:01:46.498405 | localhost | skipping: Conditional result was False
2026-09-12 01:01:46.498825 | 
2026-09-12 01:01:46.502050 | localhost | skipping: Conditional result was False
2026-09-12 01:01:46.518648 | 
2026-09-12 01:01:46.518828 | LOOP [upload-logs : Upload console log and json output]