2025-12-04 09:20:20.011655 | Job console starting...
2025-12-04 09:20:20.023019 | Updating repositories
2025-12-04 09:20:20.072773 | Preparing job workspace
2025-12-04 09:20:25.559840 | Running Ansible setup...
2025-12-04 09:20:33.743143 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-04 09:20:34.410975 | 
2025-12-04 09:20:34.411156 | PLAY [localhost]
2025-12-04 09:20:34.420878 | 
2025-12-04 09:20:34.420967 | TASK [Gathering Facts]
2025-12-04 09:20:35.488536 | localhost | ok
2025-12-04 09:20:35.525619 | 
2025-12-04 09:20:35.525869 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-04 09:20:35.990652 | localhost -> localhost | changed
2025-12-04 09:20:36.002928 | 
2025-12-04 09:20:36.003105 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-04 09:20:36.989317 | localhost -> localhost | changed
2025-12-04 09:20:37.002053 | 
2025-12-04 09:20:37.002149 | TASK [Setup log path fact]
2025-12-04 09:20:37.027918 | localhost | ok
2025-12-04 09:20:37.051549 | 
2025-12-04 09:20:37.051758 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-04 09:20:37.093883 | localhost | ok
2025-12-04 09:20:37.105206 | 
2025-12-04 09:20:37.105300 | TASK [emit-job-header : Print job information]
2025-12-04 09:20:37.157159 | # Job Information
2025-12-04 09:20:37.157371 | Ansible Version: 2.15.12
2025-12-04 09:20:37.157406 | Job: cifmw-pod-pre-commit
2025-12-04 09:20:37.157433 | Pipeline: github-check
2025-12-04 09:20:37.157534 | Executor: ze03.softwarefactory-project.io
2025-12-04 09:20:37.157592 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3502
2025-12-04 09:20:37.157626 | Log URL (when completed): https://logserver.rdoproject.org/306/rdoproject.org/306fcff62c40455098fc6ae5c9324aca/
2025-12-04 09:20:37.157652 | Event ID: 632aae90-d0f2-11f0-8dfe-af1f2c71153d
2025-12-04 09:20:37.163199 | 
2025-12-04 09:20:37.163286 | LOOP [emit-job-header : Print node information]
2025-12-04 09:20:37.289307 | localhost | ok:
2025-12-04 09:20:37.290179 | localhost | # Node Information
2025-12-04 09:20:37.290271 | localhost | Inventory Hostname: container
2025-12-04 09:20:37.290326 | localhost | Hostname: 32f9fe203e60
2025-12-04 09:20:37.290369 | localhost | Username: zuul
2025-12-04 09:20:37.290413 | localhost | Distro: CentOS 9
2025-12-04 09:20:37.290453 | localhost | Provider: k1s04
2025-12-04 09:20:37.290492 | localhost | Region:
2025-12-04 09:20:37.290529 | localhost | Label: pod-centos-9-stream
2025-12-04 09:20:37.290566 | localhost | Product Name: OpenStack Nova
2025-12-04 09:20:37.290601 | localhost | Interface IP: pod-centos-9-stream-0005545256
2025-12-04 09:20:37.326467 | 
2025-12-04 09:20:37.326636 | PLAY [all]
2025-12-04 09:20:37.335219 | 
2025-12-04 09:20:37.335317 | TASK [Gather network facts]
2025-12-04 09:20:43.556053 | container | ok
2025-12-04 09:20:43.574756 | 
2025-12-04 09:20:43.574888 | TASK [include_role : start-zuul-console]
2025-12-04 09:20:43.607234 | container | ok
2025-12-04 09:20:43.622453 | 
2025-12-04 09:20:43.622547 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-04 09:20:49.696912 | container | ok
2025-12-04 09:20:49.715583 | 
2025-12-04 09:20:49.715789 | TASK [include_role : add-build-sshkey]
2025-12-04 09:20:49.745017 | container | skipping: Conditional result was False
2025-12-04 09:20:49.756303 | 
2025-12-04 09:20:49.756460 | TASK [include_role : validate-host]
2025-12-04 09:20:49.784838 | container | skipping: Conditional result was False
2025-12-04 09:20:49.796230 | 
2025-12-04 09:20:49.796384 | TASK [include_role : prepare-workspace-openshift]
2025-12-04 09:20:49.834512 | container | ok
2025-12-04 09:20:49.860186 | 
2025-12-04 09:20:49.860407 | LOOP [prepare-workspace-openshift : include_tasks]
2025-12-04 09:20:49.942563 | container | ok:
2025-12-04 09:20:49.942888 | container | [
2025-12-04 09:20:49.942944 | container |   "container",
2025-12-04 09:20:49.942989 | container |   {
2025-12-04 09:20:49.943027 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2025-12-04 09:20:49.943063 | container |     "namespace": "main",
2025-12-04 09:20:49.943122 | container |     "pod": "pod-centos-9-stream-0005545256",
2025-12-04 09:20:49.943159 | container |     "stream_port": "34623"
2025-12-04 09:20:49.943193 | container |   }
2025-12-04 09:20:49.943227 | container | ]
2025-12-04 09:20:49.943285 | container | ok: All items complete
2025-12-04 09:20:49.943326 | 
2025-12-04 09:20:49.968536 | container | included: /var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml
2025-12-04 09:20:49.982015 | 
2025-12-04 09:20:49.982247 | TASK [prepare-workspace-openshift : Create src directory]
2025-12-04 09:20:51.472425 | container -> localhost | ok: Runtime: 0:00:00.987235
2025-12-04 09:20:51.484325 | 
2025-12-04 09:20:51.484476 | TASK [prepare-workspace-openshift : Copy src repos to the pod]
2025-12-04 09:20:53.802877 | container -> localhost | Output suppressed because no_log was given
2025-12-04 09:20:53.822952 | 
2025-12-04 09:20:53.823377 | TASK [include_role : remove-zuul-sshkey]
2025-12-04 09:20:53.850607 | container | ok
2025-12-04 09:20:53.877105 | 
2025-12-04 09:20:53.877235 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-04 09:20:54.248448 | container -> localhost | changed
2025-12-04 09:20:54.261572 | 
2025-12-04 09:20:54.261658 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-04 09:21:00.187577 | container | ok: "logs"
2025-12-04 09:21:06.091398 | container | ok: "artifacts"
2025-12-04 09:21:11.982617 | container | ok: "docs"
2025-12-04 09:21:11.998904 | 
2025-12-04 09:21:11.999095 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-04 09:21:18.015395 | container | changed: "logs"
2025-12-04 09:21:18.015778 | container | ok: All items complete
2025-12-04 09:21:18.015821 | 
2025-12-04 09:21:23.724989 | container | changed: "artifacts"
2025-12-04 09:21:29.547926 | container | changed: "docs"
2025-12-04 09:21:29.589542 | 
2025-12-04 09:21:29.589679 | PLAY RECAP
2025-12-04 09:21:29.589760 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-04 09:21:29.589791 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-04 09:21:29.589808 | 
2025-12-04 09:21:29.748381 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-04 09:21:29.749304 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-04 09:21:30.391110 | 
2025-12-04 09:21:30.391258 | PLAY [localhost]
2025-12-04 09:21:30.408660 | 
2025-12-04 09:21:30.408805 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-12-04 09:21:30.850514 | localhost | ok
2025-12-04 09:21:30.866273 | 
2025-12-04 09:21:30.866477 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-12-04 09:21:31.565532 | localhost | changed
2025-12-04 09:21:31.586378 | 
2025-12-04 09:21:31.586454 | PLAY [all]
2025-12-04 09:21:31.600490 | 
2025-12-04 09:21:31.600565 | TASK [include_role : prepare-workspace]
2025-12-04 09:21:31.627346 | container | skipping: Conditional result was False
2025-12-04 09:21:31.633671 | 
2025-12-04 09:21:31.633776 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-04 09:21:37.663883 | container | changed: "logs"
2025-12-04 09:21:43.583546 | container | changed: "artifacts"
2025-12-04 09:21:49.460319 | container | changed: "docs"
2025-12-04 09:21:49.476431 | 
2025-12-04 09:21:49.476678 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-04 09:21:55.491701 | container | changed: "logs"
2025-12-04 09:21:55.492074 | container | changed: All items complete
2025-12-04 09:21:55.492138 | 
2025-12-04 09:22:01.408935 | container | changed: "artifacts"
2025-12-04 09:22:07.279078 | container | changed: "docs"
2025-12-04 09:22:07.308755 | 
2025-12-04 09:22:07.308938 | TASK [Check if worker can sudo]
2025-12-04 09:22:13.692897 | container | ok: Runtime: 0:00:00.035492
2025-12-04 09:22:13.706681 | 
2025-12-04 09:22:13.706834 | TASK [configure-mirrors : Gather needed facts]
2025-12-04 09:22:13.724664 | container | skipping: Conditional result was False
2025-12-04 09:22:13.730438 | 
2025-12-04 09:22:13.730520 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-12-04 09:22:13.756132 | container | skipping: Conditional result was False
2025-12-04 09:22:13.787065 | 
2025-12-04 09:22:13.787147 | PLAY RECAP
2025-12-04 09:22:13.787192 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2025-12-04 09:22:13.787219 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-04 09:22:13.787237 | 
2025-12-04 09:22:13.910806 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-04 09:22:13.911686 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2025-12-04 09:22:14.516990 | 
2025-12-04 09:22:14.517271 | PLAY [Run light checks in pod]
2025-12-04 09:22:14.539588 | 
2025-12-04 09:22:14.539752 | TASK [Install packages]
2025-12-04 09:22:30.391052 | container | ok: Nothing to do
2025-12-04 09:22:30.395829 | 
2025-12-04 09:22:30.395902 | TASK [Ensure zuul-output exists]
2025-12-04 09:22:36.465713 | container | ok
2025-12-04 09:22:36.473317 | 
2025-12-04 09:22:36.473408 | TASK [Install dependencies]
2025-12-04 09:50:33.980959 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main]
2025-12-04 09:50:33.984119 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-04 09:50:34.592760 | 
2025-12-04 09:50:34.592894 | PLAY [all]
2025-12-04 09:50:34.611771 | 
2025-12-04 09:50:34.611860 | TASK [include_role : fetch-output]
2025-12-04 09:50:34.636455 | container | skipping: Conditional result was False
2025-12-04 09:50:34.642184 | 
2025-12-04 09:50:34.642257 | TASK [include_role : fetch-output-openshift]
2025-12-04 09:50:34.682931 | container | ok
2025-12-04 09:50:34.707860 | 
2025-12-04 09:50:34.708037 | TASK [fetch-output-openshift : Set log path for multiple nodes]
2025-12-04 09:50:34.774929 | container | skipping: Conditional result was False
2025-12-04 09:50:34.786639 | 
2025-12-04 09:50:34.786849 | TASK [fetch-output-openshift : Set log path for single node]
2025-12-04 09:50:34.843858 | container | ok
2025-12-04 09:50:34.853772 | 
2025-12-04 09:50:34.853912 | LOOP [fetch-output-openshift : Ensure local output dirs]
2025-12-04 09:50:35.314826 | container -> localhost | ok: "/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/logs"
2025-12-04 09:50:35.586986 | container -> localhost | changed: "/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/logs/npm"
2025-12-04 09:50:35.831423 | container -> localhost | changed: "/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/artifacts"
2025-12-04 09:50:36.054306 | container -> localhost | changed: "/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/docs"
2025-12-04 09:50:36.072713 | 
2025-12-04 09:50:36.072911 | LOOP [fetch-output-openshift : include_tasks]
2025-12-04 09:50:36.157205 | container | ok:
2025-12-04 09:50:36.157708 | container | [
2025-12-04 09:50:36.157808 | container |   "container",
2025-12-04 09:50:36.157853 | container |   {
2025-12-04 09:50:36.157891 | container |     "context": "main:zuul-worker/38-102-83-54:9023",
2025-12-04 09:50:36.157929 | container |     "namespace": "main",
2025-12-04 09:50:36.157966 | container |     "pod": "pod-centos-9-stream-0005545256",
2025-12-04 09:50:36.158002 | container |     "stream_port": "34623"
2025-12-04 09:50:36.158036 | container |   }
2025-12-04 09:50:36.158071 | container | ]
2025-12-04 09:50:36.158134 | container | changed: All items complete
2025-12-04 09:50:36.158178 | 
2025-12-04 09:50:36.176505 | container | included: /var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml
2025-12-04 09:50:36.189345 | 
2025-12-04 09:50:36.189603 | LOOP [fetch-output-openshift : Ensure local directory exists]
2025-12-04 09:50:41.455710 | container | changed: "/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/logs"
2025-12-04 09:50:47.293134 | container | changed: "/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/logs/npm"
2025-12-04 09:50:53.363126 | container | changed: "/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/artifacts"
2025-12-04 09:50:59.312962 | container | changed: "/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/docs"
2025-12-04 09:50:59.338932 | 
2025-12-04 09:50:59.339154 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor]
2025-12-04 09:51:04.601294 | container | Output suppressed because no_log was given
2025-12-04 09:51:04.616678 | 
2025-12-04 09:51:04.619840 | TASK [fetch-output-openshift : Remove empty directory]
2025-12-04 09:51:05.023194 | container -> localhost | ok: Runtime: 0:00:00.059991
2025-12-04 09:51:05.045036 | 
2025-12-04 09:51:05.045170 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-04 09:51:05.376497 | container -> localhost | find: ‘/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/artifacts’: No such file or directory
2025-12-04 09:51:05.376771 | container -> localhost | find: ‘/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/artifacts’: No such file or directory
2025-12-04 09:51:05.376820 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.013454
2025-12-04 09:51:05.376854 | container -> localhost | changed: All items complete
2025-12-04 09:51:05.376874 | 
2025-12-04 09:51:05.650925 | container -> localhost | find: ‘/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/docs’: No such file or directory
2025-12-04 09:51:05.651057 | container -> localhost | find: ‘/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/docs’: No such file or directory
2025-12-04 09:51:05.651091 | container -> localhost | ok: Item: docs Runtime: 0:00:00.013793
2025-12-04 09:51:05.702550 | 
2025-12-04 09:51:05.702687 | PLAY [all]
2025-12-04 09:51:05.720275 | 
2025-12-04 09:51:05.720357 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-04 09:51:05.762024 | container | ERROR
2025-12-04 09:51:05.762274 | container | {
2025-12-04 09:51:05.762328 | container |   "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/src/../306fcff62c40455098fc6ae5c9324aca_id_rsa.pub'. file not found, use -vvvvv to see paths searched"
2025-12-04 09:51:05.762362 | container | }
2025-12-04 09:51:05.762397 | container | ERROR: Ignoring Errors
2025-12-04 09:51:05.791397 | 
2025-12-04 09:51:05.791455 | PLAY RECAP
2025-12-04 09:51:05.791497 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1
2025-12-04 09:51:05.791516 | 
2025-12-04 09:51:05.904121 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-04 09:51:05.905823 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-04 09:51:06.565618 | 
2025-12-04 09:51:06.565766 | PLAY [localhost]
2025-12-04 09:51:06.585309 | 
2025-12-04 09:51:06.585430 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-04 09:51:07.002333 | localhost | changed
2025-12-04 09:51:07.009580 | 
2025-12-04 09:51:07.009775 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-04 09:51:07.041618 | localhost | ok
2025-12-04 09:51:07.051888 | 
2025-12-04 09:51:07.052076 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-04 09:51:07.444908 | localhost | changed
2025-12-04 09:51:07.456951 | 
2025-12-04 09:51:07.457103 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-04 09:51:08.146521 | localhost | changed
2025-12-04 09:51:08.158314 | 
2025-12-04 09:51:08.158451 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-04 09:51:08.607077 | localhost | Identity added: /var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/tmp/ansible.owf1zakw (/var/lib/zuul/builds/306fcff62c40455098fc6ae5c9324aca/work/tmp/ansible.owf1zakw)
2025-12-04 09:51:08.607349 | localhost | ok: Runtime: 0:00:00.014550
2025-12-04 09:51:08.611697 | 
2025-12-04 09:51:08.611782 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-04 09:51:08.941494 | localhost | ok: Runtime: 0:00:00.006416
2025-12-04 09:51:08.953662 | 
2025-12-04 09:51:08.953870 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-04 09:51:09.012208 | localhost | changed
2025-12-04 09:51:09.021878 | 
2025-12-04 09:51:09.022058 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-04 09:51:09.483686 | localhost | changed
2025-12-04 09:51:09.512260 | 
2025-12-04 09:51:09.512378 | PLAY [localhost]
2025-12-04 09:51:09.530273 | 
2025-12-04 09:51:09.530366 | TASK [Generate bulk log download script]
2025-12-04 09:51:09.550659 | localhost | ok
2025-12-04 09:51:09.563060 | 
2025-12-04 09:51:09.563139 | TASK [local-log-download : Check API endpoint is defined]
2025-12-04 09:51:09.614705 | localhost | ok: All assertions passed
2025-12-04 09:51:09.622012 | 
2025-12-04 09:51:09.622111 | TASK [local-log-download : Create download script]
2025-12-04 09:51:10.084477 | localhost -> localhost | changed
2025-12-04 09:51:10.104363 | 
2025-12-04 09:51:10.104499 | TASK [Register quick-download link]
2025-12-04 09:51:10.140313 | localhost | ok
2025-12-04 09:51:10.202511 | 
2025-12-04 09:51:10.202633 | PLAY [logserver.rdoproject.org]
2025-12-04 09:51:10.212444 | 
2025-12-04 09:51:10.212505 | TASK [Set zuul-log-path fact]
2025-12-04 09:51:10.230232 | logserver.rdoproject.org | ok
2025-12-04 09:51:10.239761 | 
2025-12-04 09:51:10.239828 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-04 09:51:10.277446 | logserver.rdoproject.org | ok
2025-12-04 09:51:10.284808 | 
2025-12-04 09:51:10.284890 | TASK [upload-logs : Create log directories]
2025-12-04 09:51:10.958788 | logserver.rdoproject.org | changed
2025-12-04 09:51:10.966314 | 
2025-12-04 09:51:10.966448 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-04 09:51:11.310317 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008938
2025-12-04 09:51:11.320530 | 
2025-12-04 09:51:11.320664 | TASK [upload-logs : Upload logs to log server]
2025-12-04 09:51:12.057083 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-04 09:51:12.063547 | 
2025-12-04 09:51:12.063682 | LOOP [upload-logs : Compress console log and json output]
2025-12-04 09:51:12.135017 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-04 09:51:12.146003 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-04 09:51:12.160833 | 
2025-12-04 09:51:12.161033 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-04 09:51:12.211270 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-04 09:51:12.211684 | 
2025-12-04 09:51:12.214645 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-04 09:51:12.225384 | 
2025-12-04 09:51:12.225555 | LOOP [upload-logs : Upload console log and json output]