2026-04-17 00:58:01.450947 | Job console starting
2026-04-17 00:58:01.461630 | Updating git repos
2026-04-17 00:58:01.495088 | Cloning repos into workspace
2026-04-17 00:58:01.546773 | Restoring repo states
2026-04-17 00:58:01.557209 | Merging changes
2026-04-17 00:58:02.106507 | Checking out repos
2026-04-17 00:58:02.139385 | Preparing playbooks
2026-04-17 00:58:03.151657 | Running Ansible setup
2026-04-17 00:58:06.784720 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-17 00:58:07.394790 | 
2026-04-17 00:58:07.394947 | PLAY [localhost]
2026-04-17 00:58:07.403542 | 
2026-04-17 00:58:07.403622 | TASK [Gathering Facts]
2026-04-17 00:58:08.304161 | localhost | ok
2026-04-17 00:58:08.315914 | 
2026-04-17 00:58:08.316021 | TASK [Setup log path fact]
2026-04-17 00:58:08.340408 | localhost | ok
2026-04-17 00:58:08.453874 | 
2026-04-17 00:58:08.454002 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-17 00:58:08.484661 | localhost | ok
2026-04-17 00:58:08.493329 | 
2026-04-17 00:58:08.493421 | TASK [emit-job-header : Print job information]
2026-04-17 00:58:08.533017 | # Job Information
2026-04-17 00:58:08.533286 | Ansible Version: 2.16.16
2026-04-17 00:58:08.533357 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9
2026-04-17 00:58:08.533407 | Pipeline: check
2026-04-17 00:58:08.533453 | Executor: 0a8996d2b663
2026-04-17 00:58:08.533497 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109
2026-04-17 00:58:08.533547 | Event ID: 64e04cd0-39f8-11f1-9088-4230c452b1b4
2026-04-17 00:58:08.540171 | 
2026-04-17 00:58:08.540415 | LOOP [emit-job-header : Print node information]
2026-04-17 00:58:08.640340 | localhost | ok:
2026-04-17 00:58:08.640668 | localhost | # Node Information
2026-04-17 00:58:08.640745 | localhost | Inventory Hostname: rockylinux-9
2026-04-17 00:58:08.640811 | localhost | Hostname: np0000166920
2026-04-17 00:58:08.640925 | localhost | Username: zuul
2026-04-17 00:58:08.641017 | localhost | Distro: Rocky 9.7
2026-04-17 00:58:08.641075 | localhost | Provider: yul1
2026-04-17 00:58:08.641150 | localhost | Region: ca-ymq-1
2026-04-17 00:58:08.641270 | localhost | Label: rockylinux-9
2026-04-17 00:58:08.641352 | localhost | Product Name: OpenStack Nova
2026-04-17 00:58:08.641431 | localhost | Interface IP: 199.204.45.242
2026-04-17 00:58:08.652237 | 
2026-04-17 00:58:08.652470 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-17 00:58:09.084195 | localhost -> localhost | changed
2026-04-17 00:58:09.092852 | 
2026-04-17 00:58:09.092948 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-17 00:58:10.045487 | localhost -> localhost | changed
2026-04-17 00:58:10.055547 | 
2026-04-17 00:58:10.055621 | PLAY [all]
2026-04-17 00:58:10.064398 | 
2026-04-17 00:58:10.064464 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-17 00:58:10.322376 | rockylinux-9 -> localhost | ok
2026-04-17 00:58:10.334485 | 
2026-04-17 00:58:10.334556 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-17 00:58:10.369969 | rockylinux-9 | ok
2026-04-17 00:58:10.385550 | rockylinux-9 | included: /var/lib/zuul/builds/c79a07c058b140cea29959120ff0afcd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-17 00:58:10.391030 | 
2026-04-17 00:58:10.391093 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-17 00:58:11.175328 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-17 00:58:11.175533 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/c79a07c058b140cea29959120ff0afcd/work/c79a07c058b140cea29959120ff0afcd_id_rsa
2026-04-17 00:58:11.175565 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/c79a07c058b140cea29959120ff0afcd/work/c79a07c058b140cea29959120ff0afcd_id_rsa.pub
2026-04-17 00:58:11.175589 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-17 00:58:11.175611 | rockylinux-9 -> localhost | SHA256:HD7Akq+ynMrBuCOchB8V1lM3dpcx/O6ZO3zuA4COetk zuul-build-sshkey
2026-04-17 00:58:11.176175 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-17 00:58:11.176208 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-17 00:58:11.176238 | rockylinux-9 -> localhost | |     . .. + ..+o |
2026-04-17 00:58:11.176260 | rockylinux-9 -> localhost | |    ooo  o o .o. |
2026-04-17 00:58:11.176280 | rockylinux-9 -> localhost | |   .o.o.. .    . |
2026-04-17 00:58:11.176300 | rockylinux-9 -> localhost | |    .o + o .    .|
2026-04-17 00:58:11.176319 | rockylinux-9 -> localhost | |.  .  . S   .  . |
2026-04-17 00:58:11.176413 | rockylinux-9 -> localhost | |+..  . . o   .  .|
2026-04-17 00:58:11.176438 | rockylinux-9 -> localhost | |++o.. . o     o.o|
2026-04-17 00:58:11.176459 | rockylinux-9 -> localhost | |+=o+ . o E     *o|
2026-04-17 00:58:11.176482 | rockylinux-9 -> localhost | |+++   .        +B|
2026-04-17 00:58:11.176503 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-17 00:58:11.176559 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.350319
2026-04-17 00:58:11.181653 | 
2026-04-17 00:58:11.181720 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-17 00:58:11.215381 | rockylinux-9 | ok
2026-04-17 00:58:11.224194 | rockylinux-9 | included: /var/lib/zuul/builds/c79a07c058b140cea29959120ff0afcd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-17 00:58:11.232528 | 
2026-04-17 00:58:11.232598 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-17 00:58:11.257536 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 00:58:11.266854 | 
2026-04-17 00:58:11.266958 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-17 00:58:11.829879 | rockylinux-9 | changed
2026-04-17 00:58:11.835556 | 
2026-04-17 00:58:11.835623 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-17 00:58:12.093757 | rockylinux-9 | ok
2026-04-17 00:58:12.098305 | 
2026-04-17 00:58:12.098369 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-17 00:58:13.041669 | rockylinux-9 | changed
2026-04-17 00:58:13.264301 | 
2026-04-17 00:58:13.264438 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-17 00:58:14.205334 | rockylinux-9 | changed
2026-04-17 00:58:14.270905 | 
2026-04-17 00:58:14.271085 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-17 00:58:14.296136 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 00:58:14.301904 | 
2026-04-17 00:58:14.301972 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-17 00:58:14.742207 | rockylinux-9 -> localhost | changed
2026-04-17 00:58:14.792837 | 
2026-04-17 00:58:14.800992 | TASK [add-build-sshkey : Add back temp key]
2026-04-17 00:58:15.152523 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/c79a07c058b140cea29959120ff0afcd/work/c79a07c058b140cea29959120ff0afcd_id_rsa (zuul-build-sshkey)
2026-04-17 00:58:15.152811 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012089
2026-04-17 00:58:15.159331 | 
2026-04-17 00:58:15.159399 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-17 00:58:15.578193 | rockylinux-9 | ok
2026-04-17 00:58:15.582704 | 
2026-04-17 00:58:15.582773 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-17 00:58:15.610628 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 00:58:15.622616 | 
2026-04-17 00:58:15.622692 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-17 00:58:16.071121 | rockylinux-9 | ok
2026-04-17 00:58:16.296615 | 
2026-04-17 00:58:16.296760 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-17 00:58:17.245040 | rockylinux-9 | Output suppressed because no_log was given
2026-04-17 00:58:17.260208 | 
2026-04-17 00:58:17.260333 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-17 00:58:17.524693 | rockylinux-9 | ok: "logs"
2026-04-17 00:58:17.525074 | rockylinux-9 | ok: All items complete
2026-04-17 00:58:17.525115 | 
2026-04-17 00:58:17.745435 | rockylinux-9 | ok: "artifacts"
2026-04-17 00:58:17.991815 | rockylinux-9 | ok: "docs"
2026-04-17 00:58:18.007044 | 
2026-04-17 00:58:18.007281 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-17 00:58:18.272873 | rockylinux-9 | changed: "logs"
2026-04-17 00:58:18.489057 | rockylinux-9 | changed: "artifacts"
2026-04-17 00:58:18.715212 | rockylinux-9 | changed: "docs"
2026-04-17 00:58:18.728304 | 
2026-04-17 00:58:18.729333 | PLAY RECAP
2026-04-17 00:58:18.729420 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-17 00:58:18.729468 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-17 00:58:18.731201 | 
2026-04-17 00:58:18.930401 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-17 00:58:18.933184 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-17 00:58:19.556060 | 
2026-04-17 00:58:19.624999 | PLAY [all]
2026-04-17 00:58:19.642761 | 
2026-04-17 00:58:19.642889 | TASK [setup-uv : Extract archive]
2026-04-17 00:58:22.503535 | rockylinux-9 | changed
2026-04-17 00:58:22.509935 | 
2026-04-17 00:58:22.510020 | TASK [setup-uv : Print version]
2026-04-17 00:58:22.533773 | [rockylinux-9] Waiting on logger
2026-04-17 00:58:23.045740 | rockylinux-9 | ok: Runtime: 0:00:00.008865
2026-04-17 00:58:23.052412 | 
2026-04-17 00:58:23.052466 | PLAY RECAP
2026-04-17 00:58:23.052515 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-17 00:58:23.052540 | 
2026-04-17 00:58:23.174361 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-17 00:58:23.176100 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-17 00:58:23.817142 | 
2026-04-17 00:58:23.817315 | PLAY [all]
2026-04-17 00:58:23.828885 | 
2026-04-17 00:58:23.828983 | TASK [Copy inventory file for Zuul]
2026-04-17 00:58:25.178451 | rockylinux-9 | changed
2026-04-17 00:58:25.212718 | 
2026-04-17 00:58:25.213037 | TASK [Switch "ansible_host" to private IP]
2026-04-17 00:58:25.582276 | rockylinux-9 | changed: 1 replacements made
2026-04-17 00:58:25.588400 | 
2026-04-17 00:58:25.588475 | TASK [Run Molecule scenario]
2026-04-17 00:58:25.609488 | [rockylinux-9] Waiting on logger
2026-04-17 00:58:33.737744 | [rockylinux-9] Waiting on logger
2026-04-17 00:58:43.909878 | [rockylinux-9] Waiting on logger
2026-04-17 00:58:54.086030 | [rockylinux-9] Waiting on logger
2026-04-17 00:59:00.908348 | rockylinux-9 | ERROR
2026-04-17 00:59:00.908619 | rockylinux-9 | {
2026-04-17 00:59:00.908666 | rockylinux-9 |   "delta": "0:00:34.731682",
2026-04-17 00:59:00.908698 | rockylinux-9 |   "end": "2026-04-17 00:59:00.955154",
2026-04-17 00:59:00.908727 | rockylinux-9 |   "msg": "non-zero return code",
2026-04-17 00:59:00.908756 | rockylinux-9 |   "rc": 2,
2026-04-17 00:59:00.908786 | rockylinux-9 |   "start": "2026-04-17 00:58:26.223472"
2026-04-17 00:59:00.908813 | rockylinux-9 | }
failure
2026-04-17 00:59:00.912583 | 
2026-04-17 00:59:00.912650 | PLAY RECAP
2026-04-17 00:59:00.912712 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-17 00:59:00.912744 | 
2026-04-17 00:59:01.024989 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-17 00:59:01.026226 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-17 00:59:01.602216 | 
2026-04-17 00:59:01.602357 | PLAY [all]
2026-04-17 00:59:01.613296 | 
2026-04-17 00:59:01.613431 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-17 00:59:01.658912 | rockylinux-9 | skipping: Conditional result was False
2026-04-17 00:59:01.669614 | 
2026-04-17 00:59:01.669728 | TASK [fetch-output : Set log path for single node]
2026-04-17 00:59:01.713464 | rockylinux-9 | ok
2026-04-17 00:59:01.721445 | 
2026-04-17 00:59:01.721533 | LOOP [fetch-output : Ensure local output dirs]
2026-04-17 00:59:02.487688 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/c79a07c058b140cea29959120ff0afcd/work/logs"
2026-04-17 00:59:02.953907 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/c79a07c058b140cea29959120ff0afcd/work/artifacts"
2026-04-17 00:59:03.423684 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/c79a07c058b140cea29959120ff0afcd/work/docs"
2026-04-17 00:59:03.446461 | 
2026-04-17 00:59:03.447136 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-17 00:59:04.225474 | rockylinux-9 | ok
2026-04-17 00:59:04.246130 | rockylinux-9 | changed: All items complete
2026-04-17 00:59:04.246259 | 
2026-04-17 00:59:04.752805 | rockylinux-9 | changed: .d..t...... ./
2026-04-17 00:59:05.269198 | rockylinux-9 | changed: .d..t...... ./
2026-04-17 00:59:05.291503 | 
2026-04-17 00:59:05.291634 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-17 00:59:05.749867 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007171
2026-04-17 00:59:05.999628 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007979
2026-04-17 00:59:06.012608 | 
2026-04-17 00:59:06.012764 | PLAY [all]
2026-04-17 00:59:06.020932 | 
2026-04-17 00:59:06.021043 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-17 00:59:06.505208 | rockylinux-9 | changed
2026-04-17 00:59:06.511742 | 
2026-04-17 00:59:06.512040 | PLAY RECAP
2026-04-17 00:59:06.512136 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-17 00:59:06.512197 | 
2026-04-17 00:59:06.670983 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-17 00:59:06.672699 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-17 00:59:07.271574 | 
2026-04-17 00:59:07.271724 | PLAY [localhost]
2026-04-17 00:59:07.281883 | 
2026-04-17 00:59:07.281960 | TASK [Generate Zuul manifest]
2026-04-17 00:59:07.300703 | localhost | ok
2026-04-17 00:59:07.315556 | 
2026-04-17 00:59:07.315638 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-17 00:59:07.784760 | localhost | changed
2026-04-17 00:59:07.799482 | 
2026-04-17 00:59:07.799572 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-17 00:59:08.023707 | localhost | ok
2026-04-17 00:59:08.033046 | 
2026-04-17 00:59:08.033118 | TASK [Upload logs]
2026-04-17 00:59:08.052498 | localhost | ok
2026-04-17 00:59:08.112417 | 
2026-04-17 00:59:08.112554 | TASK [Set zuul-log-path fact]
2026-04-17 00:59:08.134598 | localhost | ok
2026-04-17 00:59:08.146841 | 
2026-04-17 00:59:08.146916 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-17 00:59:08.178884 | localhost | ok
2026-04-17 00:59:08.187209 | 
2026-04-17 00:59:08.187280 | TASK [upload-logs : Create log directories]
2026-04-17 00:59:08.878966 | localhost | changed
2026-04-17 00:59:08.885561 | 
2026-04-17 00:59:08.885638 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-17 00:59:09.410782 | localhost -> localhost | ok: Runtime: 0:00:00.006311
2026-04-17 00:59:09.416217 | 
2026-04-17 00:59:09.416287 | TASK [upload-logs : Upload logs to log server]
2026-04-17 00:59:10.287515 | localhost | Output suppressed because no_log was given
2026-04-17 00:59:10.293193 | 
2026-04-17 00:59:10.293302 | LOOP [upload-logs : Compress console log and json output]
2026-04-17 00:59:10.344245 | localhost | skipping: Conditional result was False
2026-04-17 00:59:10.351904 | localhost | skipping: Conditional result was False
2026-04-17 00:59:10.368487 | 
2026-04-17 00:59:10.368706 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-17 00:59:10.411049 | localhost | skipping: Conditional result was False
2026-04-17 00:59:10.411517 | 
2026-04-17 00:59:10.414894 | localhost | skipping: Conditional result was False
2026-04-17 00:59:10.433694 | 
2026-04-17 00:59:10.433858 | LOOP [upload-logs : Upload console log and json output]