2026-04-22 13:46:37.262879 | Job console starting 2026-04-22 13:46:37.272591 | Updating git repos 2026-04-22 13:46:37.300852 | Cloning repos into workspace 2026-04-22 13:46:37.389731 | Restoring repo states 2026-04-22 13:46:37.398752 | Merging changes 2026-04-22 13:46:38.053620 | Checking out repos 2026-04-22 13:46:38.187272 | Preparing playbooks 2026-04-22 13:46:40.220133 | Running Ansible setup 2026-04-22 13:46:44.438982 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 13:46:45.165921 | 2026-04-22 13:46:45.353247 | PLAY [localhost] 2026-04-22 13:46:45.368328 | 2026-04-22 13:46:45.368428 | TASK [Gathering Facts] 2026-04-22 13:46:46.239177 | localhost | ok 2026-04-22 13:46:46.246633 | 2026-04-22 13:46:46.246743 | TASK [Setup log path fact] 2026-04-22 13:46:46.289413 | localhost | ok 2026-04-22 13:46:46.300637 | 2026-04-22 13:46:46.300748 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 13:46:46.330578 | localhost | ok 2026-04-22 13:46:46.340417 | 2026-04-22 13:46:46.340522 | TASK [emit-job-header : Print job information] 2026-04-22 13:46:46.390471 | # Job Information 2026-04-22 13:46:46.390657 | Ansible Version: 2.16.16 2026-04-22 13:46:46.390693 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-22 13:46:46.390716 | Pipeline: check 2026-04-22 13:46:46.390736 | Executor: 0a8996d2b663 2026-04-22 13:46:46.390757 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/118 2026-04-22 13:46:46.390779 | Event ID: 87752330-3e51-11f1-97de-a4705693b126 2026-04-22 13:46:46.393595 | 2026-04-22 13:46:46.393661 | LOOP [emit-job-header : Print node information] 2026-04-22 13:46:46.484388 | localhost | ok: 2026-04-22 13:46:46.484562 | localhost | # Node Information 2026-04-22 13:46:46.484593 | localhost | Inventory Hostname: rockylinux-9 2026-04-22 13:46:46.484620 | localhost | Hostname: np0000169451 2026-04-22 13:46:46.484642 | localhost | Username: zuul 2026-04-22 13:46:46.484667 | localhost | Distro: Rocky 9.7 2026-04-22 13:46:46.484688 | localhost | Provider: yul1 2026-04-22 13:46:46.484707 | localhost | Region: ca-ymq-1 2026-04-22 13:46:46.484727 | localhost | Label: rockylinux-9 2026-04-22 13:46:46.484745 | localhost | Product Name: OpenStack Nova 2026-04-22 13:46:46.484764 | localhost | Interface IP: 199.19.213.68 2026-04-22 13:46:46.505551 | 2026-04-22 13:46:46.505688 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-22 13:46:47.062024 | localhost -> localhost | changed 2026-04-22 13:46:47.067003 | 2026-04-22 13:46:47.067079 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-22 13:46:48.152571 | localhost -> localhost | changed 2026-04-22 13:46:48.163630 | 2026-04-22 13:46:48.163771 | PLAY [all] 2026-04-22 13:46:48.174698 | 2026-04-22 13:46:48.174840 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-22 13:46:48.416112 | rockylinux-9 -> localhost | ok 2026-04-22 13:46:48.421952 | 2026-04-22 13:46:48.422065 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-22 13:46:48.459668 | rockylinux-9 | ok 2026-04-22 13:46:48.478346 | rockylinux-9 | included: /var/lib/zuul/builds/8e1ffc22eb6e4b5dbb553ce6c2a4f8f7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-22 13:46:48.484936 | 2026-04-22 13:46:48.613253 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-22 13:46:50.342120 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-22 13:46:50.342354 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/8e1ffc22eb6e4b5dbb553ce6c2a4f8f7/work/8e1ffc22eb6e4b5dbb553ce6c2a4f8f7_id_rsa 2026-04-22 13:46:50.342388 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/8e1ffc22eb6e4b5dbb553ce6c2a4f8f7/work/8e1ffc22eb6e4b5dbb553ce6c2a4f8f7_id_rsa.pub 2026-04-22 13:46:50.342412 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-22 13:46:50.342434 | rockylinux-9 -> localhost | SHA256:1OHZomZc5VU0sN2MlqISYyLUTZwAhaXlzvHAt9xBzBw zuul-build-sshkey 2026-04-22 13:46:50.342475 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-22 13:46:50.342497 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-22 13:46:50.342527 | rockylinux-9 -> localhost | | o*==.=E...o+o| 2026-04-22 13:46:50.342548 | rockylinux-9 -> localhost | | ..=. +++* .o+o| 2026-04-22 13:46:50.342568 | rockylinux-9 -> localhost | | o * * B +.+.o| 2026-04-22 13:46:50.342589 | rockylinux-9 -> localhost | | + @ B + o | 2026-04-22 13:46:50.342609 | rockylinux-9 -> localhost | | o S o | 2026-04-22 13:46:50.342628 | rockylinux-9 -> localhost | | o . | 2026-04-22 13:46:50.342647 | rockylinux-9 -> localhost | | | 2026-04-22 13:46:50.342666 | rockylinux-9 -> localhost | | | 2026-04-22 13:46:50.342688 | rockylinux-9 -> localhost | | | 2026-04-22 13:46:50.342708 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-22 13:46:50.342764 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.165150 2026-04-22 13:46:50.348196 | 2026-04-22 13:46:50.348318 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-22 13:46:50.379079 | rockylinux-9 | ok 2026-04-22 13:46:50.389876 | rockylinux-9 | included: /var/lib/zuul/builds/8e1ffc22eb6e4b5dbb553ce6c2a4f8f7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-22 13:46:50.399330 | 2026-04-22 13:46:50.399423 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-22 13:46:50.426683 | rockylinux-9 | skipping: Conditional result was False 2026-04-22 13:46:50.432302 | 2026-04-22 13:46:50.432377 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-22 13:46:50.951975 | rockylinux-9 | changed 2026-04-22 13:46:51.074934 | 2026-04-22 13:46:51.075095 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-22 13:46:51.352514 | rockylinux-9 | ok 2026-04-22 13:46:51.470641 | 2026-04-22 13:46:51.470799 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-22 13:46:52.430275 | rockylinux-9 | changed 2026-04-22 13:46:52.436424 | 2026-04-22 13:46:52.436502 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-22 13:46:53.380306 | rockylinux-9 | changed 2026-04-22 13:46:53.390325 | 2026-04-22 13:46:53.390415 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-22 13:46:53.414572 | rockylinux-9 | skipping: Conditional result was False 2026-04-22 13:46:53.421144 | 2026-04-22 13:46:53.421241 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-22 13:46:53.891577 | rockylinux-9 -> localhost | changed 2026-04-22 13:46:53.911924 | 2026-04-22 13:46:53.912050 | TASK [add-build-sshkey : Add back temp key] 2026-04-22 13:46:54.300210 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/8e1ffc22eb6e4b5dbb553ce6c2a4f8f7/work/8e1ffc22eb6e4b5dbb553ce6c2a4f8f7_id_rsa (zuul-build-sshkey) 2026-04-22 13:46:54.300397 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.010745 2026-04-22 13:46:54.307196 | 2026-04-22 13:46:54.307256 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-22 13:46:54.714174 | rockylinux-9 | ok 2026-04-22 13:46:54.719325 | 2026-04-22 13:46:54.719387 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-22 13:46:54.743812 | rockylinux-9 | skipping: Conditional result was False 2026-04-22 13:46:54.753560 | 2026-04-22 13:46:54.753732 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-22 13:46:55.180301 | rockylinux-9 | ok 2026-04-22 13:46:55.186461 | 2026-04-22 13:46:55.186530 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-22 13:46:56.174225 | rockylinux-9 | Output suppressed because no_log was given 2026-04-22 13:46:56.182705 | 2026-04-22 13:46:56.182791 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-22 13:46:56.433719 | rockylinux-9 | ok: "logs" 2026-04-22 13:46:56.434108 | rockylinux-9 | ok: All items complete 2026-04-22 13:46:56.434150 | 2026-04-22 13:46:56.657043 | rockylinux-9 | ok: "artifacts" 2026-04-22 13:46:56.881007 | rockylinux-9 | ok: "docs" 2026-04-22 13:46:56.900288 | 2026-04-22 13:46:56.900447 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-22 13:46:57.158351 | rockylinux-9 | changed: "logs" 2026-04-22 13:46:57.382870 | rockylinux-9 | changed: "artifacts" 2026-04-22 13:46:57.596108 | rockylinux-9 | changed: "docs" 2026-04-22 13:46:57.618911 | 2026-04-22 13:46:57.856119 | PLAY RECAP 2026-04-22 13:46:57.856358 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 13:46:57.856438 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-22 13:46:57.856485 | 2026-04-22 13:46:58.122526 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 13:46:58.123762 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 13:46:58.697674 | 2026-04-22 13:46:58.698165 | PLAY [all] 2026-04-22 13:46:58.713574 | 2026-04-22 13:46:58.713684 | TASK [setup-uv : Extract archive] 2026-04-22 13:47:01.679204 | rockylinux-9 | changed 2026-04-22 13:47:01.684934 | 2026-04-22 13:47:01.685109 | TASK [setup-uv : Print version] 2026-04-22 13:47:01.708099 | [rockylinux-9] Waiting on logger 2026-04-22 13:47:02.223887 | rockylinux-9 | ok: Runtime: 0:00:00.014114 2026-04-22 13:47:02.231633 | 2026-04-22 13:47:02.231683 | PLAY RECAP 2026-04-22 13:47:02.231729 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 13:47:02.231753 | 2026-04-22 13:47:02.345490 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 13:47:02.346688 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 13:47:02.935011 | 2026-04-22 13:47:02.935193 | PLAY [all] 2026-04-22 13:47:02.946464 | 2026-04-22 13:47:02.946577 | TASK [Copy inventory file for Zuul] 2026-04-22 13:47:04.262111 | rockylinux-9 | changed 2026-04-22 13:47:04.279270 | 2026-04-22 13:47:04.279380 | TASK [Switch "ansible_host" to private IP] 2026-04-22 13:47:04.623226 | rockylinux-9 | changed: 1 replacements made 2026-04-22 13:47:04.742544 | 2026-04-22 13:47:04.742704 | TASK [Run Molecule scenario] 2026-04-22 13:47:04.773775 | [rockylinux-9] Waiting on logger 2026-04-22 13:47:12.905921 | [rockylinux-9] Waiting on logger 2026-04-22 13:47:23.078174 | [rockylinux-9] Waiting on logger 2026-04-22 13:47:33.254324 | [rockylinux-9] Waiting on logger 2026-04-22 13:47:43.430028 | [rockylinux-9] Waiting on logger 2026-04-22 13:47:53.606358 | [rockylinux-9] Waiting on logger 2026-04-22 13:48:02.763458 | [rockylinux-9] Waiting on logger 2026-04-22 13:48:12.933832 | [rockylinux-9] Waiting on logger 2026-04-22 13:48:23.110123 | [rockylinux-9] Waiting on logger 2026-04-22 13:48:33.286420 | [rockylinux-9] Waiting on logger 2026-04-22 13:48:43.462117 | [rockylinux-9] Waiting on logger 2026-04-22 13:48:53.638222 | [rockylinux-9] Waiting on logger 2026-04-22 13:49:02.800139 | [rockylinux-9] Waiting on logger 2026-04-22 13:49:12.966127 | [rockylinux-9] Waiting on logger 2026-04-22 13:49:23.141868 | [rockylinux-9] Waiting on logger 2026-04-22 13:49:33.318377 | [rockylinux-9] Waiting on logger 2026-04-22 13:49:43.494112 | [rockylinux-9] Waiting on logger 2026-04-22 13:49:53.669923 | [rockylinux-9] Waiting on logger 2026-04-22 13:50:02.827781 | [rockylinux-9] Waiting on logger 2026-04-22 13:50:12.998083 | [rockylinux-9] Waiting on logger 2026-04-22 13:50:23.174237 | [rockylinux-9] Waiting on logger 2026-04-22 13:50:33.350236 | [rockylinux-9] Waiting on logger 2026-04-22 13:50:43.526184 | [rockylinux-9] Waiting on logger 2026-04-22 13:50:53.702165 | [rockylinux-9] Waiting on logger 2026-04-22 13:51:02.854438 | [rockylinux-9] Waiting on logger 2026-04-22 13:51:13.030218 | [rockylinux-9] Waiting on logger 2026-04-22 13:51:23.206334 | [rockylinux-9] Waiting on logger 2026-04-22 13:51:33.382199 | [rockylinux-9] Waiting on logger 2026-04-22 13:51:43.558307 | [rockylinux-9] Waiting on logger 2026-04-22 13:51:52.715660 | [rockylinux-9] Waiting on logger 2026-04-22 13:51:58.317214 | rockylinux-9 | ok: Runtime: 0:04:52.158089 2026-04-22 13:51:58.324320 | 2026-04-22 13:51:58.324378 | PLAY RECAP 2026-04-22 13:51:58.324422 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 13:51:58.324443 | 2026-04-22 13:51:58.440585 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 13:51:58.442796 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 13:51:58.992663 | 2026-04-22 13:51:58.992789 | PLAY [all] 2026-04-22 13:51:59.003230 | 2026-04-22 13:51:59.003305 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-22 13:51:59.047965 | rockylinux-9 | skipping: Conditional result was False 2026-04-22 13:51:59.055414 | 2026-04-22 13:51:59.055522 | TASK [fetch-output : Set log path for single node] 2026-04-22 13:51:59.097815 | rockylinux-9 | ok 2026-04-22 13:51:59.104431 | 2026-04-22 13:51:59.104495 | LOOP [fetch-output : Ensure local output dirs] 2026-04-22 13:51:59.497965 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/8e1ffc22eb6e4b5dbb553ce6c2a4f8f7/work/logs" 2026-04-22 13:51:59.705013 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8e1ffc22eb6e4b5dbb553ce6c2a4f8f7/work/artifacts" 2026-04-22 13:51:59.907471 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8e1ffc22eb6e4b5dbb553ce6c2a4f8f7/work/docs" 2026-04-22 13:51:59.920897 | 2026-04-22 13:51:59.921103 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-22 13:52:00.587554 | rockylinux-9 | changed: .d..t...... ./ 2026-04-22 13:52:00.587835 | rockylinux-9 | changed: All items complete 2026-04-22 13:52:00.587876 | 2026-04-22 13:52:01.085697 | rockylinux-9 | changed: .d..t...... ./ 2026-04-22 13:52:01.588117 | rockylinux-9 | changed: .d..t...... ./ 2026-04-22 13:52:01.611256 | 2026-04-22 13:52:01.611421 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-22 13:52:02.020998 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006162 2026-04-22 13:52:02.236663 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008657 2026-04-22 13:52:02.256100 | 2026-04-22 13:52:02.256221 | PLAY [all] 2026-04-22 13:52:02.262012 | 2026-04-22 13:52:02.262078 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-22 13:52:02.734944 | rockylinux-9 | changed 2026-04-22 13:52:02.741742 | 2026-04-22 13:52:02.741853 | PLAY RECAP 2026-04-22 13:52:02.742107 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-22 13:52:02.742141 | 2026-04-22 13:52:02.894086 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 13:52:02.896170 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-22 13:52:03.477333 | 2026-04-22 13:52:03.477451 | PLAY [localhost] 2026-04-22 13:52:03.486997 | 2026-04-22 13:52:03.487077 | TASK [Generate Zuul manifest] 2026-04-22 13:52:03.504361 | localhost | ok 2026-04-22 13:52:03.518911 | 2026-04-22 13:52:03.518991 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-22 13:52:03.833504 | localhost | changed 2026-04-22 13:52:03.846727 | 2026-04-22 13:52:03.846808 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-22 13:52:03.878922 | localhost | ok 2026-04-22 13:52:03.885760 | 2026-04-22 13:52:03.885835 | TASK [Upload logs] 2026-04-22 13:52:03.906491 | localhost | ok 2026-04-22 13:52:03.964804 | 2026-04-22 13:52:03.964942 | TASK [Set zuul-log-path fact] 2026-04-22 13:52:03.984878 | localhost | ok 2026-04-22 13:52:03.997658 | 2026-04-22 13:52:03.997738 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 13:52:04.029639 | localhost | ok 2026-04-22 13:52:04.040262 | 2026-04-22 13:52:04.040349 | TASK [upload-logs : Create log directories] 2026-04-22 13:52:04.469344 | localhost | changed 2026-04-22 13:52:04.474019 | 2026-04-22 13:52:04.474089 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-22 13:52:04.880275 | localhost -> localhost | ok: Runtime: 0:00:00.006180 2026-04-22 13:52:04.887029 | 2026-04-22 13:52:04.887101 | TASK [upload-logs : Upload logs to log server] 2026-04-22 13:52:05.344112 | localhost | Output suppressed because no_log was given 2026-04-22 13:52:05.349626 | 2026-04-22 13:52:05.349731 | LOOP [upload-logs : Compress console log and json output] 2026-04-22 13:52:05.395024 | localhost | skipping: Conditional result was False 2026-04-22 13:52:05.404818 | localhost | skipping: Conditional result was False 2026-04-22 13:52:05.415344 | 2026-04-22 13:52:05.415522 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-22 13:52:05.457311 | localhost | skipping: Conditional result was False 2026-04-22 13:52:05.457682 | 2026-04-22 13:52:05.461730 | localhost | skipping: Conditional result was False 2026-04-22 13:52:05.477607 | 2026-04-22 13:52:05.477728 | LOOP [upload-logs : Upload console log and json output]