2025-12-02 11:50:23.343786 | Job console starting...
2025-12-02 11:50:23.356292 | Updating repositories
2025-12-02 11:50:23.401873 | Preparing job workspace
2025-12-02 11:50:29.905685 | Running Ansible setup...
2025-12-02 11:50:37.168480 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-02 11:50:37.801766 | 
2025-12-02 11:50:37.801936 | PLAY [localhost]
2025-12-02 11:50:37.811629 | 
2025-12-02 11:50:37.811751 | TASK [Gathering Facts]
2025-12-02 11:50:38.914866 | localhost | ok
2025-12-02 11:50:38.947005 | 
2025-12-02 11:50:38.947208 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-02 11:50:39.410976 | localhost -> localhost | changed
2025-12-02 11:50:39.417572 | 
2025-12-02 11:50:39.417649 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-02 11:50:40.347326 | localhost -> localhost | changed
2025-12-02 11:50:40.371034 | 
2025-12-02 11:50:40.371282 | TASK [Setup log path fact]
2025-12-02 11:50:40.394074 | localhost | ok
2025-12-02 11:50:40.410769 | 
2025-12-02 11:50:40.410974 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-02 11:50:40.445083 | localhost | ok
2025-12-02 11:50:40.459291 | 
2025-12-02 11:50:40.459501 | TASK [emit-job-header : Print job information]
2025-12-02 11:50:40.504073 | # Job Information
2025-12-02 11:50:40.504317 | Ansible Version: 2.15.12
2025-12-02 11:50:40.504353 | Job: cifmw-pod-pre-commit
2025-12-02 11:50:40.504380 | Pipeline: github-check
2025-12-02 11:50:40.504403 | Executor: ze03.softwarefactory-project.io
2025-12-02 11:50:40.504426 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-12-02 11:50:40.504450 | Log URL (when completed): https://logserver.rdoproject.org/c63/rdoproject.org/c63ac8b24be244ea83b71068f35f5268/
2025-12-02 11:50:40.504472 | Event ID: 00697580-cf75-11f0-86aa-3ca07a3348bc
2025-12-02 11:50:40.509356 | 
2025-12-02 11:50:40.509470 | LOOP [emit-job-header : Print node information]
2025-12-02 11:50:40.612152 | localhost | ok:
2025-12-02 11:50:40.612480 | localhost | # Node Information
2025-12-02 11:50:40.612512 | localhost | Inventory Hostname: container
2025-12-02 11:50:40.612540 | localhost | Hostname: f0ebfd678fed
2025-12-02 11:50:40.612566 | localhost | Username: zuul
2025-12-02 11:50:40.612595 | localhost | Distro: CentOS 9
2025-12-02 11:50:40.612622 | localhost | Provider: k1s04
2025-12-02 11:50:40.612649 | localhost | Region:
2025-12-02 11:50:40.612672 | localhost | Label: pod-centos-9-stream
2025-12-02 11:50:40.612690 | localhost | Product Name: OpenStack Nova
2025-12-02 11:50:40.612711 | localhost | Interface IP: pod-centos-9-stream-0005542382
2025-12-02 11:50:40.637697 | 
2025-12-02 11:50:40.637850 | PLAY [all]
2025-12-02 11:50:40.643805 | 
2025-12-02 11:50:40.643874 | TASK [Gather network facts]
2025-12-02 11:50:46.173417 | container | ok
2025-12-02 11:50:46.203336 | 
2025-12-02 11:50:46.203516 | TASK [include_role : start-zuul-console]
2025-12-02 11:50:46.237381 | container | ok
2025-12-02 11:50:46.255876 | 
2025-12-02 11:50:46.255995 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-02 11:50:52.031995 | container | ok
2025-12-02 11:50:52.040367 | 
2025-12-02 11:50:52.040431 | TASK [include_role : add-build-sshkey]
2025-12-02 11:50:52.067795 | container | skipping: Conditional result was False
2025-12-02 11:50:52.073940 | 
2025-12-02 11:50:52.074044 | TASK [include_role : validate-host]
2025-12-02 11:50:52.099300 | container | skipping: Conditional result was False
2025-12-02 11:50:52.104629 | 
2025-12-02 11:50:52.104700 | TASK [include_role : prepare-workspace-openshift]
2025-12-02 11:50:52.134033 | container | ok
2025-12-02 11:50:52.146849 | 
2025-12-02 11:50:52.147001 | LOOP [prepare-workspace-openshift : include_tasks]
2025-12-02 11:50:52.210506 | container | ok:
2025-12-02 11:50:52.210894 | container | [
2025-12-02 11:50:52.210927 | container |   "container",
2025-12-02 11:50:52.210956 | container |   {
2025-12-02 11:50:52.210980 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2025-12-02 11:50:52.211201 | container |     "namespace": "main",
2025-12-02 11:50:52.211253 | container |     "pod": "pod-centos-9-stream-0005542382",
2025-12-02 11:50:52.211278 | container |     "stream_port": "46459"
2025-12-02 11:50:52.211303 | container |   }
2025-12-02 11:50:52.211326 | container | ]
2025-12-02 11:50:52.211375 | container | ok: All items complete
2025-12-02 11:50:52.211401 | 
2025-12-02 11:50:52.222012 | container | included: /var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml
2025-12-02 11:50:52.227598 | 
2025-12-02 11:50:52.227686 | TASK [prepare-workspace-openshift : Create src directory]
2025-12-02 11:50:53.591314 | container -> localhost | ok: Runtime: 0:00:00.947439
2025-12-02 11:50:53.597611 | 
2025-12-02 11:50:53.597678 | TASK [prepare-workspace-openshift : Copy src repos to the pod]
2025-12-02 11:50:55.735872 | container -> localhost | Output suppressed because no_log was given
2025-12-02 11:50:55.809329 | 
2025-12-02 11:50:55.809498 | TASK [include_role : remove-zuul-sshkey]
2025-12-02 11:50:55.841302 | container | ok
2025-12-02 11:50:55.858909 | 
2025-12-02 11:50:55.859088 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-02 11:50:56.223821 | container -> localhost | changed
2025-12-02 11:50:56.233841 | 
2025-12-02 11:50:56.233915 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-02 11:51:01.975599 | container | ok: "logs"
2025-12-02 11:51:07.646365 | container | ok: "artifacts"
2025-12-02 11:51:13.496130 | container | ok: "docs"
2025-12-02 11:51:13.509352 | 
2025-12-02 11:51:13.509526 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-02 11:51:19.629400 | container | changed: "logs"
2025-12-02 11:51:19.629702 | container | ok: All items complete
2025-12-02 11:51:19.629774 | 
2025-12-02 11:51:25.561833 | container | changed: "artifacts"
2025-12-02 11:51:31.470439 | container | changed: "docs"
2025-12-02 11:51:31.520422 | 
2025-12-02 11:51:31.520563 | PLAY RECAP
2025-12-02 11:51:31.520646 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-02 11:51:31.520701 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-02 11:51:31.520802 | 
2025-12-02 11:51:31.665189 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-02 11:51:31.666895 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-02 11:51:32.300505 | 
2025-12-02 11:51:32.300685 | PLAY [localhost]
2025-12-02 11:51:32.319907 | 
2025-12-02 11:51:32.320038 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-12-02 11:51:32.804054 | localhost | ok
2025-12-02 11:51:32.818563 | 
2025-12-02 11:51:32.818802 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-12-02 11:51:33.589237 | localhost | changed
2025-12-02 11:51:33.626021 | 
2025-12-02 11:51:33.626192 | PLAY [all]
2025-12-02 11:51:33.645786 | 
2025-12-02 11:51:33.645872 | TASK [include_role : prepare-workspace]
2025-12-02 11:51:33.672754 | container | skipping: Conditional result was False
2025-12-02 11:51:33.680816 | 
2025-12-02 11:51:33.680919 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-02 11:51:39.395327 | container | changed: "logs"
2025-12-02 11:51:45.349329 | container | changed: "artifacts"
2025-12-02 11:51:51.205474 | container | changed: "docs"
2025-12-02 11:51:51.223325 | 
2025-12-02 11:51:51.223601 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-02 11:51:57.160151 | container | changed: "logs"
2025-12-02 11:51:57.160491 | container | changed: All items complete
2025-12-02 11:51:57.160552 | 
2025-12-02 11:52:02.833140 | container | changed: "artifacts"
2025-12-02 11:52:08.486117 | container | changed: "docs"
2025-12-02 11:52:08.511306 | 
2025-12-02 11:52:08.511525 | TASK [Check if worker can sudo]
2025-12-02 11:52:14.896998 | container | ok: Runtime: 0:00:00.020943
2025-12-02 11:52:14.908824 | 
2025-12-02 11:52:14.909037 | TASK [configure-mirrors : Gather needed facts]
2025-12-02 11:52:14.935013 | container | skipping: Conditional result was False
2025-12-02 11:52:14.943170 | 
2025-12-02 11:52:14.943273 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-12-02 11:52:14.967875 | container | skipping: Conditional result was False
2025-12-02 11:52:14.997794 | 
2025-12-02 11:52:14.997886 | PLAY RECAP
2025-12-02 11:52:14.997944 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2025-12-02 11:52:14.997981 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-02 11:52:14.998027 | 
2025-12-02 11:52:15.126045 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-02 11:52:15.127841 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2025-12-02 11:52:15.925512 | 
2025-12-02 11:52:15.925646 | PLAY [Run light checks in pod]
2025-12-02 11:52:15.945254 | 
2025-12-02 11:52:15.945360 | TASK [Install packages]
2025-12-02 11:52:37.414054 | container | ok: Nothing to do
2025-12-02 11:52:37.433663 | 
2025-12-02 11:52:37.433889 | TASK [Ensure zuul-output exists]
2025-12-02 11:52:43.485894 | container | ok
2025-12-02 11:52:43.497398 | 
2025-12-02 11:52:43.497588 | TASK [Install dependencies]
2025-12-02 12:20:38.902021 | container | changed
2025-12-02 12:20:38.921073 | 
2025-12-02 12:20:38.921168 | TASK [Run check]
2025-12-02 12:20:45.197945 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2025-12-02 12:20:45.199822 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-02 12:20:45.862684 | 
2025-12-02 12:20:45.862825 | PLAY [all]
2025-12-02 12:20:45.879941 | 
2025-12-02 12:20:45.880013 | TASK [include_role : fetch-output]
2025-12-02 12:20:45.914825 | container | skipping: Conditional result was False
2025-12-02 12:20:45.920396 | 
2025-12-02 12:20:45.920471 | TASK [include_role : fetch-output-openshift]
2025-12-02 12:20:45.950189 | container | ok
2025-12-02 12:20:45.966804 | 
2025-12-02 12:20:45.966872 | TASK [fetch-output-openshift : Set log path for multiple nodes]
2025-12-02 12:20:46.011960 | container | skipping: Conditional result was False
2025-12-02 12:20:46.017914 | 
2025-12-02 12:20:46.017983 | TASK [fetch-output-openshift : Set log path for single node]
2025-12-02 12:20:46.074300 | container | ok
2025-12-02 12:20:46.085022 | 
2025-12-02 12:20:46.085161 | LOOP [fetch-output-openshift : Ensure local output dirs]
2025-12-02 12:20:46.544800 | container -> localhost | ok: "/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/logs"
2025-12-02 12:20:46.756971 | container -> localhost | changed: "/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/logs/npm"
2025-12-02 12:20:47.013354 | container -> localhost | changed: "/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/artifacts"
2025-12-02 12:20:47.254822 | container -> localhost | changed: "/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/docs"
2025-12-02 12:20:47.268422 | 
2025-12-02 12:20:47.268569 | LOOP [fetch-output-openshift : include_tasks]
2025-12-02 12:20:47.329425 | container | ok:
2025-12-02 12:20:47.329688 | container | [
2025-12-02 12:20:47.329848 | container |   "container",
2025-12-02 12:20:47.329904 | container |   {
2025-12-02 12:20:47.329944 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2025-12-02 12:20:47.329982 | container |     "namespace": "main",
2025-12-02 12:20:47.330018 | container |     "pod": "pod-centos-9-stream-0005542382",
2025-12-02 12:20:47.330053 | container |     "stream_port": "46459"
2025-12-02 12:20:47.330087 | container |   }
2025-12-02 12:20:47.330122 | container | ]
2025-12-02 12:20:47.330182 | container | changed: All items complete
2025-12-02 12:20:47.330223 | 
2025-12-02 12:20:47.348039 | container | included: /var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml
2025-12-02 12:20:47.358911 | 
2025-12-02 12:20:47.358996 | LOOP [fetch-output-openshift : Ensure local directory exists]
2025-12-02 12:20:53.565659 | container | changed: "/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/logs"
2025-12-02 12:20:59.367555 | container | changed: "/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/logs/npm"
2025-12-02 12:21:05.198811 | container | changed: "/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/artifacts"
2025-12-02 12:21:11.191680 | container | changed: "/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/docs"
2025-12-02 12:21:11.214697 | 
2025-12-02 12:21:11.214980 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor]
2025-12-02 12:21:16.334752 | container | Output suppressed because no_log was given
2025-12-02 12:21:16.342072 | 
2025-12-02 12:21:16.342160 | TASK [fetch-output-openshift : Remove empty directory]
2025-12-02 12:21:16.736239 | container -> localhost | ok: Runtime: 0:00:00.057310
2025-12-02 12:21:16.750140 | 
2025-12-02 12:21:16.750233 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-02 12:21:17.089657 | container -> localhost | find: ‘/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/artifacts’: No such file or directory
2025-12-02 12:21:17.090172 | container -> localhost | find: ‘/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/artifacts’: No such file or directory
2025-12-02 12:21:17.090270 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.011670
2025-12-02 12:21:17.090342 | container -> localhost | changed: All items complete
2025-12-02 12:21:17.090388 | 
2025-12-02 12:21:17.337633 | container -> localhost | find: ‘/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/docs’: No such file or directory
2025-12-02 12:21:17.337882 | container -> localhost | find: ‘/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/docs’: No such file or directory
2025-12-02 12:21:17.337956 | container -> localhost | ok: Item: docs Runtime: 0:00:00.019730
2025-12-02 12:21:17.386596 | 
2025-12-02 12:21:17.386846 | PLAY [all]
2025-12-02 12:21:17.407260 | 
2025-12-02 12:21:17.407361 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-02 12:21:17.438916 | container | ERROR
2025-12-02 12:21:17.439148 | container | {
2025-12-02 12:21:17.439186 | container |   "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/src/../c63ac8b24be244ea83b71068f35f5268_id_rsa.pub'. file not found, use -vvvvv to see paths searched"
2025-12-02 12:21:17.439215 | container | }
2025-12-02 12:21:17.439251 | container | ERROR: Ignoring Errors
2025-12-02 12:21:17.467084 | 
2025-12-02 12:21:17.467165 | PLAY RECAP
2025-12-02 12:21:17.467223 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1
2025-12-02 12:21:17.467252 | 
2025-12-02 12:21:17.586560 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-02 12:21:17.587499 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-02 12:21:18.279281 | 
2025-12-02 12:21:18.279429 | PLAY [localhost]
2025-12-02 12:21:18.298771 | 
2025-12-02 12:21:18.298901 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-02 12:21:18.756246 | localhost | changed
2025-12-02 12:21:18.771497 | 
2025-12-02 12:21:18.771779 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-02 12:21:18.819942 | localhost | ok
2025-12-02 12:21:18.838236 | 
2025-12-02 12:21:18.838420 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-02 12:21:19.282542 | localhost | changed
2025-12-02 12:21:19.289145 | 
2025-12-02 12:21:19.289236 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-02 12:21:20.123933 | localhost | changed
2025-12-02 12:21:20.129610 | 
2025-12-02 12:21:20.129678 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-02 12:21:20.560399 | localhost | Identity added: /var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/tmp/ansible.kmfad5tw (/var/lib/zuul/builds/c63ac8b24be244ea83b71068f35f5268/work/tmp/ansible.kmfad5tw)
2025-12-02 12:21:20.571121 | localhost | ok: Runtime: 0:00:00.014882
2025-12-02 12:21:20.580600 | 
2025-12-02 12:21:20.583223 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-02 12:21:20.899898 | localhost | ok: Runtime: 0:00:00.010424
2025-12-02 12:21:20.904525 | 
2025-12-02 12:21:20.904589 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-02 12:21:20.989548 | localhost | changed
2025-12-02 12:21:20.999008 | 
2025-12-02 12:21:20.999147 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-02 12:21:21.477823 | localhost | changed
2025-12-02 12:21:21.498471 | 
2025-12-02 12:21:21.498535 | PLAY [localhost]
2025-12-02 12:21:21.510867 | 
2025-12-02 12:21:21.510928 | TASK [Generate bulk log download script]
2025-12-02 12:21:21.540465 | localhost | ok
2025-12-02 12:21:21.552803 | 
2025-12-02 12:21:21.552870 | TASK [local-log-download : Check API endpoint is defined]
2025-12-02 12:21:21.591718 | localhost | ok: All assertions passed
2025-12-02 12:21:21.596347 | 
2025-12-02 12:21:21.596411 | TASK [local-log-download : Create download script]
2025-12-02 12:21:22.058602 | localhost -> localhost | changed
2025-12-02 12:21:22.069031 | 
2025-12-02 12:21:22.069095 | TASK [Register quick-download link]
2025-12-02 12:21:22.127522 | localhost | ok
2025-12-02 12:21:22.143597 | 
2025-12-02 12:21:22.143657 | PLAY [logserver.rdoproject.org]
2025-12-02 12:21:22.153523 | 
2025-12-02 12:21:22.153582 | TASK [Set zuul-log-path fact]
2025-12-02 12:21:22.180837 | logserver.rdoproject.org | ok
2025-12-02 12:21:22.190251 | 
2025-12-02 12:21:22.190319 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-02 12:21:22.228366 | logserver.rdoproject.org | ok
2025-12-02 12:21:22.233863 | 
2025-12-02 12:21:22.233925 | TASK [upload-logs : Create log directories]
2025-12-02 12:21:23.089968 | logserver.rdoproject.org | changed
2025-12-02 12:21:23.097114 | 
2025-12-02 12:21:23.097299 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-02 12:21:23.472718 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009678
2025-12-02 12:21:23.483522 | 
2025-12-02 12:21:23.483776 | TASK [upload-logs : Upload logs to log server]
2025-12-02 12:21:24.304268 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-02 12:21:24.311802 | 
2025-12-02 12:21:24.311963 | LOOP [upload-logs : Compress console log and json output]
2025-12-02 12:21:24.390522 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-02 12:21:24.404882 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-02 12:21:24.418494 | 
2025-12-02 12:21:24.418718 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-02 12:21:24.490758 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-02 12:21:24.491232 | 
2025-12-02 12:21:24.496718 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-02 12:21:24.506550 | 
2025-12-02 12:21:24.506836 | LOOP [upload-logs : Upload console log and json output]