2025-11-24 12:37:06.634562 | Job console starting... 2025-11-24 12:37:06.644894 | Updating repositories 2025-11-24 12:37:06.671355 | Preparing job workspace 2025-11-24 12:37:12.345039 | Running Ansible setup... 2025-11-24 12:37:20.240156 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-11-24 12:37:20.873399 | 2025-11-24 12:37:20.873506 | PLAY [localhost] 2025-11-24 12:37:20.882031 | 2025-11-24 12:37:20.882102 | TASK [Gathering Facts] 2025-11-24 12:37:21.809007 | localhost | ok 2025-11-24 12:37:21.822693 | 2025-11-24 12:37:21.822772 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-24 12:37:22.233125 | localhost -> localhost | changed 2025-11-24 12:37:22.238915 | 2025-11-24 12:37:22.238985 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-24 12:37:23.064821 | localhost -> localhost | changed 2025-11-24 12:37:23.073535 | 2025-11-24 12:37:23.073599 | TASK [Setup log path fact] 2025-11-24 12:37:23.091030 | localhost | ok 2025-11-24 12:37:23.102696 | 2025-11-24 12:37:23.102759 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-24 12:37:23.130465 | localhost | ok 2025-11-24 12:37:23.138877 | 2025-11-24 12:37:23.138939 | TASK [emit-job-header : Print job information] 2025-11-24 12:37:23.166801 | # Job Information 2025-11-24 12:37:23.166911 | Ansible Version: 2.15.12 2025-11-24 12:37:23.166936 | Job: cifmw-pod-pre-commit 2025-11-24 12:37:23.166954 | Pipeline: github-check 2025-11-24 12:37:23.166971 | Executor: ze02.softwarefactory-project.io 2025-11-24 12:37:23.166988 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3509 2025-11-24 12:37:23.167005 | Log URL (when completed): https://logserver.rdoproject.org/d6f/rdoproject.org/d6fbfde9decd46a1bc20dc6f274f267a/ 2025-11-24 12:37:23.167022 | Event ID: 33f9ebd0-c932-11f0-8263-ebfc06ef00b6 2025-11-24 12:37:23.170919 | 2025-11-24 12:37:23.170980 | LOOP [emit-job-header : Print node information] 2025-11-24 12:37:23.265498 | localhost | ok: 2025-11-24 12:37:23.266300 | localhost | # Node Information 2025-11-24 12:37:23.266378 | localhost | Inventory Hostname: container 2025-11-24 12:37:23.266431 | localhost | Hostname: dbe04ed8bdd6 2025-11-24 12:37:23.266475 | localhost | Username: zuul 2025-11-24 12:37:23.266520 | localhost | Distro: CentOS 9 2025-11-24 12:37:23.266561 | localhost | Provider: k1s04 2025-11-24 12:37:23.266599 | localhost | Region: 2025-11-24 12:37:23.266636 | localhost | Label: pod-centos-9-stream 2025-11-24 12:37:23.266713 | localhost | Product Name: OpenStack Nova 2025-11-24 12:37:23.266786 | localhost | Interface IP: pod-centos-9-stream-0005533548 2025-11-24 12:37:23.317260 | 2025-11-24 12:37:23.317333 | PLAY [all] 2025-11-24 12:37:23.325158 | 2025-11-24 12:37:23.325241 | TASK [Gather network facts] 2025-11-24 12:37:29.560894 | container | ok 2025-11-24 12:37:29.588984 | 2025-11-24 12:37:29.589113 | TASK [include_role : start-zuul-console] 2025-11-24 12:37:29.611428 | container | ok 2025-11-24 12:37:29.626481 | 2025-11-24 12:37:29.626562 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-24 12:37:35.756601 | container | ok 2025-11-24 12:37:35.768373 | 2025-11-24 12:37:35.768441 | TASK [include_role : add-build-sshkey] 2025-11-24 12:37:35.792969 | container | skipping: Conditional result was False 2025-11-24 12:37:35.799956 | 2025-11-24 12:37:35.800032 | TASK [include_role : validate-host] 2025-11-24 12:37:35.813697 | container | skipping: Conditional result was False 2025-11-24 12:37:35.818780 | 2025-11-24 12:37:35.818843 | TASK [include_role : prepare-workspace-openshift] 2025-11-24 12:37:35.836638 | container | ok 2025-11-24 12:37:35.848878 | 2025-11-24 12:37:35.848974 | LOOP [prepare-workspace-openshift : include_tasks] 2025-11-24 12:37:35.903879 | container | ok: 2025-11-24 12:37:35.904251 | container | [ 2025-11-24 12:37:35.904330 | container | "container", 2025-11-24 12:37:35.904406 | container | { 2025-11-24 12:37:35.904478 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2025-11-24 12:37:35.904542 | container | "namespace": "main", 2025-11-24 12:37:35.904630 | container | "pod": "pod-centos-9-stream-0005533548", 2025-11-24 12:37:35.905262 | container | "stream_port": "38071" 2025-11-24 12:37:35.905334 | container | } 2025-11-24 12:37:35.905374 | container | ] 2025-11-24 12:37:35.905443 | container | ok: All items complete 2025-11-24 12:37:35.905488 | 2025-11-24 12:37:35.922169 | container | included: /var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/trusted/project_0/review.rdoproject.org/config/roles/prepare-workspace-openshift/tasks/rsync.yaml 2025-11-24 12:37:35.927516 | 2025-11-24 12:37:35.927580 | TASK [prepare-workspace-openshift : Create src directory] 2025-11-24 12:37:37.260386 | container -> localhost | ok: Runtime: 0:00:00.921422 2025-11-24 12:37:37.271906 | 2025-11-24 12:37:37.272044 | TASK [prepare-workspace-openshift : Copy src repos to the pod] 2025-11-24 12:37:39.423889 | container -> localhost | Output suppressed because no_log was given 2025-11-24 12:37:39.469456 | 2025-11-24 12:37:39.469677 | TASK [include_role : remove-zuul-sshkey] 2025-11-24 12:37:39.499589 | container | ok 2025-11-24 12:37:39.517430 | 2025-11-24 12:37:39.517513 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-24 12:37:39.869129 | container -> localhost | changed 2025-11-24 12:37:39.890003 | 2025-11-24 12:37:39.890128 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-24 12:37:45.786807 | container | ok: "logs" 2025-11-24 12:37:51.723605 | container | ok: "artifacts" 2025-11-24 12:37:57.703005 | container | ok: "docs" 2025-11-24 12:37:57.717555 | 2025-11-24 12:37:57.717770 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-24 12:38:03.540501 | container | changed: "logs" 2025-11-24 12:38:03.540928 | container | ok: All items complete 2025-11-24 12:38:03.540990 | 2025-11-24 12:38:09.436240 | container | changed: "artifacts" 2025-11-24 12:38:15.462594 | container | changed: "docs" 2025-11-24 12:38:15.510313 | 2025-11-24 12:38:15.510423 | PLAY RECAP 2025-11-24 12:38:15.510479 | container | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-11-24 12:38:15.510514 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-24 12:38:15.510538 | 2025-11-24 12:38:15.613133 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-11-24 12:38:15.613989 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-11-24 12:38:16.206911 | 2025-11-24 12:38:16.207027 | PLAY [localhost] 2025-11-24 12:38:16.223669 | 2025-11-24 12:38:16.223791 | TASK [configure-ssh-config-fips : Ensure ssh config directory] 2025-11-24 12:38:16.647934 | localhost | ok 2025-11-24 12:38:16.653362 | 2025-11-24 12:38:16.653440 | TASK [configure-ssh-config-fips : Setup ssh config] 2025-11-24 12:38:17.322174 | localhost | changed 2025-11-24 12:38:17.342492 | 2025-11-24 12:38:17.342559 | PLAY [all] 2025-11-24 12:38:17.356231 | 2025-11-24 12:38:17.356292 | TASK [include_role : prepare-workspace] 2025-11-24 12:38:17.380801 | container | skipping: Conditional result was False 2025-11-24 12:38:17.386410 | 2025-11-24 12:38:17.386483 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-24 12:38:23.321059 | container | changed: "logs" 2025-11-24 12:38:29.058371 | container | changed: "artifacts" 2025-11-24 12:38:35.142125 | container | changed: "docs" 2025-11-24 12:38:35.156232 | 2025-11-24 12:38:35.156428 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-24 12:38:41.049286 | container | changed: "logs" 2025-11-24 12:38:41.049510 | container | changed: All items complete 2025-11-24 12:38:41.049549 | 2025-11-24 12:38:47.054991 | container | changed: "artifacts" 2025-11-24 12:38:53.053781 | container | changed: "docs" 2025-11-24 12:38:53.083218 | 2025-11-24 12:38:53.083344 | TASK [Check if worker can sudo] 2025-11-24 12:38:59.214631 | container | ok: Runtime: 0:00:00.034968 2025-11-24 12:38:59.219294 | 2025-11-24 12:38:59.219354 | TASK [configure-mirrors : Gather needed facts] 2025-11-24 12:38:59.243621 | container | skipping: Conditional result was False 2025-11-24 12:38:59.248801 | 2025-11-24 12:38:59.248866 | TASK [configure-mirrors : Set up infrastructure mirrors] 2025-11-24 12:38:59.272814 | container | skipping: Conditional result was False 2025-11-24 12:38:59.292994 | 2025-11-24 12:38:59.293044 | PLAY RECAP 2025-11-24 12:38:59.293085 | container | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2025-11-24 12:38:59.293109 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-24 12:38:59.293126 | 2025-11-24 12:38:59.378024 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master] 2025-11-24 12:38:59.379892 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2025-11-24 12:39:00.015115 | 2025-11-24 12:39:00.015225 | PLAY [Run light checks in pod] 2025-11-24 12:39:00.034723 | 2025-11-24 12:39:00.034805 | TASK [Install packages] 2025-11-24 13:03:36.725372 | container | ok: Nothing to do 2025-11-24 13:03:36.735950 | 2025-11-24 13:03:36.736094 | TASK [Ensure zuul-output exists] 2025-11-24 13:03:43.100088 | container | ok 2025-11-24 13:03:43.111135 | 2025-11-24 13:03:43.111272 | TASK [Install dependencies] 2025-11-24 13:04:53.581299 | container | changed 2025-11-24 13:04:53.599813 | 2025-11-24 13:04:53.599889 | TASK [Run check] 2025-11-24 13:07:29.438989 | RUN END RESULT_TIMED_OUT: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/pod-jobs.yml@main] 2025-11-24 13:07:29.441530 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-11-24 13:07:30.145166 | 2025-11-24 13:07:30.145340 | PLAY [all] 2025-11-24 13:07:30.168140 | 2025-11-24 13:07:30.168320 | TASK [include_role : fetch-output] 2025-11-24 13:07:30.187363 | container | skipping: Conditional result was False 2025-11-24 13:07:30.199322 | 2025-11-24 13:07:30.199514 | TASK [include_role : fetch-output-openshift] 2025-11-24 13:07:30.235872 | container | ok 2025-11-24 13:07:30.272190 | 2025-11-24 13:07:30.272331 | TASK [fetch-output-openshift : Set log path for multiple nodes] 2025-11-24 13:07:30.329265 | container | skipping: Conditional result was False 2025-11-24 13:07:30.340660 | 2025-11-24 13:07:30.340840 | TASK [fetch-output-openshift : Set log path for single node] 2025-11-24 13:07:30.394822 | container | ok 2025-11-24 13:07:30.401186 | 2025-11-24 13:07:30.401279 | LOOP [fetch-output-openshift : Ensure local output dirs] 2025-11-24 13:07:30.825150 | container -> localhost | ok: "/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/logs" 2025-11-24 13:07:31.065079 | container -> localhost | changed: "/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/logs/npm" 2025-11-24 13:07:31.311138 | container -> localhost | changed: "/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/artifacts" 2025-11-24 13:07:31.531322 | container -> localhost | changed: "/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/docs" 2025-11-24 13:07:31.544987 | 2025-11-24 13:07:31.545060 | LOOP [fetch-output-openshift : include_tasks] 2025-11-24 13:07:31.594595 | container | ok: 2025-11-24 13:07:31.594902 | container | [ 2025-11-24 13:07:31.594939 | container | "container", 2025-11-24 13:07:31.594964 | container | { 2025-11-24 13:07:31.594987 | container | "context": "main:zuul-worker/38-102-83-54:9023", 2025-11-24 13:07:31.595012 | container | "namespace": "main", 2025-11-24 13:07:31.595034 | container | "pod": "pod-centos-9-stream-0005533548", 2025-11-24 13:07:31.595057 | container | "stream_port": "38071" 2025-11-24 13:07:31.595079 | container | } 2025-11-24 13:07:31.595100 | container | ] 2025-11-24 13:07:31.595138 | container | changed: All items complete 2025-11-24 13:07:31.595165 | 2025-11-24 13:07:31.612437 | container | included: /var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/trusted/project_0/review.rdoproject.org/config/roles/fetch-output-openshift/tasks/rsync.yaml 2025-11-24 13:07:31.620949 | 2025-11-24 13:07:31.621044 | LOOP [fetch-output-openshift : Ensure local directory exists] 2025-11-24 13:07:37.936946 | container | changed: "/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/logs" 2025-11-24 13:07:44.064209 | container | changed: "/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/logs/npm" 2025-11-24 13:07:50.063507 | container | changed: "/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/artifacts" 2025-11-24 13:07:55.541087 | container | changed: "/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/docs" 2025-11-24 13:07:55.554507 | 2025-11-24 13:07:55.554624 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor] 2025-11-24 13:08:00.886427 | container | Output suppressed because no_log was given 2025-11-24 13:08:00.902096 | 2025-11-24 13:08:00.902269 | TASK [fetch-output-openshift : Remove empty directory] 2025-11-24 13:08:01.218894 | container -> localhost | ok: Runtime: 0:00:00.027572 2025-11-24 13:08:01.229820 | 2025-11-24 13:08:01.229936 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-24 13:08:01.527284 | container -> localhost | find: ‘/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/artifacts’: No such file or directory 2025-11-24 13:08:01.527524 | container -> localhost | find: ‘/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/artifacts’: No such file or directory 2025-11-24 13:08:01.527569 | container -> localhost | ok: Item: artifacts Runtime: 0:00:00.018154 2025-11-24 13:08:01.527603 | container -> localhost | changed: All items complete 2025-11-24 13:08:01.527624 | 2025-11-24 13:08:01.781696 | container -> localhost | find: ‘/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/docs’: No such file or directory 2025-11-24 13:08:01.781832 | container -> localhost | find: ‘/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/docs’: No such file or directory 2025-11-24 13:08:01.781870 | container -> localhost | ok: Item: docs Runtime: 0:00:00.015525 2025-11-24 13:08:01.805276 | 2025-11-24 13:08:01.805435 | PLAY [all] 2025-11-24 13:08:01.822598 | 2025-11-24 13:08:01.822757 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-24 13:08:01.875411 | container | ERROR 2025-11-24 13:08:01.875728 | container | { 2025-11-24 13:08:01.875772 | container | "msg": "The 'file' lookup had an issue accessing the file '/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/src/../d6fbfde9decd46a1bc20dc6f274f267a_id_rsa.pub'. file not found, use -vvvvv to see paths searched" 2025-11-24 13:08:01.875802 | container | } 2025-11-24 13:08:01.875835 | container | ERROR: Ignoring Errors 2025-11-24 13:08:01.904771 | 2025-11-24 13:08:01.904861 | PLAY RECAP 2025-11-24 13:08:01.904910 | container | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 1 2025-11-24 13:08:01.904930 | 2025-11-24 13:08:02.025232 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-11-24 13:08:02.027179 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-11-24 13:08:02.687836 | 2025-11-24 13:08:02.687968 | PLAY [localhost] 2025-11-24 13:08:02.707607 | 2025-11-24 13:08:02.707709 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-24 13:08:03.062156 | localhost | changed 2025-11-24 13:08:03.076068 | 2025-11-24 13:08:03.076274 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-24 13:08:03.125300 | localhost | ok 2025-11-24 13:08:03.145058 | 2025-11-24 13:08:03.145209 | TASK [add-fileserver : Create SSH private key tempfile] 2025-11-24 13:08:03.608484 | localhost | changed 2025-11-24 13:08:03.621981 | 2025-11-24 13:08:03.622149 | TASK [add-fileserver : Create SSH private key from secret] 2025-11-24 13:08:04.366520 | localhost | changed 2025-11-24 13:08:04.372187 | 2025-11-24 13:08:04.372257 | TASK [add-fileserver : Add fileserver ssh key] 2025-11-24 13:08:04.818611 | localhost | Identity added: /var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/tmp/ansible.b_r7ghsi (/var/lib/zuul/builds/d6fbfde9decd46a1bc20dc6f274f267a/work/tmp/ansible.b_r7ghsi) 2025-11-24 13:08:04.818844 | localhost | ok: Runtime: 0:00:00.012193 2025-11-24 13:08:04.823347 | 2025-11-24 13:08:04.823416 | TASK [add-fileserver : Remove SSH private key from disk] 2025-11-24 13:08:05.104503 | localhost | ok: Runtime: 0:00:00.005513 2025-11-24 13:08:05.109516 | 2025-11-24 13:08:05.109591 | TASK [add-fileserver : Add fileserver to inventory] 2025-11-24 13:08:05.163954 | localhost | changed 2025-11-24 13:08:05.168607 | 2025-11-24 13:08:05.168690 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-11-24 13:08:05.523367 | localhost | changed 2025-11-24 13:08:05.543864 | 2025-11-24 13:08:05.543922 | PLAY [localhost] 2025-11-24 13:08:05.555830 | 2025-11-24 13:08:05.555891 | TASK [Generate bulk log download script] 2025-11-24 13:08:05.575951 | localhost | ok 2025-11-24 13:08:05.588925 | 2025-11-24 13:08:05.589005 | TASK [local-log-download : Check API endpoint is defined] 2025-11-24 13:08:05.617445 | localhost | ok: All assertions passed 2025-11-24 13:08:05.622058 | 2025-11-24 13:08:05.622130 | TASK [local-log-download : Create download script] 2025-11-24 13:08:06.042750 | localhost -> localhost | changed 2025-11-24 13:08:06.051976 | 2025-11-24 13:08:06.052046 | TASK [Register quick-download link] 2025-11-24 13:08:06.080905 | localhost | ok 2025-11-24 13:08:06.124372 | 2025-11-24 13:08:06.124481 | PLAY [logserver.rdoproject.org] 2025-11-24 13:08:06.134594 | 2025-11-24 13:08:06.134656 | TASK [Set zuul-log-path fact] 2025-11-24 13:08:06.152087 | logserver.rdoproject.org | ok 2025-11-24 13:08:06.162330 | 2025-11-24 13:08:06.162399 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-24 13:08:06.199824 | logserver.rdoproject.org | ok 2025-11-24 13:08:06.206151 | 2025-11-24 13:08:06.206281 | TASK [upload-logs : Create log directories] 2025-11-24 13:08:06.874469 | logserver.rdoproject.org | changed 2025-11-24 13:08:06.879746 | 2025-11-24 13:08:06.879836 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-24 13:08:07.179786 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.007515 2025-11-24 13:08:07.185823 | 2025-11-24 13:08:07.185890 | TASK [upload-logs : Upload logs to log server] 2025-11-24 13:08:07.895857 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-11-24 13:08:07.900609 | 2025-11-24 13:08:07.900726 | LOOP [upload-logs : Compress console log and json output] 2025-11-24 13:08:07.971383 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-24 13:08:07.981266 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-24 13:08:07.995151 | 2025-11-24 13:08:07.995360 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-24 13:08:08.045964 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-24 13:08:08.046406 | 2025-11-24 13:08:08.049273 | logserver.rdoproject.org | skipping: Conditional result was False 2025-11-24 13:08:08.062034 | 2025-11-24 13:08:08.062253 | LOOP [upload-logs : Upload console log and json output]