2026-03-09 04:33:31.470835 | Job console starting... 2026-03-09 04:33:31.484472 | Updating repositories 2026-03-09 04:33:31.521485 | Preparing job workspace 2026-03-09 04:33:37.065093 | Running Ansible setup... 2026-03-09 04:33:44.710188 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-03-09 04:33:45.312354 | 2026-03-09 04:33:45.312461 | PLAY [localhost] 2026-03-09 04:33:45.320983 | 2026-03-09 04:33:45.321050 | TASK [Gathering Facts] 2026-03-09 04:33:46.253614 | localhost | ok 2026-03-09 04:33:46.282412 | 2026-03-09 04:33:46.282574 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-09 04:33:46.715787 | localhost -> localhost | changed 2026-03-09 04:33:46.721428 | 2026-03-09 04:33:46.721494 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-09 04:33:47.598824 | localhost -> localhost | changed 2026-03-09 04:33:47.607417 | 2026-03-09 04:33:47.607499 | TASK [Setup log path fact] 2026-03-09 04:33:47.628839 | localhost | ok 2026-03-09 04:33:47.640108 | 2026-03-09 04:33:47.640169 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 04:33:47.678303 | localhost | ok 2026-03-09 04:33:47.688346 | 2026-03-09 04:33:47.688431 | TASK [emit-job-header : Print job information] 2026-03-09 04:33:47.741593 | # Job Information 2026-03-09 04:33:47.741858 | Ansible Version: 2.15.12 2026-03-09 04:33:47.741916 | Job: cifmw-pod-ansible-test 2026-03-09 04:33:47.741958 | Pipeline: github-check 2026-03-09 04:33:47.741997 | Executor: ze02.softwarefactory-project.io 2026-03-09 04:33:47.742035 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3751 2026-03-09 04:33:47.742077 | Log URL (when completed): https://logserver.rdoproject.org/37f/rdoproject.org/37f495dc88734be9a283baf8d7c1f108/ 2026-03-09 04:33:47.742116 | Event ID: 0dc90bd0-1b71-11f1-9d0f-ca1eb40f64c4 2026-03-09 04:33:47.749909 | 2026-03-09 04:33:47.750042 | LOOP [emit-job-header : Print node information] 2026-03-09 04:33:47.854349 | localhost | ok: 2026-03-09 04:33:47.854827 | localhost | # Node Information 2026-03-09 04:33:47.854890 | localhost | Inventory Hostname: container 2026-03-09 04:33:47.854946 | localhost | Hostname: 0295b1549776 2026-03-09 04:33:47.854989 | localhost | Username: zuul 2026-03-09 04:33:47.855033 | localhost | Distro: CentOS 9 2026-03-09 04:33:47.855072 | localhost | Provider: k1s04 2026-03-09 04:33:47.855109 | localhost | Region: 2026-03-09 04:33:47.855146 | localhost | Label: pod-centos-9-stream 2026-03-09 04:33:47.855205 | localhost | Product Name: OpenStack Nova 2026-03-09 04:33:47.855243 | localhost | Interface IP: pod-centos-9-stream-0005641999 2026-03-09 04:33:47.897369 | 2026-03-09 04:33:47.897512 | PLAY [all] 2026-03-09 04:33:47.909851 | 2026-03-09 04:33:47.909982 | TASK [Gather network facts] 2026-03-09 04:33:54.111856 | container | ok 2026-03-09 04:33:54.140119 | 2026-03-09 04:33:54.140246 | TASK [include_role : start-zuul-console] 2026-03-09 04:33:54.174072 | container | ok 2026-03-09 04:33:54.189532 | 2026-03-09 04:33:54.189634 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-09 04:34:00.191756 | container | ok 2026-03-09 04:34:00.210377 | 2026-03-09 04:34:00.210506 | TASK [include_role : add-build-sshkey] 2026-03-09 04:34:00.238001 | container | skipping: Conditional result was False 2026-03-09 04:34:00.248777 | 2026-03-09 04:34:00.248913 | TASK [include_role : validate-host] 2026-03-09 04:34:00.276493 | container | skipping: Conditional result was False 2026-03-09 04:34:00.287135 | 2026-03-09 04:34:00.287268 | TASK [include_role : prepare-workspace-openshift] 2026-03-09 04:34:00.323049 | container | ok 2026-03-09 04:34:00.349539 | 2026-03-09 04:34:00.349724 | LOOP [prepare-workspace-openshift : include_tasks] 2026-03-09 04:34:00.427516 | container | ok: 2026-03-09 04:34:00.427822 | container | [ 2026-03-09 04:34:00.427881 | container | "container", 2026-03-09 04:34:00.427931 | container | { 2026-03-09 04:34:00.427973 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2026-03-09 04:34:00.428013 | container | "namespace": "main", 2026-03-09 04:34:00.428069 | container | "pod": "pod-centos-9-stream-0005641999", 2026-03-09 04:34:00.428109 | container | "stream_port": "37997" 2026-03-09 04:34:00.428146 | container | } 2026-03-09 04:34:00.428183 | container | ] 2026-03-09 04:34:00.428244 | container | ok: All items complete 2026-03-09 04:34:00.428289 | 2026-03-09 04:34:00.443213 | container | included: /var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml 2026-03-09 04:34:00.457197 | 2026-03-09 04:34:00.457331 | TASK [prepare-workspace-openshift : Create src directory] 2026-03-09 04:34:01.859975 | container -> localhost | ok: Runtime: 0:00:00.935690 2026-03-09 04:34:01.871427 | 2026-03-09 04:34:01.871558 | TASK [prepare-workspace-openshift : Copy src repos to the pod] 2026-03-09 04:34:04.252289 | container -> localhost | Output suppressed because no_log was given 2026-03-09 04:34:04.260482 | 2026-03-09 04:34:04.260542 | TASK [include_role : remove-zuul-sshkey] 2026-03-09 04:34:04.288869 | container | ok 2026-03-09 04:34:04.302118 | 2026-03-09 04:34:04.302182 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-09 04:34:04.645628 | container -> localhost | changed 2026-03-09 04:34:04.658361 | 2026-03-09 04:34:04.658448 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-09 04:34:10.565282 | container | ok: "logs" 2026-03-09 04:34:16.560274 | container | ok: "artifacts" 2026-03-09 04:34:22.321770 | container | ok: "docs" 2026-03-09 04:34:22.331076 | 2026-03-09 04:34:22.331145 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-09 04:34:28.304555 | container | changed: "logs" 2026-03-09 04:34:28.305109 | container | ok: All items complete 2026-03-09 04:34:28.305168 | 2026-03-09 04:34:35.387232 | container | changed: "artifacts" 2026-03-09 04:34:41.432593 | container | changed: "docs" 2026-03-09 04:34:41.474186 | 2026-03-09 04:34:41.474408 | PLAY RECAP 2026-03-09 04:34:41.474466 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-03-09 04:34:41.474502 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-09 04:34:41.474527 | 2026-03-09 04:34:41.586436 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-03-09 04:34:41.588167 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-03-09 04:34:42.292655 | 2026-03-09 04:34:42.292819 | PLAY [localhost] 2026-03-09 04:34:42.309195 | 2026-03-09 04:34:42.309267 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-03-09 04:34:42.860839 | localhost | ok 2026-03-09 04:34:42.869452 | 2026-03-09 04:34:42.869558 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-03-09 04:34:43.640737 | localhost | changed 2026-03-09 04:34:43.687246 | 2026-03-09 04:34:43.687369 | PLAY [all] 2026-03-09 04:34:43.707135 | 2026-03-09 04:34:43.707223 | TASK [include_role : prepare-workspace] 2026-03-09 04:34:43.733645 | container | skipping: Conditional result was False 2026-03-09 04:34:43.741460 | 2026-03-09 04:34:43.741560 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-09 04:34:49.770102 | container | changed: "logs" 2026-03-09 04:34:55.914053 | container | changed: "artifacts" 2026-03-09 04:35:01.947628 | container | changed: "docs" 2026-03-09 04:35:01.956893 | 2026-03-09 04:35:01.957083 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-09 04:35:08.017041 | container | changed: "logs" 2026-03-09 04:35:08.017280 | container | changed: All items complete 2026-03-09 04:35:08.017306 | 2026-03-09 04:35:14.073324 | container | changed: "artifacts" 2026-03-09 04:35:20.104799 | container | changed: "docs" 2026-03-09 04:35:20.130055 | 2026-03-09 04:35:20.130207 | TASK [Check if worker can sudo] 2026-03-09 04:35:26.238004 | container | ok: Runtime: 0:00:00.033393 2026-03-09 04:35:26.248570 | 2026-03-09 04:35:26.248733 | TASK [configure-mirrors : Gather needed facts] 2026-03-09 04:35:26.277331 | container | skipping: Conditional result was False 2026-03-09 04:35:26.289795 | 2026-03-09 04:35:26.289986 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-03-09 04:35:26.317308 | container | skipping: Conditional result was False 2026-03-09 04:35:26.360177 | 2026-03-09 04:35:26.360246 | PLAY RECAP 2026-03-09 04:35:26.360305 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-09 04:35:26.360339 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-09 04:35:26.360364 | 2026-03-09 04:35:26.494784 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-03-09 04:35:26.496487 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2026-03-09 04:35:27.148679 | 2026-03-09 04:35:27.148892 | PLAY [Run light checks in pod] 2026-03-09 04:35:27.169016 | 2026-03-09 04:35:27.169133 | TASK [Install packages] 2026-03-09 04:36:06.370812 | container | ok: Nothing to do 2026-03-09 04:36:06.380879 | 2026-03-09 04:36:06.381026 | TASK [Ensure zuul-output exists] 2026-03-09 04:36:12.718053 | container | ok 2026-03-09 04:36:12.724639 | 2026-03-09 04:36:12.724758 | TASK [Install dependencies] 2026-03-09 04:57:41.349436 | container | changed 2026-03-09 04:57:41.359897 | 2026-03-09 04:57:41.359966 | TASK [Run check] 2026-03-09 05:03:46.560563 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2026-03-09 05:03:46.562292 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-03-09 05:03:47.160263 | 2026-03-09 05:03:47.160380 | PLAY [all] 2026-03-09 05:03:47.178003 | 2026-03-09 05:03:47.178099 | TASK [include_role : fetch-output] 2026-03-09 05:03:47.202771 | container | skipping: Conditional result was False 2026-03-09 05:03:47.208245 | 2026-03-09 05:03:47.208315 | TASK [include_role : fetch-output-openshift] 2026-03-09 05:03:47.247000 | container | ok 2026-03-09 05:03:47.263106 | 2026-03-09 05:03:47.263171 | TASK [fetch-output-openshift : Set log path for multiple nodes] 2026-03-09 05:03:47.328462 | container | skipping: Conditional result was False 2026-03-09 05:03:47.335353 | 2026-03-09 05:03:47.335444 | TASK [fetch-output-openshift : Set log path for single node] 2026-03-09 05:03:47.387445 | container | ok 2026-03-09 05:03:47.393656 | 2026-03-09 05:03:47.393778 | LOOP [fetch-output-openshift : Ensure local output dirs] 2026-03-09 05:03:47.835623 | container -> localhost | ok: "/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/logs" 2026-03-09 05:03:48.103750 | container -> localhost | changed: "/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/logs/npm" 2026-03-09 05:03:48.365552 | container -> localhost | changed: "/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/artifacts" 2026-03-09 05:03:48.632534 | container -> localhost | changed: "/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/docs" 2026-03-09 05:03:48.649050 | 2026-03-09 05:03:48.649257 | LOOP [fetch-output-openshift : include_tasks] 2026-03-09 05:03:48.733837 | container | ok: 2026-03-09 05:03:48.734105 | container | [ 2026-03-09 05:03:48.734160 | container | "container", 2026-03-09 05:03:48.734202 | container | { 2026-03-09 05:03:48.734239 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2026-03-09 05:03:48.734277 | container | "namespace": "main", 2026-03-09 05:03:48.734313 | container | "pod": "pod-centos-9-stream-0005641999", 2026-03-09 05:03:48.734348 | container | "stream_port": "37997" 2026-03-09 05:03:48.734381 | container | } 2026-03-09 05:03:48.734415 | container | ] 2026-03-09 05:03:48.734473 | container | changed: All items complete 2026-03-09 05:03:48.734515 | 2026-03-09 05:03:48.751915 | container | included: /var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml 2026-03-09 05:03:48.764251 | 2026-03-09 05:03:48.764394 | LOOP [fetch-output-openshift : Ensure local directory exists] 2026-03-09 05:03:54.884584 | container | changed: "/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/logs" 2026-03-09 05:04:00.703133 | container | changed: "/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/logs/npm" 2026-03-09 05:04:06.433775 | container | changed: "/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/artifacts" 2026-03-09 05:04:12.215929 | container | changed: "/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/docs" 2026-03-09 05:04:12.237561 | 2026-03-09 05:04:12.237829 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor] 2026-03-09 05:04:17.349087 | container | Output suppressed because no_log was given 2026-03-09 05:04:17.360510 | 2026-03-09 05:04:17.360642 | TASK [fetch-output-openshift : Remove empty directory] 2026-03-09 05:04:17.663776 | container -> localhost | ok: Runtime: 0:00:00.033432 2026-03-09 05:04:17.678232 | 2026-03-09 05:04:17.678318 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-09 05:04:17.997653 | container -> localhost | find: ‘/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/artifacts’: No such file or directory 2026-03-09 05:04:17.997982 | container -> localhost | find: ‘/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/artifacts’: No such file or directory 2026-03-09 05:04:17.998035 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.012435 2026-03-09 05:04:17.998075 | container -> localhost | changed: All items complete 2026-03-09 05:04:17.998096 | 2026-03-09 05:04:18.263254 | container -> localhost | find: ‘/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/docs’: No such file or directory 2026-03-09 05:04:18.263333 | container -> localhost | find: ‘/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/docs’: No such file or directory 2026-03-09 05:04:18.263366 | container -> localhost | ok: Item: docs Runtime: 0:00:00.011697 2026-03-09 05:04:18.287550 | 2026-03-09 05:04:18.287654 | PLAY [all] 2026-03-09 05:04:18.300075 | 2026-03-09 05:04:18.300136 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-09 05:04:18.329696 | container | ERROR 2026-03-09 05:04:18.329940 | container | { 2026-03-09 05:04:18.329979 | container | "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/src/../37f495dc88734be9a283baf8d7c1f108_id_rsa.pub'. file not found, use -vvvvv to see paths searched" 2026-03-09 05:04:18.330009 | container | } 2026-03-09 05:04:18.330045 | container | ERROR: Ignoring Errors 2026-03-09 05:04:18.356083 | 2026-03-09 05:04:18.356131 | PLAY RECAP 2026-03-09 05:04:18.356174 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1 2026-03-09 05:04:18.356194 | 2026-03-09 05:04:18.450984 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-03-09 05:04:18.452488 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-03-09 05:04:19.052484 | 2026-03-09 05:04:19.052599 | PLAY [localhost] 2026-03-09 05:04:19.070464 | 2026-03-09 05:04:19.070571 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-09 05:04:19.471264 | localhost | changed 2026-03-09 05:04:19.481311 | 2026-03-09 05:04:19.481483 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-09 05:04:19.516931 | localhost | ok 2026-03-09 05:04:19.534183 | 2026-03-09 05:04:19.534323 | TASK [add-fileserver : Create SSH private key tempfile] 2026-03-09 05:04:19.913562 | localhost | changed 2026-03-09 05:04:19.919270 | 2026-03-09 05:04:19.919338 | TASK [add-fileserver : Create SSH private key from secret] 2026-03-09 05:04:20.669172 | localhost | changed 2026-03-09 05:04:20.679804 | 2026-03-09 05:04:20.679962 | TASK [add-fileserver : Add fileserver ssh key] 2026-03-09 05:04:21.175309 | localhost | Identity added: /var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/tmp/ansible.d5mzc24w (/var/lib/zuul/builds/37f495dc88734be9a283baf8d7c1f108/work/tmp/ansible.d5mzc24w) 2026-03-09 05:04:21.175616 | localhost | ok: Runtime: 0:00:00.013479 2026-03-09 05:04:21.184804 | 2026-03-09 05:04:21.184943 | TASK [add-fileserver : Remove SSH private key from disk] 2026-03-09 05:04:21.508914 | localhost | ok: Runtime: 0:00:00.008883 2026-03-09 05:04:21.519817 | 2026-03-09 05:04:21.519960 | TASK [add-fileserver : Add fileserver to inventory] 2026-03-09 05:04:21.608893 | localhost | changed 2026-03-09 05:04:21.617828 | 2026-03-09 05:04:21.617956 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-03-09 05:04:22.082222 | localhost | changed 2026-03-09 05:04:22.112069 | 2026-03-09 05:04:22.112147 | PLAY [localhost] 2026-03-09 05:04:22.128691 | 2026-03-09 05:04:22.128772 | TASK [Generate bulk log download script] 2026-03-09 05:04:22.149102 | localhost | ok 2026-03-09 05:04:22.164815 | 2026-03-09 05:04:22.164901 | TASK [local-log-download : Check API endpoint is defined] 2026-03-09 05:04:22.214588 | localhost | ok: All assertions passed 2026-03-09 05:04:22.220804 | 2026-03-09 05:04:22.220887 | TASK [local-log-download : Create download script] 2026-03-09 05:04:22.723934 | localhost -> localhost | changed 2026-03-09 05:04:22.745259 | 2026-03-09 05:04:22.745397 | TASK [Register quick-download link] 2026-03-09 05:04:22.782125 | localhost | ok 2026-03-09 05:04:22.840609 | 2026-03-09 05:04:22.840768 | PLAY [logserver.rdoproject.org] 2026-03-09 05:04:22.854013 | 2026-03-09 05:04:22.854094 | TASK [Set zuul-log-path fact] 2026-03-09 05:04:22.872079 | logserver.rdoproject.org | ok 2026-03-09 05:04:22.884403 | 2026-03-09 05:04:22.884489 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 05:04:22.912615 | logserver.rdoproject.org | ok 2026-03-09 05:04:22.920094 | 2026-03-09 05:04:22.920178 | TASK [upload-logs : Create log directories] 2026-03-09 05:04:23.737283 | logserver.rdoproject.org | changed 2026-03-09 05:04:23.740641 | 2026-03-09 05:04:23.740721 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-09 05:04:24.027742 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.007808 2026-03-09 05:04:24.032316 | 2026-03-09 05:04:24.032376 | TASK [upload-logs : Upload logs to log server] 2026-03-09 05:04:24.765489 | logserver.rdoproject.org | Output suppressed because no_log was given 2026-03-09 05:04:24.772566 | 2026-03-09 05:04:24.772746 | LOOP [upload-logs : Compress console log and json output] 2026-03-09 05:04:24.839768 | logserver.rdoproject.org | skipping: Conditional result was False 2026-03-09 05:04:24.851131 | logserver.rdoproject.org | skipping: Conditional result was False 2026-03-09 05:04:24.865118 | 2026-03-09 05:04:24.865254 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-09 05:04:24.910916 | logserver.rdoproject.org | skipping: Conditional result was False 2026-03-09 05:04:24.911470 | 2026-03-09 05:04:24.914102 | logserver.rdoproject.org | skipping: Conditional result was False 2026-03-09 05:04:24.925385 | 2026-03-09 05:04:24.925461 | LOOP [upload-logs : Upload console log and json output]