2026-04-09 16:00:16.262616 | Job console starting
2026-04-09 16:00:16.274072 | Updating git repos
2026-04-09 16:00:16.313374 | Cloning repos into workspace
2026-04-09 16:00:16.376074 | Restoring repo states
2026-04-09 16:00:16.383855 | Merging changes
2026-04-09 16:00:16.822422 | Checking out repos
2026-04-09 16:00:16.899761 | Preparing playbooks
2026-04-09 16:00:18.510464 | Running Ansible setup
2026-04-09 16:00:22.328628 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-09 16:00:23.022140 | 
2026-04-09 16:00:23.022291 | PLAY [localhost]
2026-04-09 16:00:23.030335 | 
2026-04-09 16:00:23.030440 | TASK [Gathering Facts]
2026-04-09 16:00:23.948220 | localhost | ok
2026-04-09 16:00:23.955784 | 
2026-04-09 16:00:23.955872 | TASK [Setup log path fact]
2026-04-09 16:00:23.975027 | localhost | ok
2026-04-09 16:00:23.987060 | 
2026-04-09 16:00:23.987141 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-09 16:00:24.015878 | localhost | ok
2026-04-09 16:00:24.024886 | 
2026-04-09 16:00:24.024996 | TASK [emit-job-header : Print job information]
2026-04-09 16:00:24.058792 | # Job Information
2026-04-09 16:00:24.059083 | Ansible Version: 2.16.16
2026-04-09 16:00:24.059129 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9
2026-04-09 16:00:24.059159 | Pipeline: check
2026-04-09 16:00:24.059185 | Executor: 0a8996d2b663
2026-04-09 16:00:24.060724 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/93
2026-04-09 16:00:24.060768 | Event ID: 592c8010-342c-11f1-8e5e-2b2d0b84186e
2026-04-09 16:00:24.066142 | 
2026-04-09 16:00:24.066244 | LOOP [emit-job-header : Print node information]
2026-04-09 16:00:24.183177 | localhost | ok:
2026-04-09 16:00:24.346618 | localhost | # Node Information
2026-04-09 16:00:24.346722 | localhost | Inventory Hostname: rockylinux-9
2026-04-09 16:00:24.346748 | localhost | Hostname: np0000164568
2026-04-09 16:00:24.346769 | localhost | Username: zuul
2026-04-09 16:00:24.346793 | localhost | Distro: Rocky 9.7
2026-04-09 16:00:24.346813 | localhost | Provider: yul1
2026-04-09 16:00:24.346832 | localhost | Region: ca-ymq-1
2026-04-09 16:00:24.346851 | localhost | Label: rockylinux-9
2026-04-09 16:00:24.346870 | localhost | Product Name: OpenStack Nova
2026-04-09 16:00:24.346889 | localhost | Interface IP: 199.19.213.221
2026-04-09 16:00:24.361897 | 
2026-04-09 16:00:24.362077 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-09 16:00:24.851666 | localhost -> localhost | changed
2026-04-09 16:00:24.858002 | 
2026-04-09 16:00:24.858101 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-09 16:00:26.047826 | localhost -> localhost | changed
2026-04-09 16:00:26.053913 | 
2026-04-09 16:00:26.053970 | PLAY [all]
2026-04-09 16:00:26.061534 | 
2026-04-09 16:00:26.061599 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-09 16:00:26.320658 | rockylinux-9 -> localhost | ok
2026-04-09 16:00:26.329273 | 
2026-04-09 16:00:26.329387 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-09 16:00:26.360556 | rockylinux-9 | ok
2026-04-09 16:00:26.377089 | rockylinux-9 | included: /var/lib/zuul/builds/d75c99b9c6764574a2480f4e9aa20132/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-09 16:00:26.382396 | 
2026-04-09 16:00:26.382479 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-09 16:00:27.474643 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-09 16:00:27.474867 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/d75c99b9c6764574a2480f4e9aa20132/work/d75c99b9c6764574a2480f4e9aa20132_id_rsa
2026-04-09 16:00:27.474898 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/d75c99b9c6764574a2480f4e9aa20132/work/d75c99b9c6764574a2480f4e9aa20132_id_rsa.pub
2026-04-09 16:00:27.474921 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-09 16:00:27.474942 | rockylinux-9 -> localhost | SHA256:woX2T8WpB0hMucShKybU/CfGv0RxLjSgtc0uPIigNbU zuul-build-sshkey
2026-04-09 16:00:27.474980 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-09 16:00:27.475002 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-09 16:00:27.475027 | rockylinux-9 -> localhost | |    . o++o       |
2026-04-09 16:00:27.475049 | rockylinux-9 -> localhost | |   + + O=. . .   |
2026-04-09 16:00:27.475070 | rockylinux-9 -> localhost | |. + E =.O.o +    |
2026-04-09 16:00:27.475090 | rockylinux-9 -> localhost | |.+ o B *.= +     |
2026-04-09 16:00:27.475110 | rockylinux-9 -> localhost | |. o + % S + .    |
2026-04-09 16:00:27.475130 | rockylinux-9 -> localhost | |   o o O + .     |
2026-04-09 16:00:27.475150 | rockylinux-9 -> localhost | |        o .      |
2026-04-09 16:00:27.475233 | rockylinux-9 -> localhost | |       . .       |
2026-04-09 16:00:27.475267 | rockylinux-9 -> localhost | |        .        |
2026-04-09 16:00:27.475289 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-09 16:00:27.475357 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.445746
2026-04-09 16:00:27.480753 | 
2026-04-09 16:00:27.480826 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-09 16:00:27.517575 | rockylinux-9 | ok
2026-04-09 16:00:27.528834 | rockylinux-9 | included: /var/lib/zuul/builds/d75c99b9c6764574a2480f4e9aa20132/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-09 16:00:27.536874 | 
2026-04-09 16:00:27.536946 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-09 16:00:27.552322 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 16:00:27.560097 | 
2026-04-09 16:00:27.560167 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-09 16:00:28.028574 | rockylinux-9 | changed
2026-04-09 16:00:28.033616 | 
2026-04-09 16:00:28.033682 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-09 16:00:28.318475 | rockylinux-9 | ok
2026-04-09 16:00:28.323662 | 
2026-04-09 16:00:28.323730 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-09 16:00:29.260409 | rockylinux-9 | changed
2026-04-09 16:00:29.267218 | 
2026-04-09 16:00:29.267354 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-09 16:00:30.210359 | rockylinux-9 | changed
2026-04-09 16:00:30.222008 | 
2026-04-09 16:00:30.222142 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-09 16:00:30.238564 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 16:00:30.245581 | 
2026-04-09 16:00:30.245652 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-09 16:00:30.594003 | rockylinux-9 -> localhost | changed
2026-04-09 16:00:30.603360 | 
2026-04-09 16:00:30.603432 | TASK [add-build-sshkey : Add back temp key]
2026-04-09 16:00:30.905706 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/d75c99b9c6764574a2480f4e9aa20132/work/d75c99b9c6764574a2480f4e9aa20132_id_rsa (zuul-build-sshkey)
2026-04-09 16:00:30.905877 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.018238
2026-04-09 16:00:30.911905 | 
2026-04-09 16:00:30.911975 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-09 16:00:31.278923 | rockylinux-9 | ok
2026-04-09 16:00:31.286573 | 
2026-04-09 16:00:31.286738 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-09 16:00:31.313257 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 16:00:31.330465 | 
2026-04-09 16:00:31.330623 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-09 16:00:31.739367 | rockylinux-9 | ok
2026-04-09 16:00:31.745251 | 
2026-04-09 16:00:31.745325 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-09 16:00:32.728328 | rockylinux-9 | Output suppressed because no_log was given
2026-04-09 16:00:32.741350 | 
2026-04-09 16:00:32.741502 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-09 16:00:33.009864 | rockylinux-9 | ok: "logs"
2026-04-09 16:00:33.010088 | rockylinux-9 | ok: All items complete
2026-04-09 16:00:33.010118 | 
2026-04-09 16:00:33.221470 | rockylinux-9 | ok: "artifacts"
2026-04-09 16:00:33.450997 | rockylinux-9 | ok: "docs"
2026-04-09 16:00:33.464668 | 
2026-04-09 16:00:33.464837 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-09 16:00:33.732516 | rockylinux-9 | changed: "logs"
2026-04-09 16:00:33.970563 | rockylinux-9 | changed: "artifacts"
2026-04-09 16:00:34.187592 | rockylinux-9 | changed: "docs"
2026-04-09 16:00:34.202816 | 
2026-04-09 16:00:34.202923 | PLAY RECAP
2026-04-09 16:00:34.202971 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:00:34.203001 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-09 16:00:34.203024 | 
2026-04-09 16:00:34.380987 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-09 16:00:34.382800 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-09 16:00:35.000826 | 
2026-04-09 16:00:35.000948 | PLAY [all]
2026-04-09 16:00:35.012516 | 
2026-04-09 16:00:35.012631 | TASK [setup-uv : Extract archive]
2026-04-09 16:00:37.740879 | rockylinux-9 | changed
2026-04-09 16:00:37.749163 | 
2026-04-09 16:00:37.749327 | TASK [setup-uv : Print version]
2026-04-09 16:00:37.778719 | [rockylinux-9] Waiting on logger
2026-04-09 16:00:38.291928 | rockylinux-9 | ok: Runtime: 0:00:00.014867
2026-04-09 16:00:38.298535 | 
2026-04-09 16:00:38.298586 | PLAY RECAP
2026-04-09 16:00:38.298634 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:00:38.298658 | 
2026-04-09 16:00:38.437978 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-09 16:00:38.671033 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-09 16:00:39.280418 | 
2026-04-09 16:00:39.280615 | PLAY [all]
2026-04-09 16:00:39.297998 | 
2026-04-09 16:00:39.298235 | TASK [Copy inventory file for Zuul]
2026-04-09 16:00:40.612258 | rockylinux-9 | changed
2026-04-09 16:00:40.840374 | 
2026-04-09 16:00:40.840497 | TASK [Switch "ansible_host" to private IP]
2026-04-09 16:00:41.198682 | rockylinux-9 | changed: 1 replacements made
2026-04-09 16:00:41.206672 | 
2026-04-09 16:00:41.206782 | TASK [Run Molecule scenario]
2026-04-09 16:00:41.229816 | [rockylinux-9] Waiting on logger
2026-04-09 16:00:49.385949 | [rockylinux-9] Waiting on logger
2026-04-09 16:00:59.558500 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:09.734086 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:18.891976 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:29.062382 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:39.237998 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:49.414156 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:59.625968 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:08.779329 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:18.950106 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:29.126153 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:39.302038 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:49.478168 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:59.654425 | [rockylinux-9] Waiting on logger
2026-04-09 16:03:08.811690 | [rockylinux-9] Waiting on logger
2026-04-09 16:03:18.982237 | [rockylinux-9] Waiting on logger
2026-04-09 16:03:29.162450 | [rockylinux-9] Waiting on logger
2026-04-09 16:03:39.334423 | [rockylinux-9] Waiting on logger
2026-04-09 16:03:49.510543 | [rockylinux-9] Waiting on logger
2026-04-09 16:03:59.686026 | [rockylinux-9] Waiting on logger
2026-04-09 16:04:08.843714 | [rockylinux-9] Waiting on logger
2026-04-09 16:04:19.014064 | [rockylinux-9] Waiting on logger
2026-04-09 16:04:29.190183 | [rockylinux-9] Waiting on logger
2026-04-09 16:04:39.366216 | [rockylinux-9] Waiting on logger
2026-04-09 16:04:49.542456 | [rockylinux-9] Waiting on logger
2026-04-09 16:04:59.717970 | [rockylinux-9] Waiting on logger
2026-04-09 16:05:08.875311 | [rockylinux-9] Waiting on logger
2026-04-09 16:05:19.046043 | [rockylinux-9] Waiting on logger
2026-04-09 16:05:29.222089 | [rockylinux-9] Waiting on logger
2026-04-09 16:05:38.285834 | rockylinux-9 | ok: Runtime: 0:04:55.782927
2026-04-09 16:05:38.291721 | 
2026-04-09 16:05:38.291767 | PLAY RECAP
2026-04-09 16:05:38.291807 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:05:38.291828 | 
2026-04-09 16:05:38.385737 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-09 16:05:38.388244 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-09 16:05:38.988581 | 
2026-04-09 16:05:38.988738 | PLAY [all]
2026-04-09 16:05:38.999700 | 
2026-04-09 16:05:38.999774 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-09 16:05:39.045097 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 16:05:39.054419 | 
2026-04-09 16:05:39.054582 | TASK [fetch-output : Set log path for single node]
2026-04-09 16:05:39.098224 | rockylinux-9 | ok
2026-04-09 16:05:39.106127 | 
2026-04-09 16:05:39.106218 | LOOP [fetch-output : Ensure local output dirs]
2026-04-09 16:05:39.481171 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/d75c99b9c6764574a2480f4e9aa20132/work/logs"
2026-04-09 16:05:39.686394 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/d75c99b9c6764574a2480f4e9aa20132/work/artifacts"
2026-04-09 16:05:39.910501 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/d75c99b9c6764574a2480f4e9aa20132/work/docs"
2026-04-09 16:05:39.924599 | 
2026-04-09 16:05:39.924724 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-09 16:05:40.589743 | rockylinux-9 | ok
2026-04-09 16:05:40.589928 | rockylinux-9 | changed: All items complete
2026-04-09 16:05:40.589956 | 
2026-04-09 16:05:41.077943 | rockylinux-9 | changed: .d..t...... ./
2026-04-09 16:05:41.578700 | rockylinux-9 | changed: .d..t...... ./
2026-04-09 16:05:41.605522 | 
2026-04-09 16:05:41.605687 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-09 16:05:41.994356 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006208
2026-04-09 16:05:42.233755 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007692
2026-04-09 16:05:42.255552 | 
2026-04-09 16:05:42.255738 | PLAY [all]
2026-04-09 16:05:42.261817 | 
2026-04-09 16:05:42.261886 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-09 16:05:42.722790 | rockylinux-9 | changed
2026-04-09 16:05:42.729980 | 
2026-04-09 16:05:42.730051 | PLAY RECAP
2026-04-09 16:05:42.730111 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-09 16:05:42.730143 | 
2026-04-09 16:05:42.913373 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-09 16:05:42.915754 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-09 16:05:43.462439 | 
2026-04-09 16:05:43.462571 | PLAY [localhost]
2026-04-09 16:05:43.472263 | 
2026-04-09 16:05:43.472336 | TASK [Generate Zuul manifest]
2026-04-09 16:05:43.488926 | localhost | ok
2026-04-09 16:05:43.502111 | 
2026-04-09 16:05:43.502185 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-09 16:05:43.805654 | localhost | changed
2026-04-09 16:05:43.821371 | 
2026-04-09 16:05:43.821505 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-09 16:05:43.852249 | localhost | ok
2026-04-09 16:05:43.862220 | 
2026-04-09 16:05:43.862378 | TASK [Upload logs]
2026-04-09 16:05:43.883981 | localhost | ok
2026-04-09 16:05:43.943075 | 
2026-04-09 16:05:43.943213 | TASK [Set zuul-log-path fact]
2026-04-09 16:05:43.961405 | localhost | ok
2026-04-09 16:05:43.973991 | 
2026-04-09 16:05:43.974058 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-09 16:05:44.004413 | localhost | ok
2026-04-09 16:05:44.013586 | 
2026-04-09 16:05:44.013648 | TASK [upload-logs : Create log directories]
2026-04-09 16:05:44.388921 | localhost | changed
2026-04-09 16:05:44.394963 | 
2026-04-09 16:05:44.395060 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-09 16:05:44.780766 | localhost -> localhost | ok: Runtime: 0:00:00.005454
2026-04-09 16:05:44.788154 | 
2026-04-09 16:05:44.788240 | TASK [upload-logs : Upload logs to log server]
2026-04-09 16:05:45.214781 | localhost | Output suppressed because no_log was given
2026-04-09 16:05:45.220383 | 
2026-04-09 16:05:45.220450 | LOOP [upload-logs : Compress console log and json output]
2026-04-09 16:05:45.264455 | localhost | skipping: Conditional result was False
2026-04-09 16:05:45.271786 | localhost | skipping: Conditional result was False
2026-04-09 16:05:45.284673 | 
2026-04-09 16:05:45.284872 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-09 16:05:45.325323 | localhost | skipping: Conditional result was False
2026-04-09 16:05:45.325697 | 
2026-04-09 16:05:45.329617 | localhost | skipping: Conditional result was False
2026-04-09 16:05:45.339171 | 
2026-04-09 16:05:45.339262 | LOOP [upload-logs : Upload console log and json output]