2026-03-09 15:22:45.757148 | Job console starting
2026-03-09 15:22:45.772450 | Updating git repos
2026-03-09 15:22:45.828141 | Cloning repos into workspace
2026-03-09 15:22:45.983960 | Restoring repo states
2026-03-09 15:22:45.997232 | Merging changes
2026-03-09 15:22:46.275879 | Checking out repos
2026-03-09 15:22:46.335794 | Preparing playbooks
2026-03-09 15:22:47.422250 | Running Ansible setup
2026-03-09 15:22:51.119048 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-09 15:22:51.728226 | 
2026-03-09 15:22:51.728431 | PLAY [localhost]
2026-03-09 15:22:51.735756 | 
2026-03-09 15:22:51.745891 | TASK [Gathering Facts]
2026-03-09 15:22:52.690794 | localhost | ok
2026-03-09 15:22:52.697996 | 
2026-03-09 15:22:52.707967 | TASK [Setup log path fact]
2026-03-09 15:22:52.726611 | localhost | ok
2026-03-09 15:22:52.736574 | 
2026-03-09 15:22:52.737345 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-09 15:22:52.767139 | localhost | ok
2026-03-09 15:22:52.780050 | 
2026-03-09 15:22:52.780173 | TASK [emit-job-header : Print job information]
2026-03-09 15:22:52.809579 | # Job Information
2026-03-09 15:22:52.809758 | Ansible Version: 2.16.16
2026-03-09 15:22:52.809792 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-03-09 15:22:52.809815 | Pipeline: check
2026-03-09 15:22:52.809836 | Executor: 0a8996d2b663
2026-03-09 15:22:52.809857 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/98
2026-03-09 15:22:52.809879 | Event ID: b83b96a0-1bcb-11f1-9a32-4521dd0a5981
2026-03-09 15:22:52.812698 | 
2026-03-09 15:22:52.812793 | LOOP [emit-job-header : Print node information]
2026-03-09 15:22:52.894641 | localhost | ok:
2026-03-09 15:22:52.903141 | localhost | # Node Information
2026-03-09 15:22:52.903246 | localhost | Inventory Hostname: rockylinux-9
2026-03-09 15:22:52.903272 | localhost | Hostname: np0000158186
2026-03-09 15:22:52.903293 | localhost | Username: zuul
2026-03-09 15:22:52.903318 | localhost | Distro: Rocky 9.7
2026-03-09 15:22:52.903341 | localhost | Provider: yul1
2026-03-09 15:22:52.903360 | localhost | Region: ca-ymq-1
2026-03-09 15:22:52.903379 | localhost | Label: rockylinux-9
2026-03-09 15:22:52.903398 | localhost | Product Name: OpenStack Nova
2026-03-09 15:22:52.903416 | localhost | Interface IP: 162.253.55.207
2026-03-09 15:22:52.917815 | 
2026-03-09 15:22:52.917980 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-09 15:22:53.342696 | localhost -> localhost | changed
2026-03-09 15:22:53.350489 | 
2026-03-09 15:22:53.350585 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-09 15:22:54.398634 | localhost -> localhost | changed
2026-03-09 15:22:54.405410 | 
2026-03-09 15:22:54.405467 | PLAY [all]
2026-03-09 15:22:54.413271 | 
2026-03-09 15:22:54.413344 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-09 15:22:54.691388 | rockylinux-9 -> localhost | ok
2026-03-09 15:22:54.731292 | 
2026-03-09 15:22:54.736801 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-09 15:22:54.785111 | rockylinux-9 | ok
2026-03-09 15:22:54.828806 | rockylinux-9 | included: /var/lib/zuul/builds/8f9051d10c0044ec9028fd490549c3b1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-09 15:22:54.833907 | 
2026-03-09 15:22:54.833968 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-09 15:22:56.176297 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-03-09 15:22:56.176606 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/8f9051d10c0044ec9028fd490549c3b1/work/8f9051d10c0044ec9028fd490549c3b1_id_rsa
2026-03-09 15:22:56.176665 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/8f9051d10c0044ec9028fd490549c3b1/work/8f9051d10c0044ec9028fd490549c3b1_id_rsa.pub
2026-03-09 15:22:56.176702 | rockylinux-9 -> localhost | The key fingerprint is:
2026-03-09 15:22:56.176737 | rockylinux-9 -> localhost | SHA256:FERTYGbx6jYtTWno/x3vHFzJP5KH+Qy30r6T4sPxqDk zuul-build-sshkey
2026-03-09 15:22:56.177369 | rockylinux-9 -> localhost | The key's randomart image is:
2026-03-09 15:22:56.177405 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-03-09 15:22:56.177455 | rockylinux-9 -> localhost | |       o@+.      |
2026-03-09 15:22:56.177481 | rockylinux-9 -> localhost | |       + +       |
2026-03-09 15:22:56.177502 | rockylinux-9 -> localhost | |        . .      |
2026-03-09 15:22:56.177523 | rockylinux-9 -> localhost | |       . o .  . .|
2026-03-09 15:22:56.177543 | rockylinux-9 -> localhost | |        S +    o.|
2026-03-09 15:22:56.177563 | rockylinux-9 -> localhost | |       o =   .= o|
2026-03-09 15:22:56.179381 | rockylinux-9 -> localhost | |        * o .***o|
2026-03-09 15:22:56.179411 | rockylinux-9 -> localhost | |       . + E.BBO+|
2026-03-09 15:22:56.179436 | rockylinux-9 -> localhost | |          .+=.*B*|
2026-03-09 15:22:56.179458 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-03-09 15:22:56.179534 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.918123
2026-03-09 15:22:56.192120 | 
2026-03-09 15:22:56.193042 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-09 15:22:56.227817 | rockylinux-9 | ok
2026-03-09 15:22:56.252111 | rockylinux-9 | included: /var/lib/zuul/builds/8f9051d10c0044ec9028fd490549c3b1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-09 15:22:56.260297 | 
2026-03-09 15:22:56.260360 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-09 15:22:56.297485 | rockylinux-9 | skipping: Conditional result was False
2026-03-09 15:22:56.311715 | 
2026-03-09 15:22:56.311792 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-09 15:22:56.790777 | rockylinux-9 | changed
2026-03-09 15:22:56.829871 | 
2026-03-09 15:22:56.830041 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-09 15:22:57.089491 | rockylinux-9 | ok
2026-03-09 15:22:57.095419 | 
2026-03-09 15:22:57.095493 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-09 15:22:57.985897 | rockylinux-9 | changed
2026-03-09 15:22:58.012588 | 
2026-03-09 15:22:58.012694 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-09 15:22:58.911300 | rockylinux-9 | changed
2026-03-09 15:22:58.921850 | 
2026-03-09 15:22:58.921944 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-09 15:22:58.950605 | rockylinux-9 | skipping: Conditional result was False
2026-03-09 15:22:58.961230 | 
2026-03-09 15:22:58.968813 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-09 15:22:59.404459 | rockylinux-9 -> localhost | changed
2026-03-09 15:22:59.419045 | 
2026-03-09 15:22:59.419200 | TASK [add-build-sshkey : Add back temp key]
2026-03-09 15:22:59.881115 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/8f9051d10c0044ec9028fd490549c3b1/work/8f9051d10c0044ec9028fd490549c3b1_id_rsa (zuul-build-sshkey)
2026-03-09 15:22:59.881305 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.011536
2026-03-09 15:22:59.888591 | 
2026-03-09 15:22:59.888657 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-09 15:23:00.286491 | rockylinux-9 | ok
2026-03-09 15:23:00.290711 | 
2026-03-09 15:23:00.290775 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-09 15:23:00.356820 | rockylinux-9 | skipping: Conditional result was False
2026-03-09 15:23:00.366224 | 
2026-03-09 15:23:00.366294 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-09 15:23:00.759738 | rockylinux-9 | ok
2026-03-09 15:23:00.781989 | 
2026-03-09 15:23:00.782065 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-09 15:23:01.853217 | rockylinux-9 | Output suppressed because no_log was given
2026-03-09 15:23:01.861799 | 
2026-03-09 15:23:01.861865 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-09 15:23:02.122048 | rockylinux-9 | ok: "logs"
2026-03-09 15:23:02.122425 | rockylinux-9 | ok: All items complete
2026-03-09 15:23:02.122462 | 
2026-03-09 15:23:02.333442 | rockylinux-9 | ok: "artifacts"
2026-03-09 15:23:02.544701 | rockylinux-9 | ok: "docs"
2026-03-09 15:23:02.563524 | 
2026-03-09 15:23:02.563638 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-09 15:23:02.809110 | rockylinux-9 | changed: "logs"
2026-03-09 15:23:03.025218 | rockylinux-9 | changed: "artifacts"
2026-03-09 15:23:03.245994 | rockylinux-9 | changed: "docs"
2026-03-09 15:23:03.255502 | 
2026-03-09 15:23:03.255593 | PLAY RECAP
2026-03-09 15:23:03.255634 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-09 15:23:03.255664 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-03-09 15:23:03.255684 | 
2026-03-09 15:23:03.540406 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-09 15:23:03.542067 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-09 15:23:04.416379 | 
2026-03-09 15:23:04.416713 | PLAY [all]
2026-03-09 15:23:04.432228 | 
2026-03-09 15:23:04.432314 | TASK [setup-uv : Extract archive]
2026-03-09 15:23:07.225508 | rockylinux-9 | changed
2026-03-09 15:23:07.234251 | 
2026-03-09 15:23:07.234347 | TASK [setup-uv : Print version]
2026-03-09 15:23:07.287788 | [rockylinux-9] Waiting on logger
2026-03-09 15:23:07.817902 | rockylinux-9 | ok: Runtime: 0:00:00.014789
2026-03-09 15:23:07.827193 | 
2026-03-09 15:23:07.827247 | PLAY RECAP
2026-03-09 15:23:07.827294 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-09 15:23:07.827319 | 
2026-03-09 15:23:07.953618 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-09 15:23:07.954866 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-09 15:23:08.710619 | 
2026-03-09 15:23:08.710746 | PLAY [all]
2026-03-09 15:23:08.721750 | 
2026-03-09 15:23:08.721825 | TASK [Copy inventory file for Zuul]
2026-03-09 15:23:10.282776 | rockylinux-9 | changed
2026-03-09 15:23:10.287473 | 
2026-03-09 15:23:10.287547 | TASK [Switch "ansible_host" to private IP]
2026-03-09 15:23:10.640287 | rockylinux-9 | changed: 1 replacements made
2026-03-09 15:23:10.651021 | 
2026-03-09 15:23:10.651107 | TASK [Run Molecule scenario]
2026-03-09 15:23:10.690053 | [rockylinux-9] Waiting on logger
2026-03-09 15:23:18.825815 | [rockylinux-9] Waiting on logger
2026-03-09 15:23:29.001939 | [rockylinux-9] Waiting on logger
2026-03-09 15:23:38.315001 | [rockylinux-9] Waiting on logger
2026-03-09 15:23:48.485872 | [rockylinux-9] Waiting on logger
2026-03-09 15:23:58.661819 | [rockylinux-9] Waiting on logger
2026-03-09 15:24:08.841832 | [rockylinux-9] Waiting on logger
2026-03-09 15:24:19.015261 | [rockylinux-9] Waiting on logger
2026-03-09 15:24:29.193854 | [rockylinux-9] Waiting on logger
2026-03-09 15:24:38.409995 | [rockylinux-9] Waiting on logger
2026-03-09 15:24:48.678061 | [rockylinux-9] Waiting on logger
2026-03-09 15:24:58.853894 | [rockylinux-9] Waiting on logger
2026-03-09 15:25:01.100923 | rockylinux-9 | ok: Runtime: 0:01:49.205637
2026-03-09 15:25:01.108861 | 
2026-03-09 15:25:01.108965 | PLAY RECAP
2026-03-09 15:25:01.109022 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-09 15:25:01.109054 | 
2026-03-09 15:25:01.227591 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-09 15:25:01.229172 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-09 15:25:01.807744 | 
2026-03-09 15:25:01.807872 | PLAY [all]
2026-03-09 15:25:01.818059 | 
2026-03-09 15:25:01.818130 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-09 15:25:01.873639 | rockylinux-9 | skipping: Conditional result was False
2026-03-09 15:25:01.882028 | 
2026-03-09 15:25:01.882182 | TASK [fetch-output : Set log path for single node]
2026-03-09 15:25:01.928823 | rockylinux-9 | ok
2026-03-09 15:25:01.934552 | 
2026-03-09 15:25:01.934652 | LOOP [fetch-output : Ensure local output dirs]
2026-03-09 15:25:02.375686 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/8f9051d10c0044ec9028fd490549c3b1/work/logs"
2026-03-09 15:25:02.588255 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8f9051d10c0044ec9028fd490549c3b1/work/artifacts"
2026-03-09 15:25:02.821454 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8f9051d10c0044ec9028fd490549c3b1/work/docs"
2026-03-09 15:25:02.830581 | 
2026-03-09 15:25:02.830650 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-09 15:25:03.541755 | rockylinux-9 | changed: .d..t...... ./
2026-03-09 15:25:03.542074 | rockylinux-9 | changed: All items complete
2026-03-09 15:25:03.542113 | 
2026-03-09 15:25:04.045315 | rockylinux-9 | changed: .d..t...... ./
2026-03-09 15:25:04.562938 | rockylinux-9 | changed: .d..t...... ./
2026-03-09 15:25:04.582634 | 
2026-03-09 15:25:04.582723 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-09 15:25:04.993464 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007895
2026-03-09 15:25:05.203022 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007032
2026-03-09 15:25:05.211984 | 
2026-03-09 15:25:05.212039 | PLAY [all]
2026-03-09 15:25:05.217815 | 
2026-03-09 15:25:05.217878 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-09 15:25:05.676906 | rockylinux-9 | changed
2026-03-09 15:25:05.683631 | 
2026-03-09 15:25:05.683685 | PLAY RECAP
2026-03-09 15:25:05.683731 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-09 15:25:05.683753 | 
2026-03-09 15:25:05.808533 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-09 15:25:05.810828 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-03-09 15:25:06.354832 | 
2026-03-09 15:25:06.354965 | PLAY [localhost]
2026-03-09 15:25:06.364710 | 
2026-03-09 15:25:06.364788 | TASK [Generate Zuul manifest]
2026-03-09 15:25:06.382881 | localhost | ok
2026-03-09 15:25:06.396807 | 
2026-03-09 15:25:06.396885 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-09 15:25:06.789103 | localhost | changed
2026-03-09 15:25:06.804566 | 
2026-03-09 15:25:06.804646 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-09 15:25:06.836346 | localhost | ok
2026-03-09 15:25:06.845176 | 
2026-03-09 15:25:06.845265 | TASK [Upload logs]
2026-03-09 15:25:06.867489 | localhost | ok
2026-03-09 15:25:06.927243 | 
2026-03-09 15:25:06.927340 | TASK [Set zuul-log-path fact]
2026-03-09 15:25:06.946456 | localhost | ok
2026-03-09 15:25:06.961231 | 
2026-03-09 15:25:06.961298 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-09 15:25:06.989838 | localhost | ok
2026-03-09 15:25:06.996846 | 
2026-03-09 15:25:06.996912 | TASK [upload-logs : Create log directories]
2026-03-09 15:25:07.424771 | localhost | changed
2026-03-09 15:25:07.496650 | 
2026-03-09 15:25:07.496798 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-09 15:25:07.894981 | localhost -> localhost | ok: Runtime: 0:00:00.004869
2026-03-09 15:25:07.902024 | 
2026-03-09 15:25:07.902132 | TASK [upload-logs : Upload logs to log server]
2026-03-09 15:25:08.365910 | localhost | Output suppressed because no_log was given
2026-03-09 15:25:08.369521 | 
2026-03-09 15:25:08.369585 | LOOP [upload-logs : Compress console log and json output]
2026-03-09 15:25:08.415551 | localhost | skipping: Conditional result was False
2026-03-09 15:25:08.422783 | localhost | skipping: Conditional result was False
2026-03-09 15:25:08.430312 | 
2026-03-09 15:25:08.430410 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-09 15:25:08.475334 | localhost | skipping: Conditional result was False
2026-03-09 15:25:08.475751 | 
2026-03-09 15:25:08.479182 | localhost | skipping: Conditional result was False
2026-03-09 15:25:08.492124 | 
2026-03-09 15:25:08.492280 | LOOP [upload-logs : Upload console log and json output]