2026-04-27 13:24:50.531547 | Job console starting 2026-04-27 13:24:50.555465 | Updating git repos 2026-04-27 13:24:50.608560 | Cloning repos into workspace 2026-04-27 13:24:50.670969 | Restoring repo states 2026-04-27 13:24:50.690217 | Merging changes 2026-04-27 13:24:51.445557 | Checking out repos 2026-04-27 13:24:51.513844 | Preparing playbooks 2026-04-27 13:24:53.263905 | Running Ansible setup 2026-04-27 13:26:18.353715 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-27 13:26:19.120121 | 2026-04-27 13:26:19.120291 | PLAY [Base pre] 2026-04-27 13:26:19.137498 | 2026-04-27 13:26:19.137659 | TASK [Setup log path fact] 2026-04-27 13:26:19.167582 | debian-bookworm | ok 2026-04-27 13:26:19.183781 | 2026-04-27 13:26:19.183918 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-27 13:26:19.233427 | debian-bookworm | ok 2026-04-27 13:26:19.243459 | 2026-04-27 13:26:19.243593 | TASK [emit-job-header : Print job information] 2026-04-27 13:26:19.298392 | # Job Information 2026-04-27 13:26:19.298695 | Ansible Version: 2.16.14 2026-04-27 13:26:19.298759 | Job: python-osism-unit-tests 2026-04-27 13:26:19.298819 | Pipeline: check 2026-04-27 13:26:19.298886 | Executor: 521e9411259a 2026-04-27 13:26:19.298924 | Triggered by: https://github.com/osism/python-osism/pull/2215 2026-04-27 13:26:19.298963 | Event ID: 71b0f710-423c-11f1-8a7b-4045665ba361 2026-04-27 13:26:19.307897 | 2026-04-27 13:26:19.308037 | LOOP [emit-job-header : Print node information] 2026-04-27 13:26:19.439098 | debian-bookworm | ok: 2026-04-27 13:26:19.440298 | debian-bookworm | # Node Information 2026-04-27 13:26:19.440382 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-04-27 13:26:19.440410 | debian-bookworm | Hostname: debian 2026-04-27 13:26:19.440433 | debian-bookworm | Username: zuul 2026-04-27 13:26:19.440456 | debian-bookworm | Distro: Debian 12.13 2026-04-27 13:26:19.440480 | debian-bookworm | Provider: regiocloud-a 2026-04-27 13:26:19.440502 | debian-bookworm | Region: 2026-04-27 13:26:19.440523 | debian-bookworm | Label: debian-bookworm 2026-04-27 13:26:19.440544 | debian-bookworm | Product Name: OpenStack Nova 2026-04-27 13:26:19.440579 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec9:9c9a 2026-04-27 13:26:19.472858 | 2026-04-27 13:26:19.473016 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-27 13:26:19.948304 | debian-bookworm -> localhost | changed 2026-04-27 13:26:19.956165 | 2026-04-27 13:26:19.956300 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-27 13:26:21.033419 | debian-bookworm -> localhost | changed 2026-04-27 13:26:21.051498 | 2026-04-27 13:26:21.051783 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-27 13:26:21.346013 | debian-bookworm -> localhost | ok 2026-04-27 13:26:21.364445 | 2026-04-27 13:26:21.364675 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-27 13:26:21.408781 | debian-bookworm | ok 2026-04-27 13:26:21.437501 | debian-bookworm | included: /var/lib/zuul/builds/b3ac3ec230224e99b1dec92ba1b22f2c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-27 13:26:21.444688 | 2026-04-27 13:26:21.444799 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-27 13:26:23.556059 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-04-27 13:26:23.556285 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b3ac3ec230224e99b1dec92ba1b22f2c/work/b3ac3ec230224e99b1dec92ba1b22f2c_id_rsa 2026-04-27 13:26:23.556325 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b3ac3ec230224e99b1dec92ba1b22f2c/work/b3ac3ec230224e99b1dec92ba1b22f2c_id_rsa.pub 2026-04-27 13:26:23.556354 | debian-bookworm -> localhost | The key fingerprint is: 2026-04-27 13:26:23.556379 | debian-bookworm -> localhost | SHA256:4XEAVETOuhXPXqizSHrLMplHgAxjoK7IO/fvniEkYEg zuul-build-sshkey 2026-04-27 13:26:23.556401 | debian-bookworm -> localhost | The key's randomart image is: 2026-04-27 13:26:23.556435 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-04-27 13:26:23.556457 | debian-bookworm -> localhost | |oE .o== | 2026-04-27 13:26:23.556479 | debian-bookworm -> localhost | |*. o . | 2026-04-27 13:26:23.556499 | debian-bookworm -> localhost | |+* . * . | 2026-04-27 13:26:23.556519 | debian-bookworm -> localhost | |o + . o B . | 2026-04-27 13:26:23.556538 | debian-bookworm -> localhost | | . . o. S + . | 2026-04-27 13:26:23.556594 | debian-bookworm -> localhost | |+ o .o o . | 2026-04-27 13:26:23.556618 | debian-bookworm -> localhost | |o. =o.o . | 2026-04-27 13:26:23.556639 | debian-bookworm -> localhost | | ...=++.oo | 2026-04-27 13:26:23.556660 | debian-bookworm -> localhost | | .o o*B*. | 2026-04-27 13:26:23.556680 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-04-27 13:26:23.556736 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.610345 2026-04-27 13:26:23.564342 | 2026-04-27 13:26:23.564466 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-27 13:26:23.593007 | debian-bookworm | ok 2026-04-27 13:26:23.604835 | debian-bookworm | included: /var/lib/zuul/builds/b3ac3ec230224e99b1dec92ba1b22f2c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-27 13:26:23.614696 | 2026-04-27 13:26:23.614810 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-27 13:26:23.639649 | debian-bookworm | skipping: Conditional result was False 2026-04-27 13:26:23.646550 | 2026-04-27 13:26:23.646710 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-27 13:26:24.296349 | debian-bookworm | changed 2026-04-27 13:26:24.304129 | 2026-04-27 13:26:24.304266 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-27 13:26:24.569851 | debian-bookworm | ok 2026-04-27 13:26:24.578296 | 2026-04-27 13:26:24.578421 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-27 13:26:25.238015 | debian-bookworm | changed 2026-04-27 13:26:25.245879 | 2026-04-27 13:26:25.246001 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-27 13:26:25.914239 | debian-bookworm | changed 2026-04-27 13:26:25.925112 | 2026-04-27 13:26:25.925254 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-27 13:26:25.949590 | debian-bookworm | skipping: Conditional result was False 2026-04-27 13:26:25.958303 | 2026-04-27 13:26:25.958428 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-27 13:26:26.424261 | debian-bookworm -> localhost | changed 2026-04-27 13:26:26.439600 | 2026-04-27 13:26:26.439742 | TASK [add-build-sshkey : Add back temp key] 2026-04-27 13:26:26.780078 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b3ac3ec230224e99b1dec92ba1b22f2c/work/b3ac3ec230224e99b1dec92ba1b22f2c_id_rsa (zuul-build-sshkey) 2026-04-27 13:26:26.780842 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020249 2026-04-27 13:26:26.797547 | 2026-04-27 13:26:26.797777 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-27 13:26:27.215984 | debian-bookworm | ok 2026-04-27 13:26:27.221351 | 2026-04-27 13:26:27.221458 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-27 13:26:27.245269 | debian-bookworm | skipping: Conditional result was False 2026-04-27 13:26:27.256793 | 2026-04-27 13:26:27.256929 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-27 13:26:27.732860 | debian-bookworm | ok 2026-04-27 13:26:27.747851 | 2026-04-27 13:26:27.747983 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-27 13:26:27.788087 | debian-bookworm | ok 2026-04-27 13:26:27.795272 | 2026-04-27 13:26:27.795403 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-27 13:26:28.174543 | debian-bookworm -> localhost | ok 2026-04-27 13:26:28.181493 | 2026-04-27 13:26:28.181639 | TASK [validate-host : Collect information about the host] 2026-04-27 13:27:09.380225 | debian-bookworm | ok 2026-04-27 13:27:09.390780 | 2026-04-27 13:27:09.390966 | TASK [validate-host : Sanitize hostname] 2026-04-27 13:27:09.508547 | debian-bookworm | ok 2026-04-27 13:27:09.552541 | 2026-04-27 13:27:09.552695 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-27 13:27:10.134399 | debian-bookworm -> localhost | changed 2026-04-27 13:27:10.146659 | 2026-04-27 13:27:10.146829 | TASK [validate-host : Collect information about zuul worker] 2026-04-27 13:27:10.552462 | debian-bookworm | ok 2026-04-27 13:27:10.557377 | 2026-04-27 13:27:10.557486 | TASK [validate-host : Write out all zuul information for each host] 2026-04-27 13:27:11.123132 | debian-bookworm -> localhost | changed 2026-04-27 13:27:11.133476 | 2026-04-27 13:27:11.133606 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-27 13:27:11.398927 | debian-bookworm | ok 2026-04-27 13:27:11.405758 | 2026-04-27 13:27:11.405888 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-27 13:27:12.369189 | debian-bookworm | changed: 2026-04-27 13:27:12.369450 | debian-bookworm | cd+++++++++ src/ 2026-04-27 13:27:12.369498 | debian-bookworm | cd+++++++++ src/github.com/ 2026-04-27 13:27:12.369533 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-04-27 13:27:12.369586 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-04-27 13:27:12.369616 | debian-bookworm | Containerfile 2026-04-27 13:27:12.369800 | debian-bookworm | Containerfile 2026-04-27 13:27:12.391290 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006845 2026-04-27 13:31:31.942643 | 2026-04-27 13:31:31.943031 | TASK [upload-logs : Upload logs to log server] 2026-04-27 13:31:32.501578 | localhost | Output suppressed because no_log was given 2026-04-27 13:31:32.504866 | 2026-04-27 13:31:32.505027 | LOOP [upload-logs : Compress console log and json output] 2026-04-27 13:31:32.567491 | localhost | skipping: Conditional result was False 2026-04-27 13:31:32.572723 | localhost | skipping: Conditional result was False 2026-04-27 13:31:32.586783 | 2026-04-27 13:31:32.587065 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-27 13:31:32.641637 | localhost | skipping: Conditional result was False 2026-04-27 13:31:32.642203 | 2026-04-27 13:31:32.645404 | localhost | skipping: Conditional result was False 2026-04-27 13:31:32.659392 | 2026-04-27 13:31:32.659627 | LOOP [upload-logs : Upload console log and json output]