2026-04-14 02:50:30.188362 | Job console starting 2026-04-14 02:50:30.213097 | Updating git repos 2026-04-14 02:50:30.245289 | Cloning repos into workspace 2026-04-14 02:50:30.435756 | Restoring repo states 2026-04-14 02:50:30.447437 | Merging changes 2026-04-14 02:50:31.158645 | Checking out repos 2026-04-14 02:50:31.224128 | Preparing playbooks 2026-04-14 02:50:33.715281 | Running Ansible setup 2026-04-14 02:50:38.053813 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:50:38.717487 | 2026-04-14 02:50:38.717882 | PLAY [localhost] 2026-04-14 02:50:38.726571 | 2026-04-14 02:50:38.726671 | TASK [Gathering Facts] 2026-04-14 02:50:39.681213 | localhost | ok 2026-04-14 02:50:39.687943 | 2026-04-14 02:50:39.689156 | TASK [Setup log path fact] 2026-04-14 02:50:39.715001 | localhost | ok 2026-04-14 02:50:39.726807 | 2026-04-14 02:50:39.726901 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:50:39.758440 | localhost | ok 2026-04-14 02:50:39.768232 | 2026-04-14 02:50:39.768332 | TASK [emit-job-header : Print job information] 2026-04-14 02:50:39.819613 | # Job Information 2026-04-14 02:50:39.819875 | Ansible Version: 2.16.16 2026-04-14 02:50:39.819959 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-14 02:50:39.820020 | Pipeline: check 2026-04-14 02:50:39.820070 | Executor: 0a8996d2b663 2026-04-14 02:50:39.820118 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/112 2026-04-14 02:50:39.820170 | Event ID: 9246c490-37ac-11f1-8fb9-92e03caab9e4 2026-04-14 02:50:39.826581 | 2026-04-14 02:50:40.035039 | LOOP [emit-job-header : Print node information] 2026-04-14 02:50:40.138464 | localhost | ok: 2026-04-14 02:50:40.138706 | localhost | # Node Information 2026-04-14 02:50:40.138768 | localhost | Inventory Hostname: rockylinux-9 2026-04-14 02:50:40.138813 | localhost | Hostname: np0000166006 2026-04-14 02:50:40.138851 | localhost | Username: zuul 2026-04-14 02:50:40.138894 | localhost | Distro: Rocky 9.7 2026-04-14 02:50:40.138953 | localhost | Provider: yul1 2026-04-14 02:50:40.139006 | localhost | Region: ca-ymq-1 2026-04-14 02:50:40.139045 | localhost | Label: rockylinux-9 2026-04-14 02:50:40.139081 | localhost | Product Name: OpenStack Nova 2026-04-14 02:50:40.139117 | localhost | Interface IP: 199.19.213.27 2026-04-14 02:50:40.154535 | 2026-04-14 02:50:40.154716 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-14 02:50:40.594708 | localhost -> localhost | changed 2026-04-14 02:50:40.602068 | 2026-04-14 02:50:40.602244 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-14 02:50:41.540745 | localhost -> localhost | changed 2026-04-14 02:50:41.547701 | 2026-04-14 02:50:41.547783 | PLAY [all] 2026-04-14 02:50:41.562472 | 2026-04-14 02:50:41.562591 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-14 02:50:42.186037 | rockylinux-9 -> localhost | ok 2026-04-14 02:50:42.195546 | 2026-04-14 02:50:42.195711 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-14 02:50:42.227771 | rockylinux-9 | ok 2026-04-14 02:50:42.261496 | rockylinux-9 | included: /var/lib/zuul/builds/c67c4d620b4a4e38a3d62964e8417dbe/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-14 02:50:42.267335 | 2026-04-14 02:50:42.267399 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-14 02:50:43.077135 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-14 02:50:43.077350 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/c67c4d620b4a4e38a3d62964e8417dbe/work/c67c4d620b4a4e38a3d62964e8417dbe_id_rsa 2026-04-14 02:50:43.077387 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/c67c4d620b4a4e38a3d62964e8417dbe/work/c67c4d620b4a4e38a3d62964e8417dbe_id_rsa.pub 2026-04-14 02:50:43.077412 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-14 02:50:43.077435 | rockylinux-9 -> localhost | SHA256:Q+4628KoEfi+NkDAnjrM1zrE4skVlLz9EZFv+ucTHKY zuul-build-sshkey 2026-04-14 02:50:43.077500 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-14 02:50:43.077527 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-14 02:50:43.077558 | rockylinux-9 -> localhost | |. . . .o | 2026-04-14 02:50:43.077582 | rockylinux-9 -> localhost | |.. + o | 2026-04-14 02:50:43.077604 | rockylinux-9 -> localhost | |o o o + | 2026-04-14 02:50:43.077627 | rockylinux-9 -> localhost | | = o . + o o | 2026-04-14 02:50:43.077649 | rockylinux-9 -> localhost | |* o o . S + . | 2026-04-14 02:50:43.077671 | rockylinux-9 -> localhost | |+= * . + E o | 2026-04-14 02:50:43.077692 | rockylinux-9 -> localhost | |ooO .o o . | 2026-04-14 02:50:43.077713 | rockylinux-9 -> localhost | | =o+. +o . o | 2026-04-14 02:50:43.077738 | rockylinux-9 -> localhost | | .++. o+. o.. | 2026-04-14 02:50:43.077760 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-14 02:50:43.077817 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.325324 2026-04-14 02:50:43.083018 | 2026-04-14 02:50:43.083097 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-14 02:50:43.116780 | rockylinux-9 | ok 2026-04-14 02:50:43.129504 | rockylinux-9 | included: /var/lib/zuul/builds/c67c4d620b4a4e38a3d62964e8417dbe/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-14 02:50:43.139984 | 2026-04-14 02:50:43.140149 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-14 02:50:43.165493 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:50:43.176918 | 2026-04-14 02:50:43.177058 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-14 02:50:43.703195 | rockylinux-9 | changed 2026-04-14 02:50:43.774892 | 2026-04-14 02:50:43.775017 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-14 02:50:44.050473 | rockylinux-9 | ok 2026-04-14 02:50:44.055289 | 2026-04-14 02:50:44.055356 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-14 02:50:44.983721 | rockylinux-9 | changed 2026-04-14 02:50:44.988372 | 2026-04-14 02:50:44.988442 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-14 02:50:45.917042 | rockylinux-9 | changed 2026-04-14 02:50:45.921771 | 2026-04-14 02:50:45.921833 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-14 02:50:45.960919 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:50:45.967006 | 2026-04-14 02:50:45.967074 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-14 02:50:46.363541 | rockylinux-9 -> localhost | changed 2026-04-14 02:50:46.375246 | 2026-04-14 02:50:46.375387 | TASK [add-build-sshkey : Add back temp key] 2026-04-14 02:50:46.650235 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/c67c4d620b4a4e38a3d62964e8417dbe/work/c67c4d620b4a4e38a3d62964e8417dbe_id_rsa (zuul-build-sshkey) 2026-04-14 02:50:46.650461 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012132 2026-04-14 02:50:46.658356 | 2026-04-14 02:50:46.658438 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-14 02:50:47.046735 | rockylinux-9 | ok 2026-04-14 02:50:47.052458 | 2026-04-14 02:50:47.052541 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-14 02:50:47.077607 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:50:47.091005 | 2026-04-14 02:50:47.091091 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-14 02:50:47.493819 | rockylinux-9 | ok 2026-04-14 02:50:47.499886 | 2026-04-14 02:50:47.499948 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-14 02:50:48.670196 | rockylinux-9 | Output suppressed because no_log was given 2026-04-14 02:50:48.681414 | 2026-04-14 02:50:48.681502 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-14 02:50:48.959722 | rockylinux-9 | ok: "logs" 2026-04-14 02:50:48.960121 | rockylinux-9 | ok: All items complete 2026-04-14 02:50:48.960195 | 2026-04-14 02:50:49.188071 | rockylinux-9 | ok: "artifacts" 2026-04-14 02:50:49.425659 | rockylinux-9 | ok: "docs" 2026-04-14 02:50:49.440741 | 2026-04-14 02:50:49.440896 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-14 02:50:49.701050 | rockylinux-9 | changed: "logs" 2026-04-14 02:50:49.923270 | rockylinux-9 | changed: "artifacts" 2026-04-14 02:50:50.193220 | rockylinux-9 | changed: "docs" 2026-04-14 02:50:50.215700 | 2026-04-14 02:50:50.215810 | PLAY RECAP 2026-04-14 02:50:50.215869 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:50:50.215909 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-14 02:50:50.215939 | 2026-04-14 02:50:50.358139 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:50:50.365277 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:50:50.979015 | 2026-04-14 02:50:50.979159 | PLAY [all] 2026-04-14 02:50:50.990182 | 2026-04-14 02:50:50.990256 | TASK [setup-uv : Extract archive] 2026-04-14 02:50:53.839969 | rockylinux-9 | changed 2026-04-14 02:50:53.847806 | 2026-04-14 02:50:53.847929 | TASK [setup-uv : Print version] 2026-04-14 02:50:53.871470 | [rockylinux-9] Waiting on logger 2026-04-14 02:50:54.411384 | rockylinux-9 | ok: Runtime: 0:00:00.015961 2026-04-14 02:50:54.419846 | 2026-04-14 02:50:54.419939 | PLAY RECAP 2026-04-14 02:50:54.419989 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:50:54.420015 | 2026-04-14 02:50:54.575442 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:50:54.577020 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:50:55.222610 | 2026-04-14 02:50:55.222737 | PLAY [all] 2026-04-14 02:50:55.233538 | 2026-04-14 02:50:55.233625 | TASK [Copy inventory file for Zuul] 2026-04-14 02:50:56.656922 | rockylinux-9 | changed 2026-04-14 02:50:56.664661 | 2026-04-14 02:50:56.664765 | TASK [Switch "ansible_host" to private IP] 2026-04-14 02:50:57.020876 | rockylinux-9 | changed: 1 replacements made 2026-04-14 02:50:57.038014 | 2026-04-14 02:50:57.038207 | TASK [Run Molecule scenario] 2026-04-14 02:50:57.087405 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:05.254333 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:15.494846 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:25.670142 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:35.846303 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:44.998095 | [rockylinux-9] Waiting on logger 2026-04-14 02:51:55.174182 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:05.354216 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:15.526138 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:25.702230 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:35.878159 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:45.030468 | [rockylinux-9] Waiting on logger 2026-04-14 02:52:55.206208 | [rockylinux-9] Waiting on logger 2026-04-14 02:53:03.150039 | rockylinux-9 | ok: Runtime: 0:02:05.500178 2026-04-14 02:53:03.159908 | 2026-04-14 02:53:03.159953 | PLAY RECAP 2026-04-14 02:53:03.159993 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:53:03.160016 | 2026-04-14 02:53:03.249477 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:53:03.251924 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:53:03.790751 | 2026-04-14 02:53:03.790870 | PLAY [all] 2026-04-14 02:53:03.801093 | 2026-04-14 02:53:03.801173 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-14 02:53:03.845588 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:53:03.854526 | 2026-04-14 02:53:03.854603 | TASK [fetch-output : Set log path for single node] 2026-04-14 02:53:03.898979 | rockylinux-9 | ok 2026-04-14 02:53:03.904930 | 2026-04-14 02:53:03.905007 | LOOP [fetch-output : Ensure local output dirs] 2026-04-14 02:53:04.275600 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/c67c4d620b4a4e38a3d62964e8417dbe/work/logs" 2026-04-14 02:53:04.481271 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/c67c4d620b4a4e38a3d62964e8417dbe/work/artifacts" 2026-04-14 02:53:04.702052 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/c67c4d620b4a4e38a3d62964e8417dbe/work/docs" 2026-04-14 02:53:04.722565 | 2026-04-14 02:53:04.722736 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-14 02:53:05.406960 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:53:05.407270 | rockylinux-9 | changed: All items complete 2026-04-14 02:53:05.407305 | 2026-04-14 02:53:05.921233 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:53:06.433093 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:53:06.446599 | 2026-04-14 02:53:06.446728 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-14 02:53:06.848639 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007969 2026-04-14 02:53:07.087067 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008442 2026-04-14 02:53:07.107004 | 2026-04-14 02:53:07.107115 | PLAY [all] 2026-04-14 02:53:07.112644 | 2026-04-14 02:53:07.112710 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-14 02:53:07.561974 | rockylinux-9 | changed 2026-04-14 02:53:07.568344 | 2026-04-14 02:53:07.568397 | PLAY RECAP 2026-04-14 02:53:07.568444 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-14 02:53:07.568465 | 2026-04-14 02:53:07.729530 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:53:07.730665 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-14 02:53:08.288661 | 2026-04-14 02:53:08.289278 | PLAY [localhost] 2026-04-14 02:53:08.300771 | 2026-04-14 02:53:08.300851 | TASK [Generate Zuul manifest] 2026-04-14 02:53:08.323606 | localhost | ok 2026-04-14 02:53:08.342304 | 2026-04-14 02:53:08.342388 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-14 02:53:08.711173 | localhost | changed 2026-04-14 02:53:08.726561 | 2026-04-14 02:53:08.726778 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-14 02:53:08.757793 | localhost | ok 2026-04-14 02:53:08.766674 | 2026-04-14 02:53:08.766847 | TASK [Upload logs] 2026-04-14 02:53:08.791055 | localhost | ok 2026-04-14 02:53:08.856352 | 2026-04-14 02:53:08.856509 | TASK [Set zuul-log-path fact] 2026-04-14 02:53:08.879880 | localhost | ok 2026-04-14 02:53:08.892445 | 2026-04-14 02:53:08.892662 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:53:08.926054 | localhost | ok 2026-04-14 02:53:08.935281 | 2026-04-14 02:53:08.935384 | TASK [upload-logs : Create log directories] 2026-04-14 02:53:09.333944 | localhost | changed 2026-04-14 02:53:09.340529 | 2026-04-14 02:53:09.340644 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-14 02:53:09.738276 | localhost -> localhost | ok: Runtime: 0:00:00.005180 2026-04-14 02:53:09.745612 | 2026-04-14 02:53:09.745752 | TASK [upload-logs : Upload logs to log server] 2026-04-14 02:53:10.237812 | localhost | Output suppressed because no_log was given 2026-04-14 02:53:10.243749 | 2026-04-14 02:53:10.243882 | LOOP [upload-logs : Compress console log and json output] 2026-04-14 02:53:10.292180 | localhost | skipping: Conditional result was False 2026-04-14 02:53:10.298876 | localhost | skipping: Conditional result was False 2026-04-14 02:53:10.308014 | 2026-04-14 02:53:10.308253 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-14 02:53:10.354335 | localhost | skipping: Conditional result was False 2026-04-14 02:53:10.354924 | 2026-04-14 02:53:10.357869 | localhost | skipping: Conditional result was False 2026-04-14 02:53:10.372626 | 2026-04-14 02:53:10.372847 | LOOP [upload-logs : Upload console log and json output]