2026-01-29 09:11:29.446853 | Job console starting... 2026-01-29 09:11:29.457091 | Updating repositories 2026-01-29 09:11:29.478435 | Preparing job workspace 2026-01-29 09:11:34.714372 | Running Ansible setup... 2026-01-29 09:11:42.853985 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-01-29 09:11:43.461361 | 2026-01-29 09:11:43.461510 | PLAY [localhost] 2026-01-29 09:11:43.477614 | 2026-01-29 09:11:43.477728 | TASK [Gathering Facts] 2026-01-29 09:11:44.499795 | localhost | ok 2026-01-29 09:11:44.515955 | 2026-01-29 09:11:44.516094 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-29 09:11:44.928851 | localhost -> localhost | changed 2026-01-29 09:11:44.940022 | 2026-01-29 09:11:44.940183 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-29 09:11:45.798946 | localhost -> localhost | changed 2026-01-29 09:11:45.808170 | 2026-01-29 09:11:45.808255 | TASK [Setup log path fact] 2026-01-29 09:11:45.830555 | localhost | ok 2026-01-29 09:11:45.843617 | 2026-01-29 09:11:45.843746 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-29 09:11:45.881523 | localhost | ok 2026-01-29 09:11:45.889338 | 2026-01-29 09:11:45.889410 | TASK [emit-job-header : Print job information] 2026-01-29 09:11:45.937392 | # Job Information 2026-01-29 09:11:45.937526 | Ansible Version: 2.15.12 2026-01-29 09:11:45.937552 | Job: cifmw-pod-pre-commit 2026-01-29 09:11:45.937572 | Pipeline: github-check 2026-01-29 09:11:45.937591 | Executor: ze03.softwarefactory-project.io 2026-01-29 09:11:45.937609 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3635 2026-01-29 09:11:45.937629 | Log URL (when completed): https://logserver.rdoproject.org/59a/rdoproject.org/59a058f470de478386d02b01d3d457bb/ 2026-01-29 09:11:45.937647 | Event ID: 6cf731b0-fcf2-11f0-9e81-b8af9c8da1ee 2026-01-29 09:11:45.941138 | 2026-01-29 09:11:45.941197 | LOOP [emit-job-header : Print node information] 2026-01-29 09:11:46.057080 | localhost | ok: 2026-01-29 09:11:46.057502 | localhost | # Node Information 2026-01-29 09:11:46.057564 | localhost | Inventory Hostname: container 2026-01-29 09:11:46.057614 | localhost | Hostname: c164744c8482 2026-01-29 09:11:46.057661 | localhost | Username: zuul 2026-01-29 09:11:46.057717 | localhost | Distro: CentOS 9 2026-01-29 09:11:46.057804 | localhost | Provider: k1s04 2026-01-29 09:11:46.057844 | localhost | Region: 2026-01-29 09:11:46.057881 | localhost | Label: pod-centos-9-stream 2026-01-29 09:11:46.057962 | localhost | Product Name: OpenStack Nova 2026-01-29 09:11:46.059436 | localhost | Interface IP: pod-centos-9-stream-0005600402 2026-01-29 09:11:46.112580 | 2026-01-29 09:11:46.112712 | PLAY [all] 2026-01-29 09:11:46.122329 | 2026-01-29 09:11:46.122438 | TASK [Gather network facts] 2026-01-29 09:11:51.436320 | container | ok 2026-01-29 09:11:51.448694 | 2026-01-29 09:11:51.448771 | TASK [include_role : start-zuul-console] 2026-01-29 09:11:51.468305 | container | ok 2026-01-29 09:11:51.480200 | 2026-01-29 09:11:51.480280 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-01-29 09:11:57.243818 | container | ok 2026-01-29 09:11:57.266182 | 2026-01-29 09:11:57.266338 | TASK [include_role : add-build-sshkey] 2026-01-29 09:11:57.294142 | container | skipping: Conditional result was False 2026-01-29 09:11:57.300939 | 2026-01-29 09:11:57.301040 | TASK [include_role : validate-host] 2026-01-29 09:11:57.331011 | container | skipping: Conditional result was False 2026-01-29 09:11:57.343462 | 2026-01-29 09:11:57.343628 | TASK [include_role : prepare-workspace-openshift] 2026-01-29 09:11:57.378388 | container | ok 2026-01-29 09:11:57.394299 | 2026-01-29 09:11:57.394420 | LOOP [prepare-workspace-openshift : include_tasks] 2026-01-29 09:11:57.467986 | container | ok: 2026-01-29 09:11:57.468279 | container | [ 2026-01-29 09:11:57.468335 | container | "container", 2026-01-29 09:11:57.468382 | container | { 2026-01-29 09:11:57.468422 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2026-01-29 09:11:57.468458 | container | "namespace": "main", 2026-01-29 09:11:57.468514 | container | "pod": "pod-centos-9-stream-0005600402", 2026-01-29 09:11:57.468552 | container | "stream_port": "39729" 2026-01-29 09:11:57.468588 | container | } 2026-01-29 09:11:57.468622 | container | ] 2026-01-29 09:11:57.468678 | container | ok: All items complete 2026-01-29 09:11:57.468721 | 2026-01-29 09:11:57.484180 | container | included: /var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml 2026-01-29 09:11:57.495959 | 2026-01-29 09:11:57.496194 | TASK [prepare-workspace-openshift : Create src directory] 2026-01-29 09:11:59.026000 | container -> localhost | ok: Runtime: 0:00:00.991930 2026-01-29 09:11:59.034060 | 2026-01-29 09:11:59.034142 | TASK [prepare-workspace-openshift : Copy src repos to the pod] 2026-01-29 09:12:01.475057 | container -> localhost | Output suppressed because no_log was given 2026-01-29 09:12:01.483619 | 2026-01-29 09:12:01.483679 | TASK [include_role : remove-zuul-sshkey] 2026-01-29 09:12:01.502400 | container | ok 2026-01-29 09:12:01.538578 | 2026-01-29 09:12:01.538694 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-29 09:12:01.908674 | container -> localhost | changed 2026-01-29 09:12:01.918860 | 2026-01-29 09:12:01.919006 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-29 09:12:07.528262 | container | ok: "logs" 2026-01-29 09:12:13.512168 | container | ok: "artifacts" 2026-01-29 09:12:19.379847 | container | ok: "docs" 2026-01-29 09:12:19.389248 | 2026-01-29 09:12:19.389368 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-29 09:12:25.547763 | container | changed: "logs" 2026-01-29 09:12:25.548327 | container | ok: All items complete 2026-01-29 09:12:25.548385 | 2026-01-29 09:12:31.841662 | container | changed: "artifacts" 2026-01-29 09:12:37.854098 | container | changed: "docs" 2026-01-29 09:12:37.905313 | 2026-01-29 09:12:37.905452 | PLAY RECAP 2026-01-29 09:12:37.905525 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-01-29 09:12:37.905573 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-29 09:12:37.905607 | 2026-01-29 09:12:38.033623 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2026-01-29 09:12:38.034513 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-01-29 09:12:38.720307 | 2026-01-29 09:12:38.720469 | PLAY [localhost] 2026-01-29 09:12:38.738829 | 2026-01-29 09:12:38.738996 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2026-01-29 09:12:39.223947 | localhost | ok 2026-01-29 09:12:39.235886 | 2026-01-29 09:12:39.236061 | TASK [configure-ssh-config-fips : Setup ssh config] 2026-01-29 09:12:39.965192 | localhost | changed 2026-01-29 09:12:39.991913 | 2026-01-29 09:12:39.992052 | PLAY [all] 2026-01-29 09:12:40.010893 | 2026-01-29 09:12:40.011025 | TASK [include_role : prepare-workspace] 2026-01-29 09:12:40.035261 | container | skipping: Conditional result was False 2026-01-29 09:12:40.042530 | 2026-01-29 09:12:40.042626 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-29 09:12:45.605359 | container | changed: "logs" 2026-01-29 09:12:51.395752 | container | changed: "artifacts" 2026-01-29 09:12:57.232028 | container | changed: "docs" 2026-01-29 09:12:57.240055 | 2026-01-29 09:12:57.240186 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-29 09:13:06.834359 | container | changed: "logs" 2026-01-29 09:13:06.834883 | container | changed: All items complete 2026-01-29 09:13:06.834974 | 2026-01-29 09:13:12.564010 | container | changed: "artifacts" 2026-01-29 09:13:18.208682 | container | changed: "docs" 2026-01-29 09:13:18.227306 | 2026-01-29 09:13:18.227416 | TASK [Check if worker can sudo] 2026-01-29 09:13:24.233200 | container | ok: Runtime: 0:00:00.034937 2026-01-29 09:13:24.238062 | 2026-01-29 09:13:24.238153 | TASK [configure-mirrors : Gather needed facts] 2026-01-29 09:13:24.262355 | container | skipping: Conditional result was False 2026-01-29 09:13:24.268326 | 2026-01-29 09:13:24.268412 | TASK [configure-mirrors : Set up infrastructure mirrors] 2026-01-29 09:13:24.292486 | container | skipping: Conditional result was False 2026-01-29 09:13:24.315328 | 2026-01-29 09:13:24.315429 | PLAY RECAP 2026-01-29 09:13:24.315471 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-01-29 09:13:24.315498 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-29 09:13:24.315516 | 2026-01-29 09:13:24.426491 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2026-01-29 09:13:24.427428 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2026-01-29 09:13:25.031438 | 2026-01-29 09:13:25.031576 | PLAY [Run light checks in pod] 2026-01-29 09:13:25.052310 | 2026-01-29 09:13:25.052448 | TASK [Install packages] 2026-01-29 09:13:44.478535 | container | ok: Nothing to do 2026-01-29 09:13:44.484958 | 2026-01-29 09:13:44.485192 | TASK [Ensure zuul-output exists] 2026-01-29 09:13:50.048375 | container | ok 2026-01-29 09:13:50.053579 | 2026-01-29 09:13:50.053655 | TASK [Install dependencies] 2026-01-29 09:41:44.483598 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2026-01-29 09:41:44.485879 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-01-29 09:41:45.118921 | 2026-01-29 09:41:45.119038 | PLAY [all] 2026-01-29 09:41:45.138118 | 2026-01-29 09:41:45.138236 | TASK [include_role : fetch-output] 2026-01-29 09:41:45.163645 | container | skipping: Conditional result was False 2026-01-29 09:41:45.169738 | 2026-01-29 09:41:45.169817 | TASK [include_role : fetch-output-openshift] 2026-01-29 09:41:45.223819 | container | ok 2026-01-29 09:41:45.248535 | 2026-01-29 09:41:45.248653 | TASK [fetch-output-openshift : Set log path for multiple nodes] 2026-01-29 09:41:45.314434 | container | skipping: Conditional result was False 2026-01-29 09:41:45.325316 | 2026-01-29 09:41:45.325452 | TASK [fetch-output-openshift : Set log path for single node] 2026-01-29 09:41:45.371282 | container | ok 2026-01-29 09:41:45.380868 | 2026-01-29 09:41:45.381000 | LOOP [fetch-output-openshift : Ensure local output dirs] 2026-01-29 09:41:45.821159 | container -> localhost | ok: "/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/logs" 2026-01-29 09:41:46.080078 | container -> localhost | changed: "/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/logs/npm" 2026-01-29 09:41:46.347932 | container -> localhost | changed: "/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/artifacts" 2026-01-29 09:41:46.629668 | container -> localhost | changed: "/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/docs" 2026-01-29 09:41:46.646684 | 2026-01-29 09:41:46.646918 | LOOP [fetch-output-openshift : include_tasks] 2026-01-29 09:41:46.701651 | container | ok: 2026-01-29 09:41:46.701896 | container | [ 2026-01-29 09:41:46.701922 | container | "container", 2026-01-29 09:41:46.701941 | container | { 2026-01-29 09:41:46.701957 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2026-01-29 09:41:46.701975 | container | "namespace": "main", 2026-01-29 09:41:46.701992 | container | "pod": "pod-centos-9-stream-0005600402", 2026-01-29 09:41:46.702008 | container | "stream_port": "39729" 2026-01-29 09:41:46.702024 | container | } 2026-01-29 09:41:46.702040 | container | ] 2026-01-29 09:41:46.702071 | container | changed: All items complete 2026-01-29 09:41:46.702091 | 2026-01-29 09:41:46.718458 | container | included: /var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml 2026-01-29 09:41:46.731627 | 2026-01-29 09:41:46.731797 | LOOP [fetch-output-openshift : Ensure local directory exists] 2026-01-29 09:41:52.776109 | container | changed: "/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/logs" 2026-01-29 09:41:58.929173 | container | changed: "/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/logs/npm" 2026-01-29 09:42:04.892417 | container | changed: "/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/artifacts" 2026-01-29 09:42:10.995409 | container | changed: "/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/docs" 2026-01-29 09:42:11.015515 | 2026-01-29 09:42:11.015768 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor] 2026-01-29 09:42:16.645945 | container | Output suppressed because no_log was given 2026-01-29 09:42:16.657233 | 2026-01-29 09:42:16.657369 | TASK [fetch-output-openshift : Remove empty directory] 2026-01-29 09:42:17.061802 | container -> localhost | ok: Runtime: 0:00:00.061519 2026-01-29 09:42:17.082629 | 2026-01-29 09:42:17.082797 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-29 09:42:17.438045 | container -> localhost | find: ‘/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/artifacts’: No such file or directory 2026-01-29 09:42:17.438289 | container -> localhost | find: ‘/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/artifacts’: No such file or directory 2026-01-29 09:42:17.438349 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.013819 2026-01-29 09:42:17.438388 | container -> localhost | changed: All items complete 2026-01-29 09:42:17.438414 | 2026-01-29 09:42:17.724482 | container -> localhost | find: ‘/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/docs’: No such file or directory 2026-01-29 09:42:17.724662 | container -> localhost | find: ‘/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/docs’: No such file or directory 2026-01-29 09:42:17.724765 | container -> localhost | ok: Item: docs Runtime: 0:00:00.014556 2026-01-29 09:42:17.777475 | 2026-01-29 09:42:17.777592 | PLAY [all] 2026-01-29 09:42:17.796575 | 2026-01-29 09:42:17.796673 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-01-29 09:42:17.837687 | container | ERROR 2026-01-29 09:42:17.838050 | container | { 2026-01-29 09:42:17.838100 | container | "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/src/../59a058f470de478386d02b01d3d457bb_id_rsa.pub'. file not found, use -vvvvv to see paths searched" 2026-01-29 09:42:17.838132 | container | } 2026-01-29 09:42:17.838166 | container | ERROR: Ignoring Errors 2026-01-29 09:42:17.866099 | 2026-01-29 09:42:17.866191 | PLAY RECAP 2026-01-29 09:42:17.866247 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1 2026-01-29 09:42:17.866275 | 2026-01-29 09:42:17.974435 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2026-01-29 09:42:17.976066 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2026-01-29 09:42:18.613912 | 2026-01-29 09:42:18.614056 | PLAY [localhost] 2026-01-29 09:42:18.633783 | 2026-01-29 09:42:18.633907 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-29 09:42:19.079816 | localhost | changed 2026-01-29 09:42:19.084507 | 2026-01-29 09:42:19.084584 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-29 09:42:19.124560 | localhost | ok 2026-01-29 09:42:19.135397 | 2026-01-29 09:42:19.135493 | TASK [add-fileserver : Create SSH private key tempfile] 2026-01-29 09:42:19.571649 | localhost | changed 2026-01-29 09:42:19.577329 | 2026-01-29 09:42:19.577409 | TASK [add-fileserver : Create SSH private key from secret] 2026-01-29 09:42:20.337447 | localhost | changed 2026-01-29 09:42:20.342516 | 2026-01-29 09:42:20.342581 | TASK [add-fileserver : Add fileserver ssh key] 2026-01-29 09:42:20.877304 | localhost | Identity added: /var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/tmp/ansible.o3057ea4 (/var/lib/zuul/builds/59a058f470de478386d02b01d3d457bb/work/tmp/ansible.o3057ea4) 2026-01-29 09:42:20.877627 | localhost | ok: Runtime: 0:00:00.013945 2026-01-29 09:42:20.887157 | 2026-01-29 09:42:20.887298 | TASK [add-fileserver : Remove SSH private key from disk] 2026-01-29 09:42:21.182882 | localhost | ok: Runtime: 0:00:00.008587 2026-01-29 09:42:21.188176 | 2026-01-29 09:42:21.188241 | TASK [add-fileserver : Add fileserver to inventory] 2026-01-29 09:42:21.272952 | localhost | changed 2026-01-29 09:42:21.282224 | 2026-01-29 09:42:21.282361 | TASK [add-fileserver : Add fileserver server to known hosts] 2026-01-29 09:42:21.743036 | localhost | changed 2026-01-29 09:42:21.772646 | 2026-01-29 09:42:21.772799 | PLAY [localhost] 2026-01-29 09:42:21.790042 | 2026-01-29 09:42:21.790103 | TASK [Generate bulk log download script] 2026-01-29 09:42:21.809498 | localhost | ok 2026-01-29 09:42:21.821436 | 2026-01-29 09:42:21.821500 | TASK [local-log-download : Check API endpoint is defined] 2026-01-29 09:42:21.862445 | localhost | ok: All assertions passed 2026-01-29 09:42:21.867121 | 2026-01-29 09:42:21.867183 | TASK [local-log-download : Create download script] 2026-01-29 09:42:22.351177 | localhost -> localhost | changed 2026-01-29 09:42:22.371060 | 2026-01-29 09:42:22.371465 | TASK [Register quick-download link] 2026-01-29 09:42:22.409909 | localhost | ok 2026-01-29 09:42:22.466569 | 2026-01-29 09:42:22.466687 | PLAY [logserver.rdoproject.org] 2026-01-29 09:42:22.480474 | 2026-01-29 09:42:22.480556 | TASK [Set zuul-log-path fact] 2026-01-29 09:42:22.499879 | logserver.rdoproject.org | ok 2026-01-29 09:42:22.512302 | 2026-01-29 09:42:22.512395 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-29 09:42:22.551547 | logserver.rdoproject.org | ok 2026-01-29 09:42:22.559299 | 2026-01-29 09:42:22.559383 | TASK [upload-logs : Create log directories] 2026-01-29 09:42:23.189630 | logserver.rdoproject.org | changed 2026-01-29 09:42:23.194505 | 2026-01-29 09:42:23.194598 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-01-29 09:42:23.519666 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.018645 2026-01-29 09:42:23.529939 | 2026-01-29 09:42:23.530098 | TASK [upload-logs : Upload logs to log server] 2026-01-29 09:42:24.277857 | logserver.rdoproject.org | Output suppressed because no_log was given 2026-01-29 09:42:24.281010 | 2026-01-29 09:42:24.281082 | LOOP [upload-logs : Compress console log and json output] 2026-01-29 09:42:24.348783 | logserver.rdoproject.org | skipping: Conditional result was False 2026-01-29 09:42:24.361059 | logserver.rdoproject.org | skipping: Conditional result was False 2026-01-29 09:42:24.370527 | 2026-01-29 09:42:24.370665 | LOOP [upload-logs : Upload compressed console log and json output] 2026-01-29 09:42:24.419165 | logserver.rdoproject.org | skipping: Conditional result was False 2026-01-29 09:42:24.419554 | 2026-01-29 09:42:24.422660 | logserver.rdoproject.org | skipping: Conditional result was False 2026-01-29 09:42:24.430801 | 2026-01-29 09:42:24.430922 | LOOP [upload-logs : Upload console log and json output]