2025-10-08 06:29:08.856739 | Job console starting 2025-10-08 06:29:08.970093 | Updating git repos 2025-10-08 06:29:09.050792 | Cloning repos into workspace 2025-10-08 06:29:09.136770 | Restoring repo states 2025-10-08 06:29:09.163471 | Merging changes 2025-10-08 06:29:09.756620 | Checking out repos 2025-10-08 06:29:09.794120 | Preparing playbooks 2025-10-08 06:29:10.758819 | Running Ansible setup 2025-10-08 06:30:36.094784 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-08 06:30:36.895927 | 2025-10-08 06:30:36.896089 | PLAY [Base pre] 2025-10-08 06:30:36.912051 | 2025-10-08 06:30:36.912184 | TASK [Setup log path fact] 2025-10-08 06:30:36.949053 | debian-bookworm | ok 2025-10-08 06:30:36.970905 | 2025-10-08 06:30:36.971060 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-08 06:30:37.018928 | debian-bookworm | ok 2025-10-08 06:30:37.032357 | 2025-10-08 06:30:37.032494 | TASK [emit-job-header : Print job information] 2025-10-08 06:30:37.089998 | # Job Information 2025-10-08 06:30:37.090309 | Ansible Version: 2.16.14 2025-10-08 06:30:37.090385 | Job: flake8 2025-10-08 06:30:37.090456 | Pipeline: check 2025-10-08 06:30:37.090507 | Executor: 521e9411259a 2025-10-08 06:30:37.090601 | Triggered by: https://github.com/osism/container-images/pull/840 2025-10-08 06:30:37.090653 | Event ID: 138c7710-a410-11f0-8a0a-70b4e5b43a27 2025-10-08 06:30:37.098271 | 2025-10-08 06:30:37.098417 | LOOP [emit-job-header : Print node information] 2025-10-08 06:30:37.202281 | debian-bookworm | ok: 2025-10-08 06:30:37.202486 | debian-bookworm | # Node Information 2025-10-08 06:30:37.202520 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-08 06:30:37.202581 | debian-bookworm | Hostname: debian 2025-10-08 06:30:37.202605 | debian-bookworm | Username: zuul 2025-10-08 06:30:37.202627 | debian-bookworm | Distro: Debian 12.12 2025-10-08 06:30:37.202651 | debian-bookworm | Provider: regiocloud-a 2025-10-08 06:30:37.202672 | debian-bookworm | Region: 2025-10-08 06:30:37.202694 | debian-bookworm | Label: debian-bookworm 2025-10-08 06:30:37.202714 | debian-bookworm | Product Name: OpenStack Nova 2025-10-08 06:30:37.202733 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe42:549e 2025-10-08 06:30:37.233292 | 2025-10-08 06:30:37.233463 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-08 06:30:37.707065 | debian-bookworm -> localhost | changed 2025-10-08 06:30:37.721099 | 2025-10-08 06:30:37.721244 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-08 06:30:38.763686 | debian-bookworm -> localhost | changed 2025-10-08 06:30:38.777676 | 2025-10-08 06:30:38.777805 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-08 06:30:39.054591 | debian-bookworm -> localhost | ok 2025-10-08 06:30:39.061301 | 2025-10-08 06:30:39.061433 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-08 06:30:39.090776 | debian-bookworm | ok 2025-10-08 06:30:39.108466 | debian-bookworm | included: /var/lib/zuul/builds/8741bd68644c4696a0e13d506210185a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-08 06:30:39.115388 | 2025-10-08 06:30:39.115497 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-08 06:30:40.413621 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-08 06:30:40.413860 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/8741bd68644c4696a0e13d506210185a/work/8741bd68644c4696a0e13d506210185a_id_rsa 2025-10-08 06:30:40.413900 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/8741bd68644c4696a0e13d506210185a/work/8741bd68644c4696a0e13d506210185a_id_rsa.pub 2025-10-08 06:30:40.413928 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-08 06:30:40.413954 | debian-bookworm -> localhost | SHA256:SBSB9DbQeEkQTKgb/clkd1C7HJrtv0Azku8zWl3krik zuul-build-sshkey 2025-10-08 06:30:40.413977 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-08 06:30:40.414016 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-08 06:30:40.414072 | debian-bookworm -> localhost | | =*B==. | 2025-10-08 06:30:40.414095 | debian-bookworm -> localhost | | . ++= . | 2025-10-08 06:30:40.414258 | debian-bookworm -> localhost | | o .=.o . | 2025-10-08 06:30:40.414288 | debian-bookworm -> localhost | |o . oo.O.o o | 2025-10-08 06:30:40.414311 | debian-bookworm -> localhost | | o = o*.S o | 2025-10-08 06:30:40.414336 | debian-bookworm -> localhost | |. + = + o | 2025-10-08 06:30:40.414359 | debian-bookworm -> localhost | | = . . | 2025-10-08 06:30:40.414380 | debian-bookworm -> localhost | | oEo o | 2025-10-08 06:30:40.414401 | debian-bookworm -> localhost | | ...+=. | 2025-10-08 06:30:40.414422 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-08 06:30:40.414482 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.797069 2025-10-08 06:30:40.426428 | 2025-10-08 06:30:40.426570 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-08 06:30:40.461890 | debian-bookworm | ok 2025-10-08 06:30:40.475599 | debian-bookworm | included: /var/lib/zuul/builds/8741bd68644c4696a0e13d506210185a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-08 06:30:40.485115 | 2025-10-08 06:30:40.485214 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-08 06:30:40.500367 | debian-bookworm | skipping: Conditional result was False 2025-10-08 06:30:40.506085 | 2025-10-08 06:30:40.506186 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-08 06:30:41.191769 | debian-bookworm | changed 2025-10-08 06:30:41.200112 | 2025-10-08 06:30:41.200241 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-08 06:30:41.471254 | debian-bookworm | ok 2025-10-08 06:30:41.480121 | 2025-10-08 06:30:41.480260 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-08 06:30:42.237773 | debian-bookworm | changed 2025-10-08 06:30:42.247088 | 2025-10-08 06:30:42.247224 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-08 06:30:42.943470 | debian-bookworm | changed 2025-10-08 06:30:42.951353 | 2025-10-08 06:30:42.951475 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-08 06:30:42.975525 | debian-bookworm | skipping: Conditional result was False 2025-10-08 06:30:42.986747 | 2025-10-08 06:30:42.986945 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-08 06:30:43.445970 | debian-bookworm -> localhost | changed 2025-10-08 06:30:43.465463 | 2025-10-08 06:30:43.465643 | TASK [add-build-sshkey : Add back temp key] 2025-10-08 06:30:43.907018 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/8741bd68644c4696a0e13d506210185a/work/8741bd68644c4696a0e13d506210185a_id_rsa (zuul-build-sshkey) 2025-10-08 06:30:43.907264 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018212 2025-10-08 06:30:43.915587 | 2025-10-08 06:30:43.915703 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-08 06:30:44.326252 | debian-bookworm | ok 2025-10-08 06:30:44.334306 | 2025-10-08 06:30:44.334443 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-08 06:30:44.359142 | debian-bookworm | skipping: Conditional result was False 2025-10-08 06:30:44.373358 | 2025-10-08 06:30:44.373472 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-08 06:30:44.773747 | debian-bookworm | ok 2025-10-08 06:30:44.789357 | 2025-10-08 06:30:44.789486 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-08 06:30:44.822759 | debian-bookworm | ok 2025-10-08 06:30:44.829911 | 2025-10-08 06:30:44.830028 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-08 06:30:45.118235 | debian-bookworm -> localhost | ok 2025-10-08 06:30:45.131358 | 2025-10-08 06:30:45.131533 | TASK [validate-host : Collect information about the host] 2025-10-08 06:31:26.310615 | debian-bookworm | ok 2025-10-08 06:31:26.325837 | 2025-10-08 06:31:26.325962 | TASK [validate-host : Sanitize hostname] 2025-10-08 06:31:26.432875 | debian-bookworm | ok 2025-10-08 06:31:26.471564 | 2025-10-08 06:31:26.471684 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-08 06:31:27.052641 | debian-bookworm -> localhost | changed 2025-10-08 06:31:27.058539 | 2025-10-08 06:31:27.058653 | TASK [validate-host : Collect information about zuul worker] 2025-10-08 06:31:27.468386 | debian-bookworm | ok 2025-10-08 06:31:27.475826 | 2025-10-08 06:31:27.475970 | TASK [validate-host : Write out all zuul information for each host] 2025-10-08 06:31:28.029142 | debian-bookworm -> localhost | changed 2025-10-08 06:31:28.040151 | 2025-10-08 06:31:28.040263 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-08 06:31:28.310940 | debian-bookworm | ok 2025-10-08 06:31:28.319782 | 2025-10-08 06:31:28.319907 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-08 06:31:29.205180 | debian-bookworm | changed: 2025-10-08 06:31:29.205481 | debian-bookworm | cd+++++++++ src/ 2025-10-08 06:31:29.205543 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-08 06:31:29.205585 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-08 06:31:29.205623 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/ 2025-10-08 06:31:29.205658 | debian-bookworm | Containerfile 2025-10-08 06:31:29.212031 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/ansible-lint/files/ 2025-10-08 06:31:29.212057 | debian-bookworm | Containerfile 2025-10-08 06:31:29.212364 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/ara-server/files/ 2025-10-08 06:31:29.212390 | debian-bookworm | Containerfile 2025-10-08 06:31:29.212522 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/ceph-daemon/files/ 2025-10-08 06:31:29.212547 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/ceph-daemon/files/bin/ 2025-10-08 06:31:29.212572 | debian-bookworm | Containerfile 2025-10-08 06:31:29.213552 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/cephclient/files/ 2025-10-08 06:31:29.213576 | debian-bookworm | Containerfile 2025-10-08 06:31:29.213677 | debian-bookworm | Containerfile 2025-10-08 06:31:29.213844 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/dnsmasq-osism/ 2025-10-08 06:31:29.213870 | debian-bookworm | Containerfile 2025-10-08 06:31:29.213921 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/gnmic/ 2025-10-08 06:31:29.213946 | debian-bookworm | Containerfile 2025-10-08 06:31:29.213996 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/homer/ 2025-10-08 06:31:29.214021 | debian-bookworm | Containerfile 2025-10-08 06:31:29.214073 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/homer/files/ 2025-10-08 06:31:29.214103 | debian-bookworm | Containerfile 2025-10-08 06:31:29.214681 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/lloadd/ 2025-10-08 06:31:29.214703 | debian-bookworm | Containerfile 2025-10-08 06:31:29.214779 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/netbox/ 2025-10-08 06:31:29.214812 | debian-bookworm | Containerfile 2025-10-08 06:31:29.214878 | debian-bookworm | Containerfile 2025-10-08 06:31:29.215025 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/nexus/files/ 2025-10-08 06:31:29.215048 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/nexus/files/scripts/ 2025-10-08 06:31:29.215070 | debian-bookworm | Containerfile 2025-10-08 06:31:29.215294 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/openstackclient/files/ 2025-10-08 06:31:29.215316 | debian-bookworm | Containerfile 2025-10-08 06:31:29.215434 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/prometheus-redfish-exporter/files/ 2025-10-08 06:31:29.215456 | debian-bookworm | Containerfile 2025-10-08 06:31:29.215546 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/rsync/ 2025-10-08 06:31:29.215568 | debian-bookworm | Containerfile 2025-10-08 06:31:29.215612 | debian-bookworm | Containerfile 2025-10-08 06:31:29.215807 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/seed/files/ 2025-10-08 06:31:29.215830 | debian-bookworm | Containerfile 2025-10-08 06:31:29.216054 | debian-bookworm | cd+++++++++ src/github.com/osism/container-images/tempest/files/ 2025-10-08 06:31:29.216077 | debian-bookworm | Containerfile 2025-10-08 06:31:29.225314 | 2025-10-08 06:31:29.225395 | PLAY RECAP 2025-10-08 06:31:29.225448 | debian-bookworm | ok: 26 changed: 11 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2025-10-08 06:31:29.225472 | 2025-10-08 06:31:29.359884 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-08 06:31:29.362121 | RUN START: [untrusted : github.com/osism/zuul-jobs/playbooks/flake8/run.yaml@main] 2025-10-08 06:31:30.102358 | 2025-10-08 06:31:30.102518 | PLAY [Run flake8] 2025-10-08 06:31:30.117792 | 2025-10-08 06:31:30.117910 | LOOP [Install python] 2025-10-08 06:31:30.151963 | debian-bookworm | ok: "ensure-python" 2025-10-08 06:31:30.158908 | debian-bookworm | ok: "ensure-pip" 2025-10-08 06:31:30.215867 | 2025-10-08 06:31:30.216001 | TASK [ensure-python : Validate python_version value] 2025-10-08 06:31:30.241201 | debian-bookworm | skipping: Conditional result was False 2025-10-08 06:31:30.247585 | 2025-10-08 06:31:30.247701 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2025-10-08 06:31:30.271913 | debian-bookworm | skipping: Conditional result was False 2025-10-08 06:31:30.281961 | 2025-10-08 06:31:30.282119 | TASK [ensure-python : Pull in venv package] 2025-10-08 06:31:30.307653 | debian-bookworm | skipping: Conditional result was False 2025-10-08 06:31:30.321670 | 2025-10-08 06:31:30.321865 | TASK [ensure-python : Set default RPM package name] 2025-10-08 06:31:30.346425 | debian-bookworm | skipping: Conditional result was False 2025-10-08 06:31:30.358093 | 2025-10-08 06:31:30.360580 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9 and Python 3.9] 2025-10-08 06:31:30.399267 | debian-bookworm | skipping: Conditional result was False 2025-10-08 06:31:30.414562 | 2025-10-08 06:31:30.414690 | TASK [ensure-python : Install RPM package] 2025-10-08 06:31:30.449361 | debian-bookworm | skipping: Conditional result was False 2025-10-08 06:31:30.455241 | 2025-10-08 06:31:30.455356 | TASK [ensure-python : Install python using pyenv] 2025-10-08 06:31:30.479361 | debian-bookworm | skipping: Conditional result was False 2025-10-08 06:31:30.485377 | 2025-10-08 06:31:30.485484 | TASK [ensure-python : Activate python using stow] 2025-10-08 06:31:30.518751 | debian-bookworm | skipping: Conditional result was False 2025-10-08 06:31:30.532163 | 2025-10-08 06:31:30.532283 | TASK [ensure-pip : Check if pip is installed] 2025-10-08 06:31:31.075998 | debian-bookworm | ok: Runtime: 0:00:00.007544 2025-10-08 06:31:31.087787 | 2025-10-08 06:31:31.087923 | LOOP [ensure-pip : Install pip from packages] 2025-10-08 06:31:31.125818 | debian-bookworm | ok: "/var/lib/zuul/builds/8741bd68644c4696a0e13d506210185a/untrusted/project_2/github.com/osism/openinfra-zuul-jobs/roles/ensure-pip/tasks/Debian.yaml" 2025-10-08 06:31:31.125993 | debian-bookworm | ok: All items complete 2025-10-08 06:31:31.126026 | 2025-10-08 06:31:31.148929 | debian-bookworm | included: /var/lib/zuul/builds/8741bd68644c4696a0e13d506210185a/untrusted/project_2/github.com/osism/openinfra-zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2025-10-08 06:31:31.161428 | 2025-10-08 06:31:31.161548 | TASK [ensure-pip : Update package lists] 2025-10-08 06:33:43.581523 | debian-bookworm | ERROR 2025-10-08 06:33:43.581993 | debian-bookworm | { 2025-10-08 06:33:43.582510 | debian-bookworm | "msg": "Timeout (32s) waiting for privilege escalation prompt: " 2025-10-08 06:33:43.582590 | debian-bookworm | } failure 2025-10-08 06:33:43.584890 | 2025-10-08 06:33:43.584998 | PLAY RECAP 2025-10-08 06:33:43.585081 | debian-bookworm | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 8 rescued: 0 ignored: 0 2025-10-08 06:33:43.585121 | 2025-10-08 06:33:43.711848 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/zuul-jobs/playbooks/flake8/run.yaml@main] 2025-10-08 06:33:43.713206 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-08 06:33:44.433954 | 2025-10-08 06:33:44.434105 | PLAY [Base post] 2025-10-08 06:33:44.447354 | 2025-10-08 06:33:44.447475 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-08 06:33:45.000412 | debian-bookworm | changed 2025-10-08 06:33:45.010126 | 2025-10-08 06:33:45.010279 | PLAY RECAP 2025-10-08 06:33:45.010396 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-08 06:33:45.010513 | 2025-10-08 06:33:45.126356 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-08 06:33:45.127274 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-08 06:33:45.874134 | 2025-10-08 06:33:45.874288 | PLAY [Base post-logs] 2025-10-08 06:33:45.884415 | 2025-10-08 06:33:45.884540 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-08 06:33:46.326938 | localhost | changed 2025-10-08 06:33:46.340265 | 2025-10-08 06:33:46.340416 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-08 06:33:46.377765 | localhost | ok 2025-10-08 06:33:46.383908 | 2025-10-08 06:33:46.384081 | TASK [Set zuul-log-path fact] 2025-10-08 06:33:46.412385 | localhost | ok 2025-10-08 06:33:46.424847 | 2025-10-08 06:33:46.424975 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-08 06:33:46.450772 | localhost | ok 2025-10-08 06:33:46.455202 | 2025-10-08 06:33:46.455344 | TASK [upload-logs : Create log directories] 2025-10-08 06:33:46.933332 | localhost | changed 2025-10-08 06:33:46.938794 | 2025-10-08 06:33:46.938984 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-08 06:33:47.420896 | localhost -> localhost | ok: Runtime: 0:00:00.006550 2025-10-08 06:33:47.429944 | 2025-10-08 06:33:47.430151 | TASK [upload-logs : Upload logs to log server] 2025-10-08 06:33:47.963760 | localhost | Output suppressed because no_log was given 2025-10-08 06:33:47.965844 | 2025-10-08 06:33:47.965962 | LOOP [upload-logs : Compress console log and json output] 2025-10-08 06:33:48.022517 | localhost | skipping: Conditional result was False 2025-10-08 06:33:48.027864 | localhost | skipping: Conditional result was False 2025-10-08 06:33:48.040224 | 2025-10-08 06:33:48.040465 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-08 06:33:48.086207 | localhost | skipping: Conditional result was False 2025-10-08 06:33:48.086809 | 2025-10-08 06:33:48.090431 | localhost | skipping: Conditional result was False 2025-10-08 06:33:48.103899 | 2025-10-08 06:33:48.104118 | LOOP [upload-logs : Upload console log and json output]