2025-11-26 23:34:28.899815 | Job console starting 2025-11-26 23:34:28.910915 | Updating git repos 2025-11-26 23:34:28.960714 | Cloning repos into workspace 2025-11-26 23:34:29.017386 | Restoring repo states 2025-11-26 23:34:29.028707 | Merging changes 2025-11-26 23:34:31.188769 | Checking out repos 2025-11-26 23:34:31.230358 | Preparing playbooks 2025-11-26 23:34:31.848988 | Running Ansible setup 2025-11-26 23:34:36.895348 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-26 23:34:37.699181 | 2025-11-26 23:34:37.699367 | PLAY [Base pre] 2025-11-26 23:34:37.716085 | 2025-11-26 23:34:37.716231 | TASK [Setup log path fact] 2025-11-26 23:34:37.755151 | debian-bookworm | ok 2025-11-26 23:34:37.779772 | 2025-11-26 23:34:37.779937 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-26 23:34:37.809675 | debian-bookworm | ok 2025-11-26 23:34:37.819831 | 2025-11-26 23:34:37.819958 | TASK [emit-job-header : Print job information] 2025-11-26 23:34:37.860005 | # Job Information 2025-11-26 23:34:37.860297 | Ansible Version: 2.16.14 2025-11-26 23:34:37.860362 | Job: ansible-lint 2025-11-26 23:34:37.860400 | Pipeline: check 2025-11-26 23:34:37.860423 | Executor: 521e9411259a 2025-11-26 23:34:37.860445 | Triggered by: https://github.com/osism/ansible-collection-services/pull/2030 2025-11-26 23:34:37.860468 | Event ID: 6e2d4d20-cb20-11f0-8ad4-1e3cede8a11c 2025-11-26 23:34:37.865731 | 2025-11-26 23:34:37.865874 | LOOP [emit-job-header : Print node information] 2025-11-26 23:34:38.004094 | debian-bookworm | ok: 2025-11-26 23:34:38.006311 | debian-bookworm | # Node Information 2025-11-26 23:34:38.006433 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-11-26 23:34:38.006465 | debian-bookworm | Hostname: debian 2025-11-26 23:34:38.006489 | debian-bookworm | Username: zuul 2025-11-26 23:34:38.006512 | debian-bookworm | Distro: Debian 12.12 2025-11-26 23:34:38.006536 | debian-bookworm | Provider: regiocloud-a 2025-11-26 23:34:38.006558 | debian-bookworm | Region: 2025-11-26 23:34:38.006579 | debian-bookworm | Label: debian-bookworm 2025-11-26 23:34:38.006599 | debian-bookworm | Product Name: OpenStack Nova 2025-11-26 23:34:38.006618 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe30:cf98 2025-11-26 23:34:38.025946 | 2025-11-26 23:34:38.026091 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-26 23:34:38.832424 | debian-bookworm -> localhost | changed 2025-11-26 23:34:38.840385 | 2025-11-26 23:34:38.840687 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-26 23:34:40.733143 | debian-bookworm -> localhost | changed 2025-11-26 23:34:40.748708 | 2025-11-26 23:34:40.748848 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-26 23:34:41.182929 | debian-bookworm -> localhost | ok 2025-11-26 23:34:41.189619 | 2025-11-26 23:34:41.189755 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-26 23:34:41.239394 | debian-bookworm | ok 2025-11-26 23:34:41.267974 | debian-bookworm | included: /var/lib/zuul/builds/ef0752d8694c4e22af37c9409649d3a4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-26 23:34:41.277556 | 2025-11-26 23:34:41.277687 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-26 23:34:45.010100 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-11-26 23:34:45.010289 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ef0752d8694c4e22af37c9409649d3a4/work/ef0752d8694c4e22af37c9409649d3a4_id_rsa 2025-11-26 23:34:45.010349 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ef0752d8694c4e22af37c9409649d3a4/work/ef0752d8694c4e22af37c9409649d3a4_id_rsa.pub 2025-11-26 23:34:45.010378 | debian-bookworm -> localhost | The key fingerprint is: 2025-11-26 23:34:45.010404 | debian-bookworm -> localhost | SHA256:UsbAWPmmw5j9b0S2F1wSVaXuClCesfcMe85vVorGego zuul-build-sshkey 2025-11-26 23:34:45.010426 | debian-bookworm -> localhost | The key's randomart image is: 2025-11-26 23:34:45.010456 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-11-26 23:34:45.010477 | debian-bookworm -> localhost | | +o. .o..+| 2025-11-26 23:34:45.010499 | debian-bookworm -> localhost | | . oo . .. | 2025-11-26 23:34:45.010519 | debian-bookworm -> localhost | | .+ o. o. | 2025-11-26 23:34:45.010539 | debian-bookworm -> localhost | | oooo+o. | 2025-11-26 23:34:45.010559 | debian-bookworm -> localhost | | =.oSo+.o.. | 2025-11-26 23:34:45.010585 | debian-bookworm -> localhost | | o =. .o..* .| 2025-11-26 23:34:45.010605 | debian-bookworm -> localhost | | oE..o..=..| 2025-11-26 23:34:45.010623 | debian-bookworm -> localhost | | ....==. o| 2025-11-26 23:34:45.010643 | debian-bookworm -> localhost | | o+=. o+.| 2025-11-26 23:34:45.010663 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-11-26 23:34:45.010713 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.946774 2025-11-26 23:34:45.016841 | 2025-11-26 23:34:45.016937 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-26 23:34:45.054202 | debian-bookworm | ok 2025-11-26 23:34:45.069491 | debian-bookworm | included: /var/lib/zuul/builds/ef0752d8694c4e22af37c9409649d3a4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-26 23:34:45.088625 | 2025-11-26 23:34:45.088730 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-26 23:34:45.111571 | debian-bookworm | skipping: Conditional result was False 2025-11-26 23:34:45.117077 | 2025-11-26 23:34:45.117165 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-26 23:34:45.729999 | debian-bookworm | changed 2025-11-26 23:34:45.735720 | 2025-11-26 23:34:45.735813 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-26 23:34:45.984514 | debian-bookworm | ok 2025-11-26 23:34:45.995186 | 2025-11-26 23:34:45.995284 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-26 23:34:46.628008 | debian-bookworm | changed 2025-11-26 23:34:46.634469 | 2025-11-26 23:34:46.634564 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-26 23:34:47.288354 | debian-bookworm | changed 2025-11-26 23:34:47.294195 | 2025-11-26 23:34:47.294296 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-26 23:34:47.307247 | debian-bookworm | skipping: Conditional result was False 2025-11-26 23:34:47.314059 | 2025-11-26 23:34:47.314169 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-26 23:34:47.711998 | debian-bookworm -> localhost | changed 2025-11-26 23:34:47.723417 | 2025-11-26 23:34:47.723516 | TASK [add-build-sshkey : Add back temp key] 2025-11-26 23:34:48.024218 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ef0752d8694c4e22af37c9409649d3a4/work/ef0752d8694c4e22af37c9409649d3a4_id_rsa (zuul-build-sshkey) 2025-11-26 23:34:48.024450 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013401 2025-11-26 23:34:48.032896 | 2025-11-26 23:34:48.032993 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-26 23:34:48.414431 | debian-bookworm | ok 2025-11-26 23:34:48.425683 | 2025-11-26 23:34:48.425797 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-26 23:34:48.458926 | debian-bookworm | skipping: Conditional result was False 2025-11-26 23:34:48.470212 | 2025-11-26 23:34:48.470477 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-26 23:34:48.903063 | debian-bookworm | ok 2025-11-26 23:34:48.917243 | 2025-11-26 23:34:48.917359 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-26 23:34:48.944905 | debian-bookworm | ok 2025-11-26 23:34:48.949805 | 2025-11-26 23:34:48.949905 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-26 23:34:49.299720 | debian-bookworm -> localhost | ok 2025-11-26 23:34:49.311783 | 2025-11-26 23:34:49.311923 | TASK [validate-host : Collect information about the host] 2025-11-26 23:34:50.397114 | debian-bookworm | ok 2025-11-26 23:34:50.411253 | 2025-11-26 23:34:50.411367 | TASK [validate-host : Sanitize hostname] 2025-11-26 23:34:50.512459 | debian-bookworm | ok 2025-11-26 23:34:50.558120 | 2025-11-26 23:34:50.558230 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-26 23:34:51.085341 | debian-bookworm -> localhost | changed 2025-11-26 23:34:51.090711 | 2025-11-26 23:34:51.090809 | TASK [validate-host : Collect information about zuul worker] 2025-11-26 23:34:51.524772 | debian-bookworm | ok 2025-11-26 23:34:51.530665 | 2025-11-26 23:34:51.530765 | TASK [validate-host : Write out all zuul information for each host] 2025-11-26 23:34:52.130311 | debian-bookworm -> localhost | changed 2025-11-26 23:34:52.139154 | 2025-11-26 23:34:52.139241 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-26 23:34:52.426251 | debian-bookworm | ok 2025-11-26 23:34:52.432171 | 2025-11-26 23:34:52.432262 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-26 23:34:53.722365 | debian-bookworm | changed: 2025-11-26 23:34:53.722581 | debian-bookworm | cd+++++++++ src/ 2025-11-26 23:34:53.722615 | debian-bookworm | cd+++++++++ src/github.com/ 2025-11-26 23:34:53.722640 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-11-26 23:34:53.722663 | debian-bookworm | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-11-26 23:34:53.722683 | debian-bookworm | {{ {'http-proxy': docker_proxy_http, 'https-proxy': docker_proxy_https, 'no-proxy': docker_proxy_no_proxy | default([]) | join(',')} if (docker_configure_proxy | default(false) | bool) else {} }} 2025-11-26 23:36:59.978874 | debian-bookworm | roles/docker/defaults/main.yml:194:12 Jinja2 template rewrite recommendation: `{{ {'http-proxy': docker_proxy_http, 'https-proxy': docker_proxy_https, 'no-proxy': docker_proxy_no_proxy | default([]) | join(',')} if (docker_configure_proxy | default(false) | bool) else {} }}`. 2025-11-26 23:36:59.978886 | debian-bookworm | 2025-11-26 23:36:59.978915 | debian-bookworm | jinja[spacing]: Jinja2 spacing could be improved: {{ docker_experimental | bool }} -> {{ docker_experimental | bool }} 2025-11-26 23:36:59.978925 | debian-bookworm | roles/docker/defaults/main.yml:198:17 Jinja2 template rewrite recommendation: `{{ docker_experimental | bool }}`. 2025-11-26 23:36:59.978934 | debian-bookworm | 2025-11-26 23:36:59.978945 | debian-bookworm | jinja[spacing]: Jinja2 spacing could be improved: {{ docker_log_opts if (docker_log_opts is defined) else {} | combine({ 'max-file': docker_log_opts['max-file'] | string } if (docker_log_opts['max-file'] is defined) else {} ) | combine({ 'cache-max-file': docker_log_opts['cache-max-file'] | string } if (docker_log_opts['cache-max-file'] is defined) else {} )}} -> {{ docker_log_opts if (docker_log_opts is defined) else {} | combine({'max-file': docker_log_opts['max-file'] | string} if (docker_log_opts['max-file'] is defined) else {}) | combine({'cache-max-file': docker_log_opts['cache-max-file'] | string} if (docker_log_opts['cache-max-file'] is defined) else {}) }} 2025-11-26 23:36:59.978962 | debian-bookworm | roles/docker/defaults/main.yml:206:14 Jinja2 template rewrite recommendation: `{{ docker_log_opts if (docker_log_opts is defined) else {} | combine({'max-file': docker_log_opts['max-file'] | string} if (docker_log_opts['max-file'] is defined) else {}) | combine({'cache-max-file': docker_log_opts['cache-max-file'] | string} if (docker_log_opts['cache-max-file'] is defined) else {}) }}`. 2025-11-26 23:36:59.978976 | debian-bookworm | 2025-11-26 23:36:59.979025 | debian-bookworm | Read documentation for instructions on how to ignore specific rule violations. 2025-11-26 23:36:59.979041 | debian-bookworm | 2025-11-26 23:36:59.979050 | debian-bookworm | # Rule Violation Summary 2025-11-26 23:36:59.979059 | debian-bookworm | 2025-11-26 23:36:59.979084 | debian-bookworm | 3 jinja profile:basic tags:formatting 2025-11-26 23:36:59.979093 | debian-bookworm | 2025-11-26 23:36:59.979101 | debian-bookworm | Failed: 3 failure(s), 0 warning(s) in 744 files processed of 817 encountered. Last profile that met the validation criteria was 'min'. 2025-11-26 23:37:00.679597 | debian-bookworm | ERROR 2025-11-26 23:37:00.679991 | debian-bookworm | { 2025-11-26 23:37:00.680076 | debian-bookworm | "delta": "0:01:40.236733", 2025-11-26 23:37:00.680133 | debian-bookworm | "end": "2025-11-26 23:37:00.604554", 2025-11-26 23:37:00.680180 | debian-bookworm | "failed_when_result": true, 2025-11-26 23:37:00.680223 | debian-bookworm | "msg": "non-zero return code", 2025-11-26 23:37:00.680264 | debian-bookworm | "rc": 2, 2025-11-26 23:37:00.680304 | debian-bookworm | "start": "2025-11-26 23:35:20.367821" 2025-11-26 23:37:00.680346 | debian-bookworm | } failure 2025-11-26 23:37:00.684719 | 2025-11-26 23:37:00.684908 | PLAY RECAP 2025-11-26 23:37:00.685034 | debian-bookworm | ok: 6 changed: 4 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2025-11-26 23:37:00.685096 | 2025-11-26 23:37:00.837326 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/zuul-jobs/playbooks/ansible-lint/run.yaml@main] 2025-11-26 23:37:00.839535 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-26 23:37:01.663608 | 2025-11-26 23:37:01.663776 | PLAY [Base post] 2025-11-26 23:37:01.677656 | 2025-11-26 23:37:01.677803 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-26 23:37:02.238499 | debian-bookworm | changed 2025-11-26 23:37:02.245260 | 2025-11-26 23:37:02.245392 | PLAY RECAP 2025-11-26 23:37:02.245502 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-26 23:37:02.245568 | 2025-11-26 23:37:02.364371 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-26 23:37:02.366394 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-26 23:37:03.157814 | 2025-11-26 23:37:03.157982 | PLAY [Base post-logs] 2025-11-26 23:37:03.168845 | 2025-11-26 23:37:03.168977 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-26 23:37:03.644163 | localhost | changed 2025-11-26 23:37:03.663200 | 2025-11-26 23:37:03.663423 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-26 23:37:03.701931 | localhost | ok 2025-11-26 23:37:03.709117 | 2025-11-26 23:37:03.709297 | TASK [Set zuul-log-path fact] 2025-11-26 23:37:03.728781 | localhost | ok 2025-11-26 23:37:03.745114 | 2025-11-26 23:37:03.745280 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-26 23:37:03.773150 | localhost | ok 2025-11-26 23:37:03.780583 | 2025-11-26 23:37:03.780782 | TASK [upload-logs : Create log directories] 2025-11-26 23:37:04.320361 | localhost | changed 2025-11-26 23:37:04.325766 | 2025-11-26 23:37:04.325922 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-26 23:37:04.837430 | localhost -> localhost | ok: Runtime: 0:00:00.006886 2025-11-26 23:37:04.842650 | 2025-11-26 23:37:04.842793 | TASK [upload-logs : Upload logs to log server] 2025-11-26 23:37:05.396729 | localhost | Output suppressed because no_log was given 2025-11-26 23:37:05.398689 | 2025-11-26 23:37:05.398795 | LOOP [upload-logs : Compress console log and json output] 2025-11-26 23:37:05.447299 | localhost | skipping: Conditional result was False 2025-11-26 23:37:05.453089 | localhost | skipping: Conditional result was False 2025-11-26 23:37:05.460158 | 2025-11-26 23:37:05.460366 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-26 23:37:05.510488 | localhost | skipping: Conditional result was False 2025-11-26 23:37:05.511085 | 2025-11-26 23:37:05.514633 | localhost | skipping: Conditional result was False 2025-11-26 23:37:05.518771 | 2025-11-26 23:37:05.518914 | LOOP [upload-logs : Upload console log and json output]