2025-11-07 07:52:14.851980 | Job console starting 2025-11-07 07:52:14.882817 | Updating git repos 2025-11-07 07:52:14.931960 | Cloning repos into workspace 2025-11-07 07:52:15.089338 | Restoring repo states 2025-11-07 07:52:15.120983 | Merging changes 2025-11-07 07:52:15.738562 | Checking out repos 2025-11-07 07:52:15.900292 | Preparing playbooks 2025-11-07 07:52:17.432391 | Running Ansible setup 2025-11-07 07:52:22.023762 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-07 07:52:23.283388 | 2025-11-07 07:52:23.283511 | PLAY [Base pre] 2025-11-07 07:52:23.298612 | 2025-11-07 07:52:23.298718 | TASK [Setup log path fact] 2025-11-07 07:52:23.316289 | debian-bookworm | ok 2025-11-07 07:52:23.340461 | 2025-11-07 07:52:23.340578 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-07 07:52:23.409390 | debian-bookworm | ok 2025-11-07 07:52:23.418671 | 2025-11-07 07:52:23.418759 | TASK [emit-job-header : Print job information] 2025-11-07 07:52:23.497421 | # Job Information 2025-11-07 07:52:23.497634 | Ansible Version: 2.16.14 2025-11-07 07:52:23.497671 | Job: python-osism-test-setup 2025-11-07 07:52:23.497709 | Pipeline: check 2025-11-07 07:52:23.497736 | Executor: 521e9411259a 2025-11-07 07:52:23.497756 | Triggered by: https://github.com/osism/python-osism/pull/1786 2025-11-07 07:52:23.497779 | Event ID: a7a64240-bbae-11f0-83d5-c848757b25ec 2025-11-07 07:52:23.504095 | 2025-11-07 07:52:23.504185 | LOOP [emit-job-header : Print node information] 2025-11-07 07:52:23.639501 | debian-bookworm | ok: 2025-11-07 07:52:23.639661 | debian-bookworm | # Node Information 2025-11-07 07:52:23.639695 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-11-07 07:52:23.639720 | debian-bookworm | Hostname: debian 2025-11-07 07:52:23.639743 | debian-bookworm | Username: zuul 2025-11-07 07:52:23.639764 | debian-bookworm | Distro: Debian 12.12 2025-11-07 07:52:23.639787 | debian-bookworm | Provider: regiocloud-a 2025-11-07 07:52:23.639808 | debian-bookworm | Region: 2025-11-07 07:52:23.639829 | debian-bookworm | Label: debian-bookworm 2025-11-07 07:52:23.639849 | debian-bookworm | Product Name: OpenStack Nova 2025-11-07 07:52:23.639868 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7c:4aec 2025-11-07 07:52:23.651181 | 2025-11-07 07:52:23.651284 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-07 07:52:24.553538 | debian-bookworm -> localhost | changed 2025-11-07 07:52:24.559386 | 2025-11-07 07:52:24.559466 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-07 07:52:26.090124 | debian-bookworm -> localhost | changed 2025-11-07 07:52:26.106532 | 2025-11-07 07:52:26.106621 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-07 07:52:26.637221 | debian-bookworm -> localhost | ok 2025-11-07 07:52:26.642110 | 2025-11-07 07:52:26.642193 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-07 07:52:26.678449 | debian-bookworm | ok 2025-11-07 07:52:26.698370 | debian-bookworm | included: /var/lib/zuul/builds/e872a3c01e5b4a78a87af8855cc170f9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-07 07:52:26.703063 | 2025-11-07 07:52:26.703129 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-07 07:52:28.126794 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-11-07 07:52:28.126998 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/e872a3c01e5b4a78a87af8855cc170f9/work/e872a3c01e5b4a78a87af8855cc170f9_id_rsa 2025-11-07 07:52:28.127032 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/e872a3c01e5b4a78a87af8855cc170f9/work/e872a3c01e5b4a78a87af8855cc170f9_id_rsa.pub 2025-11-07 07:52:28.127054 | debian-bookworm -> localhost | The key fingerprint is: 2025-11-07 07:52:28.127088 | debian-bookworm -> localhost | SHA256:HuiL3lD0AHLsGC6orc1xncIFRWPc/72nNp5CKsgPZu0 zuul-build-sshkey 2025-11-07 07:52:28.127107 | debian-bookworm -> localhost | The key's randomart image is: 2025-11-07 07:52:28.127133 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-11-07 07:52:28.127157 | debian-bookworm -> localhost | | ..o+=. | 2025-11-07 07:52:28.127176 | debian-bookworm -> localhost | | .ooo... | 2025-11-07 07:52:28.127193 | debian-bookworm -> localhost | |.. + .o . | 2025-11-07 07:52:28.127209 | debian-bookworm -> localhost | |o o ...+ . | 2025-11-07 07:52:28.127225 | debian-bookworm -> localhost | |.o . oo.S . . | 2025-11-07 07:52:28.127246 | debian-bookworm -> localhost | |. o +ooo . o . | 2025-11-07 07:52:28.127264 | debian-bookworm -> localhost | | + o.o=.o o . | 2025-11-07 07:52:28.127281 | debian-bookworm -> localhost | |. o *o+. . . +..| 2025-11-07 07:52:28.127299 | debian-bookworm -> localhost | | .o o.E. +++ | 2025-11-07 07:52:28.127316 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-11-07 07:52:28.127363 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.696148 2025-11-07 07:52:28.132650 | 2025-11-07 07:52:28.132723 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-07 07:52:28.149095 | debian-bookworm | ok 2025-11-07 07:52:28.156343 | debian-bookworm | included: /var/lib/zuul/builds/e872a3c01e5b4a78a87af8855cc170f9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-07 07:52:28.163363 | 2025-11-07 07:52:28.163429 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-07 07:52:28.175856 | debian-bookworm | skipping: Conditional result was False 2025-11-07 07:52:28.180594 | 2025-11-07 07:52:28.180663 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-07 07:52:28.754804 | debian-bookworm | changed 2025-11-07 07:52:28.760592 | 2025-11-07 07:52:28.760672 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-07 07:52:29.019233 | debian-bookworm | ok 2025-11-07 07:52:29.028234 | 2025-11-07 07:52:29.028348 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-07 07:52:29.640771 | debian-bookworm | changed 2025-11-07 07:52:29.651468 | 2025-11-07 07:52:29.651586 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-07 07:52:30.238026 | debian-bookworm | changed 2025-11-07 07:52:30.243288 | 2025-11-07 07:52:30.243374 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-07 07:52:30.279824 | debian-bookworm | skipping: Conditional result was False 2025-11-07 07:52:30.286741 | 2025-11-07 07:52:30.286854 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-07 07:52:30.888013 | debian-bookworm -> localhost | changed 2025-11-07 07:52:30.898962 | 2025-11-07 07:52:30.899062 | TASK [add-build-sshkey : Add back temp key] 2025-11-07 07:52:31.259443 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/e872a3c01e5b4a78a87af8855cc170f9/work/e872a3c01e5b4a78a87af8855cc170f9_id_rsa (zuul-build-sshkey) 2025-11-07 07:52:31.259902 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016066 2025-11-07 07:52:31.270859 | 2025-11-07 07:52:31.270999 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-07 07:52:31.644147 | debian-bookworm | ok 2025-11-07 07:52:31.649336 | 2025-11-07 07:52:31.649423 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-07 07:52:31.702643 | debian-bookworm | skipping: Conditional result was False 2025-11-07 07:52:31.717781 | 2025-11-07 07:52:31.717897 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-07 07:52:32.171530 | debian-bookworm | ok 2025-11-07 07:52:32.183426 | 2025-11-07 07:52:32.183514 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-07 07:52:32.230977 | debian-bookworm | ok 2025-11-07 07:52:32.235902 | 2025-11-07 07:52:32.235984 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-07 07:52:32.717036 | debian-bookworm -> localhost | ok 2025-11-07 07:52:32.732110 | 2025-11-07 07:52:32.732208 | TASK [validate-host : Collect information about the host] 2025-11-07 07:52:33.917708 | debian-bookworm | ok 2025-11-07 07:52:33.926166 | 2025-11-07 07:52:33.926245 | TASK [validate-host : Sanitize hostname] 2025-11-07 07:52:34.004852 | debian-bookworm | ok 2025-11-07 07:52:34.034001 | 2025-11-07 07:52:34.034102 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-07 07:52:34.878645 | debian-bookworm -> localhost | changed 2025-11-07 07:52:34.884239 | 2025-11-07 07:52:34.884329 | TASK [validate-host : Collect information about zuul worker] 2025-11-07 07:52:35.463990 | debian-bookworm | ok 2025-11-07 07:52:35.469838 | 2025-11-07 07:52:35.469976 | TASK [validate-host : Write out all zuul information for each host] 2025-11-07 07:52:36.112079 | debian-bookworm -> localhost | changed 2025-11-07 07:52:36.123378 | 2025-11-07 07:52:36.123455 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-07 07:52:36.414934 | debian-bookworm | ok 2025-11-07 07:52:36.420222 | 2025-11-07 07:52:36.420296 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-07 07:52:37.515946 | debian-bookworm | changed: 2025-11-07 07:52:37.516101 | debian-bookworm | cd+++++++++ src/ 2025-11-07 07:52:37.516130 | debian-bookworm | cd+++++++++ src/github.com/ 2025-11-07 07:52:37.516149 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-11-07 07:52:37.516167 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-11-07 07:52:37.516184 | debian-bookworm | Containerfile 2025-11-07 07:52:37.516278 | debian-bookworm | Containerfile 2025-11-07 07:52:37.529744 | debian-bookworm | localhost | ok: Runtime: 0:00:00.007174 2025-11-07 07:53:52.371493 | 2025-11-07 07:53:52.371623 | TASK [upload-logs : Upload logs to log server] 2025-11-07 07:53:52.936955 | localhost | Output suppressed because no_log was given 2025-11-07 07:53:52.941491 | 2025-11-07 07:53:52.941707 | LOOP [upload-logs : Compress console log and json output] 2025-11-07 07:53:53.000918 | localhost | skipping: Conditional result was False 2025-11-07 07:53:53.009079 | localhost | skipping: Conditional result was False 2025-11-07 07:53:53.025672 | 2025-11-07 07:53:53.025991 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-07 07:53:53.074467 | localhost | skipping: Conditional result was False 2025-11-07 07:53:53.074819 | 2025-11-07 07:53:53.081450 | localhost | skipping: Conditional result was False 2025-11-07 07:53:53.091554 | 2025-11-07 07:53:53.091795 | LOOP [upload-logs : Upload console log and json output]