2026-06-09 19:53:42.329320 | Job console starting
2026-06-09 19:53:42.453959 | Updating git repos
2026-06-09 19:53:42.489303 | Cloning repos into workspace
2026-06-09 19:53:43.751836 | Restoring repo states
2026-06-09 19:53:43.772832 | Merging changes
2026-06-09 19:53:44.554749 | Checking out repos
2026-06-09 19:53:44.763538 | Preparing playbooks
2026-06-09 19:53:46.392283 | Running Ansible setup
2026-06-09 19:53:54.318397 | PRE-RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/base/pre.yaml@main]
2026-06-09 19:53:54.978486 | 
2026-06-09 19:53:54.978630 | PLAY [localhost]
2026-06-09 19:53:54.985671 | 
2026-06-09 19:53:54.985736 | TASK [Gathering Facts]
2026-06-09 19:53:56.007006 | localhost | ok
2026-06-09 19:53:56.021823 | 
2026-06-09 19:53:56.021987 | TASK [Setup log path fact]
2026-06-09 19:53:56.048188 | localhost | ok
2026-06-09 19:53:56.071771 | 
2026-06-09 19:53:56.071877 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-09 19:53:56.112413 | localhost | ok
2026-06-09 19:53:56.126949 | 
2026-06-09 19:53:56.127094 | TASK [emit-job-header : Print job information]
2026-06-09 19:53:56.190873 | # Job Information
2026-06-09 19:53:56.191119 | Ansible Version: 2.16.18
2026-06-09 19:53:56.191191 | Job: dci-agent-syntax-check-job
2026-06-09 19:53:56.191233 | Pipeline: check
2026-06-09 19:53:56.191271 | Executor: 38.102.83.47
2026-06-09 19:53:56.191310 | Triggered by: https://github.com/distributedci/dci-pipeline/pull/39
2026-06-09 19:53:56.191354 | Event ID: d53c9a10-643c-11f1-8eb4-3c87d42188f7
2026-06-09 19:53:56.197070 | 
2026-06-09 19:53:56.197211 | LOOP [emit-job-header : Print node information]
2026-06-09 19:53:56.315292 | localhost | ok:
2026-06-09 19:53:56.315795 | localhost | # Node Information
2026-06-09 19:53:56.315888 | localhost | Inventory Hostname: testrunner
2026-06-09 19:53:56.315935 | localhost | Hostname: 895ad8c2ade1
2026-06-09 19:53:56.315972 | localhost | Username: zuul
2026-06-09 19:53:56.316017 | localhost | Distro: CentOS 9
2026-06-09 19:53:56.316054 | localhost | Provider: k1s05
2026-06-09 19:53:56.316091 | localhost | Region:
2026-06-09 19:53:56.316127 | localhost | Label: pod-centos-9-stream
2026-06-09 19:53:56.316162 | localhost | Product Name: OpenStack Nova
2026-06-09 19:53:56.316197 | localhost | Interface IP: pod-centos-9-stream-0000069029
2026-06-09 19:53:56.337223 | 
2026-06-09 19:53:56.337465 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-09 19:53:56.884302 | localhost -> localhost | changed
2026-06-09 19:53:56.893927 | 
2026-06-09 19:53:56.894063 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-09 19:53:57.915104 | localhost -> localhost | changed
2026-06-09 19:53:57.922076 | 
2026-06-09 19:53:57.922146 | PLAY [all]
2026-06-09 19:53:57.928328 | 
2026-06-09 19:53:57.928402 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-09 19:54:04.107958 | testrunner | ok
2026-06-09 19:54:04.121375 | 
2026-06-09 19:54:04.121578 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-09 19:54:04.150916 | testrunner | skipping: Conditional result was False
2026-06-09 19:54:04.160162 | 
2026-06-09 19:54:04.160523 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-09 19:54:04.198165 | testrunner | skipping: Conditional result was False
2026-06-09 19:54:04.211099 | 
2026-06-09 19:54:04.211273 | TASK [validate-host : Collect information about the host]
2026-06-09 19:54:04.237677 | testrunner | skipping: Conditional result was False
2026-06-09 19:54:04.247005 | 
2026-06-09 19:54:04.247145 | TASK [validate-host : Sanitize hostname]
2026-06-09 19:54:04.274135 | testrunner | skipping: Conditional result was False
2026-06-09 19:54:04.284278 | 
2026-06-09 19:54:04.284420 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-09 19:54:04.322069 | testrunner | skipping: Conditional result was False
2026-06-09 19:54:04.331279 | 
2026-06-09 19:54:04.331421 | TASK [validate-host : Collect information about zuul worker]
2026-06-09 19:54:04.359458 | testrunner | skipping: Conditional result was False
2026-06-09 19:54:04.370352 | 
2026-06-09 19:54:04.370601 | TASK [validate-host : Write out all zuul information for each host]
2026-06-09 19:54:04.407651 | testrunner | skipping: Conditional result was False
2026-06-09 19:54:04.421972 | 
2026-06-09 19:54:04.422136 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-09 19:54:04.452559 | testrunner | skipping: Conditional result was False
2026-06-09 19:54:04.463983 | 
2026-06-09 19:54:04.464144 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-09 19:54:04.505454 | 
2026-06-09 19:54:04.505723 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-09 19:54:04.542714 | testrunner | skipping: Conditional result was False
2026-06-09 19:54:04.555133 | 
2026-06-09 19:54:04.555294 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-09 19:54:04.582510 | testrunner | skipping: Conditional result was False
2026-06-09 19:54:04.596965 | 
2026-06-09 19:54:04.597103 | LOOP [prepare-workspace-openshift : Include rsync tasks]
2026-06-09 19:54:04.669643 | testrunner | ok:
2026-06-09 19:54:04.669915 | testrunner | [
2026-06-09 19:54:04.669967 | testrunner |   "testrunner",
2026-06-09 19:54:04.670007 | testrunner |   {
2026-06-09 19:54:04.670065 | testrunner |     "context": "main:zuul-worker/38-102-83-186:9023",
2026-06-09 19:54:04.670104 | testrunner |     "namespace": "main",
2026-06-09 19:54:04.670140 | testrunner |     "pod": "pod-centos-9-stream-0000069029",
2026-06-09 19:54:04.670175 | testrunner |     "resources": {
2026-06-09 19:54:04.670209 | testrunner |       "cores": 0,
2026-06-09 19:54:04.670242 | testrunner |       "instances": 1,
2026-06-09 19:54:04.670276 | testrunner |       "ram": 0
2026-06-09 19:54:04.670308 | testrunner |     },
2026-06-09 19:54:04.670342 | testrunner |     "stream_port1": 34013,
2026-06-09 19:54:04.670376 | testrunner |     "stream_port2": 38561
2026-06-09 19:54:04.670410 | testrunner |   }
2026-06-09 19:54:04.670484 | testrunner | ]
2026-06-09 19:54:04.670623 | testrunner | ok: All items complete
2026-06-09 19:54:04.670680 | 
2026-06-09 19:54:04.689605 | testrunner | included: /var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/prepare-workspace-openshift/tasks/rsync.yaml
2026-06-09 19:54:04.699643 | 
2026-06-09 19:54:04.699778 | TASK [prepare-workspace-openshift : Create src directory]
2026-06-09 19:54:06.226463 | testrunner -> localhost | ok: Runtime: 0:00:00.972168
2026-06-09 19:54:06.234228 | 
2026-06-09 19:54:06.234358 | TASK [prepare-workspace-openshift : Copy src repos to the pod]
2026-06-09 19:54:08.347925 | testrunner -> localhost | Output suppressed because no_log was given
2026-06-09 19:54:08.363060 | 
2026-06-09 19:54:08.363356 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-09 19:54:08.846663 | testrunner -> localhost | changed
2026-06-09 19:54:08.853954 | 
2026-06-09 19:54:08.854022 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-09 19:54:14.859397 | testrunner | ok: "logs"
2026-06-09 19:54:20.883198 | testrunner | ok: "artifacts"
2026-06-09 19:54:26.856915 | testrunner | ok: "docs"
2026-06-09 19:54:26.868753 | 
2026-06-09 19:54:26.869158 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-09 19:54:32.769282 | testrunner | changed: "logs"
2026-06-09 19:54:32.771261 | testrunner | ok: All items complete
2026-06-09 19:54:32.771312 | 
2026-06-09 19:54:39.667608 | testrunner | changed: "artifacts"
2026-06-09 19:54:45.696192 | testrunner | changed: "docs"
2026-06-09 19:54:45.719310 | 
2026-06-09 19:54:45.719544 | PLAY RECAP
2026-06-09 19:54:45.719638 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-09 19:54:45.719695 | testrunner | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 11 rescued: 0 ignored: 0
2026-06-09 19:54:45.719734 | 
2026-06-09 19:54:45.875849 | PRE-RUN END RESULT_NORMAL: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/base/pre.yaml@main]
2026-06-09 19:54:45.876944 | PRE-RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/pre.yaml@main]
2026-06-09 19:54:46.497930 | 
2026-06-09 19:54:46.498053 | PLAY [testrunner]
2026-06-09 19:54:46.507182 | 
2026-06-09 19:54:46.507258 | TASK [Remove any ansible installation from the host, it will be installed via pip later]
2026-06-09 19:55:03.316608 | testrunner | ok: Nothing to do
2026-06-09 19:55:03.324206 | 
2026-06-09 19:55:03.324363 | TASK [Check if ansible-lint is enabled]
2026-06-09 19:55:09.565722 | testrunner | ok
2026-06-09 19:55:09.571895 | 
2026-06-09 19:55:09.572026 | TASK [Install ansible-lint modules if ansible-lint is enabled]
2026-06-09 19:55:09.588583 | testrunner | skipping: Conditional result was False
2026-06-09 19:55:09.595501 | 
2026-06-09 19:55:09.595646 | TASK [Install dci-ansible test requirements]
2026-06-09 19:55:51.606042 | testrunner | changed
2026-06-09 19:55:51.614404 | 
2026-06-09 19:55:51.614581 | TASK [Check if test/requirements.yml is defined]
2026-06-09 19:55:57.634704 | testrunner | ok
2026-06-09 19:55:57.641148 | 
2026-06-09 19:55:57.641280 | TASK [Install ansible-galaxy]
2026-06-09 19:55:57.668833 | testrunner | skipping: Conditional result was False
2026-06-09 19:55:57.678272 | 
2026-06-09 19:55:57.678412 | TASK [Install ansible role modules]
2026-06-09 19:55:58.295205 | testrunner | skipping: Conditional result was False
2026-06-09 19:55:58.303768 | 
2026-06-09 19:55:58.303909 | TASK [Install ansible collection modules]
2026-06-09 19:55:58.847315 | testrunner | skipping: Conditional result was False
2026-06-09 19:55:58.850945 | 
2026-06-09 19:55:58.851042 | PLAY RECAP
2026-06-09 19:55:58.851132 | testrunner | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2026-06-09 19:55:58.851174 | 
2026-06-09 19:55:58.982682 | PRE-RUN END RESULT_NORMAL: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/pre.yaml@main]
2026-06-09 19:55:58.985155 | RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/main.yaml@main]
2026-06-09 19:55:59.552008 | 
2026-06-09 19:55:59.552175 | PLAY [testrunner]
2026-06-09 19:55:59.561722 | 
2026-06-09 19:55:59.561804 | TASK [Debug project_name]
2026-06-09 19:55:59.629857 | testrunner | ok
2026-06-09 19:55:59.632767 | 
2026-06-09 19:55:59.632836 | TASK [Check requirements.yml]
2026-06-09 19:56:06.014392 | testrunner | ok
2026-06-09 19:56:06.021287 | 
2026-06-09 19:56:06.021457 | TASK [Install dependencies]
2026-06-09 19:56:06.341256 | testrunner | skipping: Conditional result was False
2026-06-09 19:56:06.348715 | 
2026-06-09 19:56:06.348847 | TASK [Test if main playbook exists]
2026-06-09 19:56:12.400377 | testrunner | ok
2026-06-09 19:56:12.406368 | 
2026-06-09 19:56:12.406529 | TASK [Run syntax checks]
2026-06-09 19:56:12.713597 | testrunner | skipping: Conditional result was False
2026-06-09 19:56:12.721753 | 
2026-06-09 19:56:12.721888 | TASK [Check if ansible-lint is enabled]
2026-06-09 19:56:18.870562 | testrunner | ok
2026-06-09 19:56:18.874181 | 
2026-06-09 19:56:18.874239 | TASK [Run ansible-lint]
2026-06-09 19:56:19.164728 | testrunner | skipping: Conditional result was False
2026-06-09 19:56:19.167512 | 
2026-06-09 19:56:19.167607 | PLAY RECAP
2026-06-09 19:56:19.167701 | testrunner | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-09 19:56:19.167743 | 
2026-06-09 19:56:19.299006 | RUN END RESULT_NORMAL: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/main.yaml@main]
2026-06-09 19:56:19.301653 | POST-RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/base/post.yaml@main]
2026-06-09 19:56:19.945238 | 
2026-06-09 19:56:19.945357 | PLAY [all]
2026-06-09 19:56:19.953616 | 
2026-06-09 19:56:19.953685 | TASK [include_role : fetch-output]
2026-06-09 19:56:19.977938 | testrunner | skipping: Conditional result was False
2026-06-09 19:56:19.982516 | 
2026-06-09 19:56:19.982589 | TASK [include_role : fetch-output-openshift]
2026-06-09 19:56:20.019660 | testrunner | ok
2026-06-09 19:56:20.031535 | 
2026-06-09 19:56:20.031626 | TASK [fetch-output-openshift : Set log path for multiple nodes]
2026-06-09 19:56:20.108358 | testrunner | skipping: Conditional result was False
2026-06-09 19:56:20.116305 | 
2026-06-09 19:56:20.116503 | TASK [fetch-output-openshift : Set log path for single node]
2026-06-09 19:56:20.159937 | testrunner | ok
2026-06-09 19:56:20.166273 | 
2026-06-09 19:56:20.166406 | LOOP [fetch-output-openshift : Ensure local output dirs]
2026-06-09 19:56:20.635873 | testrunner -> localhost | changed: "/var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/work/logs"
2026-06-09 19:56:20.911671 | testrunner -> localhost | changed: "/var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/work/logs/npm"
2026-06-09 19:56:21.191720 | testrunner -> localhost | changed: "/var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/work/artifacts"
2026-06-09 19:56:21.463990 | testrunner -> localhost | changed: "/var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/work/docs"
2026-06-09 19:56:21.475772 | 
2026-06-09 19:56:21.475852 | LOOP [fetch-output-openshift : Include rsync tasks]
2026-06-09 19:56:21.551928 | testrunner | ok:
2026-06-09 19:56:21.552478 | testrunner | [
2026-06-09 19:56:21.552557 | testrunner |   "testrunner",
2026-06-09 19:56:21.552604 | testrunner |   {
2026-06-09 19:56:21.552644 | testrunner |     "context": "main:zuul-worker/38-102-83-186:9023",
2026-06-09 19:56:21.552684 | testrunner |     "namespace": "main",
2026-06-09 19:56:21.552724 | testrunner |     "pod": "pod-centos-9-stream-0000069029",
2026-06-09 19:56:21.552762 | testrunner |     "resources": {
2026-06-09 19:56:21.552800 | testrunner |       "cores": 0,
2026-06-09 19:56:21.552837 | testrunner |       "instances": 1,
2026-06-09 19:56:21.552873 | testrunner |       "ram": 0
2026-06-09 19:56:21.552910 | testrunner |     },
2026-06-09 19:56:21.552954 | testrunner |     "stream_port1": 34013,
2026-06-09 19:56:21.552996 | testrunner |     "stream_port2": 38561
2026-06-09 19:56:21.553035 | testrunner |   }
2026-06-09 19:56:21.553078 | testrunner | ]
2026-06-09 19:56:21.553156 | testrunner | changed: All items complete
2026-06-09 19:56:21.553207 | 
2026-06-09 19:56:21.560165 | testrunner | included: /var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/fetch-output-openshift/tasks/rsync.yaml
2026-06-09 19:56:21.566708 | 
2026-06-09 19:56:21.566793 | LOOP [fetch-output-openshift : Fetch zuul-output from the pod]
2026-06-09 19:56:26.926170 | testrunner | Output suppressed because no_log was given
2026-06-09 19:56:26.933915 | 
2026-06-09 19:56:26.934054 | TASK [fetch-output-openshift : Remove empty directory]
2026-06-09 19:56:27.311858 | testrunner -> localhost | ok: Runtime: 0:00:00.028884
2026-06-09 19:56:27.329353 | 
2026-06-09 19:56:27.329522 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-09 19:56:27.723190 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/work/artifacts’: No such file or directory
2026-06-09 19:56:27.723478 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/work/artifacts’: No such file or directory
2026-06-09 19:56:27.723542 | testrunner -> localhost | ok: Item: artifacts Runtime: 0:00:00.013352
2026-06-09 19:56:28.034976 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/work/docs’: No such file or directory
2026-06-09 19:56:28.035172 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/work/docs’: No such file or directory
2026-06-09 19:56:28.035249 | testrunner -> localhost | ok: Item: docs Runtime: 0:00:00.010880
2026-06-09 19:56:28.049277 | 
2026-06-09 19:56:28.049394 | PLAY [localhost]
2026-06-09 19:56:28.061594 | 
2026-06-09 19:56:28.061720 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-09 19:56:28.514020 | localhost | changed
2026-06-09 19:56:28.537756 | 
2026-06-09 19:56:28.537911 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-09 19:56:28.570817 | localhost | ok
2026-06-09 19:56:28.585326 | 
2026-06-09 19:56:28.585535 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-09 19:56:29.050081 | localhost | changed
2026-06-09 19:56:29.054888 | 
2026-06-09 19:56:29.054969 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-09 19:56:29.860987 | localhost | changed
2026-06-09 19:56:29.869031 | 
2026-06-09 19:56:29.869182 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-09 19:56:30.192373 | localhost | Identity added: /var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/work/tmp/ansible.f7naehus (/var/lib/zuul/builds/ec4cdc6324cc48d5bdc809683f84468f/work/tmp/ansible.f7naehus)
2026-06-09 19:56:30.192769 | localhost | ok: Runtime: 0:00:00.010735
2026-06-09 19:56:30.200465 | 
2026-06-09 19:56:30.200600 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-09 19:56:30.516668 | localhost | ok: Runtime: 0:00:00.008554
2026-06-09 19:56:30.521028 | 
2026-06-09 19:56:30.521095 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-09 19:56:30.592815 | localhost | changed
2026-06-09 19:56:30.596414 | 
2026-06-09 19:56:30.596500 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-09 19:56:30.991757 | localhost | changed
2026-06-09 19:56:30.996931 | 
2026-06-09 19:56:30.996992 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-09 19:56:31.000962 | 
2026-06-09 19:56:31.001020 | TASK [Gathering Facts]
2026-06-09 19:56:32.458649 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-09 19:56:32.471612 | 
2026-06-09 19:56:32.471753 | TASK [Set zuul-log-path fact]
2026-06-09 19:56:32.495828 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-09 19:56:32.516502 | 
2026-06-09 19:56:32.516672 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-09 19:56:32.556872 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-09 19:56:32.565553 | 
2026-06-09 19:56:32.565645 | TASK [upload-logs : Create log directories]
2026-06-09 19:56:32.923915 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-09 19:56:32.930507 | 
2026-06-09 19:56:32.930647 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-09 19:56:33.292165 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.009283
2026-06-09 19:56:33.301625 | 
2026-06-09 19:56:33.301774 | TASK [upload-logs : Upload logs to log server]
2026-06-09 19:56:34.461915 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given
2026-06-09 19:56:34.468206 | 
2026-06-09 19:56:34.468336 | LOOP [upload-logs : Compress console log and json output]