2026-06-12 16:20:43.586835 | Job console starting
2026-06-12 16:20:43.721520 | Updating git repos
2026-06-12 16:20:43.754422 | Cloning repos into workspace
2026-06-12 16:20:45.033967 | Restoring repo states
2026-06-12 16:20:45.069950 | Merging changes
2026-06-12 16:20:45.845376 | Checking out repos
2026-06-12 16:20:46.026395 | Preparing playbooks
2026-06-12 16:20:47.738572 | Running Ansible setup
2026-06-12 16:20:55.393188 | PRE-RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/base/pre.yaml@main]
2026-06-12 16:20:56.065642 | 
2026-06-12 16:20:56.065781 | PLAY [localhost]
2026-06-12 16:20:56.073171 | 
2026-06-12 16:20:56.073245 | TASK [Gathering Facts]
2026-06-12 16:20:57.034933 | localhost | ok
2026-06-12 16:20:57.053438 | 
2026-06-12 16:20:57.053535 | TASK [Setup log path fact]
2026-06-12 16:20:57.074072 | localhost | ok
2026-06-12 16:20:57.089843 | 
2026-06-12 16:20:57.089933 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-12 16:20:57.128685 | localhost | ok
2026-06-12 16:20:57.140100 | 
2026-06-12 16:20:57.140237 | TASK [emit-job-header : Print job information]
2026-06-12 16:20:57.191739 | # Job Information
2026-06-12 16:20:57.192156 | Ansible Version: 2.16.18
2026-06-12 16:20:57.192243 | Job: dci-agent-syntax-check-job
2026-06-12 16:20:57.192290 | Pipeline: check
2026-06-12 16:20:57.192332 | Executor: 38.102.83.40
2026-06-12 16:20:57.192372 | Triggered by: https://github.com/distributedci/dci-pipeline/pull/39
2026-06-12 16:20:57.192420 | Event ID: 937cd0f0-667a-11f1-8294-f0a9b65889ab
2026-06-12 16:20:57.198539 | 
2026-06-12 16:20:57.198700 | LOOP [emit-job-header : Print node information]
2026-06-12 16:20:57.293852 | localhost | ok:
2026-06-12 16:20:57.294169 | localhost | # Node Information
2026-06-12 16:20:57.294225 | localhost | Inventory Hostname: testrunner
2026-06-12 16:20:57.294268 | localhost | Hostname: cee06eedfa91
2026-06-12 16:20:57.294307 | localhost | Username: zuul
2026-06-12 16:20:57.294350 | localhost | Distro: CentOS 9
2026-06-12 16:20:57.294388 | localhost | Provider: k1s05
2026-06-12 16:20:57.294426 | localhost | Region:
2026-06-12 16:20:57.294483 | localhost | Label: pod-centos-9-stream
2026-06-12 16:20:57.294524 | localhost | Product Name: OpenStack Nova
2026-06-12 16:20:57.294571 | localhost | Interface IP: pod-centos-9-stream-0000072173
2026-06-12 16:20:57.307172 | 
2026-06-12 16:20:57.307398 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-12 16:20:57.790083 | localhost -> localhost | changed
2026-06-12 16:20:57.799354 | 
2026-06-12 16:20:57.799491 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-12 16:20:58.808675 | localhost -> localhost | changed
2026-06-12 16:20:58.820685 | 
2026-06-12 16:20:58.820810 | PLAY [all]
2026-06-12 16:20:58.832918 | 
2026-06-12 16:20:58.833055 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-12 16:21:04.806229 | testrunner | ok
2026-06-12 16:21:04.816242 | 
2026-06-12 16:21:04.816346 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-12 16:21:04.851379 | testrunner | skipping: Conditional result was False
2026-06-12 16:21:04.858969 | 
2026-06-12 16:21:04.859106 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-12 16:21:04.895495 | testrunner | skipping: Conditional result was False
2026-06-12 16:21:04.903344 | 
2026-06-12 16:21:04.903478 | TASK [validate-host : Collect information about the host]
2026-06-12 16:21:04.940278 | testrunner | skipping: Conditional result was False
2026-06-12 16:21:04.950825 | 
2026-06-12 16:21:04.950966 | TASK [validate-host : Sanitize hostname]
2026-06-12 16:21:04.986854 | testrunner | skipping: Conditional result was False
2026-06-12 16:21:04.995738 | 
2026-06-12 16:21:04.995889 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-12 16:21:05.042721 | testrunner | skipping: Conditional result was False
2026-06-12 16:21:05.051153 | 
2026-06-12 16:21:05.051331 | TASK [validate-host : Collect information about zuul worker]
2026-06-12 16:21:05.069468 | testrunner | skipping: Conditional result was False
2026-06-12 16:21:05.077514 | 
2026-06-12 16:21:05.077684 | TASK [validate-host : Write out all zuul information for each host]
2026-06-12 16:21:05.103267 | testrunner | skipping: Conditional result was False
2026-06-12 16:21:05.116835 | 
2026-06-12 16:21:05.116971 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-06-12 16:21:05.144488 | testrunner | skipping: Conditional result was False
2026-06-12 16:21:05.155466 | 
2026-06-12 16:21:05.155634 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-06-12 16:21:05.195704 | 
2026-06-12 16:21:05.196005 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-12 16:21:05.232359 | testrunner | skipping: Conditional result was False
2026-06-12 16:21:05.240721 | 
2026-06-12 16:21:05.240861 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-12 16:21:05.278512 | testrunner | skipping: Conditional result was False
2026-06-12 16:21:05.296216 | 
2026-06-12 16:21:05.296354 | LOOP [prepare-workspace-openshift : Include rsync tasks]
2026-06-12 16:21:05.373859 | testrunner | ok:
2026-06-12 16:21:05.375555 | testrunner | [
2026-06-12 16:21:05.375766 | testrunner |   "testrunner",
2026-06-12 16:21:05.375906 | testrunner |   {
2026-06-12 16:21:05.375957 | testrunner |     "context": "main:zuul-worker/38-102-83-186:9023",
2026-06-12 16:21:05.376044 | testrunner |     "namespace": "main",
2026-06-12 16:21:05.376130 | testrunner |     "pod": "pod-centos-9-stream-0000072173",
2026-06-12 16:21:05.376215 | testrunner |     "resources": {
2026-06-12 16:21:05.376295 | testrunner |       "cores": 0,
2026-06-12 16:21:05.376339 | testrunner |       "instances": 1,
2026-06-12 16:21:05.376372 | testrunner |       "ram": 0
2026-06-12 16:21:05.376404 | testrunner |     },
2026-06-12 16:21:05.376437 | testrunner |     "stream_port1": 46237,
2026-06-12 16:21:05.376470 | testrunner |     "stream_port2": 45433
2026-06-12 16:21:05.376504 | testrunner |   }
2026-06-12 16:21:05.376537 | testrunner | ]
2026-06-12 16:21:05.376653 | testrunner | ok: All items complete
2026-06-12 16:21:05.376709 | 
2026-06-12 16:21:05.395102 | testrunner | included: /var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/prepare-workspace-openshift/tasks/rsync.yaml
2026-06-12 16:21:05.408942 | 
2026-06-12 16:21:05.409068 | TASK [prepare-workspace-openshift : Create src directory]
2026-06-12 16:21:06.920293 | testrunner -> localhost | ok: Runtime: 0:00:00.962094
2026-06-12 16:21:06.932643 | 
2026-06-12 16:21:06.932838 | TASK [prepare-workspace-openshift : Copy src repos to the pod]
2026-06-12 16:21:09.074925 | testrunner -> localhost | Output suppressed because no_log was given
2026-06-12 16:21:09.090060 | 
2026-06-12 16:21:09.090191 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-12 16:21:09.583100 | testrunner -> localhost | changed
2026-06-12 16:21:09.601828 | 
2026-06-12 16:21:09.602052 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-12 16:21:15.517304 | testrunner | ok: "logs"
2026-06-12 16:21:21.388088 | testrunner | ok: "artifacts"
2026-06-12 16:21:27.516917 | testrunner | ok: "docs"
2026-06-12 16:21:27.531324 | 
2026-06-12 16:21:27.531493 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-12 16:21:33.744561 | testrunner | changed: "logs"
2026-06-12 16:21:33.744946 | testrunner | ok: All items complete
2026-06-12 16:21:33.744985 | 
2026-06-12 16:21:39.657935 | testrunner | changed: "artifacts"
2026-06-12 16:21:45.613549 | testrunner | changed: "docs"
2026-06-12 16:21:45.634864 | 
2026-06-12 16:21:45.635073 | PLAY RECAP
2026-06-12 16:21:45.635180 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-12 16:21:45.635242 | testrunner | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 11 rescued: 0 ignored: 0
2026-06-12 16:21:45.635281 | 
2026-06-12 16:21:45.819608 | PRE-RUN END RESULT_NORMAL: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/base/pre.yaml@main]
2026-06-12 16:21:45.821819 | PRE-RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/pre.yaml@main]
2026-06-12 16:21:46.489241 | 
2026-06-12 16:21:46.489543 | PLAY [testrunner]
2026-06-12 16:21:46.498562 | 
2026-06-12 16:21:46.498651 | TASK [Remove any ansible installation from the host, it will be installed via pip later]
2026-06-12 16:22:04.517434 | testrunner | ok: Nothing to do
2026-06-12 16:22:04.524818 | 
2026-06-12 16:22:04.524966 | TASK [Check if ansible-lint is enabled]
2026-06-12 16:22:10.605982 | testrunner | ok
2026-06-12 16:22:10.612415 | 
2026-06-12 16:22:10.612550 | TASK [Install ansible-lint modules if ansible-lint is enabled]
2026-06-12 16:22:10.639221 | testrunner | skipping: Conditional result was False
2026-06-12 16:22:10.646160 | 
2026-06-12 16:22:10.646291 | TASK [Install dci-ansible test requirements]
2026-06-12 16:22:51.091531 | testrunner | changed
2026-06-12 16:22:51.100262 | 
2026-06-12 16:22:51.100465 | TASK [Check if test/requirements.yml is defined]
2026-06-12 16:22:57.058817 | testrunner | ok
2026-06-12 16:22:57.065192 | 
2026-06-12 16:22:57.065324 | TASK [Install ansible-galaxy]
2026-06-12 16:22:57.091557 | testrunner | skipping: Conditional result was False
2026-06-12 16:22:57.099818 | 
2026-06-12 16:22:57.099951 | TASK [Install ansible role modules]
2026-06-12 16:22:57.689042 | testrunner | skipping: Conditional result was False
2026-06-12 16:22:57.696341 | 
2026-06-12 16:22:57.696500 | TASK [Install ansible collection modules]
2026-06-12 16:22:58.189142 | testrunner | skipping: Conditional result was False
2026-06-12 16:22:58.190530 | 
2026-06-12 16:22:58.190602 | PLAY RECAP
2026-06-12 16:22:58.190665 | testrunner | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2026-06-12 16:22:58.190689 | 
2026-06-12 16:22:58.334745 | PRE-RUN END RESULT_NORMAL: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/pre.yaml@main]
2026-06-12 16:22:58.336097 | RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/main.yaml@main]
2026-06-12 16:22:58.965841 | 
2026-06-12 16:22:58.965981 | PLAY [testrunner]
2026-06-12 16:22:58.975151 | 
2026-06-12 16:22:58.975239 | TASK [Debug project_name]
2026-06-12 16:22:59.057043 | testrunner | ok
2026-06-12 16:22:59.063119 | 
2026-06-12 16:22:59.063266 | TASK [Check requirements.yml]
2026-06-12 16:23:05.082943 | testrunner | ok
2026-06-12 16:23:05.090323 | 
2026-06-12 16:23:05.090459 | TASK [Install dependencies]
2026-06-12 16:23:05.401417 | testrunner | skipping: Conditional result was False
2026-06-12 16:23:05.409538 | 
2026-06-12 16:23:05.409710 | TASK [Test if main playbook exists]
2026-06-12 16:23:13.990604 | testrunner | ok
2026-06-12 16:23:13.997437 | 
2026-06-12 16:23:13.997604 | TASK [Run syntax checks]
2026-06-12 16:23:14.304521 | testrunner | skipping: Conditional result was False
2026-06-12 16:23:14.313994 | 
2026-06-12 16:23:14.314129 | TASK [Check if ansible-lint is enabled]
2026-06-12 16:23:20.382955 | testrunner | ok
2026-06-12 16:23:20.393797 | 
2026-06-12 16:23:20.393925 | TASK [Run ansible-lint]
2026-06-12 16:23:20.690785 | testrunner | skipping: Conditional result was False
2026-06-12 16:23:20.692374 | 
2026-06-12 16:23:20.692437 | PLAY RECAP
2026-06-12 16:23:20.692500 | testrunner | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-06-12 16:23:20.692527 | 
2026-06-12 16:23:20.813413 | RUN END RESULT_NORMAL: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/agent-syntax/main.yaml@main]
2026-06-12 16:23:20.815843 | POST-RUN START: [trusted : gitlab.com/softwarefactory-project/centosinfra-prod/dci-config/playbooks/base/post.yaml@main]
2026-06-12 16:23:21.450626 | 
2026-06-12 16:23:21.450740 | PLAY [all]
2026-06-12 16:23:21.459099 | 
2026-06-12 16:23:21.459165 | TASK [include_role : fetch-output]
2026-06-12 16:23:21.483454 | testrunner | skipping: Conditional result was False
2026-06-12 16:23:21.488321 | 
2026-06-12 16:23:21.488390 | TASK [include_role : fetch-output-openshift]
2026-06-12 16:23:21.527836 | testrunner | ok
2026-06-12 16:23:21.543372 | 
2026-06-12 16:23:21.543463 | TASK [fetch-output-openshift : Set log path for multiple nodes]
2026-06-12 16:23:21.618824 | testrunner | skipping: Conditional result was False
2026-06-12 16:23:21.626345 | 
2026-06-12 16:23:21.626486 | TASK [fetch-output-openshift : Set log path for single node]
2026-06-12 16:23:21.678881 | testrunner | ok
2026-06-12 16:23:21.685132 | 
2026-06-12 16:23:21.685261 | LOOP [fetch-output-openshift : Ensure local output dirs]
2026-06-12 16:23:22.122430 | testrunner -> localhost | changed: "/var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/work/logs"
2026-06-12 16:23:22.372092 | testrunner -> localhost | changed: "/var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/work/logs/npm"
2026-06-12 16:23:22.663015 | testrunner -> localhost | changed: "/var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/work/artifacts"
2026-06-12 16:23:22.950188 | testrunner -> localhost | changed: "/var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/work/docs"
2026-06-12 16:23:22.963854 | 
2026-06-12 16:23:22.964011 | LOOP [fetch-output-openshift : Include rsync tasks]
2026-06-12 16:23:23.053123 | testrunner | ok:
2026-06-12 16:23:23.053382 | testrunner | [
2026-06-12 16:23:23.053438 | testrunner |   "testrunner",
2026-06-12 16:23:23.053479 | testrunner |   {
2026-06-12 16:23:23.053517 | testrunner |     "context": "main:zuul-worker/38-102-83-186:9023",
2026-06-12 16:23:23.053555 | testrunner |     "namespace": "main",
2026-06-12 16:23:23.053634 | testrunner |     "pod": "pod-centos-9-stream-0000072173",
2026-06-12 16:23:23.053675 | testrunner |     "resources": {
2026-06-12 16:23:23.053711 | testrunner |       "cores": 0,
2026-06-12 16:23:23.053746 | testrunner |       "instances": 1,
2026-06-12 16:23:23.053781 | testrunner |       "ram": 0
2026-06-12 16:23:23.053820 | testrunner |     },
2026-06-12 16:23:23.053862 | testrunner |     "stream_port1": 46237,
2026-06-12 16:23:23.053899 | testrunner |     "stream_port2": 45433
2026-06-12 16:23:23.053935 | testrunner |   }
2026-06-12 16:23:23.053969 | testrunner | ]
2026-06-12 16:23:23.054024 | testrunner | changed: All items complete
2026-06-12 16:23:23.054066 | 
2026-06-12 16:23:23.071231 | testrunner | included: /var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/fetch-output-openshift/tasks/rsync.yaml
2026-06-12 16:23:23.083071 | 
2026-06-12 16:23:23.083202 | LOOP [fetch-output-openshift : Fetch zuul-output from the pod]
2026-06-12 16:23:28.597294 | testrunner | Output suppressed because no_log was given
2026-06-12 16:23:28.604790 | 
2026-06-12 16:23:28.604934 | TASK [fetch-output-openshift : Remove empty directory]
2026-06-12 16:23:28.963095 | testrunner -> localhost | ok: Runtime: 0:00:00.025946
2026-06-12 16:23:28.977990 | 
2026-06-12 16:23:28.978117 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-12 16:23:29.390359 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/work/artifacts’: No such file or directory
2026-06-12 16:23:29.390838 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/work/artifacts’: No such file or directory
2026-06-12 16:23:29.390937 | testrunner -> localhost | ok: Item: artifacts Runtime: 0:00:00.015114
2026-06-12 16:23:29.696907 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/work/docs’: No such file or directory
2026-06-12 16:23:29.697050 | testrunner -> localhost | find: ‘/var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/work/docs’: No such file or directory
2026-06-12 16:23:29.697097 | testrunner -> localhost | ok: Item: docs Runtime: 0:00:00.013909
2026-06-12 16:23:29.714920 | 
2026-06-12 16:23:29.715039 | PLAY [localhost]
2026-06-12 16:23:29.729682 | 
2026-06-12 16:23:29.729814 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-12 16:23:30.169794 | localhost | changed
2026-06-12 16:23:30.192778 | 
2026-06-12 16:23:30.192922 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-12 16:23:30.241460 | localhost | ok
2026-06-12 16:23:30.257525 | 
2026-06-12 16:23:30.257731 | TASK [add-fileserver : Create SSH private key tempfile]
2026-06-12 16:23:30.753872 | localhost | changed
2026-06-12 16:23:30.758925 | 
2026-06-12 16:23:30.759008 | TASK [add-fileserver : Create SSH private key from secret]
2026-06-12 16:23:31.602224 | localhost | changed
2026-06-12 16:23:31.609767 | 
2026-06-12 16:23:31.609898 | TASK [add-fileserver : Add fileserver ssh key]
2026-06-12 16:23:31.955505 | localhost | Identity added: /var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/work/tmp/ansible.bt9otkyw (/var/lib/zuul/builds/a05fdfc8072347229918d08efa31d9e2/work/tmp/ansible.bt9otkyw)
2026-06-12 16:23:31.956233 | localhost | ok: Runtime: 0:00:00.020734
2026-06-12 16:23:31.963731 | 
2026-06-12 16:23:31.963900 | TASK [add-fileserver : Remove SSH private key from disk]
2026-06-12 16:23:32.380341 | localhost | ok: Runtime: 0:00:00.012034
2026-06-12 16:23:32.389282 | 
2026-06-12 16:23:32.389415 | TASK [add-fileserver : Add fileserver to inventory]
2026-06-12 16:23:32.476249 | localhost | changed
2026-06-12 16:23:32.483766 | 
2026-06-12 16:23:32.483898 | TASK [add-fileserver : Add fileserver server to known hosts]
2026-06-12 16:23:32.931776 | localhost | changed
2026-06-12 16:23:32.943264 | 
2026-06-12 16:23:32.943389 | PLAY [a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com]
2026-06-12 16:23:32.950689 | 
2026-06-12 16:23:32.950816 | TASK [Gathering Facts]
2026-06-12 16:23:34.459528 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-12 16:23:34.468362 | 
2026-06-12 16:23:34.468444 | TASK [Set zuul-log-path fact]
2026-06-12 16:23:34.500846 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-12 16:23:34.514496 | 
2026-06-12 16:23:34.514625 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-12 16:23:34.556928 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | ok
2026-06-12 16:23:34.565721 | 
2026-06-12 16:23:34.565799 | TASK [upload-logs : Create log directories]
2026-06-12 16:23:34.914669 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | changed
2026-06-12 16:23:34.922913 | 
2026-06-12 16:23:34.923047 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-06-12 16:23:35.271183 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com -> localhost | ok: Runtime: 0:00:00.007461
2026-06-12 16:23:35.280200 | 
2026-06-12 16:23:35.280338 | TASK [upload-logs : Upload logs to log server]
2026-06-12 16:23:36.469184 | a71a93e18f1524007a0994a866a4da93-48298913.us-east-1.elb.amazonaws.com | Output suppressed because no_log was given
2026-06-12 16:23:36.475097 | 
2026-06-12 16:23:36.475228 | LOOP [upload-logs : Compress console log and json output]