2026-04-19 01:00:51.476110 | Job console starting 2026-04-19 01:00:51.490070 | Updating git repos 2026-04-19 01:00:51.526368 | Cloning repos into workspace 2026-04-19 01:00:55.196280 | Restoring repo states 2026-04-19 01:00:55.206069 | Merging changes 2026-04-19 01:00:56.391417 | Checking out repos 2026-04-19 01:00:57.272044 | Preparing playbooks 2026-04-19 01:01:14.558061 | Running Ansible setup 2026-04-19 01:01:19.149716 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-19 01:01:19.809616 | 2026-04-19 01:01:19.809756 | PLAY [localhost] 2026-04-19 01:01:19.817676 | 2026-04-19 01:01:19.817764 | TASK [Gathering Facts] 2026-04-19 01:01:20.700984 | localhost | ok 2026-04-19 01:01:20.707797 | 2026-04-19 01:01:20.707873 | TASK [Setup log path fact] 2026-04-19 01:01:20.725562 | localhost | ok 2026-04-19 01:01:20.735195 | 2026-04-19 01:01:20.735259 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-19 01:01:20.763422 | localhost | ok 2026-04-19 01:01:20.769352 | 2026-04-19 01:01:20.769448 | TASK [emit-job-header : Print job information] 2026-04-19 01:01:20.810596 | # Job Information 2026-04-19 01:01:20.810821 | Ansible Version: 2.16.16 2026-04-19 01:01:20.810894 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-19 01:01:20.810945 | Pipeline: check 2026-04-19 01:01:20.810990 | Executor: 0a8996d2b663 2026-04-19 01:01:20.811036 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/105 2026-04-19 01:01:20.811087 | Event ID: 132c0160-3b8b-11f1-9de8-8d94a4603602 2026-04-19 01:01:20.814928 | 2026-04-19 01:01:20.814993 | LOOP [emit-job-header : Print node information] 2026-04-19 01:01:20.926488 | localhost | ok: 2026-04-19 01:01:20.926742 | localhost | # Node Information 2026-04-19 01:01:20.926806 | localhost | Inventory Hostname: rockylinux-9 2026-04-19 01:01:20.926854 | localhost | Hostname: np0000167690 2026-04-19 01:01:20.926899 | localhost | Username: zuul 2026-04-19 01:01:20.926949 | localhost | Distro: Rocky 9.7 2026-04-19 01:01:20.926993 | localhost | Provider: yul1 2026-04-19 01:01:20.927035 | localhost | Region: ca-ymq-1 2026-04-19 01:01:20.927076 | localhost | Label: rockylinux-9 2026-04-19 01:01:20.927117 | localhost | Product Name: OpenStack Nova 2026-04-19 01:01:20.927157 | localhost | Interface IP: 199.204.45.89 2026-04-19 01:01:20.936759 | 2026-04-19 01:01:20.936837 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-19 01:01:21.432053 | localhost -> localhost | changed 2026-04-19 01:01:21.439212 | 2026-04-19 01:01:21.439354 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-19 01:01:22.567571 | localhost -> localhost | changed 2026-04-19 01:01:22.578700 | 2026-04-19 01:01:22.578798 | PLAY [all] 2026-04-19 01:01:22.586225 | 2026-04-19 01:01:22.586316 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-19 01:01:22.967819 | rockylinux-9 -> localhost | ok 2026-04-19 01:01:22.973177 | 2026-04-19 01:01:22.973256 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-19 01:01:23.021736 | rockylinux-9 | ok 2026-04-19 01:01:23.039962 | rockylinux-9 | included: /var/lib/zuul/builds/5eb87c657cb64f1b95c6cec9cf8591bc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-19 01:01:23.045745 | 2026-04-19 01:01:23.116333 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-19 01:01:24.335235 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-19 01:01:24.335401 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/5eb87c657cb64f1b95c6cec9cf8591bc/work/5eb87c657cb64f1b95c6cec9cf8591bc_id_rsa 2026-04-19 01:01:24.335432 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/5eb87c657cb64f1b95c6cec9cf8591bc/work/5eb87c657cb64f1b95c6cec9cf8591bc_id_rsa.pub 2026-04-19 01:01:24.335455 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-19 01:01:24.335476 | rockylinux-9 -> localhost | SHA256:aEqxbcPf/oWkrOjHssOVzE9Io36lq6FfU7i2plUzlp8 zuul-build-sshkey 2026-04-19 01:01:24.335509 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-19 01:01:24.335530 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-19 01:01:24.335556 | rockylinux-9 -> localhost | | | 2026-04-19 01:01:24.335577 | rockylinux-9 -> localhost | | | 2026-04-19 01:01:24.335597 | rockylinux-9 -> localhost | | . | 2026-04-19 01:01:24.335616 | rockylinux-9 -> localhost | | = .o. . | 2026-04-19 01:01:24.335635 | rockylinux-9 -> localhost | | o B=S+B . | 2026-04-19 01:01:24.335654 | rockylinux-9 -> localhost | | . +.o*Bo* o | 2026-04-19 01:01:24.335672 | rockylinux-9 -> localhost | | .o.oB=+ E . | 2026-04-19 01:01:24.335691 | rockylinux-9 -> localhost | | .=**=. . | 2026-04-19 01:01:24.335713 | rockylinux-9 -> localhost | | .oBX+.... | 2026-04-19 01:01:24.335732 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-19 01:01:24.335779 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.666200 2026-04-19 01:01:24.340326 | 2026-04-19 01:01:24.340390 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-19 01:01:24.369099 | rockylinux-9 | ok 2026-04-19 01:01:24.507433 | rockylinux-9 | included: /var/lib/zuul/builds/5eb87c657cb64f1b95c6cec9cf8591bc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-19 01:01:24.516045 | 2026-04-19 01:01:24.516107 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-19 01:01:24.540931 | rockylinux-9 | skipping: Conditional result was False 2026-04-19 01:01:24.552418 | 2026-04-19 01:01:24.552503 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-19 01:01:25.048834 | rockylinux-9 | changed 2026-04-19 01:01:25.055718 | 2026-04-19 01:01:25.055811 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-19 01:01:25.308183 | rockylinux-9 | ok 2026-04-19 01:01:25.314316 | 2026-04-19 01:01:25.314384 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-19 01:01:26.286480 | rockylinux-9 | changed 2026-04-19 01:01:26.290913 | 2026-04-19 01:01:26.290981 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-19 01:01:27.226036 | rockylinux-9 | changed 2026-04-19 01:01:27.231138 | 2026-04-19 01:01:27.231208 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-19 01:01:27.255832 | rockylinux-9 | skipping: Conditional result was False 2026-04-19 01:01:27.261841 | 2026-04-19 01:01:27.261934 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-19 01:01:27.963353 | rockylinux-9 -> localhost | changed 2026-04-19 01:01:27.972247 | 2026-04-19 01:01:27.972328 | TASK [add-build-sshkey : Add back temp key] 2026-04-19 01:01:28.307067 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/5eb87c657cb64f1b95c6cec9cf8591bc/work/5eb87c657cb64f1b95c6cec9cf8591bc_id_rsa (zuul-build-sshkey) 2026-04-19 01:01:28.307301 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012870 2026-04-19 01:01:28.316904 | 2026-04-19 01:01:28.317022 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-19 01:01:28.692911 | rockylinux-9 | ok 2026-04-19 01:01:28.837531 | 2026-04-19 01:01:28.837663 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-19 01:01:28.882214 | rockylinux-9 | skipping: Conditional result was False 2026-04-19 01:01:28.890896 | 2026-04-19 01:01:28.890983 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-19 01:01:29.300197 | rockylinux-9 | ok 2026-04-19 01:01:29.308252 | 2026-04-19 01:01:29.308397 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-19 01:01:30.304828 | rockylinux-9 | Output suppressed because no_log was given 2026-04-19 01:01:30.312804 | 2026-04-19 01:01:30.312879 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-19 01:01:30.564045 | rockylinux-9 | ok: "logs" 2026-04-19 01:01:30.564282 | rockylinux-9 | ok: All items complete 2026-04-19 01:01:30.564312 | 2026-04-19 01:01:30.781835 | rockylinux-9 | ok: "artifacts" 2026-04-19 01:01:30.997777 | rockylinux-9 | ok: "docs" 2026-04-19 01:01:31.026022 | 2026-04-19 01:01:31.026140 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-19 01:01:31.277147 | rockylinux-9 | changed: "logs" 2026-04-19 01:01:31.539214 | rockylinux-9 | changed: "artifacts" 2026-04-19 01:01:31.720640 | rockylinux-9 | changed: "docs" 2026-04-19 01:01:31.741138 | 2026-04-19 01:01:31.741287 | PLAY RECAP 2026-04-19 01:01:31.741518 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-19 01:01:31.741558 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-19 01:01:31.741648 | 2026-04-19 01:01:31.903190 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-19 01:01:32.474948 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-19 01:01:33.311326 | 2026-04-19 01:01:33.311449 | PLAY [all] 2026-04-19 01:01:33.329850 | 2026-04-19 01:01:33.329978 | TASK [setup-uv : Extract archive] 2026-04-19 01:01:35.999618 | rockylinux-9 | changed 2026-04-19 01:01:36.010949 | 2026-04-19 01:01:36.011140 | TASK [setup-uv : Print version] 2026-04-19 01:01:36.042716 | [rockylinux-9] Waiting on logger 2026-04-19 01:01:36.553154 | rockylinux-9 | ok: Runtime: 0:00:00.015756 2026-04-19 01:01:36.559258 | 2026-04-19 01:01:36.559315 | PLAY RECAP 2026-04-19 01:01:36.559371 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-19 01:01:36.559397 | 2026-04-19 01:01:36.770671 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-19 01:01:36.771840 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-19 01:01:37.342757 | 2026-04-19 01:01:37.342883 | PLAY [all] 2026-04-19 01:01:37.353651 | 2026-04-19 01:01:37.353727 | TASK [Copy inventory file for Zuul] 2026-04-19 01:01:38.656274 | rockylinux-9 | changed 2026-04-19 01:01:38.723526 | 2026-04-19 01:01:38.723752 | TASK [Switch "ansible_host" to private IP] 2026-04-19 01:01:39.093782 | rockylinux-9 | changed: 1 replacements made 2026-04-19 01:01:39.098777 | 2026-04-19 01:01:39.098847 | TASK [Run Molecule scenario] 2026-04-19 01:01:39.119322 | [rockylinux-9] Waiting on logger 2026-04-19 01:01:47.269904 | [rockylinux-9] Waiting on logger 2026-04-19 01:01:57.445937 | [rockylinux-9] Waiting on logger 2026-04-19 01:02:07.622061 | [rockylinux-9] Waiting on logger 2026-04-19 01:02:17.802080 | [rockylinux-9] Waiting on logger 2026-04-19 01:02:27.973921 | [rockylinux-9] Waiting on logger 2026-04-19 01:02:37.131164 | [rockylinux-9] Waiting on logger 2026-04-19 01:02:47.301898 | [rockylinux-9] Waiting on logger 2026-04-19 01:02:57.477811 | [rockylinux-9] Waiting on logger 2026-04-19 01:03:07.653785 | [rockylinux-9] Waiting on logger 2026-04-19 01:03:17.830001 | [rockylinux-9] Waiting on logger 2026-04-19 01:03:28.005961 | [rockylinux-9] Waiting on logger 2026-04-19 01:03:37.167068 | [rockylinux-9] Waiting on logger 2026-04-19 01:03:45.005436 | rockylinux-9 | ok: Runtime: 0:02:04.717908 2026-04-19 01:03:45.012647 | 2026-04-19 01:03:45.012710 | PLAY RECAP 2026-04-19 01:03:45.012750 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-19 01:03:45.012772 | 2026-04-19 01:03:45.118737 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-19 01:03:45.121810 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-19 01:03:45.675457 | 2026-04-19 01:03:45.675630 | PLAY [all] 2026-04-19 01:03:45.687023 | 2026-04-19 01:03:45.687147 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-19 01:03:45.732170 | rockylinux-9 | skipping: Conditional result was False 2026-04-19 01:03:45.742063 | 2026-04-19 01:03:45.742179 | TASK [fetch-output : Set log path for single node] 2026-04-19 01:03:45.786512 | rockylinux-9 | ok 2026-04-19 01:03:45.790810 | 2026-04-19 01:03:45.790880 | LOOP [fetch-output : Ensure local output dirs] 2026-04-19 01:03:46.149918 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/5eb87c657cb64f1b95c6cec9cf8591bc/work/logs" 2026-04-19 01:03:46.353104 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5eb87c657cb64f1b95c6cec9cf8591bc/work/artifacts" 2026-04-19 01:03:46.549837 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5eb87c657cb64f1b95c6cec9cf8591bc/work/docs" 2026-04-19 01:03:46.562700 | 2026-04-19 01:03:46.562813 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-19 01:03:47.272323 | rockylinux-9 | changed: .d..t...... ./ 2026-04-19 01:03:47.272727 | rockylinux-9 | changed: All items complete 2026-04-19 01:03:47.272816 | 2026-04-19 01:03:47.801496 | rockylinux-9 | changed: .d..t...... ./ 2026-04-19 01:03:48.308821 | rockylinux-9 | changed: .d..t...... ./ 2026-04-19 01:03:48.335252 | 2026-04-19 01:03:48.335396 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-19 01:03:48.779593 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006475 2026-04-19 01:03:48.996873 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006125 2026-04-19 01:03:49.017290 | 2026-04-19 01:03:49.017399 | PLAY [all] 2026-04-19 01:03:49.022872 | 2026-04-19 01:03:49.022939 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-19 01:03:49.471813 | rockylinux-9 | changed 2026-04-19 01:03:49.478428 | 2026-04-19 01:03:49.478484 | PLAY RECAP 2026-04-19 01:03:49.478532 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-19 01:03:49.478554 | 2026-04-19 01:03:49.585284 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-19 01:03:49.587508 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-19 01:03:50.135865 | 2026-04-19 01:03:50.135999 | PLAY [localhost] 2026-04-19 01:03:50.145954 | 2026-04-19 01:03:50.146054 | TASK [Generate Zuul manifest] 2026-04-19 01:03:50.167533 | localhost | ok 2026-04-19 01:03:50.184593 | 2026-04-19 01:03:50.184731 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-19 01:03:50.487802 | localhost | changed 2026-04-19 01:03:50.498697 | 2026-04-19 01:03:50.498780 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-19 01:03:50.529801 | localhost | ok 2026-04-19 01:03:50.538440 | 2026-04-19 01:03:50.538503 | TASK [Upload logs] 2026-04-19 01:03:50.558592 | localhost | ok 2026-04-19 01:03:50.615908 | 2026-04-19 01:03:50.616026 | TASK [Set zuul-log-path fact] 2026-04-19 01:03:50.637742 | localhost | ok 2026-04-19 01:03:50.650920 | 2026-04-19 01:03:50.650987 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-19 01:03:50.682036 | localhost | ok 2026-04-19 01:03:50.691035 | 2026-04-19 01:03:50.691102 | TASK [upload-logs : Create log directories] 2026-04-19 01:03:51.118160 | localhost | changed 2026-04-19 01:03:51.124196 | 2026-04-19 01:03:51.124267 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-19 01:03:51.531867 | localhost -> localhost | ok: Runtime: 0:00:00.005829 2026-04-19 01:03:51.539066 | 2026-04-19 01:03:51.539143 | TASK [upload-logs : Upload logs to log server] 2026-04-19 01:03:51.986421 | localhost | Output suppressed because no_log was given 2026-04-19 01:03:51.990521 | 2026-04-19 01:03:51.990590 | LOOP [upload-logs : Compress console log and json output] 2026-04-19 01:03:52.037433 | localhost | skipping: Conditional result was False 2026-04-19 01:03:52.045331 | localhost | skipping: Conditional result was False 2026-04-19 01:03:52.055932 | 2026-04-19 01:03:52.056044 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-19 01:03:52.099043 | localhost | skipping: Conditional result was False 2026-04-19 01:03:52.099377 | 2026-04-19 01:03:52.104502 | localhost | skipping: Conditional result was False 2026-04-19 01:03:52.119192 | 2026-04-19 01:03:52.119378 | LOOP [upload-logs : Upload console log and json output]