2026-04-22 09:26:39.847035 | Job console starting
2026-04-22 09:26:39.865400 | Updating git repos
2026-04-22 09:26:39.900481 | Cloning repos into workspace
2026-04-22 09:26:40.282055 | Restoring repo states
2026-04-22 09:26:40.290756 | Merging changes
2026-04-22 09:26:40.755998 | Checking out repos
2026-04-22 09:26:40.855677 | Preparing playbooks
2026-04-22 09:26:42.889350 | Running Ansible setup
2026-04-22 09:26:47.348764 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-22 09:26:47.927383 | 
2026-04-22 09:26:47.927527 | PLAY [localhost]
2026-04-22 09:26:47.935156 | 
2026-04-22 09:26:47.935229 | TASK [Gathering Facts]
2026-04-22 09:26:48.903368 | localhost | ok
2026-04-22 09:26:48.910431 | 
2026-04-22 09:26:48.910509 | TASK [Setup log path fact]
2026-04-22 09:26:48.933107 | localhost | ok
2026-04-22 09:26:48.944916 | 
2026-04-22 09:26:48.945000 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-22 09:26:48.974818 | localhost | ok
2026-04-22 09:26:48.983478 | 
2026-04-22 09:26:48.983590 | TASK [emit-job-header : Print job information]
2026-04-22 09:26:49.025858 | # Job Information
2026-04-22 09:26:49.026013 | Ansible Version: 2.16.16
2026-04-22 09:26:49.026057 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-22 09:26:49.026089 | Pipeline: check
2026-04-22 09:26:49.026116 | Executor: 0a8996d2b663
2026-04-22 09:26:49.026143 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/118
2026-04-22 09:26:49.026173 | Event ID: 427684f0-3e2d-11f1-83da-a94e8dbaadfb
2026-04-22 09:26:49.029860 | 
2026-04-22 09:26:49.029953 | LOOP [emit-job-header : Print node information]
2026-04-22 09:26:49.127427 | localhost | ok:
2026-04-22 09:26:49.127880 | localhost | # Node Information
2026-04-22 09:26:49.127964 | localhost | Inventory Hostname: rockylinux-9
2026-04-22 09:26:49.128017 | localhost | Hostname: np0000169242
2026-04-22 09:26:49.128064 | localhost | Username: zuul
2026-04-22 09:26:49.128115 | localhost | Distro: Rocky 9.7
2026-04-22 09:26:49.128162 | localhost | Provider: yul1
2026-04-22 09:26:49.128207 | localhost | Region: ca-ymq-1
2026-04-22 09:26:49.128253 | localhost | Label: rockylinux-9
2026-04-22 09:26:49.128297 | localhost | Product Name: OpenStack Nova
2026-04-22 09:26:49.128339 | localhost | Interface IP: 199.19.213.238
2026-04-22 09:26:49.144569 | 
2026-04-22 09:26:49.144742 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-22 09:26:49.609118 | localhost -> localhost | changed
2026-04-22 09:26:49.615818 | 
2026-04-22 09:26:49.615917 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-22 09:26:50.866191 | localhost -> localhost | changed
2026-04-22 09:26:50.874099 | 
2026-04-22 09:26:50.874176 | PLAY [all]
2026-04-22 09:26:50.882017 | 
2026-04-22 09:26:50.882080 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-22 09:26:51.129263 | rockylinux-9 -> localhost | ok
2026-04-22 09:26:51.139482 | 
2026-04-22 09:26:51.139579 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-22 09:26:51.171669 | rockylinux-9 | ok
2026-04-22 09:26:51.189767 | rockylinux-9 | included: /var/lib/zuul/builds/502f64460554406e8a6ca3eb70f9504c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-22 09:26:51.194811 | 
2026-04-22 09:26:51.194873 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-22 09:26:52.262221 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-22 09:26:52.262417 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/502f64460554406e8a6ca3eb70f9504c/work/502f64460554406e8a6ca3eb70f9504c_id_rsa
2026-04-22 09:26:52.262449 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/502f64460554406e8a6ca3eb70f9504c/work/502f64460554406e8a6ca3eb70f9504c_id_rsa.pub
2026-04-22 09:26:52.262472 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-22 09:26:52.262493 | rockylinux-9 -> localhost | SHA256:WIgfID/Xkt9XeBKBRnDvtrwPKrjgupGfmfvQ/SfWCJo zuul-build-sshkey
2026-04-22 09:26:52.262530 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-22 09:26:52.262551 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-22 09:26:52.262575 | rockylinux-9 -> localhost | |  . .   .oo.o.   |
2026-04-22 09:26:52.262597 | rockylinux-9 -> localhost | |   o o + .o. o   |
2026-04-22 09:26:52.262618 | rockylinux-9 -> localhost | |    + * o.  + o  |
2026-04-22 09:26:52.262638 | rockylinux-9 -> localhost | |     + * . . +   |
2026-04-22 09:26:52.262658 | rockylinux-9 -> localhost | |      o S . +    |
2026-04-22 09:26:52.262679 | rockylinux-9 -> localhost | |   . . ..  + .   |
2026-04-22 09:26:52.262699 | rockylinux-9 -> localhost | |  o o .+.. o+    |
2026-04-22 09:26:52.262719 | rockylinux-9 -> localhost | |   + *E ..+.oo   |
2026-04-22 09:26:52.262742 | rockylinux-9 -> localhost | |  ooBoo. ooo...  |
2026-04-22 09:26:52.262763 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-22 09:26:52.262823 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.515567
2026-04-22 09:26:52.267816 | 
2026-04-22 09:26:52.267931 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-22 09:26:52.299512 | rockylinux-9 | ok
2026-04-22 09:26:52.310421 | rockylinux-9 | included: /var/lib/zuul/builds/502f64460554406e8a6ca3eb70f9504c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-22 09:26:52.318072 | 
2026-04-22 09:26:52.318139 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-22 09:26:52.341776 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 09:26:52.349827 | 
2026-04-22 09:26:52.349926 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-22 09:26:52.844194 | rockylinux-9 | changed
2026-04-22 09:26:52.848755 | 
2026-04-22 09:26:52.848821 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-22 09:26:53.114244 | rockylinux-9 | ok
2026-04-22 09:26:53.220432 | 
2026-04-22 09:26:53.220606 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-22 09:26:54.254933 | rockylinux-9 | changed
2026-04-22 09:26:54.260817 | 
2026-04-22 09:26:54.260896 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-22 09:26:55.203817 | rockylinux-9 | changed
2026-04-22 09:26:55.314022 | 
2026-04-22 09:26:55.314152 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-22 09:26:55.343387 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 09:26:55.348998 | 
2026-04-22 09:26:55.349069 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-22 09:26:55.729212 | rockylinux-9 -> localhost | changed
2026-04-22 09:26:55.738865 | 
2026-04-22 09:26:55.738933 | TASK [add-build-sshkey : Add back temp key]
2026-04-22 09:26:56.019906 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/502f64460554406e8a6ca3eb70f9504c/work/502f64460554406e8a6ca3eb70f9504c_id_rsa (zuul-build-sshkey)
2026-04-22 09:26:56.020129 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.018973
2026-04-22 09:26:56.033703 | 
2026-04-22 09:26:56.033874 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-22 09:26:56.488514 | rockylinux-9 | ok
2026-04-22 09:26:56.492825 | 
2026-04-22 09:26:56.492886 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-22 09:26:56.517683 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 09:26:56.531807 | 
2026-04-22 09:26:56.531870 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-22 09:26:56.934193 | rockylinux-9 | ok
2026-04-22 09:26:57.124678 | 
2026-04-22 09:26:57.124909 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-22 09:26:58.097353 | rockylinux-9 | Output suppressed because no_log was given
2026-04-22 09:26:58.105410 | 
2026-04-22 09:26:58.105545 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-22 09:26:58.347926 | rockylinux-9 | ok: "logs"
2026-04-22 09:26:58.478462 | rockylinux-9 | ok: All items complete
2026-04-22 09:26:58.478613 | 
2026-04-22 09:26:58.573160 | rockylinux-9 | ok: "artifacts"
2026-04-22 09:26:58.799893 | rockylinux-9 | ok: "docs"
2026-04-22 09:26:59.074546 | 
2026-04-22 09:26:59.074696 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-22 09:26:59.357471 | rockylinux-9 | changed: "logs"
2026-04-22 09:26:59.613170 | rockylinux-9 | changed: "artifacts"
2026-04-22 09:26:59.857018 | rockylinux-9 | changed: "docs"
2026-04-22 09:26:59.877239 | 
2026-04-22 09:26:59.877356 | PLAY RECAP
2026-04-22 09:26:59.877448 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-22 09:26:59.877512 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-22 09:26:59.877556 | 
2026-04-22 09:27:00.037902 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-22 09:27:00.039604 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-22 09:27:00.691971 | 
2026-04-22 09:27:00.692375 | PLAY [all]
2026-04-22 09:27:00.709722 | 
2026-04-22 09:27:00.710151 | TASK [setup-uv : Extract archive]
2026-04-22 09:27:03.612242 | rockylinux-9 | changed
2026-04-22 09:27:03.618212 | 
2026-04-22 09:27:03.618299 | TASK [setup-uv : Print version]
2026-04-22 09:27:03.641521 | [rockylinux-9] Waiting on logger
2026-04-22 09:27:04.154911 | rockylinux-9 | ok: Runtime: 0:00:00.014801
2026-04-22 09:27:04.361217 | 
2026-04-22 09:27:04.361336 | PLAY RECAP
2026-04-22 09:27:04.361400 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-22 09:27:04.361428 | 
2026-04-22 09:27:06.675174 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-22 09:27:06.911624 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-22 09:27:07.586322 | 
2026-04-22 09:27:07.598189 | PLAY [all]
2026-04-22 09:27:07.614958 | 
2026-04-22 09:27:07.615063 | TASK [Copy inventory file for Zuul]
2026-04-22 09:27:08.994679 | rockylinux-9 | changed
2026-04-22 09:27:09.001910 | 
2026-04-22 09:27:09.002012 | TASK [Switch "ansible_host" to private IP]
2026-04-22 09:27:09.404182 | rockylinux-9 | changed: 1 replacements made
2026-04-22 09:27:09.606645 | 
2026-04-22 09:27:09.606888 | TASK [Run Molecule scenario]
2026-04-22 09:27:09.630963 | [rockylinux-9] Waiting on logger
2026-04-22 09:27:14.735255 | [rockylinux-9] Waiting on logger
2026-04-22 09:27:24.906508 | [rockylinux-9] Waiting on logger
2026-04-22 09:27:35.077906 | [rockylinux-9] Waiting on logger
2026-04-22 09:27:45.318228 | [rockylinux-9] Waiting on logger
2026-04-22 09:27:55.493760 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:04.651369 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:14.821950 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:25.001863 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:35.173957 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:45.351199 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:55.530117 | [rockylinux-9] Waiting on logger
2026-04-22 09:29:04.687668 | [rockylinux-9] Waiting on logger
2026-04-22 09:29:14.986257 | [rockylinux-9] Waiting on logger
2026-04-22 09:29:24.044760 | rockylinux-9 | ok: Runtime: 0:02:13.613750
2026-04-22 09:29:24.052310 | 
2026-04-22 09:29:24.052379 | PLAY RECAP
2026-04-22 09:29:24.052438 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-22 09:29:24.052466 | 
2026-04-22 09:29:24.161766 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-22 09:29:24.162990 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-22 09:29:24.810762 | 
2026-04-22 09:29:25.020471 | PLAY [all]
2026-04-22 09:29:25.039044 | 
2026-04-22 09:29:25.039253 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-22 09:29:25.086622 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 09:29:25.097811 | 
2026-04-22 09:29:25.098032 | TASK [fetch-output : Set log path for single node]
2026-04-22 09:29:25.145800 | rockylinux-9 | ok
2026-04-22 09:29:25.151914 | 
2026-04-22 09:29:25.151991 | LOOP [fetch-output : Ensure local output dirs]
2026-04-22 09:29:25.568654 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/502f64460554406e8a6ca3eb70f9504c/work/logs"
2026-04-22 09:29:25.841077 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/502f64460554406e8a6ca3eb70f9504c/work/artifacts"
2026-04-22 09:29:26.096372 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/502f64460554406e8a6ca3eb70f9504c/work/docs"
2026-04-22 09:29:26.109695 | 
2026-04-22 09:29:26.110223 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-22 09:29:26.826331 | rockylinux-9 | ok
2026-04-22 09:29:26.826597 | rockylinux-9 | changed: All items complete
2026-04-22 09:29:26.826639 | 
2026-04-22 09:29:27.388522 | rockylinux-9 | changed: .d..t...... ./
2026-04-22 09:29:27.918995 | rockylinux-9 | changed: .d..t...... ./
2026-04-22 09:29:27.946228 | 
2026-04-22 09:29:27.946378 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-22 09:29:28.533386 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006779
2026-04-22 09:29:28.772363 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008062
2026-04-22 09:29:28.790316 | 
2026-04-22 09:29:28.790459 | PLAY [all]
2026-04-22 09:29:28.798372 | 
2026-04-22 09:29:28.798538 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-22 09:29:29.297379 | rockylinux-9 | changed
2026-04-22 09:29:29.305280 | 
2026-04-22 09:29:29.305354 | PLAY RECAP
2026-04-22 09:29:29.305399 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-22 09:29:29.305421 | 
2026-04-22 09:29:29.478166 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-22 09:29:29.480391 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-22 09:29:30.178323 | 
2026-04-22 09:29:30.178466 | PLAY [localhost]
2026-04-22 09:29:30.188415 | 
2026-04-22 09:29:30.188509 | TASK [Generate Zuul manifest]
2026-04-22 09:29:30.208852 | localhost | ok
2026-04-22 09:29:30.228118 | 
2026-04-22 09:29:30.228250 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-22 09:29:30.582013 | localhost | changed
2026-04-22 09:29:30.593356 | 
2026-04-22 09:29:30.593442 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-22 09:29:30.626206 | localhost | ok
2026-04-22 09:29:30.634531 | 
2026-04-22 09:29:30.634602 | TASK [Upload logs]
2026-04-22 09:29:30.657593 | localhost | ok
2026-04-22 09:29:30.714871 | 
2026-04-22 09:29:30.714986 | TASK [Set zuul-log-path fact]
2026-04-22 09:29:30.736579 | localhost | ok
2026-04-22 09:29:30.750518 | 
2026-04-22 09:29:30.750589 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-22 09:29:30.782548 | localhost | ok
2026-04-22 09:29:30.791911 | 
2026-04-22 09:29:30.792034 | TASK [upload-logs : Create log directories]
2026-04-22 09:29:31.247925 | localhost | changed
2026-04-22 09:29:31.269113 | 
2026-04-22 09:29:31.269269 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-22 09:29:31.729551 | localhost -> localhost | ok: Runtime: 0:00:00.005781
2026-04-22 09:29:31.736449 | 
2026-04-22 09:29:31.736545 | TASK [upload-logs : Upload logs to log server]
2026-04-22 09:29:32.612986 | localhost | Output suppressed because no_log was given
2026-04-22 09:29:32.617631 | 
2026-04-22 09:29:32.617705 | LOOP [upload-logs : Compress console log and json output]
2026-04-22 09:29:32.669244 | localhost | skipping: Conditional result was False
2026-04-22 09:29:32.677212 | localhost | skipping: Conditional result was False
2026-04-22 09:29:32.692535 | 
2026-04-22 09:29:32.692721 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-22 09:29:32.758532 | localhost | skipping: Conditional result was False
2026-04-22 09:29:32.759228 | 
2026-04-22 09:29:32.761209 | localhost | skipping: Conditional result was False
2026-04-22 09:29:32.778583 | 
2026-04-22 09:29:32.778760 | LOOP [upload-logs : Upload console log and json output]