2025-12-10 12:04:05.104809 | Job console starting...
2025-12-10 12:04:05.117441 | Updating repositories
2025-12-10 12:04:05.151078 | Preparing job workspace
2025-12-10 12:04:10.394704 | Running Ansible setup...
2025-12-10 12:04:18.318878 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-10 12:04:18.944702 | 
2025-12-10 12:04:18.944833 | PLAY [localhost]
2025-12-10 12:04:18.953499 | 
2025-12-10 12:04:18.953569 | TASK [Gathering Facts]
2025-12-10 12:04:20.030624 | localhost | ok
2025-12-10 12:04:20.073249 | 
2025-12-10 12:04:20.073512 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-10 12:04:20.515682 | localhost -> localhost | changed
2025-12-10 12:04:20.523497 | 
2025-12-10 12:04:20.523617 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-10 12:04:21.453720 | localhost -> localhost | changed
2025-12-10 12:04:21.462210 | 
2025-12-10 12:04:21.462272 | TASK [Setup log path fact]
2025-12-10 12:04:21.481506 | localhost | ok
2025-12-10 12:04:21.492990 | 
2025-12-10 12:04:21.493056 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-10 12:04:21.520354 | localhost | ok
2025-12-10 12:04:21.527807 | 
2025-12-10 12:04:21.527868 | TASK [emit-job-header : Print job information]
2025-12-10 12:04:21.566217 | # Job Information
2025-12-10 12:04:21.566341 | Ansible Version: 2.15.12
2025-12-10 12:04:21.566367 | Job: cifmw-pod-ansible-test
2025-12-10 12:04:21.566386 | Pipeline: github-check
2025-12-10 12:04:21.566403 | Executor: ze03.softwarefactory-project.io
2025-12-10 12:04:21.566420 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3351
2025-12-10 12:04:21.566437 | Log URL (when completed): https://logserver.rdoproject.org/e11/rdoproject.org/e114749fa8184cf986f363c3823ba145/
2025-12-10 12:04:21.566455 | Event ID: 4072ae20-d5c0-11f0-8f86-183d065dcfa9
2025-12-10 12:04:21.569930 | 
2025-12-10 12:04:21.569991 | LOOP [emit-job-header : Print node information]
2025-12-10 12:04:21.669029 | localhost | ok:
2025-12-10 12:04:21.669278 | localhost | # Node Information
2025-12-10 12:04:21.669334 | localhost | Inventory Hostname: container
2025-12-10 12:04:21.669382 | localhost | Hostname: 939841f1d1db
2025-12-10 12:04:21.669423 | localhost | Username: zuul
2025-12-10 12:04:21.669466 | localhost | Distro: CentOS 9
2025-12-10 12:04:21.669508 | localhost | Provider: k1s04
2025-12-10 12:04:21.669546 | localhost | Region:
2025-12-10 12:04:21.669582 | localhost | Label: pod-centos-9-stream
2025-12-10 12:04:21.669618 | localhost | Product Name: OpenStack Nova
2025-12-10 12:04:21.669653 | localhost | Interface IP: pod-centos-9-stream-0005553567
2025-12-10 12:04:21.708454 | 
2025-12-10 12:04:21.708540 | PLAY [all]
2025-12-10 12:04:21.716891 | 
2025-12-10 12:04:21.716975 | TASK [Gather network facts]
2025-12-10 12:04:27.854375 | container | ok
2025-12-10 12:04:27.871946 | 
2025-12-10 12:04:27.872028 | TASK [include_role : start-zuul-console]
2025-12-10 12:04:27.892857 | container | ok
2025-12-10 12:04:27.908429 | 
2025-12-10 12:04:27.908510 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-10 12:04:33.932549 | container | ok
2025-12-10 12:04:33.942472 | 
2025-12-10 12:04:33.942574 | TASK [include_role : add-build-sshkey]
2025-12-10 12:04:33.968232 | container | skipping: Conditional result was False
2025-12-10 12:04:33.975758 | 
2025-12-10 12:04:33.975868 | TASK [include_role : validate-host]
2025-12-10 12:04:34.001685 | container | skipping: Conditional result was False
2025-12-10 12:04:34.010094 | 
2025-12-10 12:04:34.010207 | TASK [include_role : prepare-workspace-openshift]
2025-12-10 12:04:34.041244 | container | ok
2025-12-10 12:04:34.053491 | 
2025-12-10 12:04:34.053559 | LOOP [prepare-workspace-openshift : include_tasks]
2025-12-10 12:04:34.133608 | container | ok:
2025-12-10 12:04:34.133925 | container | [
2025-12-10 12:04:34.133980 | container |   "container",
2025-12-10 12:04:34.134024 | container |   {
2025-12-10 12:04:34.134060 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2025-12-10 12:04:34.134096 | container |     "namespace": "main",
2025-12-10 12:04:34.134152 | container |     "pod": "pod-centos-9-stream-0005553567",
2025-12-10 12:04:34.134188 | container |     "stream_port": "33129"
2025-12-10 12:04:34.134221 | container |   }
2025-12-10 12:04:34.134254 | container | ]
2025-12-10 12:04:34.134316 | container | ok: All items complete
2025-12-10 12:04:34.134357 | 
2025-12-10 12:04:34.149149 | container | included: /var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml
2025-12-10 12:04:34.157664 | 
2025-12-10 12:04:34.157777 | TASK [prepare-workspace-openshift : Create src directory]
2025-12-10 12:04:35.518887 | container -> localhost | ok: Runtime: 0:00:00.932291
2025-12-10 12:04:35.531393 | 
2025-12-10 12:04:35.531538 | TASK [prepare-workspace-openshift : Copy src repos to the pod]
2025-12-10 12:04:37.942295 | container -> localhost | Output suppressed because no_log was given
2025-12-10 12:04:37.955507 | 
2025-12-10 12:04:37.955643 | TASK [include_role : remove-zuul-sshkey]
2025-12-10 12:04:37.980507 | container | ok
2025-12-10 12:04:38.000293 | 
2025-12-10 12:04:38.000424 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-10 12:04:38.371427 | container -> localhost | changed
2025-12-10 12:04:38.385248 | 
2025-12-10 12:04:38.385361 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-10 12:04:44.490556 | container | ok: "logs"
2025-12-10 12:04:50.247963 | container | ok: "artifacts"
2025-12-10 12:04:56.213187 | container | ok: "docs"
2025-12-10 12:04:56.223425 | 
2025-12-10 12:04:56.223576 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-10 12:05:02.025290 | container | changed: "logs"
2025-12-10 12:05:02.026043 | container | ok: All items complete
2025-12-10 12:05:02.026130 | 
2025-12-10 12:05:08.072066 | container | changed: "artifacts"
2025-12-10 12:05:13.879257 | container | changed: "docs"
2025-12-10 12:05:13.928518 | 
2025-12-10 12:05:13.928625 | PLAY RECAP
2025-12-10 12:05:13.928683 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-10 12:05:13.928719 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-10 12:05:13.928777 | 
2025-12-10 12:05:14.026435 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-10 12:05:14.027214 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-10 12:05:14.642489 | 
2025-12-10 12:05:14.642602 | PLAY [localhost]
2025-12-10 12:05:14.658768 | 
2025-12-10 12:05:14.658858 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-12-10 12:05:15.114196 | localhost | ok
2025-12-10 12:05:15.119354 | 
2025-12-10 12:05:15.119445 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-12-10 12:05:15.729114 | localhost | changed
2025-12-10 12:05:15.748833 | 
2025-12-10 12:05:15.748893 | PLAY [all]
2025-12-10 12:05:15.762361 | 
2025-12-10 12:05:15.762425 | TASK [include_role : prepare-workspace]
2025-12-10 12:05:15.786382 | container | skipping: Conditional result was False
2025-12-10 12:05:15.791978 | 
2025-12-10 12:05:15.792045 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-10 12:05:21.530181 | container | changed: "logs"
2025-12-10 12:05:27.475963 | container | changed: "artifacts"
2025-12-10 12:05:33.421115 | container | changed: "docs"
2025-12-10 12:05:33.427641 | 
2025-12-10 12:05:33.427711 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-10 12:05:39.285452 | container | changed: "logs"
2025-12-10 12:05:39.285627 | container | changed: All items complete
2025-12-10 12:05:39.285654 | 
2025-12-10 12:05:45.105889 | container | changed: "artifacts"
2025-12-10 12:05:50.997705 | container | changed: "docs"
2025-12-10 12:05:51.022600 | 
2025-12-10 12:05:51.022759 | TASK [Check if worker can sudo]
2025-12-10 12:05:57.134111 | container | ok: Runtime: 0:00:00.035825
2025-12-10 12:05:57.146869 | 
2025-12-10 12:05:57.147038 | TASK [configure-mirrors : Gather needed facts]
2025-12-10 12:05:57.174598 | container | skipping: Conditional result was False
2025-12-10 12:05:57.187391 | 
2025-12-10 12:05:57.187604 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-12-10 12:05:57.215044 | container | skipping: Conditional result was False
2025-12-10 12:05:57.266927 | 
2025-12-10 12:05:57.267082 | PLAY RECAP
2025-12-10 12:05:57.267175 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2025-12-10 12:05:57.267241 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-10 12:05:57.267294 | 
2025-12-10 12:05:57.384109 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-10 12:05:57.386420 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2025-12-10 12:05:58.031561 | 
2025-12-10 12:05:58.031687 | PLAY [Run light checks in pod]
2025-12-10 12:05:58.051010 | 
2025-12-10 12:05:58.051118 | TASK [Install packages]
2025-12-10 12:07:23.430129 | container | ok: Nothing to do
2025-12-10 12:07:23.441364 | 
2025-12-10 12:07:23.441646 | TASK [Ensure zuul-output exists]
2025-12-10 12:07:29.702324 | container | ok
2025-12-10 12:07:29.719396 | 
2025-12-10 12:07:29.719545 | TASK [Install dependencies]
2025-12-10 12:30:51.733517 | container | changed
2025-12-10 12:30:51.800624 | 
2025-12-10 12:30:51.800897 | TASK [Run check]
2025-12-10 12:34:27.435601 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2025-12-10 12:34:27.436755 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-10 12:34:28.028109 | 
2025-12-10 12:34:28.028231 | PLAY [all]
2025-12-10 12:34:28.047050 | 
2025-12-10 12:34:28.047137 | TASK [include_role : fetch-output]
2025-12-10 12:34:28.071320 | container | skipping: Conditional result was False
2025-12-10 12:34:28.077923 | 
2025-12-10 12:34:28.078012 | TASK [include_role : fetch-output-openshift]
2025-12-10 12:34:28.118008 | container | ok
2025-12-10 12:34:28.135558 | 
2025-12-10 12:34:28.135645 | TASK [fetch-output-openshift : Set log path for multiple nodes]
2025-12-10 12:34:28.190106 | container | skipping: Conditional result was False
2025-12-10 12:34:28.195666 | 
2025-12-10 12:34:28.195762 | TASK [fetch-output-openshift : Set log path for single node]
2025-12-10 12:34:28.234470 | container | ok
2025-12-10 12:34:28.239139 | 
2025-12-10 12:34:28.239209 | LOOP [fetch-output-openshift : Ensure local output dirs]
2025-12-10 12:34:28.639817 | container -> localhost | ok: "/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/logs"
2025-12-10 12:34:28.860127 | container -> localhost | changed: "/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/logs/npm"
2025-12-10 12:34:29.097694 | container -> localhost | changed: "/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/artifacts"
2025-12-10 12:34:29.323452 | container -> localhost | changed: "/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/docs"
2025-12-10 12:34:29.335178 | 
2025-12-10 12:34:29.335302 | LOOP [fetch-output-openshift : include_tasks]
2025-12-10 12:34:29.398092 | container | ok:
2025-12-10 12:34:29.398488 | container | [
2025-12-10 12:34:29.398546 | container |   "container",
2025-12-10 12:34:29.398590 | container |   {
2025-12-10 12:34:29.398630 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2025-12-10 12:34:29.398672 | container |     "namespace": "main",
2025-12-10 12:34:29.398710 | container |     "pod": "pod-centos-9-stream-0005553567",
2025-12-10 12:34:29.398789 | container |     "stream_port": "33129"
2025-12-10 12:34:29.398829 | container |   }
2025-12-10 12:34:29.398865 | container | ]
2025-12-10 12:34:29.398935 | container | changed: All items complete
2025-12-10 12:34:29.398981 | 
2025-12-10 12:34:29.409984 | container | included: /var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml
2025-12-10 12:34:29.415988 | 
2025-12-10 12:34:29.416049 | LOOP [fetch-output-openshift : Ensure local directory exists]
2025-12-10 12:34:35.582492 | container | changed: "/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/logs"
2025-12-10 12:34:41.606924 | container | changed: "/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/logs/npm"
2025-12-10 12:34:47.578331 | container | changed: "/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/artifacts"
2025-12-10 12:34:53.708499 | container | changed: "/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/docs"
2025-12-10 12:34:53.729455 | 
2025-12-10 12:34:53.729644 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor]
2025-12-10 12:34:59.092702 | container | Output suppressed because no_log was given
2025-12-10 12:34:59.115856 | 
2025-12-10 12:34:59.116057 | TASK [fetch-output-openshift : Remove empty directory]
2025-12-10 12:34:59.484918 | container -> localhost | ok: Runtime: 0:00:00.058568
2025-12-10 12:34:59.509694 | 
2025-12-10 12:34:59.509904 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-10 12:34:59.851426 | container -> localhost | find: ‘/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/artifacts’: No such file or directory
2025-12-10 12:34:59.851677 | container -> localhost | find: ‘/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/artifacts’: No such file or directory
2025-12-10 12:34:59.851766 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.010011
2025-12-10 12:34:59.851809 | container -> localhost | changed: All items complete
2025-12-10 12:34:59.851832 | 
2025-12-10 12:35:00.191038 | container -> localhost | find: ‘/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/docs’: No such file or directory
2025-12-10 12:35:00.191194 | container -> localhost | find: ‘/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/docs’: No such file or directory
2025-12-10 12:35:00.191231 | container -> localhost | ok: Item: docs Runtime: 0:00:00.018093
2025-12-10 12:35:00.246019 | 
2025-12-10 12:35:00.246217 | PLAY [all]
2025-12-10 12:35:00.266802 | 
2025-12-10 12:35:00.266894 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-10 12:35:00.310087 | container | ERROR
2025-12-10 12:35:00.310336 | container | {
2025-12-10 12:35:00.310373 | container |   "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/src/../e114749fa8184cf986f363c3823ba145_id_rsa.pub'. file not found, use -vvvvv to see paths searched"
2025-12-10 12:35:00.310403 | container | }
2025-12-10 12:35:00.310437 | container | ERROR: Ignoring Errors
2025-12-10 12:35:00.340032 | 
2025-12-10 12:35:00.340106 | PLAY RECAP
2025-12-10 12:35:00.340168 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1
2025-12-10 12:35:00.340195 | 
2025-12-10 12:35:00.527696 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-10 12:35:00.528555 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-10 12:35:01.167824 | 
2025-12-10 12:35:01.167946 | PLAY [localhost]
2025-12-10 12:35:01.186373 | 
2025-12-10 12:35:01.186464 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-10 12:35:01.632303 | localhost | changed
2025-12-10 12:35:01.637039 | 
2025-12-10 12:35:01.637117 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-10 12:35:01.668278 | localhost | ok
2025-12-10 12:35:01.680609 | 
2025-12-10 12:35:01.680763 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-10 12:35:02.071021 | localhost | changed
2025-12-10 12:35:02.079452 | 
2025-12-10 12:35:02.079556 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-10 12:35:02.791267 | localhost | changed
2025-12-10 12:35:02.796365 | 
2025-12-10 12:35:02.796447 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-10 12:35:03.191526 | localhost | Identity added: /var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/tmp/ansible.3jsxlhgy (/var/lib/zuul/builds/e114749fa8184cf986f363c3823ba145/work/tmp/ansible.3jsxlhgy)
2025-12-10 12:35:03.191780 | localhost | ok: Runtime: 0:00:00.007762
2025-12-10 12:35:03.196069 | 
2025-12-10 12:35:03.196136 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-10 12:35:03.478261 | localhost | ok: Runtime: 0:00:00.005142
2025-12-10 12:35:03.483079 | 
2025-12-10 12:35:03.483146 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-10 12:35:03.532954 | localhost | changed
2025-12-10 12:35:03.537264 | 
2025-12-10 12:35:03.537331 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-10 12:35:03.921941 | localhost | changed
2025-12-10 12:35:03.949554 | 
2025-12-10 12:35:03.949686 | PLAY [localhost]
2025-12-10 12:35:03.967376 | 
2025-12-10 12:35:03.967543 | TASK [Generate bulk log download script]
2025-12-10 12:35:03.988213 | localhost | ok
2025-12-10 12:35:04.005448 | 
2025-12-10 12:35:04.005550 | TASK [local-log-download : Check API endpoint is defined]
2025-12-10 12:35:04.036800 | localhost | ok: All assertions passed
2025-12-10 12:35:04.043129 | 
2025-12-10 12:35:04.043216 | TASK [local-log-download : Create download script]
2025-12-10 12:35:04.463684 | localhost -> localhost | changed
2025-12-10 12:35:04.483785 | 
2025-12-10 12:35:04.483946 | TASK [Register quick-download link]
2025-12-10 12:35:04.517347 | localhost | ok
2025-12-10 12:35:04.565948 | 
2025-12-10 12:35:04.566054 | PLAY [logserver.rdoproject.org]
2025-12-10 12:35:04.575503 | 
2025-12-10 12:35:04.575562 | TASK [Set zuul-log-path fact]
2025-12-10 12:35:04.591764 | logserver.rdoproject.org | ok
2025-12-10 12:35:04.601537 | 
2025-12-10 12:35:04.601604 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-10 12:35:04.627945 | logserver.rdoproject.org | ok
2025-12-10 12:35:04.633648 | 
2025-12-10 12:35:04.633718 | TASK [upload-logs : Create log directories]
2025-12-10 12:35:05.507952 | logserver.rdoproject.org | changed
2025-12-10 12:35:05.512671 | 
2025-12-10 12:35:05.512774 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-10 12:35:05.884141 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006263
2025-12-10 12:35:05.893540 | 
2025-12-10 12:35:05.893676 | TASK [upload-logs : Upload logs to log server]
2025-12-10 12:35:06.655643 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-10 12:35:06.662310 | 
2025-12-10 12:35:06.662453 | LOOP [upload-logs : Compress console log and json output]
2025-12-10 12:35:06.743506 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-10 12:35:06.758441 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-10 12:35:06.766302 | 
2025-12-10 12:35:06.766463 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-10 12:35:06.832240 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-10 12:35:06.832485 | 
2025-12-10 12:35:06.837160 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-10 12:35:06.852416 | 
2025-12-10 12:35:06.852636 | LOOP [upload-logs : Upload console log and json output]