2025-09-20 04:30:54.675127 | Job console starting 2025-09-20 04:30:54.704162 | Updating git repos 2025-09-20 04:30:54.743851 | Cloning repos into workspace 2025-09-20 04:30:54.808136 | Restoring repo states 2025-09-20 04:30:54.817986 | Merging changes 2025-09-20 04:30:55.369318 | Checking out repos 2025-09-20 04:30:55.420679 | Preparing playbooks 2025-09-20 04:30:56.070681 | Running Ansible setup 2025-09-20 04:32:20.544921 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-20 04:32:21.319029 | 2025-09-20 04:32:21.319228 | PLAY [Base pre] 2025-09-20 04:32:21.336082 | 2025-09-20 04:32:21.336247 | TASK [Setup log path fact] 2025-09-20 04:32:21.366893 | debian-bookworm | ok 2025-09-20 04:32:21.381963 | 2025-09-20 04:32:21.382093 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-20 04:32:21.426364 | debian-bookworm | ok 2025-09-20 04:32:21.438507 | 2025-09-20 04:32:21.438621 | TASK [emit-job-header : Print job information] 2025-09-20 04:32:21.485356 | # Job Information 2025-09-20 04:32:21.485639 | Ansible Version: 2.16.14 2025-09-20 04:32:21.485700 | Job: flake8 2025-09-20 04:32:21.485758 | Pipeline: check 2025-09-20 04:32:21.485799 | Executor: 521e9411259a 2025-09-20 04:32:21.485836 | Triggered by: https://github.com/osism/container-images/pull/812 2025-09-20 04:32:21.485897 | Event ID: 940fd4c0-95da-11f0-934b-8fa8e297589b 2025-09-20 04:32:21.493310 | 2025-09-20 04:32:21.493441 | LOOP [emit-job-header : Print node information] 2025-09-20 04:32:21.607804 | debian-bookworm | ok: 2025-09-20 04:32:21.608184 | debian-bookworm | # Node Information 2025-09-20 04:32:21.608247 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-20 04:32:21.608294 | debian-bookworm | Hostname: debian 2025-09-20 04:32:21.608332 | debian-bookworm | Username: zuul 2025-09-20 04:32:21.608367 | debian-bookworm | Distro: Debian 12.12 2025-09-20 04:32:21.608408 | debian-bookworm | Provider: regiocloud-a 2025-09-20 04:32:21.608444 | debian-bookworm | Region: 2025-09-20 04:32:21.608480 | debian-bookworm | Label: debian-bookworm 2025-09-20 04:32:21.608515 | debian-bookworm | Product Name: OpenStack Nova 2025-09-20 04:32:21.608550 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fedb:f6da 2025-09-20 04:32:21.632143 | 2025-09-20 04:32:21.632302 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-20 04:32:22.104065 | debian-bookworm -> localhost | changed 2025-09-20 04:32:22.117824 | 2025-09-20 04:32:22.118018 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-20 04:32:23.133130 | debian-bookworm -> localhost | changed 2025-09-20 04:32:23.154969 | 2025-09-20 04:32:23.155156 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-20 04:32:23.445539 | debian-bookworm -> localhost | ok 2025-09-20 04:32:23.460334 | 2025-09-20 04:32:23.460526 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-20 04:32:23.497836 | debian-bookworm | ok 2025-09-20 04:32:23.521678 | debian-bookworm | included: /var/lib/zuul/builds/e203ba66140f408b996b5b0f6dfd0d41/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-20 04:32:23.527951 | 2025-09-20 04:32:23.528052 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-20 04:32:25.167009 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-20 04:32:25.167635 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/e203ba66140f408b996b5b0f6dfd0d41/work/e203ba66140f408b996b5b0f6dfd0d41_id_rsa 2025-09-20 04:32:25.167741 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/e203ba66140f408b996b5b0f6dfd0d41/work/e203ba66140f408b996b5b0f6dfd0d41_id_rsa.pub 2025-09-20 04:32:25.167809 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-20 04:32:25.167895 | debian-bookworm -> localhost | SHA256:fbdIrkMgdvH3GQp1WrzzXGwY0jrxuem/dXspHGGLoCM zuul-build-sshkey 2025-09-20 04:32:25.167955 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-20 04:32:25.168040 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-20 04:32:25.168096 | debian-bookworm -> localhost | | o | 2025-09-20 04:32:25.168151 | debian-bookworm -> localhost | | . + * | 2025-09-20 04:32:25.168201 | debian-bookworm -> localhost | | o . O * | 2025-09-20 04:32:25.168251 | debian-bookworm -> localhost | | o ooo =oB +| 2025-09-20 04:32:25.168301 | debian-bookworm -> localhost | | . oS.oo=++X.| 2025-09-20 04:32:25.168357 | debian-bookworm -> localhost | | E o .=.==.o| 2025-09-20 04:32:25.168411 | debian-bookworm -> localhost | | . .. +.o +| 2025-09-20 04:32:25.168460 | debian-bookworm -> localhost | | .. o..=| 2025-09-20 04:32:25.168512 | debian-bookworm -> localhost | | .. .++| 2025-09-20 04:32:25.168562 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-20 04:32:25.168694 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.142241 2025-09-20 04:32:25.181417 | 2025-09-20 04:32:25.181564 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-20 04:32:25.217923 | debian-bookworm | ok 2025-09-20 04:32:25.231214 | debian-bookworm | included: /var/lib/zuul/builds/e203ba66140f408b996b5b0f6dfd0d41/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-20 04:32:25.240760 | 2025-09-20 04:32:25.240882 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-20 04:32:25.275430 | debian-bookworm | skipping: Conditional result was False 2025-09-20 04:32:25.284202 | 2025-09-20 04:32:25.284325 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-20 04:32:25.943308 | debian-bookworm | changed 2025-09-20 04:32:25.953260 | 2025-09-20 04:32:25.953387 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-20 04:32:26.224946 | debian-bookworm | ok 2025-09-20 04:32:26.233766 | 2025-09-20 04:32:26.233939 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-20 04:32:26.919655 | debian-bookworm | changed 2025-09-20 04:32:26.930275 | 2025-09-20 04:32:26.930436 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-20 04:32:27.617477 | debian-bookworm | changed 2025-09-20 04:32:27.625089 | 2025-09-20 04:32:27.625217 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-20 04:32:27.660398 | debian-bookworm | skipping: Conditional result was False 2025-09-20 04:32:27.673321 | 2025-09-20 04:32:27.673493 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-20 04:32:28.154307 | debian-bookworm -> localhost | changed 2025-09-20 04:32:28.165869 | 2025-09-20 04:32:28.166005 | TASK [add-build-sshkey : Add back temp key] 2025-09-20 04:32:28.524952 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/e203ba66140f408b996b5b0f6dfd0d41/work/e203ba66140f408b996b5b0f6dfd0d41_id_rsa (zuul-build-sshkey) 2025-09-20 04:32:28.525544 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017898 2025-09-20 04:32:28.543649 | 2025-09-20 04:32:28.543817 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-20 04:32:28.957158 | debian-bookworm | ok 2025-09-20 04:32:28.965299 | 2025-09-20 04:32:28.965454 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-20 04:32:28.991965 | debian-bookworm | skipping: Conditional result was False 2025-09-20 04:32:29.012445 | 2025-09-20 04:32:29.012600 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-20 04:32:29.467090 | debian-bookworm | ok 2025-09-20 04:32:29.481160 | 2025-09-20 04:32:29.481288 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-20 04:32:29.527476 | debian-bookworm | ok 2025-09-20 04:32:29.535928 | 2025-09-20 04:32:29.536068 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-20 04:32:29.844278 | debian-bookworm -> localhost | ok 2025-09-20 04:32:29.851729 | 2025-09-20 04:32:29.851901 | TASK [validate-host : Collect information about the host] 2025-09-20 04:33:10.992237 | debian-bookworm | ok 2025-09-20 04:33:11.005987 | 2025-09-20 04:33:11.006125 | TASK [validate-host : Sanitize hostname] 2025-09-20 04:33:11.136558 | debian-bookworm | ok 2025-09-20 04:33:11.184708 | 2025-09-20 04:33:11.184911 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-20 04:33:11.757365 | debian-bookworm -> localhost | changed 2025-09-20 04:33:11.771914 | 2025-09-20 04:33:11.772128 | TASK [validate-host : Collect information about zuul worker] 2025-09-20 04:33:12.203772 | debian-bookworm | ok 2025-09-20 04:33:12.211835 | 2025-09-20 04:33:12.211991 | TASK [validate-host : Write out all zuul information for each host] 2025-09-20 04:33:12.805262 | debian-bookworm -> localhost | changed 2025-09-20 04:33:12.824936 | 2025-09-20 04:33:12.825079 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-20 04:33:13.105235 | debian-bookworm | ok 2025-09-20 04:33:13.120272 | 2025-09-20 04:33:13.120532 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-20 04:33:14.040174 | debian-bookworm | changed: 2025-09-20 04:33:14.040422 | debian-bookworm | cd+++++++++ src/ 2025-09-20 04:33:14.040462 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-20 04:33:14.040489 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-20 04:33:14.040514 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/ 2025-09-20 04:33:14.040537 | debian-bookworm | Containerfile 2025-09-20 04:33:14.052724 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/ansible-lint/files/ 2025-09-20 04:33:14.052741 | debian-bookworm | Containerfile 2025-09-20 04:33:14.052964 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/ara-server/files/ 2025-09-20 04:33:14.052981 | debian-bookworm | Containerfile 2025-09-20 04:33:14.053070 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/ceph-daemon/files/ 2025-09-20 04:33:14.053087 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/ceph-daemon/files/bin/ 2025-09-20 04:33:14.053105 | debian-bookworm | Containerfile 2025-09-20 04:33:14.053766 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/cephclient/files/ 2025-09-20 04:33:14.053784 | debian-bookworm | Containerfile 2025-09-20 04:33:14.053867 | debian-bookworm | Containerfile 2025-09-20 04:33:14.053954 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/dnsmasq-osism/ 2025-09-20 04:33:14.053970 | debian-bookworm | Containerfile 2025-09-20 04:33:14.054004 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/gnmic/ 2025-09-20 04:33:14.054022 | debian-bookworm | Containerfile 2025-09-20 04:33:14.054061 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/homer/ 2025-09-20 04:33:14.054079 | debian-bookworm | Containerfile 2025-09-20 04:33:14.054114 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/homer/files/ 2025-09-20 04:33:14.054131 | debian-bookworm | Containerfile 2025-09-20 04:33:14.054563 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/lloadd/ 2025-09-20 04:33:14.054580 | debian-bookworm | Containerfile 2025-09-20 04:33:14.054615 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/netbox/ 2025-09-20 04:33:14.054632 | debian-bookworm | Containerfile 2025-09-20 04:33:14.054670 | debian-bookworm | Containerfile 2025-09-20 04:33:14.054773 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/nexus/files/ 2025-09-20 04:33:14.054812 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/nexus/files/scripts/ 2025-09-20 04:33:14.054880 | debian-bookworm | Containerfile 2025-09-20 04:33:14.055068 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/openstackclient/files/ 2025-09-20 04:33:14.055085 | debian-bookworm | Containerfile 2025-09-20 04:33:14.055171 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/prometheus-redfish-exporter/files/ 2025-09-20 04:33:14.055188 | debian-bookworm | Containerfile 2025-09-20 04:33:14.055259 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/rsync/ 2025-09-20 04:33:14.055276 | debian-bookworm | Containerfile 2025-09-20 04:33:14.055315 | debian-bookworm | Containerfile 2025-09-20 04:33:14.055437 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/seed/files/ 2025-09-20 04:33:14.055455 | debian-bookworm | Containerfile 2025-09-20 04:33:14.055630 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/tempest/files/ 2025-09-20 04:33:14.055647 | debian-bookworm | Containerfile 2025-09-20 04:33:14.071278 | 2025-09-20 04:33:14.071370 | PLAY RECAP 2025-09-20 04:33:14.071426 | debian-bookworm | ok: 26 changed: 11 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2025-09-20 04:33:14.071451 | 2025-09-20 04:33:14.203885 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-20 04:33:14.206042 | RUN START: [untrusted : github.com/osism/zuul-jobs/playbooks/flake8/run.yaml@main] 2025-09-20 04:33:14.947884 | 2025-09-20 04:33:14.948073 | PLAY [Run flake8] 2025-09-20 04:33:14.963879 | 2025-09-20 04:33:14.964003 | LOOP [Install python] 2025-09-20 04:33:14.997833 | debian-bookworm | ok: "ensure-python" 2025-09-20 04:33:15.008149 | debian-bookworm | ok: "ensure-pip" 2025-09-20 04:33:15.069100 | 2025-09-20 04:33:15.069380 | TASK [ensure-python : Validate python_version value] 2025-09-20 04:33:15.105070 | debian-bookworm | skipping: Conditional result was False 2025-09-20 04:33:15.113461 | 2025-09-20 04:33:15.113584 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2025-09-20 04:33:15.138912 | debian-bookworm | skipping: Conditional result was False 2025-09-20 04:33:15.146935 | 2025-09-20 04:33:15.147069 | TASK [ensure-python : Pull in venv package] 2025-09-20 04:33:15.181741 | debian-bookworm | skipping: Conditional result was False 2025-09-20 04:33:15.193484 | 2025-09-20 04:33:15.193631 | TASK [ensure-python : Set default RPM package name] 2025-09-20 04:33:15.218752 | debian-bookworm | skipping: Conditional result was False 2025-09-20 04:33:15.229063 | 2025-09-20 04:33:15.229202 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9 and Python 3.9] 2025-09-20 04:33:15.254921 | debian-bookworm | skipping: Conditional result was False 2025-09-20 04:33:15.264515 | 2025-09-20 04:33:15.264646 | TASK [ensure-python : Install RPM package] 2025-09-20 04:33:15.301385 | debian-bookworm | skipping: Conditional result was False 2025-09-20 04:33:15.311947 | 2025-09-20 04:33:15.312115 | TASK [ensure-python : Install python using pyenv] 2025-09-20 04:33:15.338660 | debian-bookworm | skipping: Conditional result was False 2025-09-20 04:33:15.351391 | 2025-09-20 04:33:15.351536 | TASK [ensure-python : Activate python using stow] 2025-09-20 04:33:15.387021 | debian-bookworm | skipping: Conditional result was False 2025-09-20 04:33:15.406104 | 2025-09-20 04:33:15.406242 | TASK [ensure-pip : Check if pip is installed] 2025-09-20 04:33:15.940417 | debian-bookworm | ok: Runtime: 0:00:00.009072 2025-09-20 04:33:15.953609 | 2025-09-20 04:33:15.953771 | LOOP [ensure-pip : Install pip from packages] 2025-09-20 04:33:16.001481 | debian-bookworm | ok: "/var/lib/zuul/builds/e203ba66140f408b996b5b0f6dfd0d41/untrusted/project_2/github.com/osism/openinfra-zuul-jobs/roles/ensure-pip/tasks/Debian.yaml" 2025-09-20 04:33:16.001873 | debian-bookworm | ok: All items complete 2025-09-20 04:33:16.001958 | 2025-09-20 04:33:16.019544 | debian-bookworm | included: /var/lib/zuul/builds/e203ba66140f408b996b5b0f6dfd0d41/untrusted/project_2/github.com/osism/openinfra-zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2025-09-20 04:33:16.032380 | 2025-09-20 04:33:16.032493 | TASK [ensure-pip : Update package lists] 2025-09-20 04:35:28.414273 | debian-bookworm | ERROR 2025-09-20 04:35:28.414793 | debian-bookworm | { 2025-09-20 04:35:28.414908 | debian-bookworm | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-09-20 04:35:28.414961 | debian-bookworm | } failure 2025-09-20 04:35:28.417092 | 2025-09-20 04:35:28.417202 | PLAY RECAP 2025-09-20 04:35:28.417293 | debian-bookworm | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 8 rescued: 0 ignored: 0 2025-09-20 04:35:28.417335 | 2025-09-20 04:35:28.563171 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/zuul-jobs/playbooks/flake8/run.yaml@main] 2025-09-20 04:35:28.564135 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-20 04:35:29.295386 | 2025-09-20 04:35:29.295574 | PLAY [Base post] 2025-09-20 04:35:29.309539 | 2025-09-20 04:35:29.309692 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-20 04:35:29.885287 | debian-bookworm | changed 2025-09-20 04:35:29.894507 | 2025-09-20 04:35:29.894629 | PLAY RECAP 2025-09-20 04:35:29.894738 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-20 04:35:29.894818 | 2025-09-20 04:35:30.018983 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-20 04:35:30.019884 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-20 04:35:30.803804 | 2025-09-20 04:35:30.803975 | PLAY [Base post-logs] 2025-09-20 04:35:30.814740 | 2025-09-20 04:35:30.814907 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-20 04:35:31.286979 | localhost | changed 2025-09-20 04:35:31.297718 | 2025-09-20 04:35:31.297878 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-20 04:35:31.335349 | localhost | ok 2025-09-20 04:35:31.340185 | 2025-09-20 04:35:31.340323 | TASK [Set zuul-log-path fact] 2025-09-20 04:35:31.357621 | localhost | ok 2025-09-20 04:35:31.367931 | 2025-09-20 04:35:31.368049 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-20 04:35:31.394578 | localhost | ok 2025-09-20 04:35:31.398951 | 2025-09-20 04:35:31.399081 | TASK [upload-logs : Create log directories] 2025-09-20 04:35:31.898720 | localhost | changed 2025-09-20 04:35:31.903874 | 2025-09-20 04:35:31.904040 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-20 04:35:32.407142 | localhost -> localhost | ok: Runtime: 0:00:00.007255 2025-09-20 04:35:32.411715 | 2025-09-20 04:35:32.411833 | TASK [upload-logs : Upload logs to log server] 2025-09-20 04:35:32.991508 | localhost | Output suppressed because no_log was given 2025-09-20 04:35:32.996832 | 2025-09-20 04:35:32.997051 | LOOP [upload-logs : Compress console log and json output] 2025-09-20 04:35:33.056524 | localhost | skipping: Conditional result was False 2025-09-20 04:35:33.062478 | localhost | skipping: Conditional result was False 2025-09-20 04:35:33.070548 | 2025-09-20 04:35:33.070917 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-20 04:35:33.120271 | localhost | skipping: Conditional result was False 2025-09-20 04:35:33.120599 | 2025-09-20 04:35:33.125276 | localhost | skipping: Conditional result was False 2025-09-20 04:35:33.134481 | 2025-09-20 04:35:33.134815 | LOOP [upload-logs : Upload console log and json output]