2026-03-18 00:48:46.842234 | Job console starting 2026-03-18 00:48:46.851892 | Updating git repos 2026-03-18 00:48:46.887077 | Cloning repos into workspace 2026-03-18 00:48:46.939803 | Restoring repo states 2026-03-18 00:48:46.948225 | Merging changes 2026-03-18 00:48:47.201594 | Checking out repos 2026-03-18 00:48:47.275120 | Preparing playbooks 2026-03-18 00:48:48.355872 | Running Ansible setup 2026-03-18 00:48:52.219827 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-18 00:48:52.785936 | 2026-03-18 00:48:52.786072 | PLAY [localhost] 2026-03-18 00:48:52.793775 | 2026-03-18 00:48:52.793853 | TASK [Gathering Facts] 2026-03-18 00:48:53.700760 | localhost | ok 2026-03-18 00:48:53.711399 | 2026-03-18 00:48:53.711491 | TASK [Setup log path fact] 2026-03-18 00:48:53.730183 | localhost | ok 2026-03-18 00:48:53.744235 | 2026-03-18 00:48:53.744364 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-18 00:48:53.771977 | localhost | ok 2026-03-18 00:48:53.781046 | 2026-03-18 00:48:53.781230 | TASK [emit-job-header : Print job information] 2026-03-18 00:48:53.823751 | # Job Information 2026-03-18 00:48:53.824035 | Ansible Version: 2.16.16 2026-03-18 00:48:53.824106 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-03-18 00:48:53.824161 | Pipeline: check 2026-03-18 00:48:53.824220 | Executor: 0a8996d2b663 2026-03-18 00:48:53.824267 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/103 2026-03-18 00:48:53.824318 | Event ID: 2ec8c6a0-2264-11f1-8f62-f695a841fb8e 2026-03-18 00:48:53.829501 | 2026-03-18 00:48:53.829593 | LOOP [emit-job-header : Print node information] 2026-03-18 00:48:53.912458 | localhost | ok: 2026-03-18 00:48:53.912643 | localhost | # Node Information 2026-03-18 00:48:53.912676 | localhost | Inventory Hostname: rockylinux-9 2026-03-18 00:48:53.912701 | localhost | Hostname: np0000159594 2026-03-18 00:48:53.912725 | localhost | Username: zuul 2026-03-18 00:48:53.912752 | localhost | Distro: Rocky 9.7 2026-03-18 00:48:53.912775 | localhost | Provider: yul1 2026-03-18 00:48:53.912797 | localhost | Region: ca-ymq-1 2026-03-18 00:48:53.912818 | localhost | Label: rockylinux-9 2026-03-18 00:48:53.912839 | localhost | Product Name: OpenStack Nova 2026-03-18 00:48:53.912859 | localhost | Interface IP: 199.204.45.240 2026-03-18 00:48:53.921519 | 2026-03-18 00:48:53.921609 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-18 00:48:54.310612 | localhost -> localhost | changed 2026-03-18 00:48:54.322803 | 2026-03-18 00:48:54.322903 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-18 00:48:55.146216 | localhost -> localhost | changed 2026-03-18 00:48:55.153857 | 2026-03-18 00:48:55.153969 | PLAY [all] 2026-03-18 00:48:55.163069 | 2026-03-18 00:48:55.163170 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-18 00:48:55.389818 | rockylinux-9 -> localhost | ok 2026-03-18 00:48:55.395171 | 2026-03-18 00:48:55.395231 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-18 00:48:55.426200 | rockylinux-9 | ok 2026-03-18 00:48:55.446755 | rockylinux-9 | included: /var/lib/zuul/builds/3f224abc478341028f3a3b354dc15d4b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-18 00:48:56.966691 | 2026-03-18 00:48:56.966812 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-18 00:48:58.007643 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-03-18 00:48:58.015136 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/3f224abc478341028f3a3b354dc15d4b/work/3f224abc478341028f3a3b354dc15d4b_id_rsa 2026-03-18 00:48:58.015215 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/3f224abc478341028f3a3b354dc15d4b/work/3f224abc478341028f3a3b354dc15d4b_id_rsa.pub 2026-03-18 00:48:58.015242 | rockylinux-9 -> localhost | The key fingerprint is: 2026-03-18 00:48:58.015264 | rockylinux-9 -> localhost | SHA256:1pYEDwfjCdT7c3U06yeLUQKYIkvqqW+84JjYVes4xcY zuul-build-sshkey 2026-03-18 00:48:58.015300 | rockylinux-9 -> localhost | The key's randomart image is: 2026-03-18 00:48:58.015322 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-03-18 00:48:58.015351 | rockylinux-9 -> localhost | | .o.=+. | 2026-03-18 00:48:58.015373 | rockylinux-9 -> localhost | | o .o+B. ..| 2026-03-18 00:48:58.015393 | rockylinux-9 -> localhost | | o o .o.o. .o| 2026-03-18 00:48:58.015412 | rockylinux-9 -> localhost | | . . .o .. o..| 2026-03-18 00:48:58.015432 | rockylinux-9 -> localhost | | . . o. S.+ +.. | 2026-03-18 00:48:58.015452 | rockylinux-9 -> localhost | | o .Eo .o o ...| 2026-03-18 00:48:58.015471 | rockylinux-9 -> localhost | |.o .o. o o o.| 2026-03-18 00:48:58.015491 | rockylinux-9 -> localhost | |=+o..o . . | 2026-03-18 00:48:58.015513 | rockylinux-9 -> localhost | |+++.... | 2026-03-18 00:48:58.015534 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-03-18 00:48:58.015599 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.607574 2026-03-18 00:48:58.024719 | 2026-03-18 00:48:58.025022 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-18 00:48:58.058621 | rockylinux-9 | ok 2026-03-18 00:48:58.127131 | rockylinux-9 | included: /var/lib/zuul/builds/3f224abc478341028f3a3b354dc15d4b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-18 00:48:58.135978 | 2026-03-18 00:48:58.136108 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-18 00:48:58.159534 | rockylinux-9 | skipping: Conditional result was False 2026-03-18 00:48:58.166841 | 2026-03-18 00:48:58.166942 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-18 00:48:58.704746 | rockylinux-9 | changed 2026-03-18 00:48:58.710308 | 2026-03-18 00:48:58.710396 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-18 00:48:58.949947 | rockylinux-9 | ok 2026-03-18 00:48:59.284206 | 2026-03-18 00:48:59.284378 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-18 00:49:00.229293 | rockylinux-9 | changed 2026-03-18 00:49:00.344239 | 2026-03-18 00:49:00.344380 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-18 00:49:01.199941 | rockylinux-9 | changed 2026-03-18 00:49:01.207721 | 2026-03-18 00:49:01.207876 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-18 00:49:01.232445 | rockylinux-9 | skipping: Conditional result was False 2026-03-18 00:49:01.243546 | 2026-03-18 00:49:01.243694 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-18 00:49:01.613839 | rockylinux-9 -> localhost | changed 2026-03-18 00:49:01.623195 | 2026-03-18 00:49:01.623262 | TASK [add-build-sshkey : Add back temp key] 2026-03-18 00:49:01.898015 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/3f224abc478341028f3a3b354dc15d4b/work/3f224abc478341028f3a3b354dc15d4b_id_rsa (zuul-build-sshkey) 2026-03-18 00:49:01.898214 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013742 2026-03-18 00:49:01.904451 | 2026-03-18 00:49:01.904519 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-18 00:49:02.282077 | rockylinux-9 | ok 2026-03-18 00:49:02.286540 | 2026-03-18 00:49:02.286616 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-18 00:49:02.310263 | rockylinux-9 | skipping: Conditional result was False 2026-03-18 00:49:02.322904 | 2026-03-18 00:49:02.323014 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-18 00:49:02.696539 | rockylinux-9 | ok 2026-03-18 00:49:02.703447 | 2026-03-18 00:49:02.703533 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-18 00:49:04.427716 | rockylinux-9 | Output suppressed because no_log was given 2026-03-18 00:49:04.435856 | 2026-03-18 00:49:04.435935 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-18 00:49:04.688732 | rockylinux-9 | ok: "logs" 2026-03-18 00:49:04.689001 | rockylinux-9 | ok: All items complete 2026-03-18 00:49:04.689038 | 2026-03-18 00:49:04.904299 | rockylinux-9 | ok: "artifacts" 2026-03-18 00:49:05.118772 | rockylinux-9 | ok: "docs" 2026-03-18 00:49:05.136560 | 2026-03-18 00:49:05.136764 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-18 00:49:05.380044 | rockylinux-9 | changed: "logs" 2026-03-18 00:49:05.593090 | rockylinux-9 | changed: "artifacts" 2026-03-18 00:49:05.833726 | rockylinux-9 | changed: "docs" 2026-03-18 00:49:05.846321 | 2026-03-18 00:49:05.846458 | PLAY RECAP 2026-03-18 00:49:05.846513 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-18 00:49:05.846545 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-18 00:49:05.846571 | 2026-03-18 00:49:06.004824 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-18 00:49:06.006226 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-18 00:49:06.593084 | 2026-03-18 00:49:06.593245 | PLAY [all] 2026-03-18 00:49:06.605024 | 2026-03-18 00:49:06.605118 | TASK [setup-uv : Extract archive] 2026-03-18 00:49:09.334363 | rockylinux-9 | changed 2026-03-18 00:49:09.343171 | 2026-03-18 00:49:09.343267 | TASK [setup-uv : Print version] 2026-03-18 00:49:09.377306 | [rockylinux-9] Waiting on logger 2026-03-18 00:49:09.795164 | rockylinux-9 | ok: Runtime: 0:00:00.013474 2026-03-18 00:49:09.800866 | 2026-03-18 00:49:09.800929 | PLAY RECAP 2026-03-18 00:49:09.800992 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-18 00:49:09.801019 | 2026-03-18 00:49:09.929084 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-18 00:49:09.931330 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-18 00:49:10.579045 | 2026-03-18 00:49:10.579176 | PLAY [all] 2026-03-18 00:49:10.592891 | 2026-03-18 00:49:10.593044 | TASK [Copy inventory file for Zuul] 2026-03-18 00:49:11.867598 | rockylinux-9 | changed 2026-03-18 00:49:11.872564 | 2026-03-18 00:49:11.872640 | TASK [Switch "ansible_host" to private IP] 2026-03-18 00:49:12.204626 | rockylinux-9 | changed: 1 replacements made 2026-03-18 00:49:12.211243 | 2026-03-18 00:49:12.211378 | TASK [Run Molecule scenario] 2026-03-18 00:49:12.237134 | [rockylinux-9] Waiting on logger 2026-03-18 00:49:20.389997 | [rockylinux-9] Waiting on logger 2026-03-18 00:49:29.670205 | [rockylinux-9] Waiting on logger 2026-03-18 00:49:39.846153 | [rockylinux-9] Waiting on logger 2026-03-18 00:49:50.022242 | [rockylinux-9] Waiting on logger 2026-03-18 00:50:00.198075 | [rockylinux-9] Waiting on logger 2026-03-18 00:50:10.374002 | [rockylinux-9] Waiting on logger 2026-03-18 00:50:19.526297 | [rockylinux-9] Waiting on logger 2026-03-18 00:50:29.706124 | [rockylinux-9] Waiting on logger 2026-03-18 00:50:39.877910 | [rockylinux-9] Waiting on logger 2026-03-18 00:50:50.054202 | [rockylinux-9] Waiting on logger 2026-03-18 00:50:59.498076 | [rockylinux-9] Waiting on logger 2026-03-18 00:51:09.674099 | [rockylinux-9] Waiting on logger 2026-03-18 00:51:19.846448 | [rockylinux-9] Waiting on logger 2026-03-18 00:51:30.026547 | [rockylinux-9] Waiting on logger 2026-03-18 00:51:40.198622 | [rockylinux-9] Waiting on logger 2026-03-18 00:51:50.374039 | [rockylinux-9] Waiting on logger 2026-03-18 00:51:59.526485 | [rockylinux-9] Waiting on logger 2026-03-18 00:52:09.706125 | [rockylinux-9] Waiting on logger 2026-03-18 00:52:19.877988 | [rockylinux-9] Waiting on logger 2026-03-18 00:52:30.054322 | [rockylinux-9] Waiting on logger 2026-03-18 00:52:40.230175 | [rockylinux-9] Waiting on logger 2026-03-18 00:52:49.387015 | [rockylinux-9] Waiting on logger 2026-03-18 00:52:59.558234 | [rockylinux-9] Waiting on logger 2026-03-18 00:53:09.733979 | [rockylinux-9] Waiting on logger 2026-03-18 00:53:19.910290 | [rockylinux-9] Waiting on logger 2026-03-18 00:53:30.086319 | [rockylinux-9] Waiting on logger 2026-03-18 00:53:36.913592 | rockylinux-9 | ok: Runtime: 0:04:23.714319 2026-03-18 00:53:36.920731 | 2026-03-18 00:53:36.920799 | PLAY RECAP 2026-03-18 00:53:36.920846 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-18 00:53:36.920868 | 2026-03-18 00:53:37.030036 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-18 00:53:37.031385 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-18 00:53:37.613267 | 2026-03-18 00:53:37.613414 | PLAY [all] 2026-03-18 00:53:37.624648 | 2026-03-18 00:53:37.624785 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-18 00:53:37.670430 | rockylinux-9 | skipping: Conditional result was False 2026-03-18 00:53:37.678364 | 2026-03-18 00:53:37.678472 | TASK [fetch-output : Set log path for single node] 2026-03-18 00:53:37.710044 | rockylinux-9 | ok 2026-03-18 00:53:37.716488 | 2026-03-18 00:53:37.716554 | LOOP [fetch-output : Ensure local output dirs] 2026-03-18 00:53:38.096854 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/3f224abc478341028f3a3b354dc15d4b/work/logs" 2026-03-18 00:53:38.297083 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3f224abc478341028f3a3b354dc15d4b/work/artifacts" 2026-03-18 00:53:38.504736 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3f224abc478341028f3a3b354dc15d4b/work/docs" 2026-03-18 00:53:38.520302 | 2026-03-18 00:53:38.520465 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-18 00:53:39.230899 | rockylinux-9 | ok 2026-03-18 00:53:39.231138 | rockylinux-9 | changed: All items complete 2026-03-18 00:53:39.231166 | 2026-03-18 00:53:39.745275 | rockylinux-9 | changed: .d..t...... ./ 2026-03-18 00:53:40.264543 | rockylinux-9 | changed: .d..t...... ./ 2026-03-18 00:53:40.286237 | 2026-03-18 00:53:40.286320 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-18 00:53:40.728947 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007257 2026-03-18 00:53:40.947908 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007765 2026-03-18 00:53:40.967803 | 2026-03-18 00:53:40.967914 | PLAY [all] 2026-03-18 00:53:40.973508 | 2026-03-18 00:53:40.973570 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-18 00:53:41.421138 | rockylinux-9 | changed 2026-03-18 00:53:41.427695 | 2026-03-18 00:53:41.427754 | PLAY RECAP 2026-03-18 00:53:41.427799 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-18 00:53:41.427821 | 2026-03-18 00:53:41.554624 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-18 00:53:41.556723 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-18 00:53:42.127937 | 2026-03-18 00:53:42.128089 | PLAY [localhost] 2026-03-18 00:53:42.138913 | 2026-03-18 00:53:42.139027 | TASK [Generate Zuul manifest] 2026-03-18 00:53:42.159912 | localhost | ok 2026-03-18 00:53:42.179700 | 2026-03-18 00:53:42.179885 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-18 00:53:42.487356 | localhost | changed 2026-03-18 00:53:42.500901 | 2026-03-18 00:53:42.501029 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-18 00:53:42.530927 | localhost | ok 2026-03-18 00:53:42.539638 | 2026-03-18 00:53:42.539708 | TASK [Upload logs] 2026-03-18 00:53:42.560895 | localhost | ok 2026-03-18 00:53:42.621608 | 2026-03-18 00:53:42.621770 | TASK [Set zuul-log-path fact] 2026-03-18 00:53:42.642732 | localhost | ok 2026-03-18 00:53:42.655495 | 2026-03-18 00:53:42.655579 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-18 00:53:42.688324 | localhost | ok 2026-03-18 00:53:42.698060 | 2026-03-18 00:53:42.698139 | TASK [upload-logs : Create log directories] 2026-03-18 00:53:43.096379 | localhost | changed 2026-03-18 00:53:43.101973 | 2026-03-18 00:53:43.102043 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-18 00:53:43.509444 | localhost -> localhost | ok: Runtime: 0:00:00.005373 2026-03-18 00:53:43.516091 | 2026-03-18 00:53:43.516170 | TASK [upload-logs : Upload logs to log server] 2026-03-18 00:53:43.959694 | localhost | Output suppressed because no_log was given 2026-03-18 00:53:43.963369 | 2026-03-18 00:53:43.963436 | LOOP [upload-logs : Compress console log and json output] 2026-03-18 00:53:44.007760 | localhost | skipping: Conditional result was False 2026-03-18 00:53:44.014812 | localhost | skipping: Conditional result was False 2026-03-18 00:53:44.023805 | 2026-03-18 00:53:44.023964 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-18 00:53:44.066336 | localhost | skipping: Conditional result was False 2026-03-18 00:53:44.066726 | 2026-03-18 00:53:44.070627 | localhost | skipping: Conditional result was False 2026-03-18 00:53:44.086844 | 2026-03-18 00:53:44.087053 | LOOP [upload-logs : Upload console log and json output]