2025-09-21 03:00:14.593164 | Job console starting 2025-09-21 03:00:14.622444 | Updating git repos 2025-09-21 03:00:14.737224 | Cloning repos into workspace 2025-09-21 03:00:14.827379 | Restoring repo states 2025-09-21 03:00:14.887030 | Merging changes 2025-09-21 03:00:14.887051 | Checking out repos 2025-09-21 03:00:15.089125 | Preparing playbooks 2025-09-21 03:00:16.540826 | Running Ansible setup 2025-09-21 03:00:22.508653 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-21 03:00:24.082697 | 2025-09-21 03:00:24.095248 | PLAY [Base pre] 2025-09-21 03:00:24.133827 | 2025-09-21 03:00:24.133968 | TASK [Setup log path fact] 2025-09-21 03:00:24.188460 | debian-bookworm | ok 2025-09-21 03:00:24.226107 | 2025-09-21 03:00:24.226249 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-21 03:00:24.283934 | debian-bookworm | ok 2025-09-21 03:00:24.292242 | 2025-09-21 03:00:24.292351 | TASK [emit-job-header : Print job information] 2025-09-21 03:00:24.369216 | # Job Information 2025-09-21 03:00:24.369386 | Ansible Version: 2.16.14 2025-09-21 03:00:24.369415 | Job: ansible-lint 2025-09-21 03:00:24.369447 | Pipeline: periodic-daily 2025-09-21 03:00:24.369465 | Executor: 521e9411259a 2025-09-21 03:00:24.369482 | Triggered by: https://github.com/osism/ansible-collection-services 2025-09-21 03:00:24.369500 | Event ID: 94d94a4782a54e17bf08c1d6fe39c676 2025-09-21 03:00:24.373674 | 2025-09-21 03:00:24.373758 | LOOP [emit-job-header : Print node information] 2025-09-21 03:00:24.531595 | debian-bookworm | ok: 2025-09-21 03:00:24.531775 | debian-bookworm | # Node Information 2025-09-21 03:00:24.531804 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-21 03:00:24.531825 | debian-bookworm | Hostname: debian 2025-09-21 03:00:24.531844 | debian-bookworm | Username: zuul 2025-09-21 03:00:24.531861 | debian-bookworm | Distro: Debian 12.12 2025-09-21 03:00:24.531880 | debian-bookworm | Provider: regiocloud-a 2025-09-21 03:00:24.531897 | debian-bookworm | Region: 2025-09-21 03:00:24.531914 | debian-bookworm | Label: debian-bookworm 2025-09-21 03:00:24.531930 | debian-bookworm | Product Name: OpenStack Nova 2025-09-21 03:00:24.531946 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe04:4506 2025-09-21 03:00:24.563698 | 2025-09-21 03:00:24.563826 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-21 03:00:25.597500 | debian-bookworm -> localhost | changed 2025-09-21 03:00:25.606431 | 2025-09-21 03:00:25.606538 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-21 03:00:28.377108 | debian-bookworm -> localhost | changed 2025-09-21 03:00:28.386325 | 2025-09-21 03:00:28.386429 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-21 03:00:29.076765 | debian-bookworm -> localhost | ok 2025-09-21 03:00:29.090301 | 2025-09-21 03:00:29.090456 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-21 03:00:29.128060 | debian-bookworm | ok 2025-09-21 03:00:29.152339 | debian-bookworm | included: /var/lib/zuul/builds/7861949b62594358aa829e774fa7cd3d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-21 03:00:29.167616 | 2025-09-21 03:00:29.167720 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-21 03:00:30.551180 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-21 03:00:30.551398 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7861949b62594358aa829e774fa7cd3d/work/7861949b62594358aa829e774fa7cd3d_id_rsa 2025-09-21 03:00:30.551434 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7861949b62594358aa829e774fa7cd3d/work/7861949b62594358aa829e774fa7cd3d_id_rsa.pub 2025-09-21 03:00:30.551456 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-21 03:00:30.551477 | debian-bookworm -> localhost | SHA256:2F+k/GT1PF36SjYltpXszxwB4G4DNkfrSAfD4UAdNcg zuul-build-sshkey 2025-09-21 03:00:30.551496 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-21 03:00:30.551523 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-21 03:00:30.551542 | debian-bookworm -> localhost | | .o+=== | 2025-09-21 03:00:30.551561 | debian-bookworm -> localhost | | oE= + | 2025-09-21 03:00:30.551579 | debian-bookworm -> localhost | | * =... .| 2025-09-21 03:00:30.551596 | debian-bookworm -> localhost | | oo.Oo .o++| 2025-09-21 03:00:30.551613 | debian-bookworm -> localhost | | . S.o=+ +=*| 2025-09-21 03:00:30.551639 | debian-bookworm -> localhost | | ..=...*o| 2025-09-21 03:00:30.551658 | debian-bookworm -> localhost | | . . =o.| 2025-09-21 03:00:30.551675 | debian-bookworm -> localhost | | o =o| 2025-09-21 03:00:30.551694 | debian-bookworm -> localhost | | . +| 2025-09-21 03:00:30.551711 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-21 03:00:30.551754 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.327436 2025-09-21 03:00:30.558072 | 2025-09-21 03:00:30.558160 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-21 03:00:30.585342 | debian-bookworm | ok 2025-09-21 03:00:30.594480 | debian-bookworm | included: /var/lib/zuul/builds/7861949b62594358aa829e774fa7cd3d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-21 03:00:30.601797 | 2025-09-21 03:00:30.601869 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-21 03:00:30.619403 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:00:30.626099 | 2025-09-21 03:00:30.626225 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-21 03:00:31.266994 | debian-bookworm | changed 2025-09-21 03:00:31.272857 | 2025-09-21 03:00:31.272949 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-21 03:00:31.539082 | debian-bookworm | ok 2025-09-21 03:00:31.545803 | 2025-09-21 03:00:31.545930 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-21 03:00:32.270068 | debian-bookworm | changed 2025-09-21 03:00:32.276697 | 2025-09-21 03:00:32.276786 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-21 03:00:33.020470 | debian-bookworm | changed 2025-09-21 03:00:33.025756 | 2025-09-21 03:00:33.025847 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-21 03:00:33.075489 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:00:33.081296 | 2025-09-21 03:00:33.081399 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-21 03:00:33.867974 | debian-bookworm -> localhost | changed 2025-09-21 03:00:33.878250 | 2025-09-21 03:00:33.878374 | TASK [add-build-sshkey : Add back temp key] 2025-09-21 03:00:34.503429 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7861949b62594358aa829e774fa7cd3d/work/7861949b62594358aa829e774fa7cd3d_id_rsa (zuul-build-sshkey) 2025-09-21 03:00:34.503603 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017883 2025-09-21 03:00:34.509726 | 2025-09-21 03:00:34.509800 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-21 03:00:35.101286 | debian-bookworm | ok 2025-09-21 03:00:35.105444 | 2025-09-21 03:00:35.105520 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-21 03:00:35.130749 | debian-bookworm | skipping: Conditional result was False 2025-09-21 03:00:35.138819 | 2025-09-21 03:00:35.138928 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-21 03:00:35.571371 | debian-bookworm | ok 2025-09-21 03:00:35.586108 | 2025-09-21 03:00:35.586221 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-21 03:00:35.614251 | debian-bookworm | ok 2025-09-21 03:00:35.621689 | 2025-09-21 03:00:35.621775 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-21 03:00:36.023735 | debian-bookworm -> localhost | ok 2025-09-21 03:00:36.030187 | 2025-09-21 03:00:36.030274 | TASK [validate-host : Collect information about the host] 2025-09-21 03:00:37.582474 | debian-bookworm | ok 2025-09-21 03:00:37.593134 | 2025-09-21 03:00:37.593563 | TASK [validate-host : Sanitize hostname] 2025-09-21 03:00:37.696793 | debian-bookworm | ok 2025-09-21 03:00:37.729401 | 2025-09-21 03:00:37.729501 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-21 03:00:38.943744 | debian-bookworm -> localhost | changed 2025-09-21 03:00:38.948605 | 2025-09-21 03:00:38.948683 | TASK [validate-host : Collect information about zuul worker] 2025-09-21 03:00:39.371112 | debian-bookworm | ok 2025-09-21 03:00:39.375092 | 2025-09-21 03:00:39.375171 | TASK [validate-host : Write out all zuul information for each host] 2025-09-21 03:00:40.564398 | debian-bookworm -> localhost | changed 2025-09-21 03:00:40.575033 | 2025-09-21 03:00:40.575124 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-21 03:00:40.885215 | debian-bookworm | ok 2025-09-21 03:00:40.890985 | 2025-09-21 03:00:40.891073 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-21 03:00:42.721399 | debian-bookworm | changed: 2025-09-21 03:00:42.721634 | debian-bookworm | cd+++++++++ src/ 2025-09-21 03:00:42.721672 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-21 03:00:42.721697 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-21 03:00:42.721719 | debian-bookworm | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-09-21 03:00:42.721740 | debian-bookworm | {{ zuul_component_conf_dirs |  dict2items }} 2025-09-21 03:02:36.191842 | debian-bookworm | roles/zuul/tasks/main.yml:23:9 Jinja2 template rewrite recommendation: `{{ zuul_component_conf_dirs |  dict2items }}`. 2025-09-21 03:02:36.191860 | debian-bookworm | 2025-09-21 03:02:36.962764 | debian-bookworm | ERROR 2025-09-21 03:02:36.962958 | debian-bookworm | { 2025-09-21 03:02:36.962991 | debian-bookworm | "delta": "0:01:27.414716", 2025-09-21 03:02:36.963011 | debian-bookworm | "end": "2025-09-21 03:02:36.651763", 2025-09-21 03:02:36.963028 | debian-bookworm | "failed_when_result": true, 2025-09-21 03:02:36.963043 | debian-bookworm | "msg": "non-zero return code", 2025-09-21 03:02:36.963090 | debian-bookworm | "rc": 2, 2025-09-21 03:02:36.963109 | debian-bookworm | "start": "2025-09-21 03:01:09.237047" 2025-09-21 03:02:36.963123 | debian-bookworm | } failure 2025-09-21 03:02:36.964743 | 2025-09-21 03:02:36.964799 | PLAY RECAP 2025-09-21 03:02:36.964914 | debian-bookworm | ok: 6 changed: 4 unreachable: 0 failed: 1 skipped: 2 rescued: 0 ignored: 0 2025-09-21 03:02:36.964936 | 2025-09-21 03:02:37.237388 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/zuul-jobs/playbooks/ansible-lint/run.yaml@main] 2025-09-21 03:02:37.238036 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-21 03:02:39.058946 | 2025-09-21 03:02:39.059482 | PLAY [Base post] 2025-09-21 03:02:39.117763 | 2025-09-21 03:02:39.118456 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-21 03:02:40.102773 | debian-bookworm | changed 2025-09-21 03:02:40.111156 | 2025-09-21 03:02:40.115047 | PLAY RECAP 2025-09-21 03:02:40.115149 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-21 03:02:40.115205 | 2025-09-21 03:02:40.404272 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-21 03:02:40.405232 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-21 03:02:41.135183 | 2025-09-21 03:02:41.135471 | PLAY [Base post-logs] 2025-09-21 03:02:41.157954 | 2025-09-21 03:02:41.158198 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-21 03:02:41.934138 | localhost | changed 2025-09-21 03:02:41.945619 | 2025-09-21 03:02:41.946118 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-21 03:02:41.994487 | localhost | ok 2025-09-21 03:02:41.997756 | 2025-09-21 03:02:41.997868 | TASK [Set zuul-log-path fact] 2025-09-21 03:02:42.031123 | localhost | ok 2025-09-21 03:02:42.039670 | 2025-09-21 03:02:42.039878 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-21 03:02:42.098209 | localhost | ok 2025-09-21 03:02:42.101580 | 2025-09-21 03:02:42.101663 | TASK [upload-logs : Create log directories] 2025-09-21 03:02:42.860848 | localhost | changed 2025-09-21 03:02:42.863313 | 2025-09-21 03:02:42.863426 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-21 03:02:43.241682 | localhost -> localhost | ok: Runtime: 0:00:00.003743 2025-09-21 03:02:43.251144 | 2025-09-21 03:02:43.251235 | TASK [upload-logs : Upload logs to log server] 2025-09-21 03:02:43.828381 | localhost | Output suppressed because no_log was given 2025-09-21 03:02:43.832171 | 2025-09-21 03:02:43.832260 | LOOP [upload-logs : Compress console log and json output] 2025-09-21 03:02:43.873197 | localhost | skipping: Conditional result was False 2025-09-21 03:02:43.879606 | localhost | skipping: Conditional result was False 2025-09-21 03:02:43.888981 | 2025-09-21 03:02:43.889087 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-21 03:02:43.937271 | localhost | skipping: Conditional result was False 2025-09-21 03:02:43.937501 | 2025-09-21 03:02:43.944413 | localhost | skipping: Conditional result was False 2025-09-21 03:02:43.956070 | 2025-09-21 03:02:43.956178 | LOOP [upload-logs : Upload console log and json output]