2026-04-14 02:23:15.774025 | Job console starting 2026-04-14 02:23:15.787125 | Updating git repos 2026-04-14 02:23:15.811370 | Cloning repos into workspace 2026-04-14 02:23:15.855650 | Restoring repo states 2026-04-14 02:23:15.862863 | Merging changes 2026-04-14 02:23:16.215768 | Checking out repos 2026-04-14 02:23:16.266961 | Preparing playbooks 2026-04-14 02:23:17.649244 | Running Ansible setup 2026-04-14 02:23:21.287278 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:23:21.880300 | 2026-04-14 02:23:21.880469 | PLAY [localhost] 2026-04-14 02:23:21.888341 | 2026-04-14 02:23:21.888418 | TASK [Gathering Facts] 2026-04-14 02:23:22.755133 | localhost | ok 2026-04-14 02:23:22.764259 | 2026-04-14 02:23:22.764392 | TASK [Setup log path fact] 2026-04-14 02:23:22.791822 | localhost | ok 2026-04-14 02:23:22.812861 | 2026-04-14 02:23:22.813068 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:23:22.844067 | localhost | ok 2026-04-14 02:23:22.852924 | 2026-04-14 02:23:22.853078 | TASK [emit-job-header : Print job information] 2026-04-14 02:23:22.894301 | # Job Information 2026-04-14 02:23:22.894542 | Ansible Version: 2.16.16 2026-04-14 02:23:22.894587 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-14 02:23:22.894617 | Pipeline: check 2026-04-14 02:23:22.894645 | Executor: 0a8996d2b663 2026-04-14 02:23:22.894671 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/110 2026-04-14 02:23:22.894701 | Event ID: ca05b0c0-37a8-11f1-88dc-20bb82c8c755 2026-04-14 02:23:22.898659 | 2026-04-14 02:23:22.898760 | LOOP [emit-job-header : Print node information] 2026-04-14 02:23:22.989395 | localhost | ok: 2026-04-14 02:23:22.989624 | localhost | # Node Information 2026-04-14 02:23:22.989672 | localhost | Inventory Hostname: rockylinux-9 2026-04-14 02:23:22.989714 | localhost | Hostname: np0000165891 2026-04-14 02:23:22.989748 | localhost | Username: zuul 2026-04-14 02:23:22.989784 | localhost | Distro: Rocky 9.7 2026-04-14 02:23:22.989818 | localhost | Provider: yul1 2026-04-14 02:23:22.989849 | localhost | Region: ca-ymq-1 2026-04-14 02:23:22.989880 | localhost | Label: rockylinux-9 2026-04-14 02:23:22.989910 | localhost | Product Name: OpenStack Nova 2026-04-14 02:23:22.989941 | localhost | Interface IP: 199.19.213.27 2026-04-14 02:23:22.997469 | 2026-04-14 02:23:22.997652 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-14 02:23:23.407801 | localhost -> localhost | changed 2026-04-14 02:23:23.416696 | 2026-04-14 02:23:23.416834 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-14 02:23:24.273651 | localhost -> localhost | changed 2026-04-14 02:23:24.279457 | 2026-04-14 02:23:24.279513 | PLAY [all] 2026-04-14 02:23:24.286706 | 2026-04-14 02:23:24.286772 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-14 02:23:24.534668 | rockylinux-9 -> localhost | ok 2026-04-14 02:23:24.542817 | 2026-04-14 02:23:24.542905 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-14 02:23:24.576708 | rockylinux-9 | ok 2026-04-14 02:23:24.593788 | rockylinux-9 | included: /var/lib/zuul/builds/d990c91da3e440d284e5e5d034456cd1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-14 02:23:24.599063 | 2026-04-14 02:23:24.599123 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-14 02:23:25.846637 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-14 02:23:25.846947 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/d990c91da3e440d284e5e5d034456cd1/work/d990c91da3e440d284e5e5d034456cd1_id_rsa 2026-04-14 02:23:25.847129 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/d990c91da3e440d284e5e5d034456cd1/work/d990c91da3e440d284e5e5d034456cd1_id_rsa.pub 2026-04-14 02:23:25.847174 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-14 02:23:25.847201 | rockylinux-9 -> localhost | SHA256:zz/7Un7fTos0W4zbvZmdLDuv/TjQAYgZfT/RmGGvgiE zuul-build-sshkey 2026-04-14 02:23:25.847301 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-14 02:23:25.847425 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-14 02:23:25.847463 | rockylinux-9 -> localhost | | .= . o= | 2026-04-14 02:23:25.847488 | rockylinux-9 -> localhost | | o o o.+..| 2026-04-14 02:23:25.847659 | rockylinux-9 -> localhost | | E .. o ..| 2026-04-14 02:23:25.847689 | rockylinux-9 -> localhost | | . o +. | 2026-04-14 02:23:25.847711 | rockylinux-9 -> localhost | | S . ...o | 2026-04-14 02:23:25.847730 | rockylinux-9 -> localhost | | o ..= | 2026-04-14 02:23:25.847827 | rockylinux-9 -> localhost | | o B o.| 2026-04-14 02:23:25.847951 | rockylinux-9 -> localhost | | .+o%oX| 2026-04-14 02:23:25.847980 | rockylinux-9 -> localhost | | o@O#@| 2026-04-14 02:23:25.848150 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-14 02:23:25.848237 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.807858 2026-04-14 02:23:25.855575 | 2026-04-14 02:23:25.855706 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-14 02:23:25.889062 | rockylinux-9 | ok 2026-04-14 02:23:25.906773 | rockylinux-9 | included: /var/lib/zuul/builds/d990c91da3e440d284e5e5d034456cd1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-14 02:23:25.923251 | 2026-04-14 02:23:25.923729 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-14 02:23:25.963863 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:23:25.974891 | 2026-04-14 02:23:25.975034 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-14 02:23:26.472399 | rockylinux-9 | changed 2026-04-14 02:23:26.477989 | 2026-04-14 02:23:26.478056 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-14 02:23:26.718856 | rockylinux-9 | ok 2026-04-14 02:23:26.727823 | 2026-04-14 02:23:26.929955 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-14 02:23:27.803812 | rockylinux-9 | changed 2026-04-14 02:23:27.810816 | 2026-04-14 02:23:27.810912 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-14 02:23:28.669318 | rockylinux-9 | changed 2026-04-14 02:23:29.109064 | 2026-04-14 02:23:29.109632 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-14 02:23:29.142563 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:23:29.151684 | 2026-04-14 02:23:29.151780 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-14 02:23:29.640934 | rockylinux-9 -> localhost | changed 2026-04-14 02:23:29.652024 | 2026-04-14 02:23:29.652147 | TASK [add-build-sshkey : Add back temp key] 2026-04-14 02:23:29.944043 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/d990c91da3e440d284e5e5d034456cd1/work/d990c91da3e440d284e5e5d034456cd1_id_rsa (zuul-build-sshkey) 2026-04-14 02:23:29.944716 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015757 2026-04-14 02:23:29.951878 | 2026-04-14 02:23:29.951944 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-14 02:23:30.365183 | rockylinux-9 | ok 2026-04-14 02:23:30.452181 | 2026-04-14 02:23:30.452322 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-14 02:23:30.500080 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:23:30.510974 | 2026-04-14 02:23:30.511064 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-14 02:23:30.997586 | rockylinux-9 | ok 2026-04-14 02:23:31.006601 | 2026-04-14 02:23:31.006836 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-14 02:23:32.038685 | rockylinux-9 | Output suppressed because no_log was given 2026-04-14 02:23:32.047093 | 2026-04-14 02:23:32.047159 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-14 02:23:32.321725 | rockylinux-9 | ok: "logs" 2026-04-14 02:23:32.327928 | rockylinux-9 | ok: All items complete 2026-04-14 02:23:32.327981 | 2026-04-14 02:23:32.542197 | rockylinux-9 | ok: "artifacts" 2026-04-14 02:23:32.753918 | rockylinux-9 | ok: "docs" 2026-04-14 02:23:32.779815 | 2026-04-14 02:23:32.780697 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-14 02:23:33.052515 | rockylinux-9 | changed: "logs" 2026-04-14 02:23:33.283783 | rockylinux-9 | changed: "artifacts" 2026-04-14 02:23:33.522874 | rockylinux-9 | changed: "docs" 2026-04-14 02:23:33.538460 | 2026-04-14 02:23:33.538584 | PLAY RECAP 2026-04-14 02:23:33.538634 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:23:33.538666 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-14 02:23:33.538686 | 2026-04-14 02:23:33.680930 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:23:33.683603 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:23:34.283509 | 2026-04-14 02:23:34.283652 | PLAY [all] 2026-04-14 02:23:34.294582 | 2026-04-14 02:23:34.294701 | TASK [setup-uv : Extract archive] 2026-04-14 02:23:37.104039 | rockylinux-9 | changed 2026-04-14 02:23:37.109753 | 2026-04-14 02:23:37.109837 | TASK [setup-uv : Print version] 2026-04-14 02:23:37.130751 | [rockylinux-9] Waiting on logger 2026-04-14 02:23:37.647469 | rockylinux-9 | ok: Runtime: 0:00:00.015262 2026-04-14 02:23:37.655317 | 2026-04-14 02:23:37.655379 | PLAY RECAP 2026-04-14 02:23:37.655435 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:23:37.655474 | 2026-04-14 02:23:37.790116 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:23:37.791157 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:23:38.464288 | 2026-04-14 02:23:38.464446 | PLAY [all] 2026-04-14 02:23:38.479066 | 2026-04-14 02:23:38.479287 | TASK [Copy inventory file for Zuul] 2026-04-14 02:23:39.750461 | rockylinux-9 | changed 2026-04-14 02:23:39.758225 | 2026-04-14 02:23:39.758363 | TASK [Switch "ansible_host" to private IP] 2026-04-14 02:23:40.104020 | rockylinux-9 | changed: 1 replacements made 2026-04-14 02:23:40.122047 | 2026-04-14 02:23:40.122180 | TASK [Run Molecule scenario] 2026-04-14 02:23:40.145269 | [rockylinux-9] Waiting on logger 2026-04-14 02:23:48.294271 | [rockylinux-9] Waiting on logger 2026-04-14 02:23:58.470045 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:08.650266 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:18.822043 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:29.098205 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:38.762304 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:48.937987 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:59.110046 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:08.266025 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:18.438326 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:28.617893 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:38.790114 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:48.969960 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:58.280181 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:08.454237 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:18.634018 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:28.809989 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:38.982685 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:48.236362 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:58.406327 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:08.582097 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:18.758140 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:28.933873 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:39.114019 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:48.262028 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:58.438273 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:08.614225 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:10.858099 | rockylinux-9 | ok: Runtime: 0:04:30.077466 2026-04-14 02:28:10.863732 | 2026-04-14 02:28:10.863789 | PLAY RECAP 2026-04-14 02:28:10.863833 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:28:10.863855 | 2026-04-14 02:28:10.978553 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:28:10.980744 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:28:11.576150 | 2026-04-14 02:28:11.576291 | PLAY [all] 2026-04-14 02:28:11.587118 | 2026-04-14 02:28:11.587192 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-14 02:28:11.632581 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:28:11.642012 | 2026-04-14 02:28:11.642176 | TASK [fetch-output : Set log path for single node] 2026-04-14 02:28:11.685950 | rockylinux-9 | ok 2026-04-14 02:28:11.691717 | 2026-04-14 02:28:11.691785 | LOOP [fetch-output : Ensure local output dirs] 2026-04-14 02:28:12.084750 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/d990c91da3e440d284e5e5d034456cd1/work/logs" 2026-04-14 02:28:12.285248 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/d990c91da3e440d284e5e5d034456cd1/work/artifacts" 2026-04-14 02:28:12.514632 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/d990c91da3e440d284e5e5d034456cd1/work/docs" 2026-04-14 02:28:12.533089 | 2026-04-14 02:28:12.533262 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-14 02:28:13.293067 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:28:13.293277 | rockylinux-9 | changed: All items complete 2026-04-14 02:28:13.293305 | 2026-04-14 02:28:13.871495 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:28:14.408575 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:28:14.429606 | 2026-04-14 02:28:14.429767 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-14 02:28:14.873006 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008614 2026-04-14 02:28:15.105095 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.011129 2026-04-14 02:28:15.126088 | 2026-04-14 02:28:15.126233 | PLAY [all] 2026-04-14 02:28:15.132737 | 2026-04-14 02:28:15.132807 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-14 02:28:15.595091 | rockylinux-9 | changed 2026-04-14 02:28:15.601420 | 2026-04-14 02:28:15.601494 | PLAY RECAP 2026-04-14 02:28:15.601541 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-14 02:28:15.601564 | 2026-04-14 02:28:15.752478 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:28:15.753857 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-14 02:28:16.326139 | 2026-04-14 02:28:16.326349 | PLAY [localhost] 2026-04-14 02:28:16.337964 | 2026-04-14 02:28:16.338066 | TASK [Generate Zuul manifest] 2026-04-14 02:28:16.361370 | localhost | ok 2026-04-14 02:28:16.377507 | 2026-04-14 02:28:16.377602 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-14 02:28:16.723561 | localhost | changed 2026-04-14 02:28:16.734866 | 2026-04-14 02:28:16.734955 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-14 02:28:16.765852 | localhost | ok 2026-04-14 02:28:16.775229 | 2026-04-14 02:28:16.775327 | TASK [Upload logs] 2026-04-14 02:28:16.795028 | localhost | ok 2026-04-14 02:28:16.856194 | 2026-04-14 02:28:16.856331 | TASK [Set zuul-log-path fact] 2026-04-14 02:28:16.879143 | localhost | ok 2026-04-14 02:28:16.891717 | 2026-04-14 02:28:16.891852 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:28:16.925049 | localhost | ok 2026-04-14 02:28:16.932671 | 2026-04-14 02:28:16.932822 | TASK [upload-logs : Create log directories] 2026-04-14 02:28:17.410534 | localhost | changed 2026-04-14 02:28:17.415447 | 2026-04-14 02:28:17.415519 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-14 02:28:17.832769 | localhost -> localhost | ok: Runtime: 0:00:00.005815 2026-04-14 02:28:17.838237 | 2026-04-14 02:28:17.838300 | TASK [upload-logs : Upload logs to log server] 2026-04-14 02:28:18.323865 | localhost | Output suppressed because no_log was given 2026-04-14 02:28:18.328441 | 2026-04-14 02:28:18.328508 | LOOP [upload-logs : Compress console log and json output] 2026-04-14 02:28:18.376359 | localhost | skipping: Conditional result was False 2026-04-14 02:28:18.384508 | localhost | skipping: Conditional result was False 2026-04-14 02:28:18.398879 | 2026-04-14 02:28:18.399013 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-14 02:28:18.446103 | localhost | skipping: Conditional result was False 2026-04-14 02:28:18.446439 | 2026-04-14 02:28:18.450398 | localhost | skipping: Conditional result was False 2026-04-14 02:28:18.465176 | 2026-04-14 02:28:18.465349 | LOOP [upload-logs : Upload console log and json output]