2026-06-14 03:48:08.746327 | Job console starting
2026-06-14 03:48:08.873502 | Updating git repos
2026-06-14 03:48:08.905088 | Cloning repos into workspace
2026-06-14 03:48:10.207837 | Restoring repo states
2026-06-14 03:48:10.221974 | Merging changes
2026-06-14 03:48:10.915045 | Checking out repos
2026-06-14 03:48:11.104176 | Preparing playbooks
2026-06-14 03:48:12.753675 | Running Ansible setup
2026-06-14 03:48:21.431935 | PRE-RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/base/pre.yaml@main]
2026-06-14 03:48:22.083979 | 
2026-06-14 03:48:22.084089 | PLAY [localhost]
2026-06-14 03:48:22.090833 | 
2026-06-14 03:48:22.090897 | TASK [Gathering Facts]
2026-06-14 03:48:24.041183 | localhost | ok
2026-06-14 03:48:24.054712 | 
2026-06-14 03:48:24.054879 | TASK [Setup log path fact]
2026-06-14 03:48:24.077059 | localhost | ok
2026-06-14 03:48:24.105191 | 
2026-06-14 03:48:24.105467 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-14 03:48:24.148298 | localhost | ok
2026-06-14 03:48:24.160167 | 
2026-06-14 03:48:24.160301 | TASK [emit-job-header : Print job information]
2026-06-14 03:48:24.213607 | # Job Information
2026-06-14 03:48:24.213851 | Ansible Version: 2.16.18
2026-06-14 03:48:24.213934 | Job: dci-agent-syntax-check-job
2026-06-14 03:48:24.213980 | Pipeline: check
2026-06-14 03:48:24.214020 | Executor: 38.102.83.32
2026-06-14 03:48:24.214057 | Triggered by: https://github.com/distributedci/dci-pipeline/pull/39
2026-06-14 03:48:24.214100 | Event ID: c63bbd10-67a3-11f1-87e6-5fc22223e1ed
2026-06-14 03:48:24.219485 | 
2026-06-14 03:48:24.219643 | LOOP [emit-job-header : Print node information]
2026-06-14 03:48:24.318295 | localhost | ok:
2026-06-14 03:48:24.318658 | localhost | # Node Information
2026-06-14 03:48:24.318717 | localhost | Inventory Hostname: testrunner
2026-06-14 03:48:24.318760 | localhost | Hostname: ed9bee68c88e
2026-06-14 03:48:24.318800 | localhost | Username: zuul
2026-06-14 03:48:24.318861 | localhost | Distro: CentOS 9
2026-06-14 03:48:24.318911 | localhost | Provider: k1s06
2026-06-14 03:48:24.318957 | localhost | Region:
2026-06-14 03:48:24.319004 | localhost | Label: pod-centos-9-stream
2026-06-14 03:48:24.319050 | localhost | Product Name: OpenStack Nova
2026-06-14 03:48:24.319095 | localhost | Interface IP: pod-centos-9-stream-0000073076
2026-06-14 03:48:24.331456 | 
2026-06-14 03:48:24.331701 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-14 03:48:24.753828 | localhost -> localhost | changed
2026-06-14 03:48:24.763804 | 
2026-06-14 03:48:24.763949 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-14 03:48:25.732840 | localhost -> localhost | changed
2026-06-14 03:48:25.744937 | 
2026-06-14 03:48:25.745051 | PLAY [all]
2026-06-14 03:48:25.757210 | 
2026-06-14 03:48:25.757345 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-14 03:48:31.819956 | testrunner | ok
2026-06-14 03:48:31.832377 | 
2026-06-14 03:48:31.832505 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-14 03:48:31.860014 | testrunner | skipping: Conditional result was False
2026-06-14 03:48:31.868310 | 
2026-06-14 03:48:31.868459 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-14 03:48:31.905719 | testrunner | skipping: Conditional result was False
2026-06-14 03:48:31.913334 | 
2026-06-14 03:48:31.913462 | TASK [validate-host : Collect information about the host]
2026-06-14 03:48:31.940264 | testrunner | skipping: Conditional result was False
2026-06-14 03:48:31.950339 | 
2026-06-14 03:48:31.950472 | TASK [validate-host : Sanitize hostname]
2026-06-14 03:48:31.966998 | testrunner | skipping: Conditional result was False
2026-06-14 03:48:31.975431 | 
2026-06-14 03:48:31.975597 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-14 03:48:32.002266 | testrunner | skipping: Conditional result was False
2026-06-14 03:48:32.009860 | 
2026-06-14 03:48:32.009994 | TASK [validate-host : Collect information about zuul worker]
2026-06-14 03:48:32.036792 | testrunner | skipping: Conditional result was False
2026-06-14 03:48:32.044421 | 
2026-06-14 03:48:32.044581 | TASK [validate-host : Write out all zuul information for each host]
2026-06-14 03:48:32.081659 | testrunner | skipping: Conditional result was False
2026-06-14 03:48:32.094957 | 
2026-06-14 03:48:32.095083 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-14 03:48:32.121557 | testrunner | skipping: Conditional result was False
2026-06-14 03:48:32.130660 | 
2026-06-14 03:48:32.130789 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-14 03:48:32.169722 | 
2026-06-14 03:48:32.169957 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-14 03:48:32.206353 | testrunner | skipping: Conditional result was False
2026-06-14 03:48:32.213808 | 
2026-06-14 03:48:32.213940 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-14 03:48:32.251289 | testrunner | skipping: Conditional result was False
2026-06-14 03:48:32.268067 | 
2026-06-14 03:48:32.268198 | LOOP [prepare-workspace-openshift : Include rsync tasks]
2026-06-14 03:48:32.339876 | testrunner | ok:
2026-06-14 03:48:32.340097 | testrunner | [
2026-06-14 03:48:32.340147 | testrunner |   "testrunner",
2026-06-14 03:48:32.340186 | testrunner |   {
2026-06-14 03:48:32.340223 | testrunner |     "context": "main:zuul-worker/38-102-83-49:9023",
2026-06-14 03:48:32.340259 | testrunner |     "namespace": "main",
2026-06-14 03:48:32.340294 | testrunner |     "pod": "pod-centos-9-stream-0000073076",
2026-06-14 03:48:32.340330 | testrunner |     "resources": {
2026-06-14 03:48:32.340365 | testrunner |       "cores": 0,
2026-06-14 03:48:32.340424 | testrunner |       "instances": 1,
2026-06-14 03:48:32.340459 | testrunner |       "ram": 0
2026-06-14 03:48:32.340493 | testrunner |     },
2026-06-14 03:48:32.340565 | testrunner |     "stream_port1": 36017,
2026-06-14 03:48:32.340603 | testrunner |     "stream_port2": 37425
2026-06-14 03:48:32.340638 | testrunner |   }
2026-06-14 03:48:32.340672 | testrunner | ]
2026-06-14 03:48:32.340730 | testrunner | ok: All items complete
2026-06-14 03:48:32.340769 | 
2026-06-14 03:48:32.351038 | testrunner | included: /var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/prepare-workspace-openshift/tasks/rsync.yaml
2026-06-14 03:48:32.359506 | 
2026-06-14 03:48:32.359660 | TASK [prepare-workspace-openshift : Create src directory]
2026-06-14 03:48:33.749962 | testrunner -> localhost | ok: Runtime: 0:00:00.939095
2026-06-14 03:48:33.757185 | 
2026-06-14 03:48:33.757309 | TASK [prepare-workspace-openshift : Copy src repos to the pod]
2026-06-14 03:48:35.747080 | testrunner -> localhost | Output suppressed because no_log was given
2026-06-14 03:48:35.760687 | 
2026-06-14 03:48:35.760811 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-14 03:48:36.201059 | testrunner -> localhost | changed
2026-06-14 03:48:36.214877 | 
2026-06-14 03:48:36.215022 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-14 03:48:42.103924 | testrunner | ok: "logs"
2026-06-14 03:48:47.935875 | testrunner | ok: "artifacts"
2026-06-14 03:48:53.764970 | testrunner | ok: "docs"
2026-06-14 03:48:53.776029 | 
2026-06-14 03:48:53.776245 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-14 03:48:59.767554 | testrunner | changed: "logs"
2026-06-14 03:48:59.767950 | testrunner | ok: All items complete
2026-06-14 03:48:59.768006 | 
2026-06-14 03:49:05.559808 | testrunner | changed: "artifacts"
2026-06-14 03:49:11.631981 | testrunner | changed: "docs"
2026-06-14 03:49:11.646795 | 
2026-06-14 03:49:11.646904 | PLAY RECAP
2026-06-14 03:49:11.646947 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-14 03:49:11.646973 | testrunner | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 11 rescued: 0 ignored: 0
2026-06-14 03:49:11.646991 | 
2026-06-14 03:49:11.762317 | PRE-RUN END RESULT_NORMAL: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/base/pre.yaml@main]
2026-06-14 03:49:11.764505 | PRE-RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/pre.yaml@main]
2026-06-14 03:49:12.406767 | 
2026-06-14 03:49:12.406890 | PLAY [testrunner]
2026-06-14 03:49:12.415915 | 
2026-06-14 03:49:12.415991 | TASK [Remove any ansible installation from the host, it will be installed via pip later]
2026-06-14 03:49:28.250818 | testrunner | ok: Nothing to do
2026-06-14 03:49:28.258926 | 
2026-06-14 03:49:28.259124 | TASK [Check if ansible-lint is enabled]
2026-06-14 03:49:34.393054 | testrunner | ok
2026-06-14 03:49:34.400775 | 
2026-06-14 03:49:34.400918 | TASK [Install ansible-lint modules if ansible-lint is enabled]
2026-06-14 03:49:34.430489 | testrunner | skipping: Conditional result was False
2026-06-14 03:49:34.438430 | 
2026-06-14 03:49:34.438684 | TASK [Install dci-ansible test requirements]
2026-06-14 03:50:16.373471 | testrunner | changed
2026-06-14 03:50:16.381645 | 
2026-06-14 03:50:16.381784 | TASK [Check if test/requirements.yml is defined]
2026-06-14 03:50:22.133577 | testrunner | ok
2026-06-14 03:50:22.139941 | 
2026-06-14 03:50:22.140079 | TASK [Install ansible-galaxy]
2026-06-14 03:50:22.166058 | testrunner | skipping: Conditional result was False
2026-06-14 03:50:22.174324 | 
2026-06-14 03:50:22.174469 | TASK [Install ansible role modules]
2026-06-14 03:50:22.767979 | testrunner | skipping: Conditional result was False
2026-06-14 03:50:22.773337 | 
2026-06-14 03:50:22.773434 | TASK [Install ansible collection modules]
2026-06-14 03:50:23.208208 | testrunner | skipping: Conditional result was False
2026-06-14 03:50:23.210865 | 
2026-06-14 03:50:23.211046 | PLAY RECAP
2026-06-14 03:50:23.211146 | testrunner | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2026-06-14 03:50:23.211188 | 
2026-06-14 03:50:23.354457 | PRE-RUN END RESULT_NORMAL: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/pre.yaml@main]
2026-06-14 03:50:23.356398 | RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/main.yaml@main]
2026-06-14 03:50:23.959492 | 
2026-06-14 03:50:23.959628 | PLAY [testrunner]
2026-06-14 03:50:23.968832 | 
2026-06-14 03:50:23.968926 | TASK [Debug project_name]
2026-06-14 03:50:24.050438 | testrunner | ok
2026-06-14 03:50:24.057841 | 
2026-06-14 03:50:24.058000 | TASK [Check requirements.yml]
2026-06-14 03:50:30.030185 | testrunner | ok
2026-06-14 03:50:30.038169 | 
2026-06-14 03:50:30.038364 | TASK [Install dependencies]
2026-06-14 03:50:30.341789 | testrunner | skipping: Conditional result was False
2026-06-14 03:50:30.350693 | 
2026-06-14 03:50:30.350830 | TASK [Test if main playbook exists]
2026-06-14 03:50:36.085323 | testrunner | ok
2026-06-14 03:50:36.092582 | 
2026-06-14 03:50:36.092756 | TASK [Run syntax checks]
2026-06-14 03:50:36.413242 | testrunner | skipping: Conditional result was False
2026-06-14 03:50:36.423646 | 
2026-06-14 03:50:36.423795 | TASK [Check if ansible-lint is enabled]
2026-06-14 03:50:42.319250 | testrunner | ok
2026-06-14 03:50:42.326707 | 
2026-06-14 03:50:42.326834 | TASK [Run ansible-lint]
2026-06-14 03:50:42.617198 | testrunner | skipping: Conditional result was False
2026-06-14 03:50:42.620338 | 
2026-06-14 03:50:42.620439 | PLAY RECAP
2026-06-14 03:50:42.620563 | testrunner | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-14 03:50:42.620615 | 
2026-06-14 03:50:42.778015 | RUN END RESULT_NORMAL: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/main.yaml@main]
2026-06-14 03:50:42.779693 | POST-RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/base/post.yaml@main]
2026-06-14 03:50:43.482153 | 
2026-06-14 03:50:43.482271 | PLAY [all]
2026-06-14 03:50:43.490653 | 
2026-06-14 03:50:43.490722 | TASK [include_role : fetch-output]
2026-06-14 03:50:43.514722 | testrunner | skipping: Conditional result was False
2026-06-14 03:50:43.519157 | 
2026-06-14 03:50:43.519226 | TASK [include_role : fetch-output-openshift]
2026-06-14 03:50:43.557175 | testrunner | ok
2026-06-14 03:50:43.573217 | 
2026-06-14 03:50:43.573319 | TASK [fetch-output-openshift : Set log path for multiple nodes]
2026-06-14 03:50:43.639040 | testrunner | skipping: Conditional result was False
2026-06-14 03:50:43.647210 | 
2026-06-14 03:50:43.647390 | TASK [fetch-output-openshift : Set log path for single node]
2026-06-14 03:50:43.700675 | testrunner | ok
2026-06-14 03:50:43.707253 | 
2026-06-14 03:50:43.707398 | LOOP [fetch-output-openshift : Ensure local output dirs]
2026-06-14 03:50:44.143093 | testrunner -> localhost | changed: "/var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/work/logs"
2026-06-14 03:50:44.413055 | testrunner -> localhost | changed: "/var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/work/logs/npm"
2026-06-14 03:50:44.657888 | testrunner -> localhost | changed: "/var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/work/artifacts"
2026-06-14 03:50:44.944434 | testrunner -> localhost | changed: "/var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/work/docs"
2026-06-14 03:50:44.961663 | 
2026-06-14 03:50:44.961867 | LOOP [fetch-output-openshift : Include rsync tasks]
2026-06-14 03:50:45.044855 | testrunner | ok:
2026-06-14 03:50:45.045088 | testrunner | [
2026-06-14 03:50:45.045143 | testrunner |   "testrunner",
2026-06-14 03:50:45.045184 | testrunner |   {
2026-06-14 03:50:45.045221 | testrunner |     "context": "main:zuul-worker/38-102-83-49:9023",
2026-06-14 03:50:45.045259 | testrunner |     "namespace": "main",
2026-06-14 03:50:45.045296 | testrunner |     "pod": "pod-centos-9-stream-0000073076",
2026-06-14 03:50:45.045332 | testrunner |     "resources": {
2026-06-14 03:50:45.045367 | testrunner |       "cores": 0,
2026-06-14 03:50:45.045401 | testrunner |       "instances": 1,
2026-06-14 03:50:45.045435 | testrunner |       "ram": 0
2026-06-14 03:50:45.045469 | testrunner |     },
2026-06-14 03:50:45.045509 | testrunner |     "stream_port1": 36017,
2026-06-14 03:50:45.045589 | testrunner |     "stream_port2": 37425
2026-06-14 03:50:45.045628 | testrunner |   }
2026-06-14 03:50:45.045665 | testrunner | ]
2026-06-14 03:50:45.045720 | testrunner | changed: All items complete
2026-06-14 03:50:45.045761 | 
2026-06-14 03:50:45.064073 | testrunner | included: /var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/fetch-output-openshift/tasks/rsync.yaml
2026-06-14 03:50:45.076013 | 
2026-06-14 03:50:45.076149 | LOOP [fetch-output-openshift : Fetch zuul-output from the pod]
2026-06-14 03:50:50.254223 | testrunner | Output suppressed because no_log was given
2026-06-14 03:50:50.261920 | 
2026-06-14 03:50:50.262057 | TASK [fetch-output-openshift : Remove empty directory]
2026-06-14 03:50:50.612204 | testrunner -> localhost | ok: Runtime: 0:00:00.022606
2026-06-14 03:50:50.626045 | 
2026-06-14 03:50:50.626184 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-14 03:50:51.013092 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/work/artifacts’: No such file or directory
2026-06-14 03:50:51.013503 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/work/artifacts’: No such file or directory
2026-06-14 03:50:51.013644 | testrunner -> localhost | ok: Item: artifacts Runtime: 0:00:00.012077
2026-06-14 03:50:51.286451 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/work/docs’: No such file or directory
2026-06-14 03:50:51.286617 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/work/docs’: No such file or directory
2026-06-14 03:50:51.286667 | testrunner -> localhost | ok: Item: docs Runtime: 0:00:00.010988
2026-06-14 03:50:51.307015 | 
2026-06-14 03:50:51.307203 | PLAY [localhost]
2026-06-14 03:50:51.318735 | 
2026-06-14 03:50:51.318893 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-14 03:50:51.759316 | localhost | changed
2026-06-14 03:50:51.780422 | 
2026-06-14 03:50:51.780617 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-14 03:50:51.824661 | localhost | ok
2026-06-14 03:50:51.839094 | 
2026-06-14 03:50:51.839221 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-14 03:50:52.254554 | localhost | changed
2026-06-14 03:50:52.258370 | 
2026-06-14 03:50:52.258432 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-14 03:50:53.136641 | localhost | changed
2026-06-14 03:50:53.143948 | 
2026-06-14 03:50:53.144083 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-14 03:50:53.506128 | localhost | Identity added: /var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/work/tmp/ansible.cy1q38nr (/var/lib/zuul/builds/cabe0e39b3164cc883ac02532d1dae2f/work/tmp/ansible.cy1q38nr)
2026-06-14 03:50:53.506461 | localhost | ok: Runtime: 0:00:00.014102
2026-06-14 03:50:53.513615 | 
2026-06-14 03:50:53.513756 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-14 03:50:53.854873 | localhost | ok: Runtime: 0:00:00.008457
2026-06-14 03:50:53.862606 | 
2026-06-14 03:50:53.862729 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-14 03:50:53.949825 | localhost | changed
2026-06-14 03:50:53.956752 | 
2026-06-14 03:50:53.956884 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-14 03:50:54.391018 | localhost | changed
2026-06-14 03:50:54.396259 | 
2026-06-14 03:50:54.396312 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-14 03:50:54.399951 | 
2026-06-14 03:50:54.400010 | TASK [Gathering Facts]
2026-06-14 03:50:55.959320 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-14 03:50:55.973480 | 
2026-06-14 03:50:55.973693 | TASK [Set zuul-log-path fact]
2026-06-14 03:50:55.999652 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-14 03:50:56.019231 | 
2026-06-14 03:50:56.019374 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-14 03:50:56.059442 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-14 03:50:56.067466 | 
2026-06-14 03:50:56.067578 | TASK [upload-logs : Create log directories]
2026-06-14 03:50:56.393814 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-14 03:50:56.401323 | 
2026-06-14 03:50:56.401495 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-14 03:50:56.751873 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.009566
2026-06-14 03:50:56.761449 | 
2026-06-14 03:50:56.761628 | TASK [upload-logs : Upload logs to log server]
2026-06-14 03:50:57.848482 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given
2026-06-14 03:50:57.854174 | 
2026-06-14 03:50:57.854307 | LOOP [upload-logs : Compress console log and json output]