2025-12-04 15:25:15.791166 | Job console starting 2025-12-04 15:25:15.835194 | Updating git repos 2025-12-04 15:25:15.908690 | Cloning repos into workspace 2025-12-04 15:25:16.023733 | Restoring repo states 2025-12-04 15:25:16.049520 | Merging changes 2025-12-04 15:25:16.749485 | Checking out repos 2025-12-04 15:25:16.887799 | Preparing playbooks 2025-12-04 15:25:18.102969 | Running Ansible setup 2025-12-04 15:25:24.273437 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-04 15:25:25.274399 | 2025-12-04 15:25:25.274601 | PLAY [Base pre] 2025-12-04 15:25:25.291514 | 2025-12-04 15:25:25.291682 | TASK [Setup log path fact] 2025-12-04 15:25:25.310741 | debian-bookworm | ok 2025-12-04 15:25:25.326156 | 2025-12-04 15:25:25.326327 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-04 15:25:25.356472 | debian-bookworm | ok 2025-12-04 15:25:25.367653 | 2025-12-04 15:25:25.367792 | TASK [emit-job-header : Print job information] 2025-12-04 15:25:25.436078 | # Job Information 2025-12-04 15:25:25.436417 | Ansible Version: 2.16.14 2025-12-04 15:25:25.436476 | Job: python-black 2025-12-04 15:25:25.436515 | Pipeline: check 2025-12-04 15:25:25.436544 | Executor: 521e9411259a 2025-12-04 15:25:25.436566 | Triggered by: https://github.com/osism/python-osism/pull/1886 2025-12-04 15:25:25.436590 | Event ID: 69f2a470-d125-11f0-9941-6f83b572271d 2025-12-04 15:25:25.442307 | 2025-12-04 15:25:25.442505 | LOOP [emit-job-header : Print node information] 2025-12-04 15:25:25.687544 | debian-bookworm | ok: 2025-12-04 15:25:25.687822 | debian-bookworm | # Node Information 2025-12-04 15:25:25.687863 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-04 15:25:25.687889 | debian-bookworm | Hostname: debian 2025-12-04 15:25:25.687913 | debian-bookworm | Username: zuul 2025-12-04 15:25:25.687935 | debian-bookworm | Distro: Debian 12.12 2025-12-04 15:25:25.687959 | debian-bookworm | Provider: regiocloud-a 2025-12-04 15:25:25.687981 | debian-bookworm | Region: 2025-12-04 15:25:25.688002 | debian-bookworm | Label: debian-bookworm 2025-12-04 15:25:25.688095 | debian-bookworm | Product Name: OpenStack Nova 2025-12-04 15:25:25.688118 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4a:4311 2025-12-04 15:25:25.715401 | 2025-12-04 15:25:25.715544 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-04 15:25:26.877893 | debian-bookworm -> localhost | changed 2025-12-04 15:25:26.885329 | 2025-12-04 15:25:26.885500 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-04 15:25:29.011275 | debian-bookworm -> localhost | changed 2025-12-04 15:25:29.029757 | 2025-12-04 15:25:29.029907 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-04 15:25:29.824806 | debian-bookworm -> localhost | ok 2025-12-04 15:25:29.831406 | 2025-12-04 15:25:29.831537 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-04 15:25:29.864179 | debian-bookworm | ok 2025-12-04 15:25:29.908853 | debian-bookworm | included: /var/lib/zuul/builds/0941c2081b674d42b03b9c19f3c810f7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-04 15:25:29.916685 | 2025-12-04 15:25:29.916815 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-04 15:25:32.097527 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-04 15:25:32.097837 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0941c2081b674d42b03b9c19f3c810f7/work/0941c2081b674d42b03b9c19f3c810f7_id_rsa 2025-12-04 15:25:32.097883 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0941c2081b674d42b03b9c19f3c810f7/work/0941c2081b674d42b03b9c19f3c810f7_id_rsa.pub 2025-12-04 15:25:32.097909 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-04 15:25:32.097934 | debian-bookworm -> localhost | SHA256:XgmoHvzkQops8IzxUEhoM0uHneuC3+/huwNkXnclnao zuul-build-sshkey 2025-12-04 15:25:32.097957 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-04 15:25:32.097995 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-04 15:25:32.098018 | debian-bookworm -> localhost | |..o . . . | 2025-12-04 15:25:32.098039 | debian-bookworm -> localhost | |oB + . . + | 2025-12-04 15:25:32.098059 | debian-bookworm -> localhost | |+ * . . . + | 2025-12-04 15:25:32.098079 | debian-bookworm -> localhost | | o oo.. ..o. | 2025-12-04 15:25:32.098099 | debian-bookworm -> localhost | |= .+=...Soo | 2025-12-04 15:25:32.098126 | debian-bookworm -> localhost | |+X =o= .E. | 2025-12-04 15:25:32.098146 | debian-bookworm -> localhost | |o+*.o.+ . | 2025-12-04 15:25:32.098166 | debian-bookworm -> localhost | |. . .o.. | 2025-12-04 15:25:32.098187 | debian-bookworm -> localhost | | o*+ | 2025-12-04 15:25:32.098207 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-04 15:25:32.098260 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.362777 2025-12-04 15:25:32.105126 | 2025-12-04 15:25:32.105252 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-04 15:25:32.161932 | debian-bookworm | ok 2025-12-04 15:25:32.172943 | debian-bookworm | included: /var/lib/zuul/builds/0941c2081b674d42b03b9c19f3c810f7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-04 15:25:32.194942 | 2025-12-04 15:25:32.195084 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-04 15:25:32.234862 | debian-bookworm | skipping: Conditional result was False 2025-12-04 15:25:32.242781 | 2025-12-04 15:25:32.242949 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-04 15:25:32.993044 | debian-bookworm | changed 2025-12-04 15:25:33.014639 | 2025-12-04 15:25:33.014802 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-04 15:25:33.321087 | debian-bookworm | ok 2025-12-04 15:25:33.334790 | 2025-12-04 15:25:33.334963 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-04 15:25:34.030648 | debian-bookworm | changed 2025-12-04 15:25:34.041695 | 2025-12-04 15:25:34.041863 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-04 15:25:34.672113 | debian-bookworm | changed 2025-12-04 15:25:34.678009 | 2025-12-04 15:25:34.678142 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-04 15:25:34.702199 | debian-bookworm | skipping: Conditional result was False 2025-12-04 15:25:34.708934 | 2025-12-04 15:25:34.709066 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-04 15:25:35.404322 | debian-bookworm -> localhost | changed 2025-12-04 15:25:35.426315 | 2025-12-04 15:25:35.426480 | TASK [add-build-sshkey : Add back temp key] 2025-12-04 15:25:36.086358 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0941c2081b674d42b03b9c19f3c810f7/work/0941c2081b674d42b03b9c19f3c810f7_id_rsa (zuul-build-sshkey) 2025-12-04 15:25:36.086716 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018871 2025-12-04 15:25:36.095500 | 2025-12-04 15:25:36.095636 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-04 15:25:36.669061 | debian-bookworm | ok 2025-12-04 15:25:36.674470 | 2025-12-04 15:25:36.674598 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-04 15:25:36.724548 | debian-bookworm | skipping: Conditional result was False 2025-12-04 15:25:36.736486 | 2025-12-04 15:25:36.736621 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-04 15:25:37.328861 | debian-bookworm | ok 2025-12-04 15:25:37.351315 | 2025-12-04 15:25:37.351526 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-04 15:25:37.414517 | debian-bookworm | ok 2025-12-04 15:25:37.424681 | 2025-12-04 15:25:37.424831 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-04 15:25:37.889361 | debian-bookworm -> localhost | ok 2025-12-04 15:25:37.896675 | 2025-12-04 15:25:37.896808 | TASK [validate-host : Collect information about the host] 2025-12-04 15:25:39.112879 | debian-bookworm | ok 2025-12-04 15:25:39.131955 | 2025-12-04 15:25:39.132195 | TASK [validate-host : Sanitize hostname] 2025-12-04 15:25:39.327563 | debian-bookworm | ok 2025-12-04 15:25:39.400674 | 2025-12-04 15:25:39.400835 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-04 15:25:40.117836 | debian-bookworm -> localhost | changed 2025-12-04 15:25:40.124779 | 2025-12-04 15:25:40.124966 | TASK [validate-host : Collect information about zuul worker] 2025-12-04 15:25:40.609607 | debian-bookworm | ok 2025-12-04 15:25:40.651838 | 2025-12-04 15:25:40.652045 | TASK [validate-host : Write out all zuul information for each host] 2025-12-04 15:25:41.662356 | debian-bookworm -> localhost | changed 2025-12-04 15:25:41.681609 | 2025-12-04 15:25:41.681748 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-04 15:25:42.022513 | debian-bookworm | ok 2025-12-04 15:25:42.028655 | 2025-12-04 15:25:42.028781 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-04 15:25:43.676106 | debian-bookworm | changed: 2025-12-04 15:25:43.676327 | debian-bookworm | cd+++++++++ src/ 2025-12-04 15:25:43.676363 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-04 15:25:43.676441 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-04 15:25:43.676474 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-12-04 15:25:43.676496 | debian-bookworm | Containerfile 2025-12-04 15:25:43.676628 | debian-bookworm | Containerfile 2025-12-04 15:25:43.706780 | debian-bookworm | localhost | ok: Runtime: 0:00:00.008289 2025-12-04 15:26:11.423062 | 2025-12-04 15:26:11.423275 | TASK [upload-logs : Upload logs to log server] 2025-12-04 15:26:12.058537 | localhost | Output suppressed because no_log was given 2025-12-04 15:26:12.062675 | 2025-12-04 15:26:12.062932 | LOOP [upload-logs : Compress console log and json output] 2025-12-04 15:26:12.124425 | localhost | skipping: Conditional result was False 2025-12-04 15:26:12.129601 | localhost | skipping: Conditional result was False 2025-12-04 15:26:12.138159 | 2025-12-04 15:26:12.139171 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-04 15:26:12.206936 | localhost | skipping: Conditional result was False 2025-12-04 15:26:12.207607 | 2025-12-04 15:26:12.211578 | localhost | skipping: Conditional result was False 2025-12-04 15:26:12.225301 | 2025-12-04 15:26:12.225590 | LOOP [upload-logs : Upload console log and json output]