2025-05-14 22:55:26.488313 | Job console starting
2025-05-14 22:55:26.498522 | Updating git repos
2025-05-14 22:55:26.521936 | Cloning repos into workspace
2025-05-14 22:55:26.574662 | Restoring repo states
2025-05-14 22:55:26.585104 | Merging changes
2025-05-14 22:55:27.097177 | Checking out repos
2025-05-14 22:55:27.233847 | Preparing playbooks
2025-05-14 22:55:28.209272 | Running Ansible setup
2025-05-14 22:55:33.188298 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main]
2025-05-14 22:55:34.323426 | 
2025-05-14 22:55:34.323640 | PLAY [Base pre]
2025-05-14 22:55:34.361548 | 
2025-05-14 22:55:34.361711 | TASK [Setup log path fact]
2025-05-14 22:55:34.401567 | debian-bookworm | ok
2025-05-14 22:55:34.430131 | 
2025-05-14 22:55:34.430294 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-05-14 22:55:34.481626 | debian-bookworm | ok
2025-05-14 22:55:34.503929 | 
2025-05-14 22:55:34.504077 | TASK [emit-job-header : Print job information]
2025-05-14 22:55:34.564102 | # Job Information
2025-05-14 22:55:34.564299 | Ansible Version: 2.16.14
2025-05-14 22:55:34.564333 | Job: tox
2025-05-14 22:55:34.564365 | Pipeline: check
2025-05-14 22:55:34.564388 | Executor: 521e9411259a
2025-05-14 22:55:34.564409 | Triggered by: https://github.com/osism/openstack-image-manager/pull/929
2025-05-14 22:55:34.564431 | Event ID: 806faf90-3116-11f0-9881-eb1bef904757
2025-05-14 22:55:34.569381 | 
2025-05-14 22:55:34.569506 | LOOP [emit-job-header : Print node information]
2025-05-14 22:55:34.810389 | debian-bookworm | ok:
2025-05-14 22:55:34.812770 | debian-bookworm | # Node Information
2025-05-14 22:55:34.812865 | debian-bookworm | Inventory Hostname: debian-bookworm
2025-05-14 22:55:34.812895 | debian-bookworm | Hostname: debian
2025-05-14 22:55:34.812919 | debian-bookworm | Username: zuul
2025-05-14 22:55:34.812941 | debian-bookworm | Distro: Debian 12.10
2025-05-14 22:55:34.812965 | debian-bookworm | Provider: regiocloud-a
2025-05-14 22:55:34.812986 | debian-bookworm | Region:
2025-05-14 22:55:34.813007 | debian-bookworm | Label: debian-bookworm
2025-05-14 22:55:34.813066 | debian-bookworm | Product Name: OpenStack Nova
2025-05-14 22:55:34.813105 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feaa:d444
2025-05-14 22:55:34.837787 | 
2025-05-14 22:55:34.837920 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-05-14 22:55:35.558881 | debian-bookworm -> localhost | changed
2025-05-14 22:55:35.566712 | 
2025-05-14 22:55:35.566857 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-05-14 22:55:37.398550 | debian-bookworm -> localhost | changed
2025-05-14 22:55:37.424320 | 
2025-05-14 22:55:37.424596 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-05-14 22:55:37.835487 | debian-bookworm -> localhost | ok
2025-05-14 22:55:37.845221 | 
2025-05-14 22:55:37.846486 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-05-14 22:55:37.876332 | debian-bookworm | ok
2025-05-14 22:55:37.909090 | debian-bookworm | included: /var/lib/zuul/builds/514cfc4940f84623ae6d57339250085c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-05-14 22:55:37.920632 | 
2025-05-14 22:55:37.920741 | TASK [add-build-sshkey : Create Temp SSH key]
2025-05-14 22:55:38.943749 | debian-bookworm -> localhost | Generating public/private rsa key pair.
2025-05-14 22:55:38.943932 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/514cfc4940f84623ae6d57339250085c/work/514cfc4940f84623ae6d57339250085c_id_rsa
2025-05-14 22:55:38.943969 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/514cfc4940f84623ae6d57339250085c/work/514cfc4940f84623ae6d57339250085c_id_rsa.pub
2025-05-14 22:55:38.943995 | debian-bookworm -> localhost | The key fingerprint is:
2025-05-14 22:55:38.944021 | debian-bookworm -> localhost | SHA256:voHoGitxHEiho2C1a9MwofreYN2whyREP3tYeb1oqE4 zuul-build-sshkey
2025-05-14 22:55:38.944043 | debian-bookworm -> localhost | The key's randomart image is:
2025-05-14 22:55:38.944075 | debian-bookworm -> localhost | +---[RSA 3072]----+
2025-05-14 22:55:38.944097 | debian-bookworm -> localhost | | .oo             |
2025-05-14 22:55:38.944119 | debian-bookworm -> localhost | |.oo.o  . .       |
2025-05-14 22:55:38.944140 | debian-bookworm -> localhost | |=+.+o o . .      |
2025-05-14 22:55:38.944160 | debian-bookworm -> localhost | |*o. == o . .     |
2025-05-14 22:55:38.944180 | debian-bookworm -> localhost | |o..==.o S .      |
2025-05-14 22:55:38.944204 | debian-bookworm -> localhost | |..++.O +         |
2025-05-14 22:55:38.944225 | debian-bookworm -> localhost | | o= E + o        |
2025-05-14 22:55:38.944245 | debian-bookworm -> localhost | |.o O .   o       |
2025-05-14 22:55:38.944265 | debian-bookworm -> localhost | | .+.+   .        |
2025-05-14 22:55:38.944285 | debian-bookworm -> localhost | +----[SHA256]-----+
2025-05-14 22:55:38.944329 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.354230
2025-05-14 22:55:38.950299 | 
2025-05-14 22:55:38.950388 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-05-14 22:55:38.988304 | debian-bookworm | ok
2025-05-14 22:55:39.003898 | debian-bookworm | included: /var/lib/zuul/builds/514cfc4940f84623ae6d57339250085c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-05-14 22:55:39.023297 | 
2025-05-14 22:55:39.023385 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-05-14 22:55:39.056264 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:39.061793 | 
2025-05-14 22:55:39.061882 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-05-14 22:55:39.775747 | debian-bookworm | changed
2025-05-14 22:55:39.781327 | 
2025-05-14 22:55:39.781415 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-05-14 22:55:40.032940 | debian-bookworm | ok
2025-05-14 22:55:40.038797 | 
2025-05-14 22:55:40.038923 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-05-14 22:55:40.686129 | debian-bookworm | changed
2025-05-14 22:55:40.696141 | 
2025-05-14 22:55:40.696270 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-05-14 22:55:41.333704 | debian-bookworm | changed
2025-05-14 22:55:41.340896 | 
2025-05-14 22:55:41.341003 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-05-14 22:55:41.364534 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:41.370171 | 
2025-05-14 22:55:41.370565 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-05-14 22:55:41.818687 | debian-bookworm -> localhost | changed
2025-05-14 22:55:41.830486 | 
2025-05-14 22:55:41.830596 | TASK [add-build-sshkey : Add back temp key]
2025-05-14 22:55:42.198380 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/514cfc4940f84623ae6d57339250085c/work/514cfc4940f84623ae6d57339250085c_id_rsa (zuul-build-sshkey)
2025-05-14 22:55:42.198595 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015834
2025-05-14 22:55:42.206966 | 
2025-05-14 22:55:42.207090 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-05-14 22:55:42.720398 | debian-bookworm | ok
2025-05-14 22:55:42.725201 | 
2025-05-14 22:55:42.725292 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-05-14 22:55:42.766252 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:42.776707 | 
2025-05-14 22:55:42.776809 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-05-14 22:55:43.270983 | debian-bookworm | ok
2025-05-14 22:55:43.295712 | 
2025-05-14 22:55:43.295871 | TASK [validate-host : Define zuul_info_dir fact]
2025-05-14 22:55:43.328371 | debian-bookworm | ok
2025-05-14 22:55:43.345899 | 
2025-05-14 22:55:43.346056 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-05-14 22:55:43.772680 | debian-bookworm -> localhost | ok
2025-05-14 22:55:43.780142 | 
2025-05-14 22:55:43.780253 | TASK [validate-host : Collect information about the host]
2025-05-14 22:55:45.043638 | debian-bookworm | ok
2025-05-14 22:55:45.056195 | 
2025-05-14 22:55:45.056328 | TASK [validate-host : Sanitize hostname]
2025-05-14 22:55:45.157373 | debian-bookworm | ok
2025-05-14 22:55:45.199431 | 
2025-05-14 22:55:45.199591 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-05-14 22:55:45.856076 | debian-bookworm -> localhost | changed
2025-05-14 22:55:45.863234 | 
2025-05-14 22:55:45.863370 | TASK [validate-host : Collect information about zuul worker]
2025-05-14 22:55:46.279691 | debian-bookworm | ok
2025-05-14 22:55:46.285307 | 
2025-05-14 22:55:46.285433 | TASK [validate-host : Write out all zuul information for each host]
2025-05-14 22:55:47.042481 | debian-bookworm -> localhost | changed
2025-05-14 22:55:47.072583 | 
2025-05-14 22:55:47.072729 | TASK [prepare-workspace-log : Start zuul_console daemon.]
2025-05-14 22:55:47.349875 | debian-bookworm | ok
2025-05-14 22:55:47.357740 | 
2025-05-14 22:55:47.357860 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.]
2025-05-14 22:55:48.362140 | debian-bookworm | changed:
2025-05-14 22:55:48.362344 | debian-bookworm | cd+++++++++ src/
2025-05-14 22:55:48.362471 | debian-bookworm | cd+++++++++ src/github.com/
2025-05-14 22:55:48.362499 | debian-bookworm | cd+++++++++ src/github.com/osism/
2025-05-14 22:55:48.362522 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/
2025-05-14 22:55:48.362542 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.clouds.yml
2025-05-14 22:55:48.362562 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.flake8
2025-05-14 22:55:48.362582 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.gitignore
2025-05-14 22:55:48.362600 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.yamllint.yml
2025-05-14 22:55:48.362619 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.zuul.yaml
2025-05-14 22:55:48.362636 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/LICENSE
2025-05-14 22:55:48.362655 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/MANIFEST.in
2025-05-14 22:55:48.362673 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/Pipfile
2025-05-14 22:55:48.362690 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/Pipfile.lock
2025-05-14 22:55:48.362709 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/README.md
2025-05-14 22:55:48.362727 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/_config.yml
2025-05-14 22:55:48.362745 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/clouds.yml.sample
2025-05-14 22:55:48.362764 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/pyproject.toml
2025-05-14 22:55:48.362781 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/requirements.txt
2025-05-14 22:55:48.362799 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/secure.yml.sample
2025-05-14 22:55:48.362817 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/tox.ini
2025-05-14 22:55:48.362882 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/
2025-05-14 22:55:48.362903 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/COMMIT_EDITMSG
2025-05-14 22:55:48.362921 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/FETCH_HEAD
2025-05-14 22:55:48.362939 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/HEAD
2025-05-14 22:55:48.362956 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/ORIG_HEAD
2025-05-14 22:55:48.362973 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/config
2025-05-14 22:55:48.362991 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/config.worktree
2025-05-14 22:55:48.363023 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/description
2025-05-14 22:55:48.363042 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/index
2025-05-14 22:55:48.363060 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/packed-refs
2025-05-14 22:55:48.363077 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/branches/
2025-05-14 22:55:48.363095 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/
2025-05-14 22:55:48.363113 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/applypatch-msg.sample
2025-05-14 22:55:48.363131 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/commit-msg.sample
2025-05-14 22:55:48.363149 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/fsmonitor-watchman.sample
2025-05-14 22:55:48.363167 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/post-update.sample
2025-05-14 22:55:48.363184 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/pre-applypatch.sample
2025-05-14 22:55:48.363201 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/pre-commit.sample
2025-05-14 22:55:48.363219 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/pre-merge-commit.sample
2025-05-14 22:55:48.363236 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/pre-push.sample
2025-05-14 22:55:48.363254 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/pre-rebase.sample
2025-05-14 22:55:48.363271 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/pre-receive.sample
2025-05-14 22:55:48.363289 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/prepare-commit-msg.sample
2025-05-14 22:55:48.363306 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/push-to-checkout.sample
2025-05-14 22:55:48.363323 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/hooks/update.sample
2025-05-14 22:55:48.363341 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/info/
2025-05-14 22:55:48.363358 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/info/exclude
2025-05-14 22:55:48.363376 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/logs/
2025-05-14 22:55:48.363394 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/logs/HEAD
2025-05-14 22:55:48.363411 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/
2025-05-14 22:55:48.363428 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/05/
2025-05-14 22:55:48.363478 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/05/c7f8b02aa4aa99242aeef3c09945ad41fa455e
2025-05-14 22:55:48.363504 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/21/
2025-05-14 22:55:48.363522 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/21/b45e68769589f595474fc6b93347a741e17f54
2025-05-14 22:55:48.363540 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/2d/
2025-05-14 22:55:48.363558 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/2d/10fcdd65cf505bdfb77685cc4147d19dc68d23
2025-05-14 22:55:48.363576 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/41/
2025-05-14 22:55:48.363594 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/41/34126047d9aae8ec137c39c4fab6e391e1b09a
2025-05-14 22:55:48.363616 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/51/
2025-05-14 22:55:48.363634 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/51/fb205f0dcb59faef1d7293ead5270690f273cd
2025-05-14 22:55:48.363652 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/81/
2025-05-14 22:55:48.363669 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/81/c754806e844f145a4ad4142c9eb3a20b10a65e
2025-05-14 22:55:48.363686 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/81/e2fe07d399ef4cbd55e9020b5cc6f9d6eca188
2025-05-14 22:55:48.363704 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/97/
2025-05-14 22:55:48.363721 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/97/53d26f65f60ad5536856264d505336b476468a
2025-05-14 22:55:48.363739 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/a4/
2025-05-14 22:55:48.363757 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/a4/5ddbff098b65a6288340d859545a8380371ee3
2025-05-14 22:55:48.363774 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/b3/
2025-05-14 22:55:48.363792 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/b3/d4682ba88c29018dafc53efc01b74bf8903b7f
2025-05-14 22:55:48.363809 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/ba/
2025-05-14 22:55:48.363827 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/ba/a7c6efe9f689641be472f1c7875ec7b25ff3a7
2025-05-14 22:55:48.363844 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/d2/
2025-05-14 22:55:48.363861 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/d2/e140d24b0a247125ebd1a23824045369fa8c16
2025-05-14 22:55:48.363878 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/e5/
2025-05-14 22:55:48.363896 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/e5/8a562c06728f771ee51879bb0411a1577350f0
2025-05-14 22:55:48.363913 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/e7/
2025-05-14 22:55:48.363930 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/e7/b655bbc0f025ca57b548a69ea1333a3ac3e209
2025-05-14 22:55:48.363948 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/ec/
2025-05-14 22:55:48.363965 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/ec/c13e3c7f596cfbd6c4a32e8227febeabc60e21
2025-05-14 22:55:48.363982 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/fd/
2025-05-14 22:55:48.363999 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/fd/19816bd750365dc231c09e3e8035f40d3b47ce
2025-05-14 22:55:48.364017 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/info/
2025-05-14 22:55:48.364035 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/pack/
2025-05-14 22:55:48.364053 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/pack/pack-11b7b4f1899fa98fbbece2e005cea2589ea4a858.idx
2025-05-14 22:55:48.364072 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/objects/pack/pack-11b7b4f1899fa98fbbece2e005cea2589ea4a858.pack
2025-05-14 22:55:48.364089 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/refs/
2025-05-14 22:55:48.364107 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/refs/heads/
2025-05-14 22:55:48.364129 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/refs/heads/main
2025-05-14 22:55:48.364147 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/refs/remotes/
2025-05-14 22:55:48.364165 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/refs/remotes/origin/
2025-05-14 22:55:48.364183 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/refs/remotes/origin/main
2025-05-14 22:55:48.364200 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.git/refs/zuul/
2025-05-14 22:55:48.364217 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/refs/zuul/b28b7af69320201d1cf206ebf28373980add1451
2025-05-14 22:55:48.364235 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.git/refs/zuul/fetch
2025-05-14 22:55:48.364252 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.github/
2025-05-14 22:55:48.364269 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.github/renovate.json
2025-05-14 22:55:48.364286 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/.github/workflows/
2025-05-14 22:55:48.364303 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.github/workflows/publish.yml
2025-05-14 22:55:48.364320 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.github/workflows/update-images.yml
2025-05-14 22:55:48.364338 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/.github/workflows/validate-configuration.yml
2025-05-14 22:55:48.364355 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/contrib/
2025-05-14 22:55:48.364373 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/contrib/mirror.py
2025-05-14 22:55:48.364390 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/contrib/table.py
2025-05-14 22:55:48.364407 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/contrib/update.py
2025-05-14 22:55:48.364424 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/etc/
2025-05-14 22:55:48.364442 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/__init__.py
2025-05-14 22:55:48.364474 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/schema.yaml
2025-05-14 22:55:48.364493 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/etc/images/
2025-05-14 22:55:48.364510 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/almalinux.yml
2025-05-14 22:55:48.364528 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/centos.yml
2025-05-14 22:55:48.364545 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/cirros.yml
2025-05-14 22:55:48.364562 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/clearlinux.yml
2025-05-14 22:55:48.364579 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/debian.yml
2025-05-14 22:55:48.364596 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/fedora.yml
2025-05-14 22:55:48.364613 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/flatcar.yml
2025-05-14 22:55:48.364631 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/gardenlinux.yml
2025-05-14 22:55:48.364648 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/kubernetes.yml
2025-05-14 22:55:48.364665 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/opensuse.yml
2025-05-14 22:55:48.364683 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/opnsense.yml
2025-05-14 22:55:48.364700 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/rockylinux.yml
2025-05-14 22:55:48.364721 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/talos.yml
2025-05-14 22:55:48.364738 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/etc/images/ubuntu.yml
2025-05-14 22:55:48.364755 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/openstack_image_manager/
2025-05-14 22:55:48.364773 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/openstack_image_manager/__init__.py
2025-05-14 22:55:48.364790 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/openstack_image_manager/main.py
2025-05-14 22:55:48.364808 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/playbooks/
2025-05-14 22:55:48.364826 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/playbooks/integration-test.yml
2025-05-14 22:55:48.364847 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/playbooks/pre-integration-test.yml
2025-05-14 22:55:48.364866 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/scripts/
2025-05-14 22:55:48.364884 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/scripts/build.sh
2025-05-14 22:55:48.364901 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/scripts/push.sh
2025-05-14 22:55:48.364919 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/test/
2025-05-14 22:55:48.364936 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/test/__init__.py
2025-05-14 22:55:48.364954 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/test/integration/
2025-05-14 22:55:48.364971 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/test/integration/__init__.py
2025-05-14 22:55:48.364990 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/test/integration/test_manage_api.py
2025-05-14 22:55:48.365008 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/test/integration/fixtures/
2025-05-14 22:55:48.365026 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/test/integration/fixtures/fake_image.img
2025-05-14 22:55:48.365044 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/test/integration/fixtures/test_image.yml
2025-05-14 22:55:48.365062 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/test/unit/
2025-05-14 22:55:48.365080 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/test/unit/__init__.py
2025-05-14 22:55:48.365097 | debian-bookworm | <f+++++++++ src/github.com/osism/openstack-image-manager/test/unit/test_manage.py
2025-05-14 22:55:48.372010 | 
2025-05-14 22:55:48.372108 | PLAY RECAP
2025-05-14 22:55:48.372159 | debian-bookworm | ok: 26 changed: 11 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2025-05-14 22:55:48.372184 | 
2025-05-14 22:55:48.522965 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main]
2025-05-14 22:55:48.526187 | PRE-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-05-14 22:55:49.483604 | 
2025-05-14 22:55:49.483783 | PLAY [all]
2025-05-14 22:55:49.515188 | 
2025-05-14 22:55:49.515350 | TASK [Install binary dependencies]
2025-05-14 22:55:49.593667 | debian-bookworm | ok
2025-05-14 22:55:49.617248 | 
2025-05-14 22:55:49.617424 | TASK [bindep : Include find tasks]
2025-05-14 22:55:49.657725 | debian-bookworm | ok
2025-05-14 22:55:49.677619 | debian-bookworm | included: /var/lib/zuul/builds/514cfc4940f84623ae6d57339250085c/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/bindep/tasks/find.yaml
2025-05-14 22:55:49.687903 | 
2025-05-14 22:55:49.688054 | TASK [bindep : Look for bindep.txt]
2025-05-14 22:55:50.083514 | debian-bookworm | ok
2025-05-14 22:55:50.092239 | 
2025-05-14 22:55:50.092374 | TASK [bindep : Define bindep_file fact]
2025-05-14 22:55:50.146826 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:50.153041 | 
2025-05-14 22:55:50.153179 | TASK [bindep : Look for other-requirements.txt]
2025-05-14 22:55:50.438496 | debian-bookworm | ok
2025-05-14 22:55:50.445840 | 
2025-05-14 22:55:50.445969 | TASK [bindep : Define bindep_file fact]
2025-05-14 22:55:50.498859 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:50.506272 | 
2025-05-14 22:55:50.506406 | TASK [bindep : Look for bindep fallback file]
2025-05-14 22:55:50.571336 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:50.578983 | 
2025-05-14 22:55:50.579155 | TASK [bindep : Define bindep_file fact]
2025-05-14 22:55:50.656143 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:50.663973 | 
2025-05-14 22:55:50.664106 | TASK [bindep : Include bindep tasks]
2025-05-14 22:55:50.727537 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:50.733706 | 
2025-05-14 22:55:50.733838 | TASK [bindep : Include install tasks]
2025-05-14 22:55:50.776728 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:50.783075 | 
2025-05-14 22:55:50.783211 | LOOP [bindep : Include package tasks]
2025-05-14 22:55:50.866080 | 
2025-05-14 22:55:50.866398 | TASK [Run test-setup role]
2025-05-14 22:55:50.901908 | debian-bookworm | ok
2025-05-14 22:55:50.943779 | 
2025-05-14 22:55:50.943926 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-05-14 22:55:51.207992 | debian-bookworm | ok
2025-05-14 22:55:51.220764 | 
2025-05-14 22:55:51.220910 | TASK [test-setup : Run tools/test-setup.sh]
2025-05-14 22:55:51.768794 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:51.777260 | 
2025-05-14 22:55:51.777365 | PLAY RECAP
2025-05-14 22:55:51.777429 | debian-bookworm | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-05-14 22:55:51.777503 | 
2025-05-14 22:55:51.919084 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-05-14 22:55:51.920204 | PRE-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master]
2025-05-14 22:55:52.706745 | 
2025-05-14 22:55:52.706935 | PLAY [all]
2025-05-14 22:55:52.724941 | 
2025-05-14 22:55:52.725100 | TASK [ensure-python : Validate python_version value]
2025-05-14 22:55:52.761002 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:52.772995 | 
2025-05-14 22:55:52.773279 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)]
2025-05-14 22:55:52.800296 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:52.813170 | 
2025-05-14 22:55:52.813372 | TASK [ensure-python : Pull in venv package]
2025-05-14 22:55:52.848734 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:52.861828 | 
2025-05-14 22:55:52.862005 | TASK [ensure-python : Set default RPM package name]
2025-05-14 22:55:52.897263 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:52.912585 | 
2025-05-14 22:55:52.912763 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9 and Python 3.9]
2025-05-14 22:55:52.947667 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:52.960924 | 
2025-05-14 22:55:52.961116 | TASK [ensure-python : Install RPM package]
2025-05-14 22:55:52.996596 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:53.009737 | 
2025-05-14 22:55:53.009914 | TASK [ensure-python : Install python using pyenv]
2025-05-14 22:55:53.036651 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:53.049874 | 
2025-05-14 22:55:53.050060 | TASK [ensure-python : Activate python using stow]
2025-05-14 22:55:53.084916 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:55:53.097726 | 
2025-05-14 22:55:53.097855 | TASK [Install pip]
2025-05-14 22:55:53.121002 | debian-bookworm | ok
2025-05-14 22:55:53.145067 | 
2025-05-14 22:55:53.145175 | TASK [ensure-pip : Check if pip is installed]
2025-05-14 22:55:53.674117 | debian-bookworm | ok: Runtime: 0:00:00.007866
2025-05-14 22:55:53.692314 | 
2025-05-14 22:55:53.692583 | LOOP [ensure-pip : Install pip from packages]
2025-05-14 22:55:53.749571 | debian-bookworm | ok: "/var/lib/zuul/builds/514cfc4940f84623ae6d57339250085c/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/ensure-pip/tasks/Debian.yaml"
2025-05-14 22:55:53.771989 | debian-bookworm | included: /var/lib/zuul/builds/514cfc4940f84623ae6d57339250085c/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/ensure-pip/tasks/Debian.yaml
2025-05-14 22:55:53.784592 | 
2025-05-14 22:55:53.784711 | TASK [ensure-pip : Update package lists]
2025-05-14 22:55:57.862639 | debian-bookworm | changed
2025-05-14 22:55:57.871965 | 
2025-05-14 22:55:57.872121 | TASK [ensure-pip : Install Python 3 pip]
2025-05-14 22:56:02.543761 | debian-bookworm | changed
2025-05-14 22:56:02.552188 | 
2025-05-14 22:56:02.552326 | TASK [ensure-pip : Install Python 2 pip]
2025-05-14 22:56:02.577101 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:02.591654 | 
2025-05-14 22:56:02.591967 | TASK [ensure-pip : Ensure setuptools]
2025-05-14 22:56:02.617772 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:02.625606 | 
2025-05-14 22:56:02.625730 | TASK [ensure-pip : Check for ensurepip module]
2025-05-14 22:56:02.979320 | debian-bookworm | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT]
2025-05-14 22:56:02.979496 | debian-bookworm |                            [--altinstall] [--default-pip]
2025-05-14 22:56:02.979518 | debian-bookworm |
2025-05-14 22:56:02.979534 | debian-bookworm | options:
2025-05-14 22:56:02.979549 | debian-bookworm |   -h, --help     show this help message and exit
2025-05-14 22:56:02.979568 | debian-bookworm |   --version      Show the version of pip that is bundled with this Python.
2025-05-14 22:56:02.979581 | debian-bookworm |   -v, --verbose  Give more output. Option is additive, and can be used up to 3
2025-05-14 22:56:02.979625 | debian-bookworm |                  times.
2025-05-14 22:56:02.979649 | debian-bookworm |   -U, --upgrade  Upgrade pip and dependencies, even if already installed.
2025-05-14 22:56:02.979663 | debian-bookworm |   --user         Install using the user scheme.
2025-05-14 22:56:02.979676 | debian-bookworm |   --root ROOT    Install everything relative to this alternate root directory.
2025-05-14 22:56:02.979688 | debian-bookworm |   --altinstall   Make an alternate install, installing only the X.Y versioned
2025-05-14 22:56:02.979699 | debian-bookworm |                  scripts (Default: pipX, pipX.Y, easy_install-X.Y).
2025-05-14 22:56:02.979710 | debian-bookworm |   --default-pip  Make a default pip install, installing the unqualified pip
2025-05-14 22:56:02.979721 | debian-bookworm |                  and easy_install in addition to the versioned scripts.
2025-05-14 22:56:03.154663 | debian-bookworm | ok: Runtime: 0:00:00.081042
2025-05-14 22:56:03.167866 | 
2025-05-14 22:56:03.168021 | TASK [ensure-pip : Ensure python3-venv]
2025-05-14 22:56:03.203631 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:03.217268 | 
2025-05-14 22:56:03.217420 | TASK [ensure-pip : Install pip from source]
2025-05-14 22:56:03.243956 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:03.255967 | 
2025-05-14 22:56:03.256122 | TASK [ensure-pip : Probe for venv python full path]
2025-05-14 22:56:03.547852 | debian-bookworm | /usr/bin/python3
2025-05-14 22:56:03.787187 | debian-bookworm | ok: Runtime: 0:00:00.007170
2025-05-14 22:56:03.796864 | 
2025-05-14 22:56:03.797023 | TASK [ensure-pip : Set host default]
2025-05-14 22:56:03.875438 | debian-bookworm | ok
2025-05-14 22:56:03.885292 | 
2025-05-14 22:56:03.885444 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2025-05-14 22:56:03.963827 | debian-bookworm | ok
2025-05-14 22:56:03.977523 | 
2025-05-14 22:56:03.977655 | TASK [ensure-tox : Check if tox is installed]
2025-05-14 22:56:04.540754 | debian-bookworm | ok: Runtime: 0:00:00.007478
2025-05-14 22:56:04.553707 | 
2025-05-14 22:56:04.553882 | TASK [ensure-tox : Export preinstalled tox_exectuable]
2025-05-14 22:56:04.589391 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:04.603084 | 
2025-05-14 22:56:04.603251 | TASK [ensure-tox : Create local venv]
2025-05-14 22:56:09.138765 | debian-bookworm | ok: Runtime: 0:00:03.959497
2025-05-14 22:56:09.151666 | 
2025-05-14 22:56:09.151863 | TASK [ensure-tox : Install tox to local venv]
2025-05-14 22:56:09.983300 | debian-bookworm | Collecting tox
2025-05-14 22:56:10.037959 | debian-bookworm |   Downloading tox-4.26.0-py3-none-any.whl (172 kB)
2025-05-14 22:56:10.076309 | debian-bookworm |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 172.8/172.8 kB 5.2 MB/s eta 0:00:00
2025-05-14 22:56:10.131423 | debian-bookworm | Collecting cachetools>=5.5.1
2025-05-14 22:56:10.138789 | debian-bookworm |   Downloading cachetools-5.5.2-py3-none-any.whl (10 kB)
2025-05-14 22:56:10.164350 | debian-bookworm | Collecting chardet>=5.2
2025-05-14 22:56:10.171946 | debian-bookworm |   Downloading chardet-5.2.0-py3-none-any.whl (199 kB)
2025-05-14 22:56:10.194403 | debian-bookworm |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 10.1 MB/s eta 0:00:00
2025-05-14 22:56:10.231934 | debian-bookworm | Collecting colorama>=0.4.6
2025-05-14 22:56:10.239270 | debian-bookworm |   Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB)
2025-05-14 22:56:10.282859 | debian-bookworm | Collecting filelock>=3.16.1
2025-05-14 22:56:10.290283 | debian-bookworm |   Downloading filelock-3.18.0-py3-none-any.whl (16 kB)
2025-05-14 22:56:10.332209 | debian-bookworm | Collecting packaging>=24.2
2025-05-14 22:56:10.339879 | debian-bookworm |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-05-14 22:56:10.351040 | debian-bookworm |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 6.3 MB/s eta 0:00:00
2025-05-14 22:56:10.388891 | debian-bookworm | Collecting platformdirs>=4.3.6
2025-05-14 22:56:10.395937 | debian-bookworm |   Downloading platformdirs-4.3.8-py3-none-any.whl (18 kB)
2025-05-14 22:56:10.427986 | debian-bookworm | Collecting pluggy>=1.5
2025-05-14 22:56:10.435061 | debian-bookworm |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-05-14 22:56:10.465837 | debian-bookworm | Collecting pyproject-api>=1.8
2025-05-14 22:56:10.472742 | debian-bookworm |   Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB)
2025-05-14 22:56:10.586983 | debian-bookworm | Collecting virtualenv>=20.31
2025-05-14 22:56:10.594153 | debian-bookworm |   Downloading virtualenv-20.31.2-py3-none-any.whl (6.1 MB)
2025-05-14 22:56:10.870758 | debian-bookworm |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.1/6.1 MB 22.3 MB/s eta 0:00:00
2025-05-14 22:56:10.980621 | debian-bookworm | Collecting distlib<1,>=0.3.7
2025-05-14 22:56:10.987725 | debian-bookworm |   Downloading distlib-0.3.9-py2.py3-none-any.whl (468 kB)
2025-05-14 22:56:11.008750 | debian-bookworm |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 24.8 MB/s eta 0:00:00
2025-05-14 22:56:11.155612 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox
2025-05-14 22:56:11.872081 | debian-bookworm | Successfully installed cachetools-5.5.2 chardet-5.2.0 colorama-0.4.6 distlib-0.3.9 filelock-3.18.0 packaging-25.0 platformdirs-4.3.8 pluggy-1.5.0 pyproject-api-1.9.1 tox-4.26.0 virtualenv-20.31.2
2025-05-14 22:56:12.185240 | debian-bookworm | ok: Runtime: 0:00:02.509310
2025-05-14 22:56:12.191776 | 
2025-05-14 22:56:12.191899 | TASK [ensure-tox : Export installed tox_executable path]
2025-05-14 22:56:12.222106 | debian-bookworm | ok
2025-05-14 22:56:12.227221 | 
2025-05-14 22:56:12.227339 | TASK [ensure-tox : Output tox version]
2025-05-14 22:56:12.700482 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul
2025-05-14 22:56:12.717193 | debian-bookworm | 4.26.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py
2025-05-14 22:56:12.783328 | debian-bookworm | ok: Runtime: 0:00:00.262032
2025-05-14 22:56:12.789099 | 
2025-05-14 22:56:12.789207 | TASK [ensure-tox : Make global symlink]
2025-05-14 22:56:12.813006 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:12.819998 | 
2025-05-14 22:56:12.820086 | PLAY RECAP
2025-05-14 22:56:12.820140 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0
2025-05-14 22:56:12.820166 | 
2025-05-14 22:56:13.021994 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master]
2025-05-14 22:56:13.023193 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master]
2025-05-14 22:56:13.935881 | 
2025-05-14 22:56:13.936050 | PLAY [all]
2025-05-14 22:56:13.966089 | 
2025-05-14 22:56:13.966249 | TASK [revoke-sudo : Check if zuul is sudoer]
2025-05-14 22:56:14.479236 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known
2025-05-14 22:56:14.511532 | debian-bookworm | ok: Runtime: 0:00:00.022674
2025-05-14 22:56:14.524069 | 
2025-05-14 22:56:14.524231 | TASK [revoke-sudo : Remove sudo access for zuul user.]
2025-05-14 22:56:15.101353 | debian-bookworm | ok: Runtime: 0:00:00.006066
2025-05-14 22:56:15.115039 | 
2025-05-14 22:56:15.115230 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.]
2025-05-14 22:56:15.478198 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known
2025-05-14 22:56:15.482216 | debian-bookworm | sudo: a password is required
2025-05-14 22:56:15.649077 | debian-bookworm | ok: Runtime: 0:00:00.023134
2025-05-14 22:56:15.671013 | 
2025-05-14 22:56:15.671354 | TASK [tox : Check to see if the constraints file exists]
2025-05-14 22:56:15.713721 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:15.722016 | 
2025-05-14 22:56:15.722149 | TASK [tox : Fail if constraints file is missing]
2025-05-14 22:56:15.758185 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:15.766683 | 
2025-05-14 22:56:15.766845 | TASK [tox : Record file location]
2025-05-14 22:56:15.802021 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:15.809692 | 
2025-05-14 22:56:15.809832 | TASK [tox : Install tox siblings]
2025-05-14 22:56:15.843930 | debian-bookworm | ok
2025-05-14 22:56:15.866493 | debian-bookworm | included: /var/lib/zuul/builds/514cfc4940f84623ae6d57339250085c/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml
2025-05-14 22:56:15.887924 | 
2025-05-14 22:56:15.889428 | TASK [tox : Run tox without tests]
2025-05-14 22:56:16.932348 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt
2025-05-14 22:56:29.865608 | debian-bookworm |   test: OK (13.37 seconds)
2025-05-14 22:56:29.865696 | debian-bookworm |   congratulations :) (13.44 seconds)
2025-05-14 22:56:29.968396 | debian-bookworm | ok: Runtime: 0:00:13.673069
2025-05-14 22:56:29.975651 | 
2025-05-14 22:56:29.975774 | TASK [tox : Create a tempfile to save tox showconfig]
2025-05-14 22:56:30.393397 | debian-bookworm | changed
2025-05-14 22:56:30.403189 | 
2025-05-14 22:56:30.403343 | TASK [tox : Get tox envlist config]
2025-05-14 22:56:31.452928 | debian-bookworm | ok: Runtime: 0:00:00.316299
2025-05-14 22:56:31.466302 | 
2025-05-14 22:56:31.466528 | TASK [tox : Install any sibling python packages]
2025-05-14 22:56:31.920970 | debian-bookworm | ok: No setup.cfg, no action needed
2025-05-14 22:56:31.930713 | 
2025-05-14 22:56:31.930913 | TASK [tox : Remove tempfile]
2025-05-14 22:56:32.407689 | debian-bookworm | changed
2025-05-14 22:56:32.414155 | 
2025-05-14 22:56:32.414280 | TASK [tox : Emit tox command]
2025-05-14 22:56:32.458087 | /home/zuul/.local/tox/bin/tox   -etest  -- test/unit
2025-05-14 22:56:32.468224 | 
2025-05-14 22:56:32.468458 | TASK [tox : Run tox]
2025-05-14 22:56:33.100821 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit
2025-05-14 22:56:33.864267 | debian-bookworm | ...............
2025-05-14 22:56:33.864318 | debian-bookworm | ----------------------------------------------------------------------
2025-05-14 22:56:33.864332 | debian-bookworm | Ran 15 tests in 0.168s
2025-05-14 22:56:33.864338 | debian-bookworm |
2025-05-14 22:56:33.864343 | debian-bookworm | OK
2025-05-14 22:56:33.965357 | debian-bookworm |   test: OK (0.91=setup[0.04]+cmd[0.86] seconds)
2025-05-14 22:56:33.965480 | debian-bookworm |   congratulations :) (0.98 seconds)
2025-05-14 22:56:34.056689 | debian-bookworm | ok: Runtime: 0:00:01.212698
2025-05-14 22:56:34.062694 | 
2025-05-14 22:56:34.062810 | TASK [tox : Look for output]
2025-05-14 22:56:34.479783 | debian-bookworm | ok
2025-05-14 22:56:34.500047 | 
2025-05-14 22:56:34.500218 | TASK [tox : Return file comments to Zuul]
2025-05-14 22:56:34.537912 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:34.545680 | 
2025-05-14 22:56:34.545772 | PLAY RECAP
2025-05-14 22:56:34.545830 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-05-14 22:56:34.545856 | 
2025-05-14 22:56:34.694989 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master]
2025-05-14 22:56:34.696110 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master]
2025-05-14 22:56:35.415534 | 
2025-05-14 22:56:35.415694 | PLAY [all]
2025-05-14 22:56:35.430685 | 
2025-05-14 22:56:35.430858 | TASK [fetch-tox-output : Set tox log path for multiple nodes]
2025-05-14 22:56:35.486084 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:35.492111 | 
2025-05-14 22:56:35.492254 | TASK [fetch-tox-output : Set tox log path for single node]
2025-05-14 22:56:35.546910 | debian-bookworm | ok
2025-05-14 22:56:35.555304 | 
2025-05-14 22:56:35.555526 | TASK [fetch-tox-output : Ensure local tox dir]
2025-05-14 22:56:36.081427 | debian-bookworm -> localhost | changed
2025-05-14 22:56:36.092529 | 
2025-05-14 22:56:36.092659 | TASK [fetch-tox-output : Ensure zuul-output tox dir]
2025-05-14 22:56:36.147262 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:36.159991 | 
2025-05-14 22:56:36.160151 | TASK [fetch-tox-output : Set envlist fact]
2025-05-14 22:56:36.208506 | debian-bookworm | ok
2025-05-14 22:56:36.216969 | 
2025-05-14 22:56:36.217094 | TASK [fetch-tox-output : Get tox version]
2025-05-14 22:56:36.894313 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul
2025-05-14 22:56:36.912277 | debian-bookworm | 4.26.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py
2025-05-14 22:56:37.248505 | debian-bookworm | ok: Runtime: 0:00:00.295316
2025-05-14 22:56:37.254333 | 
2025-05-14 22:56:37.254443 | TASK [fetch-tox-output : Find all default environments]
2025-05-14 22:56:37.783625 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:37.798748 | 
2025-05-14 22:56:37.798995 | TASK [fetch-tox-output : Set envlist fact]
2025-05-14 22:56:37.834865 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:37.841824 | 
2025-05-14 22:56:37.842136 | TASK [fetch-tox-output : Find all default environments]
2025-05-14 22:56:38.381209 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:38.391115 | 
2025-05-14 22:56:38.391283 | TASK [fetch-tox-output : Set envlist fact]
2025-05-14 22:56:38.426994 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:38.436891 | 
2025-05-14 22:56:38.437058 | LOOP [fetch-tox-output : Copy tox logs]
2025-05-14 22:56:38.480846 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:38.493383 | 
2025-05-14 22:56:38.493588 | LOOP [fetch-tox-output : Collect tox logs]
2025-05-14 22:56:39.299194 | debian-bookworm | changed:
2025-05-14 22:56:39.299640 | debian-bookworm | created directory /var/lib/zuul/builds/514cfc4940f84623ae6d57339250085c/work/logs/tox/test
2025-05-14 22:56:39.299727 | debian-bookworm | cd+++++++++ ./
2025-05-14 22:56:39.299781 | debian-bookworm | >f+++++++++ 1-commands[0].log
2025-05-14 22:56:39.299842 | 
2025-05-14 22:56:39.322025 | 
2025-05-14 22:56:39.322168 | PLAY RECAP
2025-05-14 22:56:39.322253 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0
2025-05-14 22:56:39.322296 | 
2025-05-14 22:56:39.449567 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master]
2025-05-14 22:56:39.452263 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master]
2025-05-14 22:56:40.208009 | 
2025-05-14 22:56:40.208172 | PLAY [all]
2025-05-14 22:56:40.225247 | 
2025-05-14 22:56:40.225424 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-05-14 22:56:40.454003 | debian-bookworm | changed: non-zero return code
2025-05-14 22:56:40.462759 | 
2025-05-14 22:56:40.462973 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-05-14 22:56:40.488041 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:40.499113 | 
2025-05-14 22:56:40.499284 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-05-14 22:56:40.539206 | 
2025-05-14 22:56:40.539658 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-05-14 22:56:40.592147 | 
2025-05-14 22:56:40.592488 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-05-14 22:56:40.619193 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:40.631665 | 
2025-05-14 22:56:40.631853 | LOOP [fetch-subunit-output : Generate subunit file]
2025-05-14 22:56:40.667118 | 
2025-05-14 22:56:40.667446 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-05-14 22:56:40.692794 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:40.708051 | 
2025-05-14 22:56:40.708232 | TASK [fetch-subunit-output : Remove the temporary file]
2025-05-14 22:56:40.733450 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:40.746506 | 
2025-05-14 22:56:40.747197 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-05-14 22:56:40.772220 | debian-bookworm | skipping: Conditional result was False
2025-05-14 22:56:40.783042 | 
2025-05-14 22:56:40.783175 | PLAY RECAP
2025-05-14 22:56:40.783256 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-05-14 22:56:40.783296 | 
2025-05-14 22:56:40.910689 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master]
2025-05-14 22:56:40.913579 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main]
2025-05-14 22:56:41.655215 | 
2025-05-14 22:56:41.655377 | PLAY [Base post]
2025-05-14 22:56:41.669502 | 
2025-05-14 22:56:41.669637 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-05-14 22:56:42.234642 | debian-bookworm | changed
2025-05-14 22:56:42.245898 | 
2025-05-14 22:56:42.246041 | PLAY RECAP
2025-05-14 22:56:42.246129 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-05-14 22:56:42.246217 | 
2025-05-14 22:56:42.362354 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main]
2025-05-14 22:56:42.365053 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main]
2025-05-14 22:56:43.159589 | 
2025-05-14 22:56:43.159758 | PLAY [Base post-logs]
2025-05-14 22:56:43.170400 | 
2025-05-14 22:56:43.170553 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-05-14 22:56:43.637255 | localhost | changed
2025-05-14 22:56:43.658113 | 
2025-05-14 22:56:43.658335 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-05-14 22:56:43.697976 | localhost | ok
2025-05-14 22:56:43.703773 | 
2025-05-14 22:56:43.703928 | TASK [Set zuul-log-path fact]
2025-05-14 22:56:43.721016 | localhost | ok
2025-05-14 22:56:43.733467 | 
2025-05-14 22:56:43.733686 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-05-14 22:56:43.772299 | localhost | ok
2025-05-14 22:56:43.777131 | 
2025-05-14 22:56:43.777276 | TASK [upload-logs : Create log directories]
2025-05-14 22:56:44.276556 | localhost | changed
2025-05-14 22:56:44.282093 | 
2025-05-14 22:56:44.282263 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-05-14 22:56:44.807864 | localhost -> localhost | ok: Runtime: 0:00:00.007494
2025-05-14 22:56:44.817074 | 
2025-05-14 22:56:44.817306 | TASK [upload-logs : Upload logs to log server]
2025-05-14 22:56:45.378063 | localhost | Output suppressed because no_log was given
2025-05-14 22:56:45.382157 | 
2025-05-14 22:56:45.382428 | LOOP [upload-logs : Compress console log and json output]
2025-05-14 22:56:45.430188 | localhost | skipping: Conditional result was False
2025-05-14 22:56:45.435514 | localhost | skipping: Conditional result was False
2025-05-14 22:56:45.444617 | 
2025-05-14 22:56:45.444856 | LOOP [upload-logs : Upload compressed console log and json output]
2025-05-14 22:56:45.498090 | localhost | skipping: Conditional result was False
2025-05-14 22:56:45.498904 | 
2025-05-14 22:56:45.502165 | localhost | skipping: Conditional result was False
2025-05-14 22:56:45.517304 | 
2025-05-14 22:56:45.517581 | LOOP [upload-logs : Upload console log and json output]