2025-12-11 11:26:39.597468 | Job console starting... 2025-12-11 11:26:39.607064 | Updating repositories 2025-12-11 11:26:39.636133 | Preparing job workspace 2025-12-11 11:26:45.216897 | Running Ansible setup... 2025-12-11 11:26:53.371584 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-12-11 11:26:53.984097 | 2025-12-11 11:26:53.984223 | PLAY [localhost] 2025-12-11 11:26:53.994350 | 2025-12-11 11:26:53.994447 | TASK [Gathering Facts] 2025-12-11 11:26:54.985619 | localhost | ok 2025-12-11 11:26:55.009227 | 2025-12-11 11:26:55.009412 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-11 11:26:55.417963 | localhost -> localhost | changed 2025-12-11 11:26:55.423449 | 2025-12-11 11:26:55.423523 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-11 11:26:56.344984 | localhost -> localhost | changed 2025-12-11 11:26:56.353968 | 2025-12-11 11:26:56.354059 | TASK [Setup log path fact] 2025-12-11 11:26:56.372011 | localhost | ok 2025-12-11 11:26:56.383418 | 2025-12-11 11:26:56.383505 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-11 11:26:56.411326 | localhost | ok 2025-12-11 11:26:56.419695 | 2025-12-11 11:26:56.419762 | TASK [emit-job-header : Print job information] 2025-12-11 11:26:56.449851 | # Job Information 2025-12-11 11:26:56.450039 | Ansible Version: 2.15.12 2025-12-11 11:26:56.450067 | Job: cifmw-pod-pre-commit 2025-12-11 11:26:56.450090 | Pipeline: github-check 2025-12-11 11:26:56.450112 | Executor: ze04.softwarefactory-project.io 2025-12-11 11:26:56.450133 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3547 2025-12-11 11:26:56.450155 | Log URL (when completed): https://logserver.rdoproject.org/fd4/rdoproject.org/fd4f30a67aea48ac8e3efc890a224d96/ 2025-12-11 11:26:56.450176 | Event ID: 1b63ae30-d684-11f0-8730-cfd857a61b1f 2025-12-11 11:26:56.454788 | 2025-12-11 11:26:56.454861 | LOOP [emit-job-header : Print node information] 2025-12-11 11:26:56.545643 | localhost | ok: 2025-12-11 11:26:56.545834 | localhost | # Node Information 2025-12-11 11:26:56.545867 | localhost | Inventory Hostname: container 2025-12-11 11:26:56.545894 | localhost | Hostname: 49976fb307cb 2025-12-11 11:26:56.545917 | localhost | Username: zuul 2025-12-11 11:26:56.545941 | localhost | Distro: CentOS 9 2025-12-11 11:26:56.545962 | localhost | Provider: k1s04 2025-12-11 11:26:56.545983 | localhost | Region: 2025-12-11 11:26:56.546005 | localhost | Label: pod-centos-9-stream 2025-12-11 11:26:56.546026 | localhost | Product Name: OpenStack Nova 2025-12-11 11:26:56.546049 | localhost | Interface IP: pod-centos-9-stream-0005555434 2025-12-11 11:26:56.569844 | 2025-12-11 11:26:56.569948 | PLAY [all] 2025-12-11 11:26:56.575717 | 2025-12-11 11:26:56.575780 | TASK [Gather network facts] 2025-12-11 11:27:02.305219 | container | ok 2025-12-11 11:27:02.324976 | 2025-12-11 11:27:02.325040 | TASK [include_role : start-zuul-console] 2025-12-11 11:27:02.343758 | container | ok 2025-12-11 11:27:02.357943 | 2025-12-11 11:27:02.358056 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-11 11:27:08.700417 | container | ok 2025-12-11 11:27:08.711399 | 2025-12-11 11:27:08.711485 | TASK [include_role : add-build-sshkey] 2025-12-11 11:27:08.736289 | container | skipping: Conditional result was False 2025-12-11 11:27:08.742605 | 2025-12-11 11:27:08.742709 | TASK [include_role : validate-host] 2025-12-11 11:27:08.776805 | container | skipping: Conditional result was False 2025-12-11 11:27:08.783191 | 2025-12-11 11:27:08.783277 | TASK [include_role : prepare-workspace-openshift] 2025-12-11 11:27:08.813484 | container | ok 2025-12-11 11:27:08.829208 | 2025-12-11 11:27:08.829327 | LOOP [prepare-workspace-openshift : include_tasks] 2025-12-11 11:27:08.897208 | container | ok: 2025-12-11 11:27:08.897360 | container | [ 2025-12-11 11:27:08.897384 | container | "container", 2025-12-11 11:27:08.897405 | container | { 2025-12-11 11:27:08.897424 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2025-12-11 11:27:08.897440 | container | "namespace": "main", 2025-12-11 11:27:08.897487 | container | "pod": "pod-centos-9-stream-0005555434", 2025-12-11 11:27:08.897507 | container | "stream_port": "33387" 2025-12-11 11:27:08.897523 | container | } 2025-12-11 11:27:08.897539 | container | ] 2025-12-11 11:27:08.897565 | container | ok: All items complete 2025-12-11 11:27:08.897584 | 2025-12-11 11:27:08.904315 | container | included: /var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml 2025-12-11 11:27:08.909295 | 2025-12-11 11:27:08.909353 | TASK [prepare-workspace-openshift : Create src directory] 2025-12-11 11:27:10.051789 | container -> localhost | ok: Runtime: 0:00:00.710960 2025-12-11 11:27:10.057490 | 2025-12-11 11:27:10.057570 | TASK [prepare-workspace-openshift : Copy src repos to the pod] 2025-12-11 11:27:12.250444 | container -> localhost | Output suppressed because no_log was given 2025-12-11 11:27:12.259590 | 2025-12-11 11:27:12.259651 | TASK [include_role : remove-zuul-sshkey] 2025-12-11 11:27:12.281481 | container | ok 2025-12-11 11:27:12.326118 | 2025-12-11 11:27:12.326240 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-11 11:27:12.679229 | container -> localhost | changed 2025-12-11 11:27:12.700850 | 2025-12-11 11:27:12.700994 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-11 11:27:18.672650 | container | ok: "logs" 2025-12-11 11:27:24.846367 | container | ok: "artifacts" 2025-12-11 11:27:30.897373 | container | ok: "docs" 2025-12-11 11:27:30.908009 | 2025-12-11 11:27:30.908118 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-11 11:27:36.915855 | container | changed: "logs" 2025-12-11 11:27:36.916286 | container | ok: All items complete 2025-12-11 11:27:36.916345 | 2025-12-11 11:27:42.735187 | container | changed: "artifacts" 2025-12-11 11:27:48.766456 | container | changed: "docs" 2025-12-11 11:27:48.797239 | 2025-12-11 11:27:48.797357 | PLAY RECAP 2025-12-11 11:27:48.797403 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-12-11 11:27:48.797428 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-11 11:27:48.797445 | 2025-12-11 11:27:48.904093 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-12-11 11:27:48.904879 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-12-11 11:27:49.537254 | 2025-12-11 11:27:49.537382 | PLAY [localhost] 2025-12-11 11:27:49.565122 | 2025-12-11 11:27:49.565278 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2025-12-11 11:27:49.920205 | localhost | ok 2025-12-11 11:27:49.925266 | 2025-12-11 11:27:49.925346 | TASK [configure-ssh-config-fips : Setup ssh config] 2025-12-11 11:27:50.586219 | localhost | changed 2025-12-11 11:27:50.606419 | 2025-12-11 11:27:50.606480 | PLAY [all] 2025-12-11 11:27:50.619458 | 2025-12-11 11:27:50.619519 | TASK [include_role : prepare-workspace] 2025-12-11 11:27:50.645040 | container | skipping: Conditional result was False 2025-12-11 11:27:50.649849 | 2025-12-11 11:27:50.649916 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-11 11:27:56.657311 | container | changed: "logs" 2025-12-11 11:28:02.640584 | container | changed: "artifacts" 2025-12-11 11:28:08.641355 | container | changed: "docs" 2025-12-11 11:28:08.668286 | 2025-12-11 11:28:08.668705 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-11 11:28:14.497482 | container | changed: "logs" 2025-12-11 11:28:14.497765 | container | changed: All items complete 2025-12-11 11:28:14.497810 | 2025-12-11 11:28:19.828039 | container | changed: "artifacts" 2025-12-11 11:28:25.889531 | container | changed: "docs" 2025-12-11 11:28:25.910355 | 2025-12-11 11:28:25.910479 | TASK [Check if worker can sudo] 2025-12-11 11:28:32.179314 | container | ok: Runtime: 0:00:00.120435 2025-12-11 11:28:32.194953 | 2025-12-11 11:28:32.195204 | TASK [configure-mirrors : Gather needed facts] 2025-12-11 11:28:32.215373 | container | skipping: Conditional result was False 2025-12-11 11:28:32.229360 | 2025-12-11 11:28:32.229577 | TASK [configure-mirrors : Set up infrastructure mirrors] 2025-12-11 11:28:32.248532 | container | skipping: Conditional result was False 2025-12-11 11:28:32.300394 | 2025-12-11 11:28:32.300513 | PLAY RECAP 2025-12-11 11:28:32.300610 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2025-12-11 11:28:32.300934 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-11 11:28:32.301022 | 2025-12-11 11:28:32.478060 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-12-11 11:28:32.478986 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2025-12-11 11:28:33.124482 | 2025-12-11 11:28:33.124632 | PLAY [Run light checks in pod] 2025-12-11 11:28:33.143815 | 2025-12-11 11:28:33.143959 | TASK [Install packages] 2025-12-11 11:28:54.451749 | container | ok: Nothing to do 2025-12-11 11:28:54.463307 | 2025-12-11 11:28:54.463467 | TASK [Ensure zuul-output exists] 2025-12-11 11:28:59.976587 | container | ok 2025-12-11 11:28:59.987606 | 2025-12-11 11:28:59.987777 | TASK [Install dependencies] 2025-12-11 11:53:19.553057 | container | changed 2025-12-11 11:53:19.565650 | 2025-12-11 11:53:19.565742 | TASK [Run check] 2025-12-11 11:57:02.562586 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2025-12-11 11:57:02.564412 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-12-11 11:57:03.177591 | 2025-12-11 11:57:03.177742 | PLAY [all] 2025-12-11 11:57:03.204751 | 2025-12-11 11:57:03.204895 | TASK [include_role : fetch-output] 2025-12-11 11:57:03.229644 | container | skipping: Conditional result was False 2025-12-11 11:57:03.234981 | 2025-12-11 11:57:03.235052 | TASK [include_role : fetch-output-openshift] 2025-12-11 11:57:03.262938 | container | ok 2025-12-11 11:57:03.279330 | 2025-12-11 11:57:03.279416 | TASK [fetch-output-openshift : Set log path for multiple nodes] 2025-12-11 11:57:03.333768 | container | skipping: Conditional result was False 2025-12-11 11:57:03.339478 | 2025-12-11 11:57:03.339549 | TASK [fetch-output-openshift : Set log path for single node] 2025-12-11 11:57:03.378082 | container | ok 2025-12-11 11:57:03.382857 | 2025-12-11 11:57:03.382920 | LOOP [fetch-output-openshift : Ensure local output dirs] 2025-12-11 11:57:03.822345 | container -> localhost | ok: "/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/logs" 2025-12-11 11:57:04.117820 | container -> localhost | changed: "/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/logs/npm" 2025-12-11 11:57:04.385495 | container -> localhost | changed: "/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/artifacts" 2025-12-11 11:57:04.613773 | container -> localhost | changed: "/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/docs" 2025-12-11 11:57:04.632480 | 2025-12-11 11:57:04.632740 | LOOP [fetch-output-openshift : include_tasks] 2025-12-11 11:57:04.701518 | container | ok: 2025-12-11 11:57:04.701842 | container | [ 2025-12-11 11:57:04.701900 | container | "container", 2025-12-11 11:57:04.701941 | container | { 2025-12-11 11:57:04.701979 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2025-12-11 11:57:04.702018 | container | "namespace": "main", 2025-12-11 11:57:04.702055 | container | "pod": "pod-centos-9-stream-0005555434", 2025-12-11 11:57:04.702091 | container | "stream_port": "33387" 2025-12-11 11:57:04.702126 | container | } 2025-12-11 11:57:04.702162 | container | ] 2025-12-11 11:57:04.702215 | container | changed: All items complete 2025-12-11 11:57:04.702256 | 2025-12-11 11:57:04.724267 | container | included: /var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml 2025-12-11 11:57:04.736847 | 2025-12-11 11:57:04.736995 | LOOP [fetch-output-openshift : Ensure local directory exists] 2025-12-11 11:57:10.532056 | container | changed: "/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/logs" 2025-12-11 11:57:16.662492 | container | changed: "/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/logs/npm" 2025-12-11 11:57:22.700474 | container | changed: "/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/artifacts" 2025-12-11 11:57:28.652246 | container | changed: "/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/docs" 2025-12-11 11:57:28.670072 | 2025-12-11 11:57:28.670289 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor] 2025-12-11 11:57:34.016524 | container | Output suppressed because no_log was given 2025-12-11 11:57:34.022342 | 2025-12-11 11:57:34.022409 | TASK [fetch-output-openshift : Remove empty directory] 2025-12-11 11:57:34.363395 | container -> localhost | ok: Runtime: 0:00:00.035532 2025-12-11 11:57:34.375434 | 2025-12-11 11:57:34.375565 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-11 11:57:34.702586 | container -> localhost | find: ‘/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/artifacts’: No such file or directory 2025-12-11 11:57:34.702887 | container -> localhost | find: ‘/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/artifacts’: No such file or directory 2025-12-11 11:57:34.702941 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.013716 2025-12-11 11:57:34.702982 | container -> localhost | changed: All items complete 2025-12-11 11:57:34.703013 | 2025-12-11 11:57:35.019386 | container -> localhost | find: ‘/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/docs’: No such file or directory 2025-12-11 11:57:35.019533 | container -> localhost | find: ‘/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/docs’: No such file or directory 2025-12-11 11:57:35.019568 | container -> localhost | ok: Item: docs Runtime: 0:00:00.011315 2025-12-11 11:57:35.051060 | 2025-12-11 11:57:35.051269 | PLAY [all] 2025-12-11 11:57:35.068510 | 2025-12-11 11:57:35.068578 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-11 11:57:35.098320 | container | ERROR 2025-12-11 11:57:35.098565 | container | { 2025-12-11 11:57:35.098603 | container | "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/src/../fd4f30a67aea48ac8e3efc890a224d96_id_rsa.pub'. file not found, use -vvvvv to see paths searched" 2025-12-11 11:57:35.098631 | container | } 2025-12-11 11:57:35.098665 | container | ERROR: Ignoring Errors 2025-12-11 11:57:35.124621 | 2025-12-11 11:57:35.124672 | PLAY RECAP 2025-12-11 11:57:35.124747 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1 2025-12-11 11:57:35.124776 | 2025-12-11 11:57:35.233809 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-12-11 11:57:35.234586 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-12-11 11:57:35.878949 | 2025-12-11 11:57:35.879072 | PLAY [localhost] 2025-12-11 11:57:35.899311 | 2025-12-11 11:57:35.899456 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-11 11:57:36.273552 | localhost | changed 2025-12-11 11:57:36.291424 | 2025-12-11 11:57:36.291720 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-11 11:57:36.330455 | localhost | ok 2025-12-11 11:57:36.349517 | 2025-12-11 11:57:36.349612 | TASK [add-fileserver : Create SSH private key tempfile] 2025-12-11 11:57:36.743375 | localhost | changed 2025-12-11 11:57:36.755328 | 2025-12-11 11:57:36.755546 | TASK [add-fileserver : Create SSH private key from secret] 2025-12-11 11:57:37.451749 | localhost | changed 2025-12-11 11:57:37.456946 | 2025-12-11 11:57:37.457010 | TASK [add-fileserver : Add fileserver ssh key] 2025-12-11 11:57:37.944989 | localhost | Identity added: /var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/tmp/ansible.lgjm28nv (/var/lib/zuul/builds/fd4f30a67aea48ac8e3efc890a224d96/work/tmp/ansible.lgjm28nv) 2025-12-11 11:57:37.945173 | localhost | ok: Runtime: 0:00:00.008149 2025-12-11 11:57:37.949542 | 2025-12-11 11:57:37.949609 | TASK [add-fileserver : Remove SSH private key from disk] 2025-12-11 11:57:38.263314 | localhost | ok: Runtime: 0:00:00.008912 2025-12-11 11:57:38.268120 | 2025-12-11 11:57:38.268189 | TASK [add-fileserver : Add fileserver to inventory] 2025-12-11 11:57:38.353068 | localhost | changed 2025-12-11 11:57:38.362975 | 2025-12-11 11:57:38.363128 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-12-11 11:57:38.889725 | localhost | changed 2025-12-11 11:57:38.934109 | 2025-12-11 11:57:38.934210 | PLAY [localhost] 2025-12-11 11:57:38.952612 | 2025-12-11 11:57:38.952754 | TASK [Generate bulk log download script] 2025-12-11 11:57:38.975060 | localhost | ok 2025-12-11 11:57:38.991863 | 2025-12-11 11:57:38.991976 | TASK [local-log-download : Check API endpoint is defined] 2025-12-11 11:57:39.042734 | localhost | ok: All assertions passed 2025-12-11 11:57:39.049713 | 2025-12-11 11:57:39.049811 | TASK [local-log-download : Create download script] 2025-12-11 11:57:39.511130 | localhost -> localhost | changed 2025-12-11 11:57:39.520490 | 2025-12-11 11:57:39.520560 | TASK [Register quick-download link] 2025-12-11 11:57:39.548133 | localhost | ok 2025-12-11 11:57:39.592827 | 2025-12-11 11:57:39.592937 | PLAY [logserver.rdoproject.org] 2025-12-11 11:57:39.619358 | 2025-12-11 11:57:39.619568 | TASK [Set zuul-log-path fact] 2025-12-11 11:57:39.644737 | logserver.rdoproject.org | ok 2025-12-11 11:57:39.670416 | 2025-12-11 11:57:39.670623 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-11 11:57:39.714321 | logserver.rdoproject.org | ok 2025-12-11 11:57:39.725971 | 2025-12-11 11:57:39.726104 | TASK [upload-logs : Create log directories] 2025-12-11 11:57:40.627807 | logserver.rdoproject.org | changed 2025-12-11 11:57:40.634917 | 2025-12-11 11:57:40.635054 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-11 11:57:40.978097 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009216 2025-12-11 11:57:40.982994 | 2025-12-11 11:57:40.983057 | TASK [upload-logs : Upload logs to log server] 2025-12-11 11:57:41.698752 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-12-11 11:57:41.705177 | 2025-12-11 11:57:41.705309 | LOOP [upload-logs : Compress console log and json output] 2025-12-11 11:57:41.754526 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-11 11:57:41.765389 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-11 11:57:41.780729 | 2025-12-11 11:57:41.780877 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-11 11:57:41.821006 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-11 11:57:41.821411 | 2025-12-11 11:57:41.823855 | logserver.rdoproject.org | skipping: Conditional result was False 2025-12-11 11:57:41.835727 | 2025-12-11 11:57:41.835873 | LOOP [upload-logs : Upload console log and json output]