2026-05-21 04:26:43.348180 | Job console starting
2026-05-21 04:26:43.371827 | Updating git repos
2026-05-21 04:26:43.404679 | Cloning repos into workspace
2026-05-21 04:26:44.259577 | Restoring repo states
2026-05-21 04:26:44.274475 | Merging changes
2026-05-21 04:26:45.471057 | Checking out repos
2026-05-21 04:26:45.894440 | Preparing playbooks
2026-05-21 04:27:05.721475 | Running Ansible setup
2026-05-21 04:27:10.164124 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 04:27:10.803649 | 
2026-05-21 04:27:10.803777 | PLAY [localhost]
2026-05-21 04:27:10.823100 | 
2026-05-21 04:27:10.823225 | TASK [Gathering Facts]
2026-05-21 04:27:11.928726 | localhost | ok
2026-05-21 04:27:11.940498 | 
2026-05-21 04:27:11.940635 | TASK [Setup log path fact]
2026-05-21 04:27:11.971680 | localhost | ok
2026-05-21 04:27:11.984228 | 
2026-05-21 04:27:11.984341 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 04:27:12.013366 | localhost | ok
2026-05-21 04:27:12.019594 | 
2026-05-21 04:27:12.019666 | TASK [emit-job-header : Print job information]
2026-05-21 04:27:12.068751 | # Job Information
2026-05-21 04:27:12.068947 | Ansible Version: 2.16.18
2026-05-21 04:27:12.069003 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-05-21 04:27:12.069027 | Pipeline: check
2026-05-21 04:27:12.069049 | Executor: 2d72f0692154
2026-05-21 04:27:12.069070 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/126
2026-05-21 04:27:12.069092 | Event ID: 29d96d20-54cd-11f1-9691-babf9ae811a4
2026-05-21 04:27:12.071967 | 
2026-05-21 04:27:12.072035 | LOOP [emit-job-header : Print node information]
2026-05-21 04:27:12.557948 | localhost | ok:
2026-05-21 04:27:12.558122 | localhost | # Node Information
2026-05-21 04:27:12.558152 | localhost | Inventory Hostname: rockylinux-9
2026-05-21 04:27:12.558175 | localhost | Hostname: np0000175804
2026-05-21 04:27:12.558196 | localhost | Username: zuul
2026-05-21 04:27:12.558220 | localhost | Distro: Rocky 9.7
2026-05-21 04:27:12.558240 | localhost | Provider: yul1
2026-05-21 04:27:12.558259 | localhost | Region: ca-ymq-1
2026-05-21 04:27:12.558277 | localhost | Label: rockylinux-9
2026-05-21 04:27:12.558307 | localhost | Product Name: OpenStack Nova
2026-05-21 04:27:12.558326 | localhost | Interface IP: 199.204.45.242
2026-05-21 04:27:12.573131 | 
2026-05-21 04:27:12.573260 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-21 04:27:13.123205 | localhost -> localhost | changed
2026-05-21 04:27:13.129065 | 
2026-05-21 04:27:13.440900 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-21 04:27:14.914111 | localhost -> localhost | changed
2026-05-21 04:27:14.922335 | 
2026-05-21 04:27:14.922491 | PLAY [all]
2026-05-21 04:27:14.935000 | 
2026-05-21 04:27:14.935144 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-21 04:27:15.502689 | rockylinux-9 -> localhost | ok
2026-05-21 04:27:15.511447 | 
2026-05-21 04:27:15.511758 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-21 04:27:15.559279 | rockylinux-9 | ok
2026-05-21 04:27:15.581337 | rockylinux-9 | included: /var/lib/zuul/builds/5bab2753f09d452cbfec122b67761e51/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-21 04:27:15.589136 | 
2026-05-21 04:27:15.589281 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-21 04:27:17.337570 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-05-21 04:27:17.337813 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/5bab2753f09d452cbfec122b67761e51/work/5bab2753f09d452cbfec122b67761e51_id_rsa
2026-05-21 04:27:17.337848 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/5bab2753f09d452cbfec122b67761e51/work/5bab2753f09d452cbfec122b67761e51_id_rsa.pub
2026-05-21 04:27:17.337872 | rockylinux-9 -> localhost | The key fingerprint is:
2026-05-21 04:27:17.337895 | rockylinux-9 -> localhost | SHA256:uQ0Wz8vNy1vqqBumw1/asrbjWWYBiOFMH8cNS6dU/qw zuul-build-sshkey
2026-05-21 04:27:17.337933 | rockylinux-9 -> localhost | The key's randomart image is:
2026-05-21 04:27:17.337955 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-05-21 04:27:17.337983 | rockylinux-9 -> localhost | |    o ..=+o      |
2026-05-21 04:27:17.338005 | rockylinux-9 -> localhost | |   + + *.=.      |
2026-05-21 04:27:17.338027 | rockylinux-9 -> localhost | |    + o = .      |
2026-05-21 04:27:17.338048 | rockylinux-9 -> localhost | |         * o     |
2026-05-21 04:27:17.338069 | rockylinux-9 -> localhost | |        S + o    |
2026-05-21 04:27:17.338089 | rockylinux-9 -> localhost | |       . = *     |
2026-05-21 04:27:17.338110 | rockylinux-9 -> localhost | |     .  + E o .  |
2026-05-21 04:27:17.338130 | rockylinux-9 -> localhost | |      oo+X o +   |
2026-05-21 04:27:17.338153 | rockylinux-9 -> localhost | |      .=XBo.*.   |
2026-05-21 04:27:17.338174 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-05-21 04:27:17.338230 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.299911
2026-05-21 04:27:17.343174 | 
2026-05-21 04:27:17.343237 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-21 04:27:17.375256 | rockylinux-9 | ok
2026-05-21 04:27:17.384965 | rockylinux-9 | included: /var/lib/zuul/builds/5bab2753f09d452cbfec122b67761e51/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-21 04:27:17.392129 | 
2026-05-21 04:27:17.392195 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-21 04:27:17.416699 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 04:27:17.425785 | 
2026-05-21 04:27:17.425883 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-21 04:27:17.940560 | rockylinux-9 | changed
2026-05-21 04:27:17.945262 | 
2026-05-21 04:27:17.945326 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-21 04:27:18.208292 | rockylinux-9 | ok
2026-05-21 04:27:18.517397 | 
2026-05-21 04:27:18.517546 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-21 04:27:19.493941 | rockylinux-9 | changed
2026-05-21 04:27:19.503934 | 
2026-05-21 04:27:19.504204 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-21 04:27:20.431713 | rockylinux-9 | changed
2026-05-21 04:27:20.693693 | 
2026-05-21 04:27:20.693799 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-21 04:27:20.718567 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 04:27:20.732410 | 
2026-05-21 04:27:20.732627 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-21 04:27:21.444590 | rockylinux-9 -> localhost | changed
2026-05-21 04:27:21.465829 | 
2026-05-21 04:27:21.465924 | TASK [add-build-sshkey : Add back temp key]
2026-05-21 04:27:22.100089 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/5bab2753f09d452cbfec122b67761e51/work/5bab2753f09d452cbfec122b67761e51_id_rsa (zuul-build-sshkey)
2026-05-21 04:27:22.100388 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.020043
2026-05-21 04:27:22.106974 | 
2026-05-21 04:27:22.107131 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-21 04:27:22.550154 | rockylinux-9 | ok
2026-05-21 04:27:22.556652 | 
2026-05-21 04:27:22.556733 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-21 04:27:22.595780 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 04:27:22.620541 | 
2026-05-21 04:27:22.620757 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-21 04:27:23.108337 | rockylinux-9 | ok
2026-05-21 04:27:23.288657 | 
2026-05-21 04:27:23.288842 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-21 04:27:24.488867 | rockylinux-9 | Output suppressed because no_log was given
2026-05-21 04:27:24.507756 | 
2026-05-21 04:27:24.507905 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-21 04:27:24.776840 | rockylinux-9 | ok: "logs"
2026-05-21 04:27:24.777172 | rockylinux-9 | ok: All items complete
2026-05-21 04:27:24.777204 | 
2026-05-21 04:27:24.998934 | rockylinux-9 | ok: "artifacts"
2026-05-21 04:27:25.258817 | rockylinux-9 | ok: "docs"
2026-05-21 04:27:25.275352 | 
2026-05-21 04:27:25.275705 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-21 04:27:25.583774 | rockylinux-9 | changed: "logs"
2026-05-21 04:27:25.891755 | rockylinux-9 | changed: "artifacts"
2026-05-21 04:27:26.042997 | rockylinux-9 | changed: "docs"
2026-05-21 04:27:26.063037 | 
2026-05-21 04:27:26.063163 | PLAY RECAP
2026-05-21 04:27:26.063225 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 04:27:26.063269 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-21 04:27:26.063299 | 
2026-05-21 04:27:26.261325 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 04:27:26.706315 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 04:27:27.478962 | 
2026-05-21 04:27:27.894951 | PLAY [all]
2026-05-21 04:27:27.944295 | 
2026-05-21 04:27:27.944705 | TASK [setup-uv : Extract archive]
2026-05-21 04:27:30.721827 | rockylinux-9 | changed
2026-05-21 04:27:31.326182 | 
2026-05-21 04:27:31.326344 | TASK [setup-uv : Print version]
2026-05-21 04:27:31.348653 | [rockylinux-9] Waiting on logger
2026-05-21 04:27:31.865346 | rockylinux-9 | ok: Runtime: 0:00:00.014852
2026-05-21 04:27:31.966309 | 
2026-05-21 04:27:31.966436 | TASK [Install Ansible collection siblings]
2026-05-21 04:27:32.434721 | rockylinux-9 | ok:
2026-05-21 04:27:32.434991 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling
2026-05-21 04:27:32.435039 | rockylinux-9 | Skipping community.general: no checked-out sibling
2026-05-21 04:27:32.438005 | 
2026-05-21 04:27:32.438241 | PLAY RECAP
2026-05-21 04:27:32.438370 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 04:27:32.438426 | 
2026-05-21 04:27:32.581110 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 04:27:32.582591 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 04:27:33.309589 | 
2026-05-21 04:27:33.309887 | PLAY [all]
2026-05-21 04:27:33.321808 | 
2026-05-21 04:27:33.321927 | TASK [Copy inventory file for Zuul]
2026-05-21 04:27:34.696859 | rockylinux-9 | changed
2026-05-21 04:27:35.075669 | 
2026-05-21 04:27:35.075805 | TASK [Switch "ansible_host" to private IP]
2026-05-21 04:27:35.495200 | rockylinux-9 | changed: 1 replacements made
2026-05-21 04:27:35.501100 | 
2026-05-21 04:27:35.501192 | TASK [Run Molecule scenario]
2026-05-21 04:27:35.530171 | [rockylinux-9] Waiting on logger
2026-05-21 04:27:42.662044 | [rockylinux-9] Waiting on logger
2026-05-21 04:27:52.837921 | [rockylinux-9] Waiting on logger
2026-05-21 04:28:03.014089 | [rockylinux-9] Waiting on logger
2026-05-21 04:28:12.678154 | [rockylinux-9] Waiting on logger
2026-05-21 04:28:22.870728 | [rockylinux-9] Waiting on logger
2026-05-21 04:28:32.806030 | [rockylinux-9] Waiting on logger
2026-05-21 04:28:43.337939 | [rockylinux-9] Waiting on logger
2026-05-21 04:28:52.485794 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:02.662102 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:12.837952 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:22.918374 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:32.838129 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:43.014314 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:48.718071 | rockylinux-9 | ok: Runtime: 0:02:12.266841
2026-05-21 04:29:48.725371 | 
2026-05-21 04:29:48.725429 | PLAY RECAP
2026-05-21 04:29:48.725472 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 04:29:48.725495 | 
2026-05-21 04:29:48.891321 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 04:29:48.894372 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 04:29:49.529590 | 
2026-05-21 04:29:49.529744 | PLAY [all]
2026-05-21 04:29:49.541261 | 
2026-05-21 04:29:49.541365 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-21 04:29:49.587634 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 04:29:49.597732 | 
2026-05-21 04:29:49.597844 | TASK [fetch-output : Set log path for single node]
2026-05-21 04:29:49.642285 | rockylinux-9 | ok
2026-05-21 04:29:49.648840 | 
2026-05-21 04:29:49.648954 | LOOP [fetch-output : Ensure local output dirs]
2026-05-21 04:29:50.061391 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/5bab2753f09d452cbfec122b67761e51/work/logs"
2026-05-21 04:29:50.284191 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5bab2753f09d452cbfec122b67761e51/work/artifacts"
2026-05-21 04:29:51.685419 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5bab2753f09d452cbfec122b67761e51/work/docs"
2026-05-21 04:29:51.708843 | 
2026-05-21 04:29:51.709092 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-21 04:29:53.257125 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 04:29:53.257372 | rockylinux-9 | changed: All items complete
2026-05-21 04:29:53.257401 | 
2026-05-21 04:29:55.151718 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 04:29:55.687212 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 04:29:55.713626 | 
2026-05-21 04:29:55.713795 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-21 04:29:56.166677 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008091
2026-05-21 04:29:56.412704 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008345
2026-05-21 04:29:56.434175 | 
2026-05-21 04:29:56.434314 | PLAY [all]
2026-05-21 04:29:56.441840 | 
2026-05-21 04:29:56.441908 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-21 04:29:56.926571 | rockylinux-9 | changed
2026-05-21 04:29:56.931540 | 
2026-05-21 04:29:56.931589 | PLAY RECAP
2026-05-21 04:29:56.931634 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-21 04:29:56.931657 | 
2026-05-21 04:29:57.087316 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 04:29:57.090156 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-21 04:29:57.702322 | 
2026-05-21 04:29:57.702794 | PLAY [localhost]
2026-05-21 04:29:57.713655 | 
2026-05-21 04:29:57.713731 | TASK [Generate Zuul manifest]
2026-05-21 04:29:57.736643 | localhost | ok
2026-05-21 04:29:57.753876 | 
2026-05-21 04:29:57.754001 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-21 04:29:58.132335 | localhost | changed
2026-05-21 04:29:58.145728 | 
2026-05-21 04:29:58.145866 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-21 04:29:58.733162 | localhost | ok
2026-05-21 04:29:59.071239 | 
2026-05-21 04:29:59.071371 | TASK [Upload logs]
2026-05-21 04:29:59.090496 | localhost | ok
2026-05-21 04:29:59.148051 | 
2026-05-21 04:29:59.148198 | TASK [Set zuul-log-path fact]
2026-05-21 04:29:59.170184 | localhost | ok
2026-05-21 04:29:59.182907 | 
2026-05-21 04:29:59.183008 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 04:29:59.213840 | localhost | ok
2026-05-21 04:29:59.223342 | 
2026-05-21 04:29:59.223422 | TASK [upload-logs : Create log directories]
2026-05-21 04:29:59.682434 | localhost | changed
2026-05-21 04:29:59.689484 | 
2026-05-21 04:29:59.689580 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-21 04:30:00.142107 | localhost -> localhost | ok: Runtime: 0:00:00.005122
2026-05-21 04:30:00.150410 | 
2026-05-21 04:30:00.150613 | TASK [upload-logs : Upload logs to log server]
2026-05-21 04:30:00.636137 | localhost | Output suppressed because no_log was given
2026-05-21 04:30:00.642047 | 
2026-05-21 04:30:00.642177 | LOOP [upload-logs : Compress console log and json output]
2026-05-21 04:30:00.688722 | localhost | skipping: Conditional result was False
2026-05-21 04:30:00.697634 | localhost | skipping: Conditional result was False
2026-05-21 04:30:00.708053 | 
2026-05-21 04:30:00.708219 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-21 04:30:00.759445 | localhost | skipping: Conditional result was False
2026-05-21 04:30:00.759863 | 
2026-05-21 04:30:00.765085 | localhost | skipping: Conditional result was False
2026-05-21 04:30:00.780491 | 
2026-05-21 04:30:00.780659 | LOOP [upload-logs : Upload console log and json output]