2025-10-01 10:21:58.370040 | Job console starting... 2025-10-01 10:21:58.382883 | Updating repositories 2025-10-01 10:21:58.418509 | Preparing job workspace 2025-10-01 10:22:03.395148 | Running Ansible setup... 2025-10-01 10:22:11.403738 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-01 10:22:11.987457 | 2025-10-01 10:22:11.987561 | PLAY [localhost] 2025-10-01 10:22:11.996074 | 2025-10-01 10:22:11.996145 | TASK [Gathering Facts] 2025-10-01 10:22:12.957696 | localhost | ok 2025-10-01 10:22:12.976405 | 2025-10-01 10:22:12.976513 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-01 10:22:13.357053 | localhost -> localhost | changed 2025-10-01 10:22:13.363497 | 2025-10-01 10:22:13.363582 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-01 10:22:14.182158 | localhost -> localhost | changed 2025-10-01 10:22:14.191039 | 2025-10-01 10:22:14.191162 | TASK [Setup log path fact] 2025-10-01 10:22:14.209970 | localhost | ok 2025-10-01 10:22:14.222016 | 2025-10-01 10:22:14.222135 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 10:22:14.250364 | localhost | ok 2025-10-01 10:22:14.258570 | 2025-10-01 10:22:14.258639 | TASK [emit-job-header : Print job information] 2025-10-01 10:22:14.286033 | # Job Information 2025-10-01 10:22:14.286142 | Ansible Version: 2.15.12 2025-10-01 10:22:14.286167 | Job: cifmw-pod-zuul-files 2025-10-01 10:22:14.286186 | Pipeline: github-check 2025-10-01 10:22:14.286204 | Executor: ze02.softwarefactory-project.io 2025-10-01 10:22:14.286221 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3359 2025-10-01 10:22:14.286239 | Log URL (when completed): https://logserver.rdoproject.org/d89/rdoproject.org/d8948c7eb95c4a468058b0b0df5c218a/ 2025-10-01 10:22:14.286257 | Event ID: 6f0a63f0-9eb0-11f0-9ff6-cb1bd0a7f439 2025-10-01 10:22:14.290008 | 2025-10-01 10:22:14.290073 | LOOP [emit-job-header : Print node information] 2025-10-01 10:22:14.373509 | localhost | ok: 2025-10-01 10:22:14.373781 | localhost | # Node Information 2025-10-01 10:22:14.373810 | localhost | Inventory Hostname: container 2025-10-01 10:22:14.373837 | localhost | Hostname: 846f1cb6ddd8 2025-10-01 10:22:14.373857 | localhost | Username: zuul 2025-10-01 10:22:14.373877 | localhost | Distro: CentOS 9 2025-10-01 10:22:14.373895 | localhost | Provider: k1s04 2025-10-01 10:22:14.373912 | localhost | Region: 2025-10-01 10:22:14.373928 | localhost | Label: pod-centos-9-stream 2025-10-01 10:22:14.373944 | localhost | Product Name: OpenStack Nova 2025-10-01 10:22:14.373960 | localhost | Interface IP: pod-centos-9-stream-0005464188 2025-10-01 10:22:14.403204 | 2025-10-01 10:22:14.403288 | PLAY [all] 2025-10-01 10:22:14.409479 | 2025-10-01 10:22:14.409541 | TASK [Gather network facts] 2025-10-01 10:22:21.501648 | container | ok 2025-10-01 10:22:21.530802 | 2025-10-01 10:22:21.530927 | TASK [include_role : start-zuul-console] 2025-10-01 10:22:21.550698 | container | ok 2025-10-01 10:22:21.564235 | 2025-10-01 10:22:21.564364 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-01 10:22:28.507945 | container | ok 2025-10-01 10:22:28.525349 | 2025-10-01 10:22:28.525522 | TASK [include_role : add-build-sshkey] 2025-10-01 10:22:28.552849 | container | skipping: Conditional result was False 2025-10-01 10:22:28.560544 | 2025-10-01 10:22:28.560644 | TASK [include_role : validate-host] 2025-10-01 10:22:28.585876 | container | skipping: Conditional result was False 2025-10-01 10:22:28.591660 | 2025-10-01 10:22:28.591800 | TASK [include_role : prepare-workspace-openshift] 2025-10-01 10:22:28.623384 | container | ok 2025-10-01 10:22:28.640969 | 2025-10-01 10:22:28.641090 | LOOP [prepare-workspace-openshift : include_tasks] 2025-10-01 10:22:28.684905 | container | ok: 2025-10-01 10:22:28.685052 | container | [ 2025-10-01 10:22:28.685076 | container | "container", 2025-10-01 10:22:28.685097 | container | { 2025-10-01 10:22:28.685114 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2025-10-01 10:22:28.685130 | container | "namespace": "main", 2025-10-01 10:22:28.685159 | container | "pod": "pod-centos-9-stream-0005464188", 2025-10-01 10:22:28.685175 | container | "stream_port": "37659" 2025-10-01 10:22:28.685191 | container | } 2025-10-01 10:22:28.685206 | container | ] 2025-10-01 10:22:28.685232 | container | ok: All items complete 2025-10-01 10:22:28.685251 | 2025-10-01 10:22:28.693379 | container | included: /var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml 2025-10-01 10:22:28.698495 | 2025-10-01 10:22:28.698559 | TASK [prepare-workspace-openshift : Create src directory] 2025-10-01 10:22:30.207640 | container -> localhost | ok: Runtime: 0:00:01.125947 2025-10-01 10:22:30.219266 | 2025-10-01 10:22:30.219406 | TASK [prepare-workspace-openshift : Copy src repos to the pod] 2025-10-01 10:22:32.821840 | container -> localhost | Output suppressed because no_log was given 2025-10-01 10:22:32.830113 | 2025-10-01 10:22:32.830180 | TASK [include_role : remove-zuul-sshkey] 2025-10-01 10:22:32.848481 | container | ok 2025-10-01 10:22:32.863043 | 2025-10-01 10:22:32.863110 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-01 10:22:33.180882 | container -> localhost | changed 2025-10-01 10:22:33.189406 | 2025-10-01 10:22:33.189472 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-01 10:22:39.876493 | container | ok: "logs" 2025-10-01 10:22:46.366994 | container | ok: "artifacts" 2025-10-01 10:22:52.949788 | container | ok: "docs" 2025-10-01 10:22:52.956802 | 2025-10-01 10:22:52.956925 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-01 10:22:59.549014 | container | changed: "logs" 2025-10-01 10:22:59.549268 | container | ok: All items complete 2025-10-01 10:22:59.549306 | 2025-10-01 10:23:06.218420 | container | changed: "artifacts" 2025-10-01 10:23:12.091755 | container | changed: "docs" 2025-10-01 10:23:12.133561 | 2025-10-01 10:23:12.133697 | PLAY RECAP 2025-10-01 10:23:12.133757 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-01 10:23:12.133792 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 10:23:12.133816 | 2025-10-01 10:23:12.231071 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-01 10:23:12.232057 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-10-01 10:23:12.799738 | 2025-10-01 10:23:12.799842 | PLAY [localhost] 2025-10-01 10:23:12.816100 | 2025-10-01 10:23:12.816182 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2025-10-01 10:23:13.221529 | localhost | ok 2025-10-01 10:23:13.226643 | 2025-10-01 10:23:13.226732 | TASK [configure-ssh-config-fips : Setup ssh config] 2025-10-01 10:23:13.808148 | localhost | changed 2025-10-01 10:23:13.829641 | 2025-10-01 10:23:13.829776 | PLAY [all] 2025-10-01 10:23:13.844186 | 2025-10-01 10:23:13.844252 | TASK [include_role : prepare-workspace] 2025-10-01 10:23:13.858250 | container | skipping: Conditional result was False 2025-10-01 10:23:13.864682 | 2025-10-01 10:23:13.864752 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-01 10:23:19.115530 | container | changed: "logs" 2025-10-01 10:23:24.860226 | container | changed: "artifacts" 2025-10-01 10:23:30.385705 | container | changed: "docs" 2025-10-01 10:23:30.406089 | 2025-10-01 10:23:30.406284 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-01 10:23:36.230441 | container | changed: "logs" 2025-10-01 10:23:36.230816 | container | changed: All items complete 2025-10-01 10:23:36.230887 | 2025-10-01 10:23:41.839751 | container | changed: "artifacts" 2025-10-01 10:23:47.606393 | container | changed: "docs" 2025-10-01 10:23:47.632819 | 2025-10-01 10:23:47.633017 | TASK [Check if worker can sudo] 2025-10-01 10:23:53.581459 | container | ok: Runtime: 0:00:00.034185 2025-10-01 10:23:53.594251 | 2025-10-01 10:23:53.594393 | TASK [configure-mirrors : Gather needed facts] 2025-10-01 10:23:53.622551 | container | skipping: Conditional result was False 2025-10-01 10:23:53.635404 | 2025-10-01 10:23:53.635554 | TASK [configure-mirrors : Set up infrastructure mirrors] 2025-10-01 10:23:53.663411 | container | skipping: Conditional result was False 2025-10-01 10:23:53.706963 | 2025-10-01 10:23:53.707039 | PLAY RECAP 2025-10-01 10:23:53.707098 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2025-10-01 10:23:53.707133 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 10:23:53.707157 | 2025-10-01 10:23:53.814395 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-10-01 10:23:53.816069 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2025-10-01 10:23:54.412675 | 2025-10-01 10:23:54.412785 | PLAY [Run light checks in pod] 2025-10-01 10:23:54.431828 | 2025-10-01 10:23:54.431907 | TASK [Install packages] 2025-10-01 10:24:23.544867 | container | ok: Nothing to do 2025-10-01 10:24:23.559632 | 2025-10-01 10:24:23.559825 | TASK [Ensure zuul-output exists] 2025-10-01 10:24:29.127039 | container | ok 2025-10-01 10:24:29.134049 | 2025-10-01 10:24:29.134152 | TASK [Install dependencies] 2025-10-01 10:52:13.904771 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2025-10-01 10:52:13.908334 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-01 10:52:14.625864 | 2025-10-01 10:52:14.626011 | PLAY [all] 2025-10-01 10:52:14.646258 | 2025-10-01 10:52:14.646422 | TASK [include_role : fetch-output] 2025-10-01 10:52:14.662657 | container | skipping: Conditional result was False 2025-10-01 10:52:14.669117 | 2025-10-01 10:52:14.669253 | TASK [include_role : fetch-output-openshift] 2025-10-01 10:52:14.721234 | container | ok 2025-10-01 10:52:14.741001 | 2025-10-01 10:52:14.741145 | TASK [fetch-output-openshift : Set log path for multiple nodes] 2025-10-01 10:52:14.818340 | container | skipping: Conditional result was False 2025-10-01 10:52:14.824490 | 2025-10-01 10:52:14.824584 | TASK [fetch-output-openshift : Set log path for single node] 2025-10-01 10:52:14.874389 | container | ok 2025-10-01 10:52:14.879732 | 2025-10-01 10:52:14.879810 | LOOP [fetch-output-openshift : Ensure local output dirs] 2025-10-01 10:52:15.344066 | container -> localhost | ok: "/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/logs" 2025-10-01 10:52:15.651108 | container -> localhost | changed: "/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/logs/npm" 2025-10-01 10:52:15.909295 | container -> localhost | changed: "/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/artifacts" 2025-10-01 10:52:16.162624 | container -> localhost | changed: "/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/docs" 2025-10-01 10:52:16.173967 | 2025-10-01 10:52:16.174168 | LOOP [fetch-output-openshift : include_tasks] 2025-10-01 10:52:16.255784 | container | ok: 2025-10-01 10:52:16.256049 | container | [ 2025-10-01 10:52:16.256103 | container | "container", 2025-10-01 10:52:16.256144 | container | { 2025-10-01 10:52:16.256181 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2025-10-01 10:52:16.256219 | container | "namespace": "main", 2025-10-01 10:52:16.256256 | container | "pod": "pod-centos-9-stream-0005464188", 2025-10-01 10:52:16.256291 | container | "stream_port": "37659" 2025-10-01 10:52:16.256327 | container | } 2025-10-01 10:52:16.256361 | container | ] 2025-10-01 10:52:16.256414 | container | changed: All items complete 2025-10-01 10:52:16.256455 | 2025-10-01 10:52:16.268085 | container | included: /var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml 2025-10-01 10:52:16.273584 | 2025-10-01 10:52:16.273648 | LOOP [fetch-output-openshift : Ensure local directory exists] 2025-10-01 10:52:22.178853 | container | changed: "/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/logs" 2025-10-01 10:52:27.633518 | container | changed: "/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/logs/npm" 2025-10-01 10:52:33.030003 | container | changed: "/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/artifacts" 2025-10-01 10:52:38.290104 | container | changed: "/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/docs" 2025-10-01 10:52:38.310113 | 2025-10-01 10:52:38.310254 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor] 2025-10-01 10:52:43.137509 | container | Output suppressed because no_log was given 2025-10-01 10:52:43.145457 | 2025-10-01 10:52:43.145601 | TASK [fetch-output-openshift : Remove empty directory] 2025-10-01 10:52:43.511963 | container -> localhost | ok: Runtime: 0:00:00.058061 2025-10-01 10:52:43.522284 | 2025-10-01 10:52:43.522375 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-01 10:52:43.850358 | container -> localhost | find: ‘/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/artifacts’: No such file or directory 2025-10-01 10:52:43.850923 | container -> localhost | find: ‘/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/artifacts’: No such file or directory 2025-10-01 10:52:43.851035 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.013769 2025-10-01 10:52:43.851154 | container -> localhost | changed: All items complete 2025-10-01 10:52:43.851199 | 2025-10-01 10:52:44.154317 | container -> localhost | find: ‘/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/docs’: No such file or directory 2025-10-01 10:52:44.154523 | container -> localhost | find: ‘/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/docs’: No such file or directory 2025-10-01 10:52:44.154633 | container -> localhost | ok: Item: docs Runtime: 0:00:00.012934 2025-10-01 10:52:44.205920 | 2025-10-01 10:52:44.206051 | PLAY [all] 2025-10-01 10:52:44.225986 | 2025-10-01 10:52:44.226126 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-01 10:52:44.267635 | container | ERROR 2025-10-01 10:52:44.267921 | container | { 2025-10-01 10:52:44.267966 | container | "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/src/../d8948c7eb95c4a468058b0b0df5c218a_id_rsa.pub'. file not found, use -vvvvv to see paths searched" 2025-10-01 10:52:44.268007 | container | } 2025-10-01 10:52:44.268051 | container | ERROR: Ignoring Errors 2025-10-01 10:52:44.300073 | 2025-10-01 10:52:44.300200 | PLAY RECAP 2025-10-01 10:52:44.300261 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1 2025-10-01 10:52:44.300293 | 2025-10-01 10:52:44.416572 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-01 10:52:44.418236 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-01 10:52:45.080528 | 2025-10-01 10:52:45.080648 | PLAY [localhost] 2025-10-01 10:52:45.098646 | 2025-10-01 10:52:45.098766 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-01 10:52:45.529563 | localhost | changed 2025-10-01 10:52:45.541127 | 2025-10-01 10:52:45.541288 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-01 10:52:45.588910 | localhost | ok 2025-10-01 10:52:45.607441 | 2025-10-01 10:52:45.607585 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-01 10:52:46.088189 | localhost | changed 2025-10-01 10:52:46.100376 | 2025-10-01 10:52:46.100512 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-01 10:52:46.822276 | localhost | changed 2025-10-01 10:52:46.828864 | 2025-10-01 10:52:46.828947 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-01 10:52:47.251171 | localhost | Identity added: /var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/tmp/ansible._z3ja7q2 (/var/lib/zuul/builds/d8948c7eb95c4a468058b0b0df5c218a/work/tmp/ansible._z3ja7q2) 2025-10-01 10:52:47.251481 | localhost | ok: Runtime: 0:00:00.013080 2025-10-01 10:52:47.262330 | 2025-10-01 10:52:47.262482 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-01 10:52:47.596393 | localhost | ok: Runtime: 0:00:00.008447 2025-10-01 10:52:47.607063 | 2025-10-01 10:52:47.607217 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-01 10:52:47.675502 | localhost | changed 2025-10-01 10:52:47.685014 | 2025-10-01 10:52:47.685158 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-01 10:52:48.114682 | localhost | changed 2025-10-01 10:52:48.136112 | 2025-10-01 10:52:48.136202 | PLAY [localhost] 2025-10-01 10:52:48.149015 | 2025-10-01 10:52:48.149098 | TASK [Generate bulk log download script] 2025-10-01 10:52:48.167488 | localhost | ok 2025-10-01 10:52:48.180253 | 2025-10-01 10:52:48.180348 | TASK [local-log-download : Check API endpoint is defined] 2025-10-01 10:52:48.219810 | localhost | ok: All assertions passed 2025-10-01 10:52:48.224536 | 2025-10-01 10:52:48.224597 | TASK [local-log-download : Create download script] 2025-10-01 10:52:48.819597 | localhost -> localhost | changed 2025-10-01 10:52:48.828861 | 2025-10-01 10:52:48.828981 | TASK [Register quick-download link] 2025-10-01 10:52:48.848934 | localhost | ok 2025-10-01 10:52:48.898342 | 2025-10-01 10:52:48.898447 | PLAY [logserver.rdoproject.org] 2025-10-01 10:52:48.909538 | 2025-10-01 10:52:48.909659 | TASK [Set zuul-log-path fact] 2025-10-01 10:52:48.927701 | logserver.rdoproject.org | ok 2025-10-01 10:52:48.956022 | 2025-10-01 10:52:48.956152 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 10:52:48.995467 | logserver.rdoproject.org | ok 2025-10-01 10:52:49.003504 | 2025-10-01 10:52:49.003596 | TASK [upload-logs : Create log directories] 2025-10-01 10:52:49.626360 | logserver.rdoproject.org | changed 2025-10-01 10:52:49.634044 | 2025-10-01 10:52:49.634208 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-01 10:52:49.947893 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.007944 2025-10-01 10:52:49.954621 | 2025-10-01 10:52:49.954856 | TASK [upload-logs : Upload logs to log server] 2025-10-01 10:52:50.659277 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-01 10:52:50.665804 | 2025-10-01 10:52:50.665933 | LOOP [upload-logs : Compress console log and json output] 2025-10-01 10:52:50.735213 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:52:50.747352 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:52:50.761782 | 2025-10-01 10:52:50.761946 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-01 10:52:50.809697 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:52:50.810148 | 2025-10-01 10:52:50.813221 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:52:50.829241 | 2025-10-01 10:52:50.829401 | LOOP [upload-logs : Upload console log and json output]