2025-12-07 15:44:01.266032 | Job console starting...
2025-12-07 15:44:01.276276 | Updating repositories
2025-12-07 15:44:01.305348 | Preparing job workspace
2025-12-07 15:44:07.174222 | Running Ansible setup...
2025-12-07 15:44:14.991413 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-07 15:44:15.538821 | 
2025-12-07 15:44:15.538937 | PLAY [localhost]
2025-12-07 15:44:15.547761 | 
2025-12-07 15:44:15.547838 | TASK [Gathering Facts]
2025-12-07 15:44:16.523130 | localhost | ok
2025-12-07 15:44:16.553066 | 
2025-12-07 15:44:16.553233 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-07 15:44:16.966935 | localhost -> localhost | changed
2025-12-07 15:44:16.978427 | 
2025-12-07 15:44:16.978568 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-07 15:44:17.911857 | localhost -> localhost | changed
2025-12-07 15:44:17.930095 | 
2025-12-07 15:44:17.930235 | TASK [Setup log path fact]
2025-12-07 15:44:17.955055 | localhost | ok
2025-12-07 15:44:17.978932 | 
2025-12-07 15:44:17.979068 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-07 15:44:18.013791 | localhost | ok
2025-12-07 15:44:18.030214 | 
2025-12-07 15:44:18.030347 | TASK [emit-job-header : Print job information]
2025-12-07 15:44:18.084871 | # Job Information
2025-12-07 15:44:18.085160 | Ansible Version: 2.15.12
2025-12-07 15:44:18.085227 | Job: cifmw-pod-ansible-test
2025-12-07 15:44:18.085269 | Pipeline: github-check
2025-12-07 15:44:18.085308 | Executor: ze03.softwarefactory-project.io
2025-12-07 15:44:18.085345 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3497
2025-12-07 15:44:18.085385 | Log URL (when completed): https://logserver.rdoproject.org/a18/rdoproject.org/a18229dd909d4cc593ddc48e21db5298/
2025-12-07 15:44:18.085423 | Event ID: 7ac1e7b0-d383-11f0-9917-9bce1bf9bdf0
2025-12-07 15:44:18.093045 | 
2025-12-07 15:44:18.093179 | LOOP [emit-job-header : Print node information]
2025-12-07 15:44:18.207428 | localhost | ok:
2025-12-07 15:44:18.207693 | localhost | # Node Information
2025-12-07 15:44:18.207782 | localhost | Inventory Hostname: container
2025-12-07 15:44:18.207839 | localhost | Hostname: 93c76747413b
2025-12-07 15:44:18.207880 | localhost | Username: zuul
2025-12-07 15:44:18.207922 | localhost | Distro: CentOS 9
2025-12-07 15:44:18.207959 | localhost | Provider: k1s04
2025-12-07 15:44:18.207996 | localhost | Region:
2025-12-07 15:44:18.208031 | localhost | Label: pod-centos-9-stream
2025-12-07 15:44:18.208066 | localhost | Product Name: OpenStack Nova
2025-12-07 15:44:18.208100 | localhost | Interface IP: pod-centos-9-stream-0005549553
2025-12-07 15:44:18.250497 | 
2025-12-07 15:44:18.250645 | PLAY [all]
2025-12-07 15:44:18.262790 | 
2025-12-07 15:44:18.262916 | TASK [Gather network facts]
2025-12-07 15:44:24.327622 | container | ok
2025-12-07 15:44:24.356563 | 
2025-12-07 15:44:24.356713 | TASK [include_role : start-zuul-console]
2025-12-07 15:44:24.380119 | container | ok
2025-12-07 15:44:24.395300 | 
2025-12-07 15:44:24.395389 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-07 15:44:30.402960 | container | ok
2025-12-07 15:44:30.420945 | 
2025-12-07 15:44:30.421072 | TASK [include_role : add-build-sshkey]
2025-12-07 15:44:30.447683 | container | skipping: Conditional result was False
2025-12-07 15:44:30.457466 | 
2025-12-07 15:44:30.457589 | TASK [include_role : validate-host]
2025-12-07 15:44:30.483305 | container | skipping: Conditional result was False
2025-12-07 15:44:30.493182 | 
2025-12-07 15:44:30.493305 | TASK [include_role : prepare-workspace-openshift]
2025-12-07 15:44:30.527428 | container | ok
2025-12-07 15:44:30.551820 | 
2025-12-07 15:44:30.551960 | LOOP [prepare-workspace-openshift : include_tasks]
2025-12-07 15:44:30.631841 | container | ok:
2025-12-07 15:44:30.632110 | container | [
2025-12-07 15:44:30.632164 | container |   "container",
2025-12-07 15:44:30.632209 | container |   {
2025-12-07 15:44:30.632247 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2025-12-07 15:44:30.632284 | container |     "namespace": "main",
2025-12-07 15:44:30.632340 | container |     "pod": "pod-centos-9-stream-0005549553",
2025-12-07 15:44:30.632377 | container |     "stream_port": "42099"
2025-12-07 15:44:30.632412 | container |   }
2025-12-07 15:44:30.632446 | container | ]
2025-12-07 15:44:30.632505 | container | ok: All items complete
2025-12-07 15:44:30.632547 | 
2025-12-07 15:44:30.653473 | container | included: /var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml
2025-12-07 15:44:30.664495 | 
2025-12-07 15:44:30.664622 | TASK [prepare-workspace-openshift : Create src directory]
2025-12-07 15:44:32.068683 | container -> localhost | ok: Runtime: 0:00:00.953433
2025-12-07 15:44:32.079423 | 
2025-12-07 15:44:32.079558 | TASK [prepare-workspace-openshift : Copy src repos to the pod]
2025-12-07 15:44:34.282891 | container -> localhost | Output suppressed because no_log was given
2025-12-07 15:44:34.329320 | 
2025-12-07 15:44:34.329451 | TASK [include_role : remove-zuul-sshkey]
2025-12-07 15:44:34.359075 | container | ok
2025-12-07 15:44:34.376386 | 
2025-12-07 15:44:34.376492 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-07 15:44:34.747170 | container -> localhost | changed
2025-12-07 15:44:34.765544 | 
2025-12-07 15:44:34.765671 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-07 15:44:40.580132 | container | ok: "logs"
2025-12-07 15:44:46.209633 | container | ok: "artifacts"
2025-12-07 15:44:52.154189 | container | ok: "docs"
2025-12-07 15:44:52.169572 | 
2025-12-07 15:44:52.169706 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-07 15:44:57.977888 | container | changed: "logs"
2025-12-07 15:44:57.978108 | container | ok: All items complete
2025-12-07 15:44:57.978133 | 
2025-12-07 15:45:03.728862 | container | changed: "artifacts"
2025-12-07 15:45:09.569191 | container | changed: "docs"
2025-12-07 15:45:09.621088 | 
2025-12-07 15:45:09.621208 | PLAY RECAP
2025-12-07 15:45:09.621265 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-07 15:45:09.621299 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-07 15:45:09.621323 | 
2025-12-07 15:45:09.717596 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-07 15:45:09.718395 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-07 15:45:10.293888 | 
2025-12-07 15:45:10.293995 | PLAY [localhost]
2025-12-07 15:45:10.309864 | 
2025-12-07 15:45:10.309950 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-12-07 15:45:10.719719 | localhost | ok
2025-12-07 15:45:10.730790 | 
2025-12-07 15:45:10.730948 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-12-07 15:45:11.464542 | localhost | changed
2025-12-07 15:45:11.507161 | 
2025-12-07 15:45:11.507345 | PLAY [all]
2025-12-07 15:45:11.528908 | 
2025-12-07 15:45:11.529010 | TASK [include_role : prepare-workspace]
2025-12-07 15:45:11.547576 | container | skipping: Conditional result was False
2025-12-07 15:45:11.554579 | 
2025-12-07 15:45:11.554753 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-07 15:45:17.408471 | container | changed: "logs"
2025-12-07 15:45:23.279905 | container | changed: "artifacts"
2025-12-07 15:45:29.108761 | container | changed: "docs"
2025-12-07 15:45:29.125226 | 
2025-12-07 15:45:29.125423 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-07 15:45:35.117417 | container | changed: "logs"
2025-12-07 15:45:35.117792 | container | changed: All items complete
2025-12-07 15:45:35.117862 | 
2025-12-07 15:45:40.981761 | container | changed: "artifacts"
2025-12-07 15:45:46.906037 | container | changed: "docs"
2025-12-07 15:45:46.929674 | 
2025-12-07 15:45:46.929895 | TASK [Check if worker can sudo]
2025-12-07 15:45:53.046421 | container | ok: Runtime: 0:00:00.035194
2025-12-07 15:45:53.060329 | 
2025-12-07 15:45:53.060601 | TASK [configure-mirrors : Gather needed facts]
2025-12-07 15:45:53.089977 | container | skipping: Conditional result was False
2025-12-07 15:45:53.103098 | 
2025-12-07 15:45:53.103377 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-12-07 15:45:53.132960 | container | skipping: Conditional result was False
2025-12-07 15:45:53.157345 | 
2025-12-07 15:45:53.157489 | PLAY RECAP
2025-12-07 15:45:53.157538 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2025-12-07 15:45:53.157564 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-07 15:45:53.157581 | 
2025-12-07 15:45:53.333636 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-07 15:45:53.335297 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2025-12-07 15:45:53.960681 | 
2025-12-07 15:45:53.960808 | PLAY [Run light checks in pod]
2025-12-07 15:45:53.979290 | 
2025-12-07 15:45:53.979375 | TASK [Install packages]
2025-12-07 15:46:14.866715 | container | ok: Nothing to do
2025-12-07 15:46:14.877767 | 
2025-12-07 15:46:14.877923 | TASK [Ensure zuul-output exists]
2025-12-07 15:46:20.807381 | container | ok
2025-12-07 15:46:20.817343 | 
2025-12-07 15:46:20.817479 | TASK [Install dependencies]
2025-12-07 16:12:13.582808 | container | changed
2025-12-07 16:12:13.633854 | 
2025-12-07 16:12:13.633959 | TASK [Run check]
2025-12-07 16:14:23.406127 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2025-12-07 16:14:23.407064 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-07 16:14:23.979288 | 
2025-12-07 16:14:23.979399 | PLAY [all]
2025-12-07 16:14:23.996448 | 
2025-12-07 16:14:23.996528 | TASK [include_role : fetch-output]
2025-12-07 16:14:24.020458 | container | skipping: Conditional result was False
2025-12-07 16:14:24.026837 | 
2025-12-07 16:14:24.026932 | TASK [include_role : fetch-output-openshift]
2025-12-07 16:14:24.068088 | container | ok
2025-12-07 16:14:24.091241 | 
2025-12-07 16:14:24.091365 | TASK [fetch-output-openshift : Set log path for multiple nodes]
2025-12-07 16:14:24.137128 | container | skipping: Conditional result was False
2025-12-07 16:14:24.148065 | 
2025-12-07 16:14:24.148229 | TASK [fetch-output-openshift : Set log path for single node]
2025-12-07 16:14:24.184179 | container | ok
2025-12-07 16:14:24.193974 | 
2025-12-07 16:14:24.194079 | LOOP [fetch-output-openshift : Ensure local output dirs]
2025-12-07 16:14:24.624384 | container -> localhost | ok: "/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/logs"
2025-12-07 16:14:24.894378 | container -> localhost | changed: "/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/logs/npm"
2025-12-07 16:14:25.132538 | container -> localhost | changed: "/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/artifacts"
2025-12-07 16:14:25.351336 | container -> localhost | changed: "/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/docs"
2025-12-07 16:14:25.373411 | 
2025-12-07 16:14:25.373586 | LOOP [fetch-output-openshift : include_tasks]
2025-12-07 16:14:25.435373 | container | ok:
2025-12-07 16:14:25.435590 | container | [
2025-12-07 16:14:25.435644 | container |   "container",
2025-12-07 16:14:25.435682 | container |   {
2025-12-07 16:14:25.435719 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2025-12-07 16:14:25.435803 | container |     "namespace": "main",
2025-12-07 16:14:25.435842 | container |     "pod": "pod-centos-9-stream-0005549553",
2025-12-07 16:14:25.435877 | container |     "stream_port": "42099"
2025-12-07 16:14:25.435911 | container |   }
2025-12-07 16:14:25.435946 | container | ]
2025-12-07 16:14:25.435999 | container | changed: All items complete
2025-12-07 16:14:25.436040 | 
2025-12-07 16:14:25.452380 | container | included: /var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml
2025-12-07 16:14:25.460209 | 
2025-12-07 16:14:25.460290 | LOOP [fetch-output-openshift : Ensure local directory exists]
2025-12-07 16:14:30.881182 | container | changed: "/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/logs"
2025-12-07 16:14:36.386084 | container | changed: "/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/logs/npm"
2025-12-07 16:14:42.040097 | container | changed: "/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/artifacts"
2025-12-07 16:14:47.672553 | container | changed: "/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/docs"
2025-12-07 16:14:47.680953 | 
2025-12-07 16:14:47.681015 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor]
2025-12-07 16:14:52.529180 | container | Output suppressed because no_log was given
2025-12-07 16:14:52.539749 | 
2025-12-07 16:14:52.539879 | TASK [fetch-output-openshift : Remove empty directory]
2025-12-07 16:14:52.930926 | container -> localhost | ok: Runtime: 0:00:00.057028
2025-12-07 16:14:52.940683 | 
2025-12-07 16:14:52.940764 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-07 16:14:53.244692 | container -> localhost | find: ‘/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/artifacts’: No such file or directory
2025-12-07 16:14:53.244955 | container -> localhost | find: ‘/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/artifacts’: No such file or directory
2025-12-07 16:14:53.245002 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.012814
2025-12-07 16:14:53.245040 | container -> localhost | changed: All items complete
2025-12-07 16:14:53.245061 | 
2025-12-07 16:14:53.523417 | container -> localhost | find: ‘/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/docs’: No such file or directory
2025-12-07 16:14:53.523550 | container -> localhost | find: ‘/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/docs’: No such file or directory
2025-12-07 16:14:53.523619 | container -> localhost | ok: Item: docs Runtime: 0:00:00.012475
2025-12-07 16:14:53.548505 | 
2025-12-07 16:14:53.548618 | PLAY [all]
2025-12-07 16:14:53.560940 | 
2025-12-07 16:14:53.561003 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-07 16:14:53.600841 | container | ERROR
2025-12-07 16:14:53.601075 | container | {
2025-12-07 16:14:53.601113 | container |   "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/src/../a18229dd909d4cc593ddc48e21db5298_id_rsa.pub'. file not found, use -vvvvv to see paths searched"
2025-12-07 16:14:53.601143 | container | }
2025-12-07 16:14:53.601177 | container | ERROR: Ignoring Errors
2025-12-07 16:14:53.628357 | 
2025-12-07 16:14:53.628407 | PLAY RECAP
2025-12-07 16:14:53.628450 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1
2025-12-07 16:14:53.628470 | 
2025-12-07 16:14:53.722597 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-07 16:14:53.724136 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-07 16:14:54.337471 | 
2025-12-07 16:14:54.337581 | PLAY [localhost]
2025-12-07 16:14:54.355290 | 
2025-12-07 16:14:54.355372 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-07 16:14:54.754960 | localhost | changed
2025-12-07 16:14:54.765013 | 
2025-12-07 16:14:54.765195 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-07 16:14:54.801628 | localhost | ok
2025-12-07 16:14:54.819655 | 
2025-12-07 16:14:54.819827 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-07 16:14:55.230344 | localhost | changed
2025-12-07 16:14:55.241982 | 
2025-12-07 16:14:55.242120 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-07 16:14:56.002632 | localhost | changed
2025-12-07 16:14:56.013771 | 
2025-12-07 16:14:56.013910 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-07 16:14:56.485618 | localhost | Identity added: /var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/tmp/ansible.8k7k722r (/var/lib/zuul/builds/a18229dd909d4cc593ddc48e21db5298/work/tmp/ansible.8k7k722r)
2025-12-07 16:14:56.485905 | localhost | ok: Runtime: 0:00:00.013216
2025-12-07 16:14:56.492372 | 
2025-12-07 16:14:56.492461 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-07 16:14:56.812923 | localhost | ok: Runtime: 0:00:00.007619
2025-12-07 16:14:56.823248 | 
2025-12-07 16:14:56.823376 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-07 16:14:56.880503 | localhost | changed
2025-12-07 16:14:56.889499 | 
2025-12-07 16:14:56.889628 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-07 16:14:57.331637 | localhost | changed
2025-12-07 16:14:57.372905 | 
2025-12-07 16:14:57.372993 | PLAY [localhost]
2025-12-07 16:14:57.389634 | 
2025-12-07 16:14:57.389711 | TASK [Generate bulk log download script]
2025-12-07 16:14:57.410898 | localhost | ok
2025-12-07 16:14:57.430001 | 
2025-12-07 16:14:57.430151 | TASK [local-log-download : Check API endpoint is defined]
2025-12-07 16:14:57.472863 | localhost | ok: All assertions passed
2025-12-07 16:14:57.479193 | 
2025-12-07 16:14:57.479294 | TASK [local-log-download : Create download script]
2025-12-07 16:14:57.964697 | localhost -> localhost | changed
2025-12-07 16:14:57.988871 | 
2025-12-07 16:14:57.988986 | TASK [Register quick-download link]
2025-12-07 16:14:58.021444 | localhost | ok
2025-12-07 16:14:58.076773 | 
2025-12-07 16:14:58.076896 | PLAY [logserver.rdoproject.org]
2025-12-07 16:14:58.089634 | 
2025-12-07 16:14:58.089761 | TASK [Set zuul-log-path fact]
2025-12-07 16:14:58.108016 | logserver.rdoproject.org | ok
2025-12-07 16:14:58.120678 | 
2025-12-07 16:14:58.120786 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-07 16:14:58.159341 | logserver.rdoproject.org | ok
2025-12-07 16:14:58.166407 | 
2025-12-07 16:14:58.166488 | TASK [upload-logs : Create log directories]
2025-12-07 16:14:59.067281 | logserver.rdoproject.org | changed
2025-12-07 16:14:59.075272 | 
2025-12-07 16:14:59.075383 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-07 16:14:59.407474 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008100
2025-12-07 16:14:59.412192 | 
2025-12-07 16:14:59.412251 | TASK [upload-logs : Upload logs to log server]
2025-12-07 16:15:00.132120 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-07 16:15:00.136184 | 
2025-12-07 16:15:00.136277 | LOOP [upload-logs : Compress console log and json output]
2025-12-07 16:15:00.204460 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-07 16:15:00.217262 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-07 16:15:00.229459 | 
2025-12-07 16:15:00.229635 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-07 16:15:00.295191 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-07 16:15:00.295506 | 
2025-12-07 16:15:00.300130 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-07 16:15:00.314798 | 
2025-12-07 16:15:00.314975 | LOOP [upload-logs : Upload console log and json output]