2025-10-03 10:43:42.569442 | Job console starting...
2025-10-03 10:43:42.580295 | Updating repositories
2025-10-03 10:43:42.603268 | Preparing job workspace
2025-10-03 10:43:47.798156 | Running Ansible setup...
2025-10-03 10:43:55.104891 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 10:43:55.712526 | 
2025-10-03 10:43:55.712707 | PLAY [localhost]
2025-10-03 10:43:55.725778 | 
2025-10-03 10:43:55.725924 | TASK [Gathering Facts]
2025-10-03 10:43:56.743236 | localhost | ok
2025-10-03 10:43:56.772248 | 
2025-10-03 10:43:56.772415 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-03 10:43:57.207213 | localhost -> localhost | changed
2025-10-03 10:43:57.212839 | 
2025-10-03 10:43:57.212918 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-03 10:43:58.146898 | localhost -> localhost | changed
2025-10-03 10:43:58.155604 | 
2025-10-03 10:43:58.155693 | TASK [Setup log path fact]
2025-10-03 10:43:58.173890 | localhost | ok
2025-10-03 10:43:58.185164 | 
2025-10-03 10:43:58.185241 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 10:43:58.214682 | localhost | ok
2025-10-03 10:43:58.228138 | 
2025-10-03 10:43:58.228315 | TASK [emit-job-header : Print job information]
2025-10-03 10:43:58.270244 | # Job Information
2025-10-03 10:43:58.270497 | Ansible Version: 2.15.12
2025-10-03 10:43:58.270540 | Job: cifmw-pod-ansible-test
2025-10-03 10:43:58.270570 | Pipeline: github-check
2025-10-03 10:43:58.270596 | Executor: ze02.softwarefactory-project.io
2025-10-03 10:43:58.270623 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3363
2025-10-03 10:43:58.270649 | Log URL (when completed): https://logserver.rdoproject.org/fec/rdoproject.org/fecf902a20674d68964611916cdc9d0c/
2025-10-03 10:43:58.270703 | Event ID: c169a840-a045-11f0-83c7-868060d8d29b
2025-10-03 10:43:58.275220 | 
2025-10-03 10:43:58.275310 | LOOP [emit-job-header : Print node information]
2025-10-03 10:43:58.364383 | localhost | ok:
2025-10-03 10:43:58.364616 | localhost | # Node Information
2025-10-03 10:43:58.364643 | localhost | Inventory Hostname: container
2025-10-03 10:43:58.364700 | localhost | Hostname: b65dafcb3067
2025-10-03 10:43:58.364729 | localhost | Username: zuul
2025-10-03 10:43:58.364750 | localhost | Distro: CentOS 9
2025-10-03 10:43:58.364769 | localhost | Provider: k1s04
2025-10-03 10:43:58.364786 | localhost | Region:
2025-10-03 10:43:58.364803 | localhost | Label: pod-centos-9-stream
2025-10-03 10:43:58.364820 | localhost | Product Name: OpenStack Nova
2025-10-03 10:43:58.364836 | localhost | Interface IP: pod-centos-9-stream-0005468682
2025-10-03 10:43:58.390789 | 
2025-10-03 10:43:58.390925 | PLAY [all]
2025-10-03 10:43:58.396805 | 
2025-10-03 10:43:58.396886 | TASK [Gather network facts]
2025-10-03 10:44:04.307113 | container | ok
2025-10-03 10:44:04.329425 | 
2025-10-03 10:44:04.329539 | TASK [include_role : start-zuul-console]
2025-10-03 10:44:04.357761 | container | ok
2025-10-03 10:44:04.376095 | 
2025-10-03 10:44:04.376221 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-03 10:44:10.028686 | container | ok
2025-10-03 10:44:10.045033 | 
2025-10-03 10:44:10.045172 | TASK [include_role : add-build-sshkey]
2025-10-03 10:44:10.073843 | container | skipping: Conditional result was False
2025-10-03 10:44:10.088563 | 
2025-10-03 10:44:10.088823 | TASK [include_role : validate-host]
2025-10-03 10:44:10.119769 | container | skipping: Conditional result was False
2025-10-03 10:44:10.135021 | 
2025-10-03 10:44:10.135207 | TASK [include_role : prepare-workspace-openshift]
2025-10-03 10:44:10.162871 | container | ok
2025-10-03 10:44:10.186324 | 
2025-10-03 10:44:10.186469 | LOOP [prepare-workspace-openshift : include_tasks]
2025-10-03 10:44:10.236123 | container | ok:
2025-10-03 10:44:10.236338 | container | [
2025-10-03 10:44:10.236375 | container |   "container",
2025-10-03 10:44:10.236404 | container |   {
2025-10-03 10:44:10.236429 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2025-10-03 10:44:10.236453 | container |     "namespace": "main",
2025-10-03 10:44:10.236494 | container |     "pod": "pod-centos-9-stream-0005468682",
2025-10-03 10:44:10.236518 | container |     "stream_port": "41089"
2025-10-03 10:44:10.236541 | container |   }
2025-10-03 10:44:10.236564 | container | ]
2025-10-03 10:44:10.236602 | container | ok: All items complete
2025-10-03 10:44:10.236631 | 
2025-10-03 10:44:10.256550 | container | included: /var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml
2025-10-03 10:44:10.266520 | 
2025-10-03 10:44:10.266660 | TASK [prepare-workspace-openshift : Create src directory]
2025-10-03 10:44:11.782453 | container -> localhost | ok: Runtime: 0:00:01.074732
2025-10-03 10:44:11.794842 | 
2025-10-03 10:44:11.795003 | TASK [prepare-workspace-openshift : Copy src repos to the pod]
2025-10-03 10:44:14.006695 | container -> localhost | Output suppressed because no_log was given
2025-10-03 10:44:14.027410 | 
2025-10-03 10:44:14.027598 | TASK [include_role : remove-zuul-sshkey]
2025-10-03 10:44:14.061414 | container | ok
2025-10-03 10:44:14.122768 | 
2025-10-03 10:44:14.122889 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-03 10:44:14.536177 | container -> localhost | changed
2025-10-03 10:44:14.553748 | 
2025-10-03 10:44:14.553955 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-03 10:44:19.817364 | container | ok: "logs"
2025-10-03 10:44:25.384751 | container | ok: "artifacts"
2025-10-03 10:44:31.595384 | container | ok: "docs"
2025-10-03 10:44:31.617494 | 
2025-10-03 10:44:31.617736 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-03 10:44:37.347793 | container | changed: "logs"
2025-10-03 10:44:37.348015 | container | ok: All items complete
2025-10-03 10:44:37.348042 | 
2025-10-03 10:44:42.955017 | container | changed: "artifacts"
2025-10-03 10:44:48.534179 | container | changed: "docs"
2025-10-03 10:44:48.574225 | 
2025-10-03 10:44:48.574360 | PLAY RECAP
2025-10-03 10:44:48.574422 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-03 10:44:48.574460 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 10:44:48.574487 | 
2025-10-03 10:44:48.690449 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 10:44:48.692051 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-10-03 10:44:49.312573 | 
2025-10-03 10:44:49.312717 | PLAY [localhost]
2025-10-03 10:44:49.329428 | 
2025-10-03 10:44:49.329510 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-10-03 10:44:49.806355 | localhost | ok
2025-10-03 10:44:49.811521 | 
2025-10-03 10:44:49.811598 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-10-03 10:44:50.529757 | localhost | changed
2025-10-03 10:44:50.559188 | 
2025-10-03 10:44:50.559272 | PLAY [all]
2025-10-03 10:44:50.578383 | 
2025-10-03 10:44:50.578480 | TASK [include_role : prepare-workspace]
2025-10-03 10:44:50.593767 | container | skipping: Conditional result was False
2025-10-03 10:44:50.601663 | 
2025-10-03 10:44:50.601781 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-03 10:44:56.336079 | container | changed: "logs"
2025-10-03 10:45:02.184865 | container | changed: "artifacts"
2025-10-03 10:45:07.808209 | container | changed: "docs"
2025-10-03 10:45:07.825766 | 
2025-10-03 10:45:07.825973 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-03 10:45:13.836435 | container | changed: "logs"
2025-10-03 10:45:13.836814 | container | changed: All items complete
2025-10-03 10:45:13.836882 | 
2025-10-03 10:45:19.676598 | container | changed: "artifacts"
2025-10-03 10:45:25.172606 | container | changed: "docs"
2025-10-03 10:45:25.197029 | 
2025-10-03 10:45:25.197181 | TASK [Check if worker can sudo]
2025-10-03 10:45:31.004293 | container | ok: Runtime: 0:00:00.024617
2025-10-03 10:45:31.016454 | 
2025-10-03 10:45:31.016641 | TASK [configure-mirrors : Gather needed facts]
2025-10-03 10:45:31.035022 | container | skipping: Conditional result was False
2025-10-03 10:45:31.050216 | 
2025-10-03 10:45:31.050423 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-10-03 10:45:31.093032 | container | skipping: Conditional result was False
2025-10-03 10:45:31.143196 | 
2025-10-03 10:45:31.143292 | PLAY RECAP
2025-10-03 10:45:31.143352 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2025-10-03 10:45:31.143390 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 10:45:31.143415 | 
2025-10-03 10:45:31.254269 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-10-03 10:45:31.255453 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2025-10-03 10:45:31.883484 | 
2025-10-03 10:45:31.884130 | PLAY [Run light checks in pod]
2025-10-03 10:45:31.903862 | 
2025-10-03 10:45:31.903952 | TASK [Install packages]
2025-10-03 10:45:48.228307 | container | ok: Nothing to do
2025-10-03 10:45:48.240734 | 
2025-10-03 10:45:48.240917 | TASK [Ensure zuul-output exists]
2025-10-03 10:45:53.952008 | container | ok
2025-10-03 10:45:53.956938 | 
2025-10-03 10:45:53.957011 | TASK [Install dependencies]
2025-10-03 10:47:08.243647 | container | changed
2025-10-03 10:47:08.278146 | 
2025-10-03 10:47:08.278325 | TASK [Run check]
2025-10-03 11:14:01.358126 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2025-10-03 11:14:01.359919 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 11:14:01.951226 | 
2025-10-03 11:14:01.951331 | PLAY [all]
2025-10-03 11:14:01.968281 | 
2025-10-03 11:14:01.968353 | TASK [include_role : fetch-output]
2025-10-03 11:14:01.992414 | container | skipping: Conditional result was False
2025-10-03 11:14:01.997924 | 
2025-10-03 11:14:01.997993 | TASK [include_role : fetch-output-openshift]
2025-10-03 11:14:02.036798 | container | ok
2025-10-03 11:14:02.052441 | 
2025-10-03 11:14:02.052507 | TASK [fetch-output-openshift : Set log path for multiple nodes]
2025-10-03 11:14:02.127011 | container | skipping: Conditional result was False
2025-10-03 11:14:02.137521 | 
2025-10-03 11:14:02.137699 | TASK [fetch-output-openshift : Set log path for single node]
2025-10-03 11:14:02.193564 | container | ok
2025-10-03 11:14:02.203722 | 
2025-10-03 11:14:02.203861 | LOOP [fetch-output-openshift : Ensure local output dirs]
2025-10-03 11:14:02.639092 | container -> localhost | ok: "/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/logs"
2025-10-03 11:14:02.890088 | container -> localhost | changed: "/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/logs/npm"
2025-10-03 11:14:03.127871 | container -> localhost | changed: "/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/artifacts"
2025-10-03 11:14:03.356236 | container -> localhost | changed: "/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/docs"
2025-10-03 11:14:03.373947 | 
2025-10-03 11:14:03.374066 | LOOP [fetch-output-openshift : include_tasks]
2025-10-03 11:14:03.452230 | container | ok:
2025-10-03 11:14:03.452494 | container | [
2025-10-03 11:14:03.452549 | container |   "container",
2025-10-03 11:14:03.452595 | container |   {
2025-10-03 11:14:03.452633 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2025-10-03 11:14:03.452732 | container |     "namespace": "main",
2025-10-03 11:14:03.452774 | container |     "pod": "pod-centos-9-stream-0005468682",
2025-10-03 11:14:03.452810 | container |     "stream_port": "41089"
2025-10-03 11:14:03.452845 | container |   }
2025-10-03 11:14:03.452878 | container | ]
2025-10-03 11:14:03.452933 | container | changed: All items complete
2025-10-03 11:14:03.452974 | 
2025-10-03 11:14:03.475478 | container | included: /var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml
2025-10-03 11:14:03.488592 | 
2025-10-03 11:14:03.488761 | LOOP [fetch-output-openshift : Ensure local directory exists]
2025-10-03 11:14:09.033469 | container | changed: "/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/logs"
2025-10-03 11:14:14.615882 | container | changed: "/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/logs/npm"
2025-10-03 11:14:20.182643 | container | changed: "/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/artifacts"
2025-10-03 11:14:25.652481 | container | changed: "/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/docs"
2025-10-03 11:14:25.672531 | 
2025-10-03 11:14:25.672886 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor]
2025-10-03 11:14:30.609358 | container | Output suppressed because no_log was given
2025-10-03 11:14:30.616617 | 
2025-10-03 11:14:30.616778 | TASK [fetch-output-openshift : Remove empty directory]
2025-10-03 11:14:30.948832 | container -> localhost | ok: Runtime: 0:00:00.058638
2025-10-03 11:14:30.962206 | 
2025-10-03 11:14:30.962291 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-03 11:14:31.330106 | container -> localhost | find: ‘/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/artifacts’: No such file or directory
2025-10-03 11:14:31.330533 | container -> localhost | find: ‘/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/artifacts’: No such file or directory
2025-10-03 11:14:31.330639 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.013426
2025-10-03 11:14:31.330796 | container -> localhost | changed: All items complete
2025-10-03 11:14:31.330853 | 
2025-10-03 11:14:31.603784 | container -> localhost | find: ‘/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/docs’: No such file or directory
2025-10-03 11:14:31.603997 | container -> localhost | find: ‘/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/docs’: No such file or directory
2025-10-03 11:14:31.604076 | container -> localhost | ok: Item: docs Runtime: 0:00:00.012905
2025-10-03 11:14:31.631914 | 
2025-10-03 11:14:31.632025 | PLAY [all]
2025-10-03 11:14:31.644442 | 
2025-10-03 11:14:31.644502 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-03 11:14:31.684433 | container | ERROR
2025-10-03 11:14:31.684693 | container | {
2025-10-03 11:14:31.684737 | container |   "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/src/../fecf902a20674d68964611916cdc9d0c_id_rsa.pub'. file not found, use -vvvvv to see paths searched"
2025-10-03 11:14:31.684768 | container | }
2025-10-03 11:14:31.684802 | container | ERROR: Ignoring Errors
2025-10-03 11:14:31.713551 | 
2025-10-03 11:14:31.713624 | PLAY RECAP
2025-10-03 11:14:31.713720 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1
2025-10-03 11:14:31.713755 | 
2025-10-03 11:14:31.804551 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 11:14:31.806084 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-03 11:14:32.539631 | 
2025-10-03 11:14:32.539769 | PLAY [localhost]
2025-10-03 11:14:32.557751 | 
2025-10-03 11:14:32.557832 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-03 11:14:32.997972 | localhost | changed
2025-10-03 11:14:33.008420 | 
2025-10-03 11:14:33.008592 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-03 11:14:33.044590 | localhost | ok
2025-10-03 11:14:33.063150 | 
2025-10-03 11:14:33.063297 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-03 11:14:33.500820 | localhost | changed
2025-10-03 11:14:33.513398 | 
2025-10-03 11:14:33.513564 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-03 11:14:34.258591 | localhost | changed
2025-10-03 11:14:34.264114 | 
2025-10-03 11:14:34.264193 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-03 11:14:34.675749 | localhost | Identity added: /var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/tmp/ansible.azkxgt4a (/var/lib/zuul/builds/fecf902a20674d68964611916cdc9d0c/work/tmp/ansible.azkxgt4a)
2025-10-03 11:14:34.676066 | localhost | ok: Runtime: 0:00:00.006854
2025-10-03 11:14:34.685731 | 
2025-10-03 11:14:34.685869 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-03 11:14:34.936609 | localhost | ok: Runtime: 0:00:00.004538
2025-10-03 11:14:34.947810 | 
2025-10-03 11:14:34.947958 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-03 11:14:35.006901 | localhost | changed
2025-10-03 11:14:35.017299 | 
2025-10-03 11:14:35.017634 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-03 11:14:35.370484 | localhost | changed
2025-10-03 11:14:35.412314 | 
2025-10-03 11:14:35.412389 | PLAY [localhost]
2025-10-03 11:14:35.429169 | 
2025-10-03 11:14:35.429250 | TASK [Generate bulk log download script]
2025-10-03 11:14:35.450975 | localhost | ok
2025-10-03 11:14:35.467115 | 
2025-10-03 11:14:35.467207 | TASK [local-log-download : Check API endpoint is defined]
2025-10-03 11:14:35.509190 | localhost | ok: All assertions passed
2025-10-03 11:14:35.515437 | 
2025-10-03 11:14:35.515527 | TASK [local-log-download : Create download script]
2025-10-03 11:14:35.947252 | localhost -> localhost | changed
2025-10-03 11:14:35.967760 | 
2025-10-03 11:14:35.967937 | TASK [Register quick-download link]
2025-10-03 11:14:35.993795 | localhost | ok
2025-10-03 11:14:36.049545 | 
2025-10-03 11:14:36.049647 | PLAY [logserver.rdoproject.org]
2025-10-03 11:14:36.059350 | 
2025-10-03 11:14:36.059408 | TASK [Set zuul-log-path fact]
2025-10-03 11:14:36.076480 | logserver.rdoproject.org | ok
2025-10-03 11:14:36.085630 | 
2025-10-03 11:14:36.085723 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 11:14:36.124149 | logserver.rdoproject.org | ok
2025-10-03 11:14:36.131805 | 
2025-10-03 11:14:36.131893 | TASK [upload-logs : Create log directories]
2025-10-03 11:14:36.818052 | logserver.rdoproject.org | changed
2025-10-03 11:14:36.824900 | 
2025-10-03 11:14:36.825034 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-03 11:14:37.137500 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006227
2025-10-03 11:14:37.142333 | 
2025-10-03 11:14:37.142395 | TASK [upload-logs : Upload logs to log server]
2025-10-03 11:14:37.787554 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-03 11:14:37.790509 | 
2025-10-03 11:14:37.790574 | LOOP [upload-logs : Compress console log and json output]
2025-10-03 11:14:37.858843 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 11:14:37.870573 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 11:14:37.879433 | 
2025-10-03 11:14:37.879537 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-03 11:14:37.925387 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 11:14:37.925810 | 
2025-10-03 11:14:37.928761 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 11:14:37.940699 | 
2025-10-03 11:14:37.940816 | LOOP [upload-logs : Upload console log and json output]