2026-01-23 15:22:52.096240 | Job console starting...
2026-01-23 15:22:52.106282 | Updating repositories
2026-01-23 15:22:52.133062 | Preparing job workspace
2026-01-23 15:22:57.621734 | Running Ansible setup...
2026-01-23 15:23:05.450170 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-23 15:23:06.033597 | 
2026-01-23 15:23:06.033732 | PLAY [localhost]
2026-01-23 15:23:06.042125 | 
2026-01-23 15:23:06.042194 | TASK [Gathering Facts]
2026-01-23 15:23:07.001868 | localhost | ok
2026-01-23 15:23:07.019939 | 
2026-01-23 15:23:07.020043 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-23 15:23:07.409363 | localhost -> localhost | changed
2026-01-23 15:23:07.416708 | 
2026-01-23 15:23:07.416799 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-23 15:23:08.215532 | localhost -> localhost | changed
2026-01-23 15:23:08.223744 | 
2026-01-23 15:23:08.223807 | TASK [Setup log path fact]
2026-01-23 15:23:08.241339 | localhost | ok
2026-01-23 15:23:08.252238 | 
2026-01-23 15:23:08.252299 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-23 15:23:08.281165 | localhost | ok
2026-01-23 15:23:08.293044 | 
2026-01-23 15:23:08.293183 | TASK [emit-job-header : Print job information]
2026-01-23 15:23:08.326413 | # Job Information
2026-01-23 15:23:08.326606 | Ansible Version: 2.15.12
2026-01-23 15:23:08.326631 | Job: cifmw-pod-ansible-test
2026-01-23 15:23:08.326651 | Pipeline: github-check
2026-01-23 15:23:08.326686 | Executor: ze02.softwarefactory-project.io
2026-01-23 15:23:08.326706 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3621
2026-01-23 15:23:08.326725 | Log URL (when completed): https://logserver.rdoproject.org/162/rdoproject.org/162251fd362541ab8f8e19258d2c3abe/
2026-01-23 15:23:08.326744 | Event ID: 505ac670-f86f-11f0-98e5-6770e5ba961b
2026-01-23 15:23:08.330544 | 
2026-01-23 15:23:08.330606 | LOOP [emit-job-header : Print node information]
2026-01-23 15:23:08.413451 | localhost | ok:
2026-01-23 15:23:08.413622 | localhost | # Node Information
2026-01-23 15:23:08.413648 | localhost | Inventory Hostname: container
2026-01-23 15:23:08.413689 | localhost | Hostname: a98ad403fe82
2026-01-23 15:23:08.413711 | localhost | Username: zuul
2026-01-23 15:23:08.413731 | localhost | Distro: CentOS 9
2026-01-23 15:23:08.413749 | localhost | Provider: k1s04
2026-01-23 15:23:08.413766 | localhost | Region:
2026-01-23 15:23:08.413782 | localhost | Label: pod-centos-9-stream
2026-01-23 15:23:08.413798 | localhost | Product Name: OpenStack Nova
2026-01-23 15:23:08.413814 | localhost | Interface IP: pod-centos-9-stream-0005593730
2026-01-23 15:23:08.435054 | 
2026-01-23 15:23:08.435177 | PLAY [all]
2026-01-23 15:23:08.441776 | 
2026-01-23 15:23:08.441838 | TASK [Gather network facts]
2026-01-23 15:23:14.381788 | container | ok
2026-01-23 15:23:14.396913 | 
2026-01-23 15:23:14.397048 | TASK [include_role : start-zuul-console]
2026-01-23 15:23:14.419160 | container | ok
2026-01-23 15:23:14.430568 | 
2026-01-23 15:23:14.430633 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-23 15:23:20.735480 | container | ok
2026-01-23 15:23:20.754900 | 
2026-01-23 15:23:20.755067 | TASK [include_role : add-build-sshkey]
2026-01-23 15:23:20.782742 | container | skipping: Conditional result was False
2026-01-23 15:23:20.794619 | 
2026-01-23 15:23:20.794821 | TASK [include_role : validate-host]
2026-01-23 15:23:20.821561 | container | skipping: Conditional result was False
2026-01-23 15:23:20.832654 | 
2026-01-23 15:23:20.832836 | TASK [include_role : prepare-workspace-openshift]
2026-01-23 15:23:20.879445 | container | ok
2026-01-23 15:23:20.907627 | 
2026-01-23 15:23:20.907813 | LOOP [prepare-workspace-openshift : include_tasks]
2026-01-23 15:23:20.979650 | container | ok:
2026-01-23 15:23:20.979932 | container | [
2026-01-23 15:23:20.979969 | container |   "container",
2026-01-23 15:23:20.979998 | container |   {
2026-01-23 15:23:20.980023 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2026-01-23 15:23:20.980046 | container |     "namespace": "main",
2026-01-23 15:23:20.980084 | container |     "pod": "pod-centos-9-stream-0005593730",
2026-01-23 15:23:20.980107 | container |     "stream_port": "37717"
2026-01-23 15:23:20.980129 | container |   }
2026-01-23 15:23:20.980150 | container | ]
2026-01-23 15:23:20.980190 | container | ok: All items complete
2026-01-23 15:23:20.980218 | 
2026-01-23 15:23:20.998384 | container | included: /var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml
2026-01-23 15:23:21.005604 | 
2026-01-23 15:23:21.005708 | TASK [prepare-workspace-openshift : Create src directory]
2026-01-23 15:23:22.426046 | container -> localhost | ok: Runtime: 0:00:00.984866
2026-01-23 15:23:22.433248 | 
2026-01-23 15:23:22.433351 | TASK [prepare-workspace-openshift : Copy src repos to the pod]
2026-01-23 15:23:24.636182 | container -> localhost | Output suppressed because no_log was given
2026-01-23 15:23:24.687853 | 
2026-01-23 15:23:24.688004 | TASK [include_role : remove-zuul-sshkey]
2026-01-23 15:23:24.718733 | container | ok
2026-01-23 15:23:24.736435 | 
2026-01-23 15:23:24.736519 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-23 15:23:25.117572 | container -> localhost | changed
2026-01-23 15:23:25.136919 | 
2026-01-23 15:23:25.137056 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-23 15:23:31.109216 | container | ok: "logs"
2026-01-23 15:23:37.239191 | container | ok: "artifacts"
2026-01-23 15:23:43.755474 | container | ok: "docs"
2026-01-23 15:23:43.769468 | 
2026-01-23 15:23:43.769759 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-23 15:23:50.128553 | container | changed: "logs"
2026-01-23 15:23:50.128995 | container | ok: All items complete
2026-01-23 15:23:50.129054 | 
2026-01-23 15:23:56.002436 | container | changed: "artifacts"
2026-01-23 15:24:02.076728 | container | changed: "docs"
2026-01-23 15:24:02.131404 | 
2026-01-23 15:24:02.131553 | PLAY RECAP
2026-01-23 15:24:02.131611 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-01-23 15:24:02.131647 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-23 15:24:02.131698 | 
2026-01-23 15:24:02.289848 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2026-01-23 15:24:02.291596 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-01-23 15:24:02.924217 | 
2026-01-23 15:24:02.924337 | PLAY [localhost]
2026-01-23 15:24:02.940275 | 
2026-01-23 15:24:02.940348 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2026-01-23 15:24:03.382359 | localhost | ok
2026-01-23 15:24:03.393201 | 
2026-01-23 15:24:03.393358 | TASK [configure-ssh-config-fips : Setup ssh config]
2026-01-23 15:24:04.215412 | localhost | changed
2026-01-23 15:24:04.257575 | 
2026-01-23 15:24:04.257743 | PLAY [all]
2026-01-23 15:24:04.277389 | 
2026-01-23 15:24:04.277521 | TASK [include_role : prepare-workspace]
2026-01-23 15:24:04.309841 | container | skipping: Conditional result was False
2026-01-23 15:24:04.318898 | 
2026-01-23 15:24:04.319002 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-23 15:24:10.272891 | container | changed: "logs"
2026-01-23 15:24:16.119864 | container | changed: "artifacts"
2026-01-23 15:24:22.139843 | container | changed: "docs"
2026-01-23 15:24:22.153319 | 
2026-01-23 15:24:22.153533 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-23 15:24:28.403511 | container | changed: "logs"
2026-01-23 15:24:28.403753 | container | changed: All items complete
2026-01-23 15:24:28.403791 | 
2026-01-23 15:24:34.693187 | container | changed: "artifacts"
2026-01-23 15:24:41.156018 | container | changed: "docs"
2026-01-23 15:24:41.191405 | 
2026-01-23 15:24:41.191608 | TASK [Check if worker can sudo]
2026-01-23 15:24:47.805064 | container | ok: Runtime: 0:00:00.053117
2026-01-23 15:24:47.819788 | 
2026-01-23 15:24:47.820014 | TASK [configure-mirrors : Gather needed facts]
2026-01-23 15:24:47.852106 | container | skipping: Conditional result was False
2026-01-23 15:24:47.864043 | 
2026-01-23 15:24:47.864212 | TASK [configure-mirrors : Set up infrastructure mirrors]
2026-01-23 15:24:47.891253 | container | skipping: Conditional result was False
2026-01-23 15:24:47.933496 | 
2026-01-23 15:24:47.933563 | PLAY RECAP
2026-01-23 15:24:47.933618 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-01-23 15:24:47.933651 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-23 15:24:47.933716 | 
2026-01-23 15:24:48.048495 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2026-01-23 15:24:48.050226 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2026-01-23 15:24:48.699399 | 
2026-01-23 15:24:48.699529 | PLAY [Run light checks in pod]
2026-01-23 15:24:48.717914 | 
2026-01-23 15:24:48.717988 | TASK [Install packages]
2026-01-23 15:25:05.432546 | container | ok: Nothing to do
2026-01-23 15:25:05.443041 | 
2026-01-23 15:25:05.443369 | TASK [Ensure zuul-output exists]
2026-01-23 15:25:11.704820 | container | ok
2026-01-23 15:25:11.715410 | 
2026-01-23 15:25:11.715552 | TASK [Install dependencies]
2026-01-23 15:26:39.303089 | container | changed
2026-01-23 15:26:39.335912 | 
2026-01-23 15:26:39.336118 | TASK [Run check]
2026-01-23 15:53:08.110569 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2026-01-23 15:53:08.112298 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-23 15:53:08.705009 | 
2026-01-23 15:53:08.705133 | PLAY [all]
2026-01-23 15:53:08.722069 | 
2026-01-23 15:53:08.722146 | TASK [include_role : fetch-output]
2026-01-23 15:53:08.746221 | container | skipping: Conditional result was False
2026-01-23 15:53:08.751179 | 
2026-01-23 15:53:08.751253 | TASK [include_role : fetch-output-openshift]
2026-01-23 15:53:08.789751 | container | ok
2026-01-23 15:53:08.805496 | 
2026-01-23 15:53:08.805563 | TASK [fetch-output-openshift : Set log path for multiple nodes]
2026-01-23 15:53:08.870225 | container | skipping: Conditional result was False
2026-01-23 15:53:08.880711 | 
2026-01-23 15:53:08.880863 | TASK [fetch-output-openshift : Set log path for single node]
2026-01-23 15:53:08.927785 | container | ok
2026-01-23 15:53:08.937434 | 
2026-01-23 15:53:08.937574 | LOOP [fetch-output-openshift : Ensure local output dirs]
2026-01-23 15:53:09.371003 | container -> localhost | ok: "/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/logs"
2026-01-23 15:53:09.621093 | container -> localhost | changed: "/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/logs/npm"
2026-01-23 15:53:09.839219 | container -> localhost | changed: "/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/artifacts"
2026-01-23 15:53:10.076265 | container -> localhost | changed: "/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/docs"
2026-01-23 15:53:10.101066 | 
2026-01-23 15:53:10.101280 | LOOP [fetch-output-openshift : include_tasks]
2026-01-23 15:53:10.149461 | container | ok:
2026-01-23 15:53:10.149748 | container | [
2026-01-23 15:53:10.149781 | container |   "container",
2026-01-23 15:53:10.149800 | container |   {
2026-01-23 15:53:10.149819 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2026-01-23 15:53:10.149841 | container |     "namespace": "main",
2026-01-23 15:53:10.149862 | container |     "pod": "pod-centos-9-stream-0005593730",
2026-01-23 15:53:10.149885 | container |     "stream_port": "37717"
2026-01-23 15:53:10.150093 | container |   }
2026-01-23 15:53:10.150124 | container | ]
2026-01-23 15:53:10.150167 | container | changed: All items complete
2026-01-23 15:53:10.150188 | 
2026-01-23 15:53:10.175232 | container | included: /var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml
2026-01-23 15:53:10.187996 | 
2026-01-23 15:53:10.188129 | LOOP [fetch-output-openshift : Ensure local directory exists]
2026-01-23 15:53:16.271656 | container | changed: "/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/logs"
2026-01-23 15:53:22.447634 | container | changed: "/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/logs/npm"
2026-01-23 15:53:28.285491 | container | changed: "/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/artifacts"
2026-01-23 15:53:34.413785 | container | changed: "/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/docs"
2026-01-23 15:53:34.431818 | 
2026-01-23 15:53:34.432037 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor]
2026-01-23 15:53:39.985845 | container | Output suppressed because no_log was given
2026-01-23 15:53:39.997281 | 
2026-01-23 15:53:39.997441 | TASK [fetch-output-openshift : Remove empty directory]
2026-01-23 15:53:40.400615 | container -> localhost | ok: Runtime: 0:00:00.056662
2026-01-23 15:53:40.415884 | 
2026-01-23 15:53:40.416009 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-23 15:53:40.738353 | container -> localhost | find: ‘/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/artifacts’: No such file or directory
2026-01-23 15:53:40.738904 | container -> localhost | find: ‘/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/artifacts’: No such file or directory
2026-01-23 15:53:40.739008 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.012659
2026-01-23 15:53:40.739082 | container -> localhost | changed: All items complete
2026-01-23 15:53:40.739125 | 
2026-01-23 15:53:41.019455 | container -> localhost | find: ‘/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/docs’: No such file or directory
2026-01-23 15:53:41.019954 | container -> localhost | find: ‘/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/docs’: No such file or directory
2026-01-23 15:53:41.020066 | container -> localhost | ok: Item: docs Runtime: 0:00:00.014727
2026-01-23 15:53:41.076783 | 
2026-01-23 15:53:41.076902 | PLAY [all]
2026-01-23 15:53:41.095388 | 
2026-01-23 15:53:41.095467 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-01-23 15:53:41.136293 | container | ERROR
2026-01-23 15:53:41.136513 | container | {
2026-01-23 15:53:41.136554 | container |   "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/src/../162251fd362541ab8f8e19258d2c3abe_id_rsa.pub'. file not found, use -vvvvv to see paths searched"
2026-01-23 15:53:41.136583 | container | }
2026-01-23 15:53:41.136616 | container | ERROR: Ignoring Errors
2026-01-23 15:53:41.163085 | 
2026-01-23 15:53:41.163154 | PLAY RECAP
2026-01-23 15:53:41.163209 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1
2026-01-23 15:53:41.163235 | 
2026-01-23 15:53:41.264503 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2026-01-23 15:53:41.265338 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2026-01-23 15:53:41.869269 | 
2026-01-23 15:53:41.869430 | PLAY [localhost]
2026-01-23 15:53:41.886957 | 
2026-01-23 15:53:41.887044 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-23 15:53:42.306980 | localhost | changed
2026-01-23 15:53:42.318064 | 
2026-01-23 15:53:42.318223 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-23 15:53:42.346824 | localhost | ok
2026-01-23 15:53:42.364172 | 
2026-01-23 15:53:42.364331 | TASK [add-fileserver : Create SSH private key tempfile]
2026-01-23 15:53:42.744701 | localhost | changed
2026-01-23 15:53:42.756159 | 
2026-01-23 15:53:42.756294 | TASK [add-fileserver : Create SSH private key from secret]
2026-01-23 15:53:43.497476 | localhost | changed
2026-01-23 15:53:43.508268 | 
2026-01-23 15:53:43.508407 | TASK [add-fileserver : Add fileserver ssh key]
2026-01-23 15:53:43.982269 | localhost | Identity added: /var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/tmp/ansible.l066k8_w (/var/lib/zuul/builds/162251fd362541ab8f8e19258d2c3abe/work/tmp/ansible.l066k8_w)
2026-01-23 15:53:43.982585 | localhost | ok: Runtime: 0:00:00.013903
2026-01-23 15:53:43.991910 | 
2026-01-23 15:53:43.992046 | TASK [add-fileserver : Remove SSH private key from disk]
2026-01-23 15:53:44.333116 | localhost | ok: Runtime: 0:00:00.008728
2026-01-23 15:53:44.343329 | 
2026-01-23 15:53:44.343460 | TASK [add-fileserver : Add fileserver to inventory]
2026-01-23 15:53:44.431132 | localhost | changed
2026-01-23 15:53:44.440487 | 
2026-01-23 15:53:44.440715 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-01-23 15:53:44.909477 | localhost | changed
2026-01-23 15:53:44.936276 | 
2026-01-23 15:53:44.936352 | PLAY [localhost]
2026-01-23 15:53:44.952641 | 
2026-01-23 15:53:44.952744 | TASK [Generate bulk log download script]
2026-01-23 15:53:44.973506 | localhost | ok
2026-01-23 15:53:44.985173 | 
2026-01-23 15:53:44.985279 | TASK [local-log-download : Check API endpoint is defined]
2026-01-23 15:53:45.035541 | localhost | ok: All assertions passed
2026-01-23 15:53:45.041842 | 
2026-01-23 15:53:45.041927 | TASK [local-log-download : Create download script]
2026-01-23 15:53:45.504348 | localhost -> localhost | changed
2026-01-23 15:53:45.522866 | 
2026-01-23 15:53:45.523003 | TASK [Register quick-download link]
2026-01-23 15:53:45.547880 | localhost | ok
2026-01-23 15:53:45.603209 | 
2026-01-23 15:53:45.603319 | PLAY [logserver.rdoproject.org]
2026-01-23 15:53:45.612477 | 
2026-01-23 15:53:45.612538 | TASK [Set zuul-log-path fact]
2026-01-23 15:53:45.629370 | logserver.rdoproject.org | ok
2026-01-23 15:53:45.637904 | 
2026-01-23 15:53:45.637965 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-23 15:53:45.674984 | logserver.rdoproject.org | ok
2026-01-23 15:53:45.681946 | 
2026-01-23 15:53:45.682027 | TASK [upload-logs : Create log directories]
2026-01-23 15:53:46.621791 | logserver.rdoproject.org | changed
2026-01-23 15:53:46.628358 | 
2026-01-23 15:53:46.628491 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-01-23 15:53:46.920660 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005992
2026-01-23 15:53:46.926865 | 
2026-01-23 15:53:46.926949 | TASK [upload-logs : Upload logs to log server]
2026-01-23 15:53:47.649895 | logserver.rdoproject.org | Output suppressed because no_log was given
2026-01-23 15:53:47.655877 | 
2026-01-23 15:53:47.656006 | LOOP [upload-logs : Compress console log and json output]
2026-01-23 15:53:47.723197 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-23 15:53:47.734394 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-23 15:53:47.751080 | 
2026-01-23 15:53:47.751241 | LOOP [upload-logs : Upload compressed console log and json output]
2026-01-23 15:53:47.799385 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-23 15:53:47.799660 | 
2026-01-23 15:53:47.801989 | logserver.rdoproject.org | skipping: Conditional result was False
2026-01-23 15:53:47.817118 | 
2026-01-23 15:53:47.817283 | LOOP [upload-logs : Upload console log and json output]