2025-12-08 03:34:12.485573 | Job console starting... 2025-12-08 03:34:12.500417 | Updating repositories 2025-12-08 03:34:12.525701 | Preparing job workspace 2025-12-08 03:34:17.923683 | Running Ansible setup... 2025-12-08 03:34:25.647095 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-12-08 03:34:26.248651 | 2025-12-08 03:34:26.248759 | PLAY [localhost] 2025-12-08 03:34:26.257033 | 2025-12-08 03:34:26.257105 | TASK [Gathering Facts] 2025-12-08 03:34:27.260310 | localhost | ok 2025-12-08 03:34:27.288910 | 2025-12-08 03:34:27.289104 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-08 03:34:27.715485 | localhost -> localhost | changed 2025-12-08 03:34:27.720798 | 2025-12-08 03:34:27.720876 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-08 03:34:28.585517 | localhost -> localhost | changed 2025-12-08 03:34:28.593838 | 2025-12-08 03:34:28.593899 | TASK [Setup log path fact] 2025-12-08 03:34:28.614852 | localhost | ok 2025-12-08 03:34:28.640904 | 2025-12-08 03:34:28.640990 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-08 03:34:28.673811 | localhost | ok 2025-12-08 03:34:28.681691 | 2025-12-08 03:34:28.681758 | TASK [emit-job-header : Print job information] 2025-12-08 03:34:28.720121 | # Job Information 2025-12-08 03:34:28.720230 | Ansible Version: 2.15.12 2025-12-08 03:34:28.720256 | Job: cifmw-pod-ansible-test 2025-12-08 03:34:28.720276 | Pipeline: github-check 2025-12-08 03:34:28.720294 | Executor: ze01.softwarefactory-project.io 2025-12-08 03:34:28.720311 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3497 2025-12-08 03:34:28.720330 | Log URL (when completed): https://logserver.rdoproject.org/c9b/rdoproject.org/c9b77c48eac7429b984f05d518a0adbd/ 2025-12-08 03:34:28.720348 | Event ID: b1659a10-d3e6-11f0-84cd-b1370eb78d64 2025-12-08 03:34:28.723992 | 2025-12-08 03:34:28.724077 | LOOP [emit-job-header : Print node information] 2025-12-08 03:34:28.819478 | localhost | ok: 2025-12-08 03:34:28.819728 | localhost | # Node Information 2025-12-08 03:34:28.819785 | localhost | Inventory Hostname: container 2025-12-08 03:34:28.819859 | localhost | Hostname: 7baa2a3a8752 2025-12-08 03:34:28.819890 | localhost | Username: zuul 2025-12-08 03:34:28.819943 | localhost | Distro: CentOS 9 2025-12-08 03:34:28.819967 | localhost | Provider: k1s04 2025-12-08 03:34:28.820022 | localhost | Region: 2025-12-08 03:34:28.820053 | localhost | Label: pod-centos-9-stream 2025-12-08 03:34:28.820122 | localhost | Product Name: OpenStack Nova 2025-12-08 03:34:28.820150 | localhost | Interface IP: pod-centos-9-stream-0005549859 2025-12-08 03:34:28.849959 | 2025-12-08 03:34:28.850120 | PLAY [all] 2025-12-08 03:34:28.855896 | 2025-12-08 03:34:28.855959 | TASK [Gather network facts] 2025-12-08 03:34:34.942705 | container | ok 2025-12-08 03:34:34.974249 | 2025-12-08 03:34:34.974458 | TASK [include_role : start-zuul-console] 2025-12-08 03:34:34.997326 | container | ok 2025-12-08 03:34:35.013959 | 2025-12-08 03:34:35.014089 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-08 03:34:40.992372 | container | ok 2025-12-08 03:34:41.011546 | 2025-12-08 03:34:41.011707 | TASK [include_role : add-build-sshkey] 2025-12-08 03:34:41.039350 | container | skipping: Conditional result was False 2025-12-08 03:34:41.050579 | 2025-12-08 03:34:41.050709 | TASK [include_role : validate-host] 2025-12-08 03:34:41.078125 | container | skipping: Conditional result was False 2025-12-08 03:34:41.089237 | 2025-12-08 03:34:41.089367 | TASK [include_role : prepare-workspace-openshift] 2025-12-08 03:34:41.120830 | container | ok 2025-12-08 03:34:41.132782 | 2025-12-08 03:34:41.132852 | LOOP [prepare-workspace-openshift : include_tasks] 2025-12-08 03:34:41.198354 | container | ok: 2025-12-08 03:34:41.198624 | container | [ 2025-12-08 03:34:41.198674 | container | "container", 2025-12-08 03:34:41.198696 | container | { 2025-12-08 03:34:41.198712 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2025-12-08 03:34:41.198728 | container | "namespace": "main", 2025-12-08 03:34:41.198758 | container | "pod": "pod-centos-9-stream-0005549859", 2025-12-08 03:34:41.198775 | container | "stream_port": "44127" 2025-12-08 03:34:41.198790 | container | } 2025-12-08 03:34:41.198806 | container | ] 2025-12-08 03:34:41.198833 | container | ok: All items complete 2025-12-08 03:34:41.198852 | 2025-12-08 03:34:41.216125 | container | included: /var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml 2025-12-08 03:34:41.221178 | 2025-12-08 03:34:41.221242 | TASK [prepare-workspace-openshift : Create src directory] 2025-12-08 03:34:42.659354 | container -> localhost | ok: Runtime: 0:00:00.943859 2025-12-08 03:34:42.666142 | 2025-12-08 03:34:42.666227 | TASK [prepare-workspace-openshift : Copy src repos to the pod] 2025-12-08 03:34:44.941742 | container -> localhost | Output suppressed because no_log was given 2025-12-08 03:34:44.992343 | 2025-12-08 03:34:44.992491 | TASK [include_role : remove-zuul-sshkey] 2025-12-08 03:34:45.026641 | container | ok 2025-12-08 03:34:45.052307 | 2025-12-08 03:34:45.052390 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-08 03:34:45.452898 | container -> localhost | changed 2025-12-08 03:34:45.462700 | 2025-12-08 03:34:45.462765 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-08 03:34:51.191332 | container | ok: "logs" 2025-12-08 03:34:57.064709 | container | ok: "artifacts" 2025-12-08 03:35:02.879372 | container | ok: "docs" 2025-12-08 03:35:02.895384 | 2025-12-08 03:35:02.895520 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-08 03:35:08.913701 | container | changed: "logs" 2025-12-08 03:35:08.914139 | container | ok: All items complete 2025-12-08 03:35:08.914197 | 2025-12-08 03:35:14.568931 | container | changed: "artifacts" 2025-12-08 03:35:20.511734 | container | changed: "docs" 2025-12-08 03:35:20.565223 | 2025-12-08 03:35:20.565398 | PLAY RECAP 2025-12-08 03:35:20.565487 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-12-08 03:35:20.565543 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-08 03:35:20.565583 | 2025-12-08 03:35:20.690081 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-12-08 03:35:20.691728 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-12-08 03:35:21.302875 | 2025-12-08 03:35:21.302995 | PLAY [localhost] 2025-12-08 03:35:21.318952 | 2025-12-08 03:35:21.319054 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2025-12-08 03:35:21.723494 | localhost | ok 2025-12-08 03:35:21.728685 | 2025-12-08 03:35:21.728760 | TASK [configure-ssh-config-fips : Setup ssh config] 2025-12-08 03:35:22.480583 | localhost | changed 2025-12-08 03:35:22.527512 | 2025-12-08 03:35:22.527662 | PLAY [all] 2025-12-08 03:35:22.554221 | 2025-12-08 03:35:22.554427 | TASK [include_role : prepare-workspace] 2025-12-08 03:35:22.570429 | container | skipping: Conditional result was False 2025-12-08 03:35:22.576161 | 2025-12-08 03:35:22.576272 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-08 03:35:28.375138 | container | changed: "logs" 2025-12-08 03:35:34.221421 | container | changed: "artifacts" 2025-12-08 03:35:40.346565 | container | changed: "docs" 2025-12-08 03:35:40.368457 | 2025-12-08 03:35:40.368645 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-08 03:35:46.301381 | container | changed: "logs" 2025-12-08 03:35:46.301841 | container | changed: All items complete 2025-12-08 03:35:46.301913 | 2025-12-08 03:35:52.300873 | container | changed: "artifacts" 2025-12-08 03:35:58.087186 | container | changed: "docs" 2025-12-08 03:35:58.120638 | 2025-12-08 03:35:58.120876 | TASK [Check if worker can sudo] 2025-12-08 03:36:04.348241 | container | ok: Runtime: 0:00:00.034137 2025-12-08 03:36:04.358701 | 2025-12-08 03:36:04.358832 | TASK [configure-mirrors : Gather needed facts] 2025-12-08 03:36:04.386523 | container | skipping: Conditional result was False 2025-12-08 03:36:04.397217 | 2025-12-08 03:36:04.397360 | TASK [configure-mirrors : Set up infrastructure mirrors] 2025-12-08 03:36:04.423930 | container | skipping: Conditional result was False 2025-12-08 03:36:04.465791 | 2025-12-08 03:36:04.465862 | PLAY RECAP 2025-12-08 03:36:04.465917 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2025-12-08 03:36:04.465951 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-08 03:36:04.465974 | 2025-12-08 03:36:04.592542 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-12-08 03:36:04.594177 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2025-12-08 03:36:05.253685 | 2025-12-08 03:36:05.253830 | PLAY [Run light checks in pod] 2025-12-08 03:36:05.278949 | 2025-12-08 03:36:05.279084 | TASK [Install packages] 2025-12-08 03:36:22.956324 | container | ok: Nothing to do 2025-12-08 03:36:22.970836 | 2025-12-08 03:36:22.970991 | TASK [Ensure zuul-output exists] 2025-12-08 03:36:28.966057 | container | ok 2025-12-08 03:36:28.977083 | 2025-12-08 03:36:28.977227 | TASK [Install dependencies] 2025-12-08 03:37:41.975410 | container | changed 2025-12-08 03:37:41.995586 | 2025-12-08 03:37:41.995727 | TASK [Run check] 2025-12-08 04:04:34.647303 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2025-12-08 04:04:34.648889 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-12-08 04:04:35.246198 | 2025-12-08 04:04:35.246311 | PLAY [all] 2025-12-08 04:04:35.263036 | 2025-12-08 04:04:35.263105 | TASK [include_role : fetch-output] 2025-12-08 04:04:35.287054 | container | skipping: Conditional result was False 2025-12-08 04:04:35.291897 | 2025-12-08 04:04:35.291965 | TASK [include_role : fetch-output-openshift] 2025-12-08 04:04:35.339906 | container | ok 2025-12-08 04:04:35.355272 | 2025-12-08 04:04:35.355335 | TASK [fetch-output-openshift : Set log path for multiple nodes] 2025-12-08 04:04:35.419435 | container | skipping: Conditional result was False 2025-12-08 04:04:35.424194 | 2025-12-08 04:04:35.424257 | TASK [fetch-output-openshift : Set log path for single node] 2025-12-08 04:04:35.472166 | container | ok 2025-12-08 04:04:35.476469 | 2025-12-08 04:04:35.476542 | LOOP [fetch-output-openshift : Ensure local output dirs] 2025-12-08 04:04:35.882580 | container -> localhost | ok: "/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/logs" 2025-12-08 04:04:36.149805 | container -> localhost | changed: "/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/logs/npm" 2025-12-08 04:04:36.392297 | container -> localhost | changed: "/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/artifacts" 2025-12-08 04:04:36.673825 | container -> localhost | changed: "/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/docs" 2025-12-08 04:04:36.688489 | 2025-12-08 04:04:36.688613 | LOOP [fetch-output-openshift : include_tasks] 2025-12-08 04:04:36.756125 | container | ok: 2025-12-08 04:04:36.756395 | container | [ 2025-12-08 04:04:36.756443 | container | "container", 2025-12-08 04:04:36.756486 | container | { 2025-12-08 04:04:36.756528 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2025-12-08 04:04:36.756564 | container | "namespace": "main", 2025-12-08 04:04:36.756588 | container | "pod": "pod-centos-9-stream-0005549859", 2025-12-08 04:04:36.756611 | container | "stream_port": "44127" 2025-12-08 04:04:36.756633 | container | } 2025-12-08 04:04:36.756655 | container | ] 2025-12-08 04:04:36.756694 | container | changed: All items complete 2025-12-08 04:04:36.756722 | 2025-12-08 04:04:36.771196 | container | included: /var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml 2025-12-08 04:04:36.776707 | 2025-12-08 04:04:36.776768 | LOOP [fetch-output-openshift : Ensure local directory exists] 2025-12-08 04:04:42.189662 | container | changed: "/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/logs" 2025-12-08 04:04:47.190058 | container | changed: "/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/logs/npm" 2025-12-08 04:04:52.372951 | container | changed: "/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/artifacts" 2025-12-08 04:04:57.234635 | container | changed: "/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/docs" 2025-12-08 04:04:57.257355 | 2025-12-08 04:04:57.257530 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor] 2025-12-08 04:05:02.199415 | container | Output suppressed because no_log was given 2025-12-08 04:05:02.209826 | 2025-12-08 04:05:02.209955 | TASK [fetch-output-openshift : Remove empty directory] 2025-12-08 04:05:02.604047 | container -> localhost | ok: Runtime: 0:00:00.056142 2025-12-08 04:05:02.625507 | 2025-12-08 04:05:02.625660 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-08 04:05:02.949600 | container -> localhost | find: ‘/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/artifacts’: No such file or directory 2025-12-08 04:05:02.950094 | container -> localhost | find: ‘/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/artifacts’: No such file or directory 2025-12-08 04:05:02.950229 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.010463 2025-12-08 04:05:02.950321 | container -> localhost | changed: All items complete 2025-12-08 04:05:02.950374 | 2025-12-08 04:05:03.198250 | container -> localhost | find: ‘/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/docs’: No such file or directory 2025-12-08 04:05:03.198373 | container -> localhost | find: ‘/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/docs’: No such file or directory 2025-12-08 04:05:03.198418 | container -> localhost | ok: Item: docs Runtime: 0:00:00.011097 2025-12-08 04:05:03.254761 | 2025-12-08 04:05:03.254888 | PLAY [all] 2025-12-08 04:05:03.273333 | 2025-12-08 04:05:03.273423 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-08 04:05:03.318579 | container | ERROR 2025-12-08 04:05:03.318902 | container | { 2025-12-08 04:05:03.318962 | container | "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/src/../c9b77c48eac7429b984f05d518a0adbd_id_rsa.pub'. file not found, use -vvvvv to see paths searched" 2025-12-08 04:05:03.319006 | container | } 2025-12-08 04:05:03.319102 | container | ERROR: Ignoring Errors 2025-12-08 04:05:03.352235 | 2025-12-08 04:05:03.352349 | PLAY RECAP 2025-12-08 04:05:03.352407 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1 2025-12-08 04:05:03.352436 | 2025-12-08 04:05:03.457338 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-12-08 04:05:03.458227 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-12-08 04:05:04.068618 | 2025-12-08 04:05:04.068743 | PLAY [localhost] 2025-12-08 04:05:04.086720 | 2025-12-08 04:05:04.086808 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-08 04:05:04.517554 | localhost | changed 2025-12-08 04:05:04.528045 | 2025-12-08 04:05:04.528236 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-08 04:05:04.565242 | localhost | ok 2025-12-08 04:05:04.582527 | 2025-12-08 04:05:04.582671 | TASK [add-fileserver : Create SSH private key tempfile] 2025-12-08 04:05:04.984137 | localhost | changed 2025-12-08 04:05:04.995475 | 2025-12-08 04:05:04.995619 | TASK [add-fileserver : Create SSH private key from secret] 2025-12-08 04:05:05.675848 | localhost | changed 2025-12-08 04:05:05.680787 | 2025-12-08 04:05:05.680854 | TASK [add-fileserver : Add fileserver ssh key] 2025-12-08 04:05:06.138842 | localhost | Identity added: /var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/tmp/ansible.eft613ee (/var/lib/zuul/builds/c9b77c48eac7429b984f05d518a0adbd/work/tmp/ansible.eft613ee) 2025-12-08 04:05:06.139096 | localhost | ok: Runtime: 0:00:00.012605 2025-12-08 04:05:06.145085 | 2025-12-08 04:05:06.145176 | TASK [add-fileserver : Remove SSH private key from disk] 2025-12-08 04:05:06.455329 | localhost | ok: Runtime: 0:00:00.008224 2025-12-08 04:05:06.465187 | 2025-12-08 04:05:06.465325 | TASK [add-fileserver : Add fileserver to inventory] 2025-12-08 04:05:06.552380 | localhost | changed 2025-12-08 04:05:06.561276 | 2025-12-08 04:05:06.561409 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-12-08 04:05:06.953002 | localhost | changed 2025-12-08 04:05:06.990700 | 2025-12-08 04:05:06.990775 | PLAY [localhost] 2025-12-08 04:05:07.006916 | 2025-12-08 04:05:07.006998 | TASK [Generate bulk log download script] 2025-12-08 04:05:07.027275 | localhost | ok 2025-12-08 04:05:07.043209 | 2025-12-08 04:05:07.043297 | TASK [local-log-download : Check API endpoint is defined] 2025-12-08 04:05:07.095638 | localhost | ok: All assertions passed 2025-12-08 04:05:07.105125 | 2025-12-08 04:05:07.105259 | TASK [local-log-download : Create download script] 2025-12-08 04:05:07.569115 | localhost -> localhost | changed 2025-12-08 04:05:07.589592 | 2025-12-08 04:05:07.589780 | TASK [Register quick-download link] 2025-12-08 04:05:07.612942 | localhost | ok 2025-12-08 04:05:07.656092 | 2025-12-08 04:05:07.656197 | PLAY [logserver.rdoproject.org] 2025-12-08 04:05:07.665656 | 2025-12-08 04:05:07.665715 | TASK [Set zuul-log-path fact] 2025-12-08 04:05:07.682305 | logserver.rdoproject.org | ok 2025-12-08 04:05:07.691296 | 2025-12-08 04:05:07.691383 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-08 04:05:07.727645 | logserver.rdoproject.org | ok 2025-12-08 04:05:07.732615 | 2025-12-08 04:05:07.732677 | TASK [upload-logs : Create log directories] 2025-12-08 04:05:08.581807 | logserver.rdoproject.org | changed 2025-12-08 04:05:08.586477 | 2025-12-08 04:05:08.586570 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-08 04:05:08.908862 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008466 2025-12-08 04:05:08.918328 | 2025-12-08 04:05:08.918498 | TASK [upload-logs : Upload logs to log server] 2025-12-08 04:05:09.611953 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-12-08 04:05:09.615780 | 2025-12-08 04:05:09.615867 | LOOP [upload-logs : Compress console log and json output] 2025-12-08 04:05:09.679377 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-08 04:05:09.700274 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-08 04:05:09.711985 | 2025-12-08 04:05:09.712268 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-08 04:05:09.779575 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-08 04:05:09.780047 | 2025-12-08 04:05:09.785599 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-08 04:05:09.800184 | 2025-12-08 04:05:09.800380 | LOOP [upload-logs : Upload console log and json output]