2026-09-02 21:02:00.432889 | Job console starting 2026-09-02 21:02:00.443607 | Updating git repos 2026-09-02 21:02:00.492660 | Cloning repos into workspace 2026-09-02 21:02:00.542136 | Restoring repo states 2026-09-02 21:02:00.547573 | Merging changes 2026-09-02 21:02:01.170639 | Checking out repos 2026-09-02 21:02:01.225951 | Preparing playbooks 2026-09-02 21:02:02.445966 | Running Ansible setup 2026-09-02 21:02:07.869578 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-02 21:02:08.804088 | 2026-09-02 21:02:08.804244 | PLAY [Base pre] 2026-09-02 21:02:08.840001 | 2026-09-02 21:02:08.840147 | TASK [Setup log path fact] 2026-09-02 21:02:08.874376 | debian-bookworm | ok 2026-09-02 21:02:08.890514 | 2026-09-02 21:02:08.890661 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-02 21:02:08.939576 | debian-bookworm | ok 2026-09-02 21:02:08.949649 | 2026-09-02 21:02:08.949772 | TASK [emit-job-header : Print job information] 2026-09-02 21:02:08.990119 | # Job Information 2026-09-02 21:02:08.990301 | Ansible Version: 2.16.14 2026-09-02 21:02:08.990337 | Job: tox 2026-09-02 21:02:08.990371 | Pipeline: check 2026-09-02 21:02:08.990394 | Executor: 521e9411259a 2026-09-02 21:02:08.990415 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1263 2026-09-02 21:02:08.990437 | Event ID: 844e6990-a711-11f1-8f16-52f4d227ae62 2026-09-02 21:02:08.995531 | 2026-09-02 21:02:08.995645 | LOOP [emit-job-header : Print node information] 2026-09-02 21:02:09.193663 | debian-bookworm | ok: 2026-09-02 21:02:09.193943 | debian-bookworm | # Node Information 2026-09-02 21:02:09.193990 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-02 21:02:09.194018 | debian-bookworm | Hostname: debian 2026-09-02 21:02:09.194042 | debian-bookworm | Username: zuul 2026-09-02 21:02:09.194063 | debian-bookworm | Distro: Debian 12.15 2026-09-02 21:02:09.194087 | debian-bookworm | Provider: regiocloud-a 2026-09-02 21:02:09.194107 | debian-bookworm | Region: 2026-09-02 21:02:09.194128 | debian-bookworm | Label: debian-bookworm 2026-09-02 21:02:09.194148 | debian-bookworm | Product Name: OpenStack Nova 2026-09-02 21:02:09.194168 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fea1:3cb2 2026-09-02 21:02:09.212869 | 2026-09-02 21:02:09.212968 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-02 21:02:09.831381 | debian-bookworm -> localhost | changed 2026-09-02 21:02:09.838131 | 2026-09-02 21:02:09.838237 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-02 21:02:11.026767 | debian-bookworm -> localhost | changed 2026-09-02 21:02:11.035285 | 2026-09-02 21:02:11.035366 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-02 21:02:11.412406 | debian-bookworm -> localhost | ok 2026-09-02 21:02:11.417177 | 2026-09-02 21:02:11.417254 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-02 21:02:11.454712 | debian-bookworm | ok 2026-09-02 21:02:11.467053 | debian-bookworm | included: /var/lib/zuul/builds/1f8a5423ea234275a0e276406096e8e7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-02 21:02:11.472368 | 2026-09-02 21:02:11.472439 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-02 21:02:13.047340 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-02 21:02:13.047503 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1f8a5423ea234275a0e276406096e8e7/work/1f8a5423ea234275a0e276406096e8e7_id_rsa 2026-09-02 21:02:13.047534 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1f8a5423ea234275a0e276406096e8e7/work/1f8a5423ea234275a0e276406096e8e7_id_rsa.pub 2026-09-02 21:02:13.047556 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-02 21:02:13.047577 | debian-bookworm -> localhost | SHA256:Y2Y+LMuDww851wEB8oksJyajwt94TlZgxXRMHQVdHpE zuul-build-sshkey 2026-09-02 21:02:13.047595 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-02 21:02:13.047622 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-02 21:02:13.047641 | debian-bookworm -> localhost | | . ..+ooo.o=..+o| 2026-09-02 21:02:13.047659 | debian-bookworm -> localhost | | . + ..o.. . ..E.| 2026-09-02 21:02:13.047675 | debian-bookworm -> localhost | |=.+ oo. . | 2026-09-02 21:02:13.047692 | debian-bookworm -> localhost | |== . .. | 2026-09-02 21:02:13.047708 | debian-bookworm -> localhost | |o. .S | 2026-09-02 21:02:13.047729 | debian-bookworm -> localhost | |. . o..B o | 2026-09-02 21:02:13.047746 | debian-bookworm -> localhost | | ++*o = | 2026-09-02 21:02:13.047762 | debian-bookworm -> localhost | | O=.o . | 2026-09-02 21:02:13.047779 | debian-bookworm -> localhost | | ++. | 2026-09-02 21:02:13.047795 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-02 21:02:13.047859 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.081595 2026-09-02 21:02:13.053032 | 2026-09-02 21:02:13.053112 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-02 21:02:13.070158 | debian-bookworm | ok 2026-09-02 21:02:13.077915 | debian-bookworm | included: /var/lib/zuul/builds/1f8a5423ea234275a0e276406096e8e7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-02 21:02:13.084374 | 2026-09-02 21:02:13.084442 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-02 21:02:13.096621 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:02:13.101042 | 2026-09-02 21:02:13.101111 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-02 21:02:13.724245 | debian-bookworm | changed 2026-09-02 21:02:13.729058 | 2026-09-02 21:02:13.729133 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-02 21:02:14.007414 | debian-bookworm | ok 2026-09-02 21:02:14.011915 | 2026-09-02 21:02:14.011990 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-02 21:02:14.664492 | debian-bookworm | changed 2026-09-02 21:02:14.669563 | 2026-09-02 21:02:14.669649 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-02 21:02:15.293669 | debian-bookworm | changed 2026-09-02 21:02:15.298170 | 2026-09-02 21:02:15.298245 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-02 21:02:15.310578 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:02:15.315235 | 2026-09-02 21:02:15.315304 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-02 21:02:15.745266 | debian-bookworm -> localhost | changed 2026-09-02 21:02:15.753724 | 2026-09-02 21:02:15.753817 | TASK [add-build-sshkey : Add back temp key] 2026-09-02 21:02:16.051178 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1f8a5423ea234275a0e276406096e8e7/work/1f8a5423ea234275a0e276406096e8e7_id_rsa (zuul-build-sshkey) 2026-09-02 21:02:16.051383 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.007405 2026-09-02 21:02:16.058113 | 2026-09-02 21:02:16.058196 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-02 21:02:16.463679 | debian-bookworm | ok 2026-09-02 21:02:16.467826 | 2026-09-02 21:02:16.467911 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-02 21:02:16.491411 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:02:16.500364 | 2026-09-02 21:02:16.500451 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-02 21:02:16.963583 | debian-bookworm | ok 2026-09-02 21:02:16.976589 | 2026-09-02 21:02:16.976677 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-02 21:02:17.013320 | debian-bookworm | ok 2026-09-02 21:02:17.017689 | 2026-09-02 21:02:17.017765 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-02 21:02:17.287097 | debian-bookworm -> localhost | ok 2026-09-02 21:02:17.294950 | 2026-09-02 21:02:17.295053 | TASK [validate-host : Collect information about the host] 2026-09-02 21:02:18.431351 | debian-bookworm | ok 2026-09-02 21:02:18.442319 | 2026-09-02 21:02:18.442407 | TASK [validate-host : Sanitize hostname] 2026-09-02 21:02:18.568617 | debian-bookworm | ok 2026-09-02 21:02:18.573531 | 2026-09-02 21:02:18.573617 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-02 21:02:19.066656 | debian-bookworm -> localhost | changed 2026-09-02 21:02:19.072614 | 2026-09-02 21:02:19.072699 | TASK [validate-host : Collect information about zuul worker] 2026-09-02 21:02:19.448257 | debian-bookworm | ok 2026-09-02 21:02:19.452710 | 2026-09-02 21:02:19.452867 | TASK [validate-host : Write out all zuul information for each host] 2026-09-02 21:02:20.127565 | debian-bookworm -> localhost | changed 2026-09-02 21:02:20.136544 | 2026-09-02 21:02:20.136636 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-02 21:02:20.409931 | debian-bookworm | ok 2026-09-02 21:02:20.417679 | 2026-09-02 21:02:20.417769 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-02 21:02:21.335874 | debian-bookworm | changed: 2026-09-02 21:02:21.336048 | debian-bookworm | cd+++++++++ src/ 2026-09-02 21:02:21.336083 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-02 21:02:21.336108 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-02 21:02:21.336130 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-09-02 21:02:21.336151 | debian-bookworm | =7.0.3 2026-09-02 21:02:58.944331 | debian-bookworm | Downloading cachetools-7.1.8-py3-none-any.whl (16 kB) 2026-09-02 21:02:58.997157 | debian-bookworm | Collecting colorama>=0.4.6 2026-09-02 21:02:59.009386 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-09-02 21:02:59.095318 | debian-bookworm | Collecting filelock>=3.25 2026-09-02 21:02:59.107389 | debian-bookworm | Downloading filelock-3.32.5-py3-none-any.whl (100 kB) 2026-09-02 21:02:59.127092 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.0/100.0 kB 6.7 MB/s eta 0:00:00 2026-09-02 21:02:59.196207 | debian-bookworm | Collecting packaging>=26 2026-09-02 21:02:59.209171 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-09-02 21:02:59.249537 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 3.7 MB/s eta 0:00:00 2026-09-02 21:02:59.332585 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-09-02 21:02:59.344036 | debian-bookworm | Downloading platformdirs-4.11.7-py3-none-any.whl (23 kB) 2026-09-02 21:02:59.408146 | debian-bookworm | Collecting pluggy>=1.6 2026-09-02 21:02:59.419916 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-09-02 21:02:59.466231 | debian-bookworm | Collecting pyproject-api>=1.10 2026-09-02 21:02:59.481898 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-09-02 21:02:59.539710 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-09-02 21:02:59.552269 | debian-bookworm | Downloading python_discovery-1.6.0-py3-none-any.whl (38 kB) 2026-09-02 21:02:59.619236 | debian-bookworm | Collecting tomli-w>=1.2 2026-09-02 21:02:59.632525 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-09-02 21:02:59.715217 | debian-bookworm | Collecting typing-extensions>=4.15 2026-09-02 21:02:59.727750 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-09-02 21:02:59.740686 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 5.1 MB/s eta 0:00:00 2026-09-02 21:02:59.912025 | debian-bookworm | Collecting virtualenv>=21.1 2026-09-02 21:02:59.924324 | debian-bookworm | Downloading virtualenv-21.7.8-py3-none-any.whl (5.3 MB) 2026-09-02 21:03:00.222763 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 18.1 MB/s eta 0:00:00 2026-09-02 21:03:00.323691 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-09-02 21:03:00.336943 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-09-02 21:03:00.359495 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 24.7 MB/s eta 0:00:00 2026-09-02 21:03:00.476928 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-09-02 21:03:01.586243 | debian-bookworm | Successfully installed cachetools-7.1.8 colorama-0.4.6 distlib-0.4.3 filelock-3.32.5 packaging-26.3 platformdirs-4.11.7 pluggy-1.6.0 pyproject-api-1.11.0 python-discovery-1.6.0 tomli-w-1.2.0 tox-4.61.2 typing-extensions-4.16.0 virtualenv-21.7.8 2026-09-02 21:03:02.253328 | debian-bookworm | ok: Runtime: 0:00:03.760413 2026-09-02 21:03:02.266520 | 2026-09-02 21:03:02.266686 | TASK [ensure-tox : Export installed tox_executable path] 2026-09-02 21:03:02.314413 | debian-bookworm | ok 2026-09-02 21:03:02.322052 | 2026-09-02 21:03:02.322178 | TASK [ensure-tox : Output tox version] 2026-09-02 21:03:02.966005 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-09-02 21:03:02.986261 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-02 21:03:03.353642 | debian-bookworm | ok: Runtime: 0:00:00.398887 2026-09-02 21:03:03.366235 | 2026-09-02 21:03:03.366386 | TASK [ensure-tox : Make global symlink] 2026-09-02 21:03:03.392580 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:03.407669 | 2026-09-02 21:03:03.407783 | PLAY RECAP 2026-09-02 21:03:03.407941 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-09-02 21:03:03.407993 | 2026-09-02 21:03:03.530920 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-09-02 21:03:03.533491 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-02 21:03:04.284094 | 2026-09-02 21:03:04.284249 | PLAY [all] 2026-09-02 21:03:04.299377 | 2026-09-02 21:03:04.299496 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-09-02 21:03:04.765088 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-02 21:03:04.827716 | debian-bookworm | ok: Runtime: 0:00:00.031991 2026-09-02 21:03:04.839791 | 2026-09-02 21:03:04.839986 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-09-02 21:03:05.373644 | debian-bookworm | ok: Runtime: 0:00:00.008273 2026-09-02 21:03:05.386813 | 2026-09-02 21:03:05.387173 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-09-02 21:03:05.693654 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-02 21:03:05.697758 | debian-bookworm | sudo: a password is required 2026-09-02 21:03:05.921985 | debian-bookworm | ok: Runtime: 0:00:00.026224 2026-09-02 21:03:05.939644 | 2026-09-02 21:03:05.939770 | TASK [tox : Check to see if the constraints file exists] 2026-09-02 21:03:05.974949 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:05.989296 | 2026-09-02 21:03:05.989474 | TASK [tox : Fail if constraints file is missing] 2026-09-02 21:03:06.024543 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:06.037994 | 2026-09-02 21:03:06.038159 | TASK [tox : Record file location] 2026-09-02 21:03:06.073438 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:06.085007 | 2026-09-02 21:03:06.085159 | TASK [tox : Install tox siblings] 2026-09-02 21:03:06.122601 | debian-bookworm | ok 2026-09-02 21:03:06.134955 | debian-bookworm | included: /var/lib/zuul/builds/1f8a5423ea234275a0e276406096e8e7/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-09-02 21:03:06.141089 | 2026-09-02 21:03:06.141209 | TASK [tox : Run tox without tests] 2026-09-02 21:03:07.460232 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-09-02 21:03:22.369790 | debian-bookworm | test: OK (15.61 seconds) 2026-09-02 21:03:22.369841 | debian-bookworm | congratulations :) (15.65 seconds) 2026-09-02 21:03:22.680920 | debian-bookworm | ok: Runtime: 0:00:15.996281 2026-09-02 21:03:22.694546 | 2026-09-02 21:03:22.694721 | TASK [tox : Create a tempfile to save tox showconfig] 2026-09-02 21:03:23.142755 | debian-bookworm | changed 2026-09-02 21:03:23.149890 | 2026-09-02 21:03:23.150020 | TASK [tox : Detect tox version] 2026-09-02 21:03:23.745966 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-02 21:03:24.182445 | debian-bookworm | ok: Runtime: 0:00:00.361404 2026-09-02 21:03:24.195599 | 2026-09-02 21:03:24.195762 | TASK [tox : Get tox envlist config] 2026-09-02 21:03:25.230713 | debian-bookworm | ok: Runtime: 0:00:00.433260 2026-09-02 21:03:25.236727 | 2026-09-02 21:03:25.236844 | TASK [tox : Install any sibling python packages] 2026-09-02 21:03:25.701604 | debian-bookworm | ok: No setup.cfg, no action needed 2026-09-02 21:03:25.707315 | 2026-09-02 21:03:25.707419 | TASK [tox : Remove tempfile] 2026-09-02 21:03:26.204049 | debian-bookworm | changed 2026-09-02 21:03:26.209766 | 2026-09-02 21:03:26.209917 | TASK [tox : Emit tox command] 2026-09-02 21:03:26.249066 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-09-02 21:03:26.254946 | 2026-09-02 21:03:26.255063 | TASK [tox : Run tox] 2026-09-02 21:03:27.016455 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-09-02 21:03:27.656509 | debian-bookworm | ...........2026-09-02 21:03:27.656 | ERROR | contrib.check_updates:main:219 - operational failure: boom 2026-09-02 21:03:27.665378 | debian-bookworm | ......../home/zuul/src/github.com/osism/openstack-image-manager/.tox/test/lib/python3.11/site-packages/openstack/resource.py:874: RemovedInSDK50Warning: The _compute_attributes method is deprecated for removal 2026-09-02 21:03:27.665456 | debian-bookworm | warnings.warn( 2026-09-02 21:03:27.669218 | debian-bookworm | 2026-09-02 21:03:27.668 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 90 2026-09-02 21:03:27.669772 | debian-bookworm | 2026-09-02 21:03:27.669 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-02 21:03:27.669814 | debian-bookworm | 2026-09-02 21:03:27.669 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 10 2026-09-02 21:03:27.670141 | debian-bookworm | 2026-09-02 21:03:27.669 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-02 21:03:27.670192 | debian-bookworm | 2026-09-02 21:03:27.669 | WARNING | openstack_image_manager.main:check_image_age:1334 - Image 'Ubuntu 20.04 (1)' is 20 days older than the newest image in the definition 2026-09-02 21:03:27.676527 | debian-bookworm | ..2026-09-02 21:03:27.676 | ERROR | openstack_image_manager.main:_download:714 - aria2c is not installed; cannot prefetch image 2026-09-02 21:03:27.680860 | debian-bookworm | .2026-09-02 21:03:27.680 | ERROR | openstack_image_manager.main:_download:745 - aria2c exited with rc=1 for http://x/y 2026-09-02 21:03:27.688633 | debian-bookworm | ..2026-09-02 21:03:27.688 | ERROR | openstack_image_manager.main:_download:739 - aria2c timed out downloading http://x/y 2026-09-02 21:03:27.696428 | debian-bookworm | ..2026-09-02 21:03:27.696 | ERROR | openstack_image_manager.main:get_checksum_from_checksum_url:278 - Failed to fetch checksum file from https://url.com/image.qcow2.sha512: 404 2026-09-02 21:03:27.703602 | debian-bookworm | ..2026-09-02 21:03:27.703 | ERROR | openstack_image_manager.main:get_checksum_from_checksum_url:278 - Failed to fetch checksum file from https://url.com/image.qcow2.sha512: unreachable 2026-09-02 21:03:27.710993 | debian-bookworm | ..2026-09-02 21:03:27.710 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: 404 2026-09-02 21:03:27.714435 | debian-bookworm | .2026-09-02 21:03:27.714 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: unreachable 2026-09-02 21:03:27.726799 | debian-bookworm | ...2026-09-02 21:03:27.726 | ERROR | openstack_image_manager.main:_has_space_for_download:693 - Not enough disk to prefetch http://x/y: need ~2362232012 bytes, 1073741824 available at /tmp 2026-09-02 21:03:27.730515 | debian-bookworm | .2026-09-02 21:03:27.730 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://x/y; skipping disk check 2026-09-02 21:03:27.736258 | debian-bookworm | .2026-09-02 21:03:27.735 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-02 21:03:27.736702 | debian-bookworm | 2026-09-02 21:03:27.736 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-02 21:03:28.054313 | debian-bookworm | 2026-09-02 21:03:28.053 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-02 21:03:28.057227 | debian-bookworm | 2026-09-02 21:03:28.056 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-02 21:03:28.058003 | debian-bookworm | 2026-09-02 21:03:28.057 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-02 21:03:28.068098 | debian-bookworm | .2026-09-02 21:03:28.067 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-02 21:03:28.076704 | debian-bookworm | 2026-09-02 21:03:28.068 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-02 21:03:28.076831 | debian-bookworm | 2026-09-02 21:03:28.069 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-02 21:03:28.076859 | debian-bookworm | 2026-09-02 21:03:28.069 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL file:///path/to/file.img 2026-09-02 21:03:28.076883 | debian-bookworm | 2026-09-02 21:03:28.069 | INFO | openstack_image_manager.main:import_image:539 - Importing local file '/path/to/file.img' as image Ubuntu 20.04 (1) 2026-09-02 21:03:28.076899 | debian-bookworm | .2026-09-02 21:03:28.075 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-02 21:03:28.076912 | debian-bookworm | 2026-09-02 21:03:28.075 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-02 21:03:28.077545 | debian-bookworm | 2026-09-02 21:03:28.076 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-02 21:03:28.084430 | debian-bookworm | 2026-09-02 21:03:28.077 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-02 21:03:28.084494 | debian-bookworm | 2026-09-02 21:03:28.077 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-02 21:03:28.084511 | debian-bookworm | 2026-09-02 21:03:28.077 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-02 21:03:28.084522 | debian-bookworm | 2026-09-02 21:03:28.077 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-02 21:03:28.084546 | debian-bookworm | 2026-09-02 21:03:28.078 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-02 21:03:28.084560 | debian-bookworm | .2026-09-02 21:03:28.083 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-02 21:03:28.084573 | debian-bookworm | 2026-09-02 21:03:28.083 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-02 21:03:28.087877 | debian-bookworm | 2026-09-02 21:03:28.084 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-02 21:03:28.087951 | debian-bookworm | 2026-09-02 21:03:28.085 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-02 21:03:28.087974 | debian-bookworm | 2026-09-02 21:03:28.085 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-02 21:03:28.087991 | debian-bookworm | 2026-09-02 21:03:28.085 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-02 21:03:28.088009 | debian-bookworm | 2026-09-02 21:03:28.085 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-02 21:03:28.088028 | debian-bookworm | 2026-09-02 21:03:28.085 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-02 21:03:28.088050 | debian-bookworm | 2026-09-02 21:03:28.085 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-02 21:03:28.378990 | debian-bookworm | 2026-09-02 21:03:28.378 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-02 21:03:28.381869 | debian-bookworm | 2026-09-02 21:03:28.381 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-02 21:03:28.382648 | debian-bookworm | 2026-09-02 21:03:28.382 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-02 21:03:28.408353 | debian-bookworm | ...2026-09-02 21:03:28.408 | ERROR | openstack_image_manager.main:main:366 - Skipping cleanup of outdated images because of previous errors 2026-09-02 21:03:28.495600 | debian-bookworm | .2026-09-02 21:03:28.495 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04' (removal candidate) 2026-09-02 21:03:28.500679 | debian-bookworm | .2026-09-02 21:03:28.500 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-02 21:03:28.501332 | debian-bookworm | 2026-09-02 21:03:28.500 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-02 21:03:28.501380 | debian-bookworm | 2026-09-02 21:03:28.501 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-02 21:03:28.501813 | debian-bookworm | 2026-09-02 21:03:28.501 | INFO | openstack_image_manager.main:manage_outdated_images:1430 - Deleting Ubuntu 20.04_2 2026-09-02 21:03:28.502661 | debian-bookworm | 2026-09-02 21:03:28.502 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-02 21:03:28.503078 | debian-bookworm | 2026-09-02 21:03:28.502 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-02 21:03:28.503111 | debian-bookworm | 2026-09-02 21:03:28.502 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-02 21:03:28.503124 | debian-bookworm | 2026-09-02 21:03:28.502 | INFO | openstack_image_manager.main:manage_outdated_images:1433 - Image 'Ubuntu 20.04_2' will not be deleted, because 'keep' flag is True 2026-09-02 21:03:28.506617 | debian-bookworm | .2026-09-02 21:03:28.506 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-02 21:03:28.507070 | debian-bookworm | 2026-09-02 21:03:28.506 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-02 21:03:28.510992 | debian-bookworm | .2026-09-02 21:03:28.510 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-02 21:03:28.511426 | debian-bookworm | 2026-09-02 21:03:28.511 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-02 21:03:28.782352 | debian-bookworm | 2026-09-02 21:03:28.781 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-02 21:03:28.785702 | debian-bookworm | 2026-09-02 21:03:28.784 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-02 21:03:28.788740 | debian-bookworm | 2026-09-02 21:03:28.786 | ERROR | openstack_image_manager.main:_prefetch_import:655 - glance-direct import failed for Ubuntu 20.04 (1) 2026-09-02 21:03:28.788795 | debian-bookworm | 409 conflict on fixed id 2026-09-02 21:03:28.788811 | debian-bookworm | 2026-09-02 21:03:28.786 | ERROR | openstack_image_manager.main:_prefetch_import:658 - PREFETCH: glance-direct import failed for 'Ubuntu 20.04 (1)' 2026-09-02 21:03:28.799583 | debian-bookworm | .2026-09-02 21:03:28.799 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-02 21:03:28.803141 | debian-bookworm | 2026-09-02 21:03:28.799 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-02 21:03:29.059001 | debian-bookworm | 2026-09-02 21:03:29.058 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-02 21:03:29.062273 | debian-bookworm | 2026-09-02 21:03:29.061 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download failed for 'Ubuntu 20.04 (1)' 2026-09-02 21:03:29.074088 | debian-bookworm | .2026-09-02 21:03:29.073 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-02 21:03:29.080512 | debian-bookworm | 2026-09-02 21:03:29.074 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-02 21:03:29.080600 | debian-bookworm | 2026-09-02 21:03:29.075 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-02 21:03:29.080617 | debian-bookworm | 2026-09-02 21:03:29.076 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-02 21:03:29.080653 | debian-bookworm | 2026-09-02 21:03:29.077 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-02 21:03:29.080668 | debian-bookworm | 2026-09-02 21:03:29.077 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-02 21:03:29.080680 | debian-bookworm | 2026-09-02 21:03:29.077 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-02 21:03:29.080692 | debian-bookworm | 2026-09-02 21:03:29.077 | INFO | openstack_image_manager.main:process_image:1020 - Skipping required import of image 'Ubuntu 20.04 (1)', running in dry-run mode 2026-09-02 21:03:29.088467 | debian-bookworm | .2026-09-02 21:03:29.082 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (latest)' 2026-09-02 21:03:29.088535 | debian-bookworm | 2026-09-02 21:03:29.083 | ERROR | openstack_image_manager.main:process_image:948 - Could not find checksum for image 'Ubuntu 20.04', check the checksums_url or checksum_url 2026-09-02 21:03:29.088975 | debian-bookworm | .2026-09-02 21:03:29.088 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-1' 2026-09-02 21:03:29.089648 | debian-bookworm | 2026-09-02 21:03:29.089 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-02 21:03:29.096416 | debian-bookworm | 2026-09-02 21:03:29.090 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-1' successfully completed, reloading images 2026-09-02 21:03:29.096467 | debian-bookworm | .2026-09-02 21:03:29.096 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(1)' 2026-09-02 21:03:29.096884 | debian-bookworm | 2026-09-02 21:03:29.096 | INFO | openstack_image_manager.main:process_image:1025 - Skipping image 'Ubuntu 20.04-(1)' (only importing the latest version from type multi) 2026-09-02 21:03:29.097051 | debian-bookworm | 2026-09-02 21:03:29.096 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(2)' 2026-09-02 21:03:29.100799 | debian-bookworm | 2026-09-02 21:03:29.100 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com2: 200 2026-09-02 21:03:29.102085 | debian-bookworm | 2026-09-02 21:03:29.101 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-(2)' successfully completed, reloading images 2026-09-02 21:03:29.127194 | debian-bookworm | ...2026-09-02 21:03:29.126 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-02 21:03:29.127685 | debian-bookworm | 2026-09-02 21:03:29.127 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-02 21:03:29.127996 | debian-bookworm | 2026-09-02 21:03:29.127 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-02 21:03:29.128010 | debian-bookworm | 2026-09-02 21:03:29.127 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-02 21:03:29.128403 | debian-bookworm | 2026-09-02 21:03:29.127 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04 (1) to Ubuntu 20.04 2026-09-02 21:03:29.132171 | debian-bookworm | .2026-09-02 21:03:29.131 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-02 21:03:29.132586 | debian-bookworm | 2026-09-02 21:03:29.132 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-02 21:03:29.132897 | debian-bookworm | 2026-09-02 21:03:29.132 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-02 21:03:29.132923 | debian-bookworm | 2026-09-02 21:03:29.132 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-02 21:03:29.132928 | debian-bookworm | 2026-09-02 21:03:29.132 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04-(1) to Ubuntu 20.04 2026-09-02 21:03:29.168271 | debian-bookworm | ..2026-09-02 21:03:29.167 | INFO | openstack_image_manager.main:set_properties:1063 - Checking parameters of 'Ubuntu 20.04 (1)' 2026-09-02 21:03:29.168738 | debian-bookworm | 2026-09-02 21:03:29.168 | INFO | openstack_image_manager.main:set_properties:1129 - Setting internal_version = 1 2026-09-02 21:03:29.168756 | debian-bookworm | 2026-09-02 21:03:29.168 | INFO | openstack_image_manager.main:set_properties:1132 - Setting image_original_user = ubuntu 2026-09-02 21:03:29.168762 | debian-bookworm | 2026-09-02 21:03:29.168 | INFO | openstack_image_manager.main:set_properties:1145 - Adding tag my_tag 2026-09-02 21:03:29.168773 | debian-bookworm | 2026-09-02 21:03:29.168 | INFO | openstack_image_manager.main:set_properties:1150 - Deleting tag fake_tag 2026-09-02 21:03:29.169091 | debian-bookworm | 2026-09-02 21:03:29.168 | INFO | openstack_image_manager.main:set_properties:1161 - Setting property image_build_date: 2021-01-01 != 2021-01-21 2026-09-02 21:03:29.169668 | debian-bookworm | 2026-09-02 21:03:29.169 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_distro: ubuntu 2026-09-02 21:03:29.169686 | debian-bookworm | 2026-09-02 21:03:29.169 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_version: 20.04 2026-09-02 21:03:29.169691 | debian-bookworm | 2026-09-02 21:03:29.169 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property image_source: http://url.com 2026-09-02 21:03:29.170249 | debian-bookworm | 2026-09-02 21:03:29.169 | INFO | openstack_image_manager.main:set_properties:1185 - Checking status of 'Ubuntu 20.04 (1)' 2026-09-02 21:03:29.170266 | debian-bookworm | 2026-09-02 21:03:29.169 | INFO | openstack_image_manager.main:set_properties:1190 - Deactivating image 'Ubuntu 20.04 (1)' 2026-09-02 21:03:29.170271 | debian-bookworm | 2026-09-02 21:03:29.169 | INFO | openstack_image_manager.main:set_properties:1197 - Checking visibility of 'Ubuntu 20.04 (1)' 2026-09-02 21:03:29.198711 | debian-bookworm | ...2026-09-02 21:03:29.198 | ERROR | openstack_image_manager.main:wait_for_image:814 - Image import timed out 2026-09-02 21:03:29.203274 | debian-bookworm | .2026-09-02 21:03:29.202 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-02 21:03:29.203440 | debian-bookworm | boom 2026-09-02 21:03:29.203728 | debian-bookworm | 2026-09-02 21:03:29.203 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-02 21:03:29.203739 | debian-bookworm | boom 2026-09-02 21:03:29.203933 | debian-bookworm | 2026-09-02 21:03:29.203 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-02 21:03:29.203942 | debian-bookworm | boom 2026-09-02 21:03:29.203946 | debian-bookworm | 2026-09-02 21:03:29.203 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-02 21:03:29.203951 | debian-bookworm | boom 2026-09-02 21:03:29.204110 | debian-bookworm | 2026-09-02 21:03:29.203 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-02 21:03:29.204120 | debian-bookworm | boom 2026-09-02 21:03:29.204125 | debian-bookworm | 2026-09-02 21:03:29.203 | ERROR | openstack_image_manager.main:wait_for_image:848 - Giving up on image after repeated errors 2026-09-02 21:03:29.208664 | debian-bookworm | .2026-09-02 21:03:29.208 | ERROR | openstack_image_manager.main:wait_for_image:837 - Image entered terminal state 'killed' 2026-09-02 21:03:29.212364 | debian-bookworm | .2026-09-02 21:03:29.212 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-02 21:03:29.212391 | debian-bookworm | 2026-09-02 21:03:29.212 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-02 21:03:29.212798 | debian-bookworm | 2026-09-02 21:03:29.212 | ERROR | openstack_image_manager.main:import_image:584 - Web-download import for image Ubuntu 20.04 (1) failed 2026-09-02 21:03:29.212830 | debian-bookworm | glance api error 2026-09-02 21:03:29.212836 | debian-bookworm | 2026-09-02 21:03:29.212 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-02 21:03:29.226909 | debian-bookworm | .......2026-09-02 21:03:29 | INFO  | main:223 - Processing file /tmp/tmpb1cewsyj/example.yml 2026-09-02 21:03:29.232988 | debian-bookworm | 2026-09-02 21:03:29 | INFO  | main:232 - Skipping disabled image Example 1.0 2026-09-02 21:03:29.240577 | debian-bookworm | .2026-09-02 21:03:29 | INFO  | main:223 - Processing file /tmp/tmp8zifdv6b/example.yml 2026-09-02 21:03:29.243956 | debian-bookworm | 2026-09-02 21:03:29 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-02 21:03:29.243981 | debian-bookworm | 2026-09-02 21:03:29 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-02 21:03:29.243988 | debian-bookworm | 2026-09-02 21:03:29 | INFO  | update_image:178 - New URL is https://nbg1.your-objectstorage.com/osism/openstack-images/example-1.0/20260101-example-1.0.qcow2 2026-09-02 21:03:29.246785 | debian-bookworm | 2026-09-02 21:03:29 | INFO  | main:242 - Dry-run enabled, not writing /tmp/tmp8zifdv6b/example.yml 2026-09-02 21:03:29.254036 | debian-bookworm | .2026-09-02 21:03:29 | INFO  | main:223 - Processing file /tmp/tmpl61a39tc/example.yml 2026-09-02 21:03:29.257335 | debian-bookworm | 2026-09-02 21:03:29 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-02 21:03:29.257363 | debian-bookworm | 2026-09-02 21:03:29 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:0000000000000000000000000000000000000000000000000000000000000000 2026-09-02 21:03:29.257877 | debian-bookworm | 2026-09-02 21:03:29 | INFO  | update_image:158 - Image Example 1.0 is up-to-date, nothing to do 2026-09-02 21:03:29.264969 | debian-bookworm | .2026-09-02 21:03:29 | INFO  | main:223 - Processing file /tmp/tmpbseaiur0/example.yml 2026-09-02 21:03:29.268429 | debian-bookworm | 2026-09-02 21:03:29 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-02 21:03:29.268455 | debian-bookworm | 2026-09-02 21:03:29 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-02 21:03:29.268472 | debian-bookworm | 2026-09-02 21:03:29 | INFO  | update_image:178 - New URL is https://nbg1.your-objectstorage.com/osism/openstack-images/example-1.0/20260101-example-1.0.qcow2 2026-09-02 21:03:29.271605 | debian-bookworm | . 2026-09-02 21:03:29.272133 | debian-bookworm | ---------------------------------------------------------------------- 2026-09-02 21:03:29.272189 | debian-bookworm | Ran 74 tests in 1.642s 2026-09-02 21:03:29.272195 | debian-bookworm | 2026-09-02 21:03:29.272200 | debian-bookworm | OK 2026-09-02 21:03:29.413123 | debian-bookworm | test: OK (2.46=setup[0.06]+cmd[2.39] seconds) 2026-09-02 21:03:29.413196 | debian-bookworm | congratulations :) (2.50 seconds) 2026-09-02 21:03:29.786288 | debian-bookworm | ok: Runtime: 0:00:02.936831 2026-09-02 21:03:29.796070 | 2026-09-02 21:03:29.796207 | TASK [tox : Look for output] 2026-09-02 21:03:30.223564 | debian-bookworm | ok 2026-09-02 21:03:30.243010 | 2026-09-02 21:03:30.243146 | TASK [tox : Return file comments to Zuul] 2026-09-02 21:03:30.288603 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:30.299393 | 2026-09-02 21:03:30.299514 | PLAY RECAP 2026-09-02 21:03:30.299597 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-02 21:03:30.299634 | 2026-09-02 21:03:30.435939 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-02 21:03:30.437030 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-02 21:03:31.267761 | 2026-09-02 21:03:31.267946 | PLAY [all] 2026-09-02 21:03:31.284848 | 2026-09-02 21:03:31.285026 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-09-02 21:03:31.339984 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:31.346980 | 2026-09-02 21:03:31.347120 | TASK [fetch-tox-output : Set tox log path for single node] 2026-09-02 21:03:31.385650 | debian-bookworm | ok 2026-09-02 21:03:31.390861 | 2026-09-02 21:03:31.391004 | TASK [fetch-tox-output : Ensure local tox dir] 2026-09-02 21:03:31.856734 | debian-bookworm -> localhost | changed 2026-09-02 21:03:31.865186 | 2026-09-02 21:03:31.865306 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-09-02 21:03:31.909159 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:31.915092 | 2026-09-02 21:03:31.915215 | TASK [fetch-tox-output : Set envlist fact] 2026-09-02 21:03:31.953802 | debian-bookworm | ok 2026-09-02 21:03:31.959732 | 2026-09-02 21:03:31.959844 | TASK [fetch-tox-output : Get tox version] 2026-09-02 21:03:32.688089 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-09-02 21:03:32.708226 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-02 21:03:32.990085 | debian-bookworm | ok: Runtime: 0:00:00.359967 2026-09-02 21:03:33.002382 | 2026-09-02 21:03:33.002544 | TASK [fetch-tox-output : Find all default environments] 2026-09-02 21:03:33.536519 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:33.543323 | 2026-09-02 21:03:33.543452 | TASK [fetch-tox-output : Set envlist fact] 2026-09-02 21:03:33.567250 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:33.574928 | 2026-09-02 21:03:33.575057 | TASK [fetch-tox-output : Find all default environments] 2026-09-02 21:03:34.102778 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:34.116594 | 2026-09-02 21:03:34.116797 | TASK [fetch-tox-output : Set envlist fact] 2026-09-02 21:03:34.152142 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:34.162988 | 2026-09-02 21:03:34.163139 | LOOP [fetch-tox-output : Copy tox logs] 2026-09-02 21:03:34.216050 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:34.233340 | 2026-09-02 21:03:34.233566 | LOOP [fetch-tox-output : Collect tox logs] 2026-09-02 21:03:35.059906 | debian-bookworm | changed: 2026-09-02 21:03:35.060180 | debian-bookworm | created directory /var/lib/zuul/builds/1f8a5423ea234275a0e276406096e8e7/work/logs/tox/test 2026-09-02 21:03:35.060323 | debian-bookworm | cd+++++++++ ./ 2026-09-02 21:03:35.060370 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-09-02 21:03:35.060415 | 2026-09-02 21:03:35.081852 | 2026-09-02 21:03:35.082020 | PLAY RECAP 2026-09-02 21:03:35.082083 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-09-02 21:03:35.082108 | 2026-09-02 21:03:35.237046 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-02 21:03:35.238096 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-02 21:03:35.955380 | 2026-09-02 21:03:35.955522 | PLAY [all] 2026-09-02 21:03:35.971334 | 2026-09-02 21:03:35.971465 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-09-02 21:03:36.186074 | debian-bookworm | changed: non-zero return code 2026-09-02 21:03:36.197271 | 2026-09-02 21:03:36.197564 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-09-02 21:03:36.224433 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:36.237009 | 2026-09-02 21:03:36.237177 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-09-02 21:03:36.274962 | 2026-09-02 21:03:36.275231 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-09-02 21:03:36.314675 | 2026-09-02 21:03:36.315039 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-09-02 21:03:36.339759 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:36.352233 | 2026-09-02 21:03:36.352387 | LOOP [fetch-subunit-output : Generate subunit file] 2026-09-02 21:03:36.394081 | 2026-09-02 21:03:36.394356 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-09-02 21:03:36.423984 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:36.429736 | 2026-09-02 21:03:36.429850 | TASK [fetch-subunit-output : Remove the temporary file] 2026-09-02 21:03:36.452982 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:36.459128 | 2026-09-02 21:03:36.459230 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-09-02 21:03:36.482119 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:03:36.488346 | 2026-09-02 21:03:36.488424 | PLAY RECAP 2026-09-02 21:03:36.488477 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-09-02 21:03:36.488503 | 2026-09-02 21:03:36.607163 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-02 21:03:36.609467 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-02 21:03:37.338476 | 2026-09-02 21:03:37.338629 | PLAY [Base post-fetch] 2026-09-02 21:03:37.352419 | 2026-09-02 21:03:37.352550 | TASK [diagnose-network : Ensure log directory exists] 2026-09-02 21:03:37.751067 | debian-bookworm | ok 2026-09-02 21:03:37.761817 | 2026-09-02 21:03:37.762018 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-09-02 21:03:49.495473 | debian-bookworm | ok: Runtime: 0:00:06.147012 2026-09-02 21:03:49.505436 | 2026-09-02 21:03:49.505584 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-09-02 21:03:55.538043 | debian-bookworm | ok: Runtime: 0:00:00.009304 2026-09-02 21:03:55.544147 | 2026-09-02 21:03:55.544273 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-09-02 21:04:01.508234 | debian-bookworm | ok: Runtime: 0:00:00.008212 2026-09-02 21:04:01.522537 | 2026-09-02 21:04:01.522649 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-02 21:04:01.587302 | debian-bookworm | skipping: Conditional result was False 2026-09-02 21:04:01.600930 | 2026-09-02 21:04:01.601097 | TASK [fetch-output : Set log path for single node] 2026-09-02 21:04:01.649437 | debian-bookworm | ok 2026-09-02 21:04:01.654390 | 2026-09-02 21:04:01.654498 | LOOP [fetch-output : Ensure local output dirs] 2026-09-02 21:04:01.935688 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/1f8a5423ea234275a0e276406096e8e7/work/logs" 2026-09-02 21:04:02.210739 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1f8a5423ea234275a0e276406096e8e7/work/artifacts" 2026-09-02 21:04:02.462513 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1f8a5423ea234275a0e276406096e8e7/work/docs" 2026-09-02 21:04:02.475065 | 2026-09-02 21:04:02.475189 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-02 21:04:03.273290 | debian-bookworm | changed: 2026-09-02 21:04:03.273636 | debian-bookworm | .d..t...... ./ 2026-09-02 21:04:03.273698 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-02 21:04:03.273742 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-02 21:04:03.273781 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-02 21:04:03.273832 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-02 21:04:03.273871 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-02 21:04:03.273997 | debian-bookworm | changed: All items complete 2026-09-02 21:04:03.274046 | 2026-09-02 21:04:03.856730 | debian-bookworm | changed: .d..t...... ./ 2026-09-02 21:04:04.533083 | debian-bookworm | changed: .d..t...... ./ 2026-09-02 21:04:04.562506 | 2026-09-02 21:04:04.562649 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-02 21:04:04.890420 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009570 2026-09-02 21:04:05.173799 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007705 2026-09-02 21:04:05.184739 | 2026-09-02 21:04:05.184852 | PLAY RECAP 2026-09-02 21:04:05.184959 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-02 21:04:05.184998 | 2026-09-02 21:04:05.310159 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-02 21:04:05.312888 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-02 21:04:06.029871 | 2026-09-02 21:04:06.030058 | PLAY [Base post] 2026-09-02 21:04:06.043168 | 2026-09-02 21:04:06.043297 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-02 21:04:06.687147 | debian-bookworm | changed 2026-09-02 21:04:06.696837 | 2026-09-02 21:04:06.696981 | PLAY RECAP 2026-09-02 21:04:06.697058 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-02 21:04:06.697139 | 2026-09-02 21:04:06.807793 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-02 21:04:06.810302 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-02 21:04:07.573805 | 2026-09-02 21:04:07.573982 | PLAY [Base post-logs] 2026-09-02 21:04:07.584502 | 2026-09-02 21:04:07.584632 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-02 21:04:08.059353 | localhost | changed 2026-09-02 21:04:08.075543 | 2026-09-02 21:04:08.075711 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-02 21:04:08.115454 | localhost | ok 2026-09-02 21:04:08.122497 | 2026-09-02 21:04:08.122665 | TASK [Set zuul-log-path fact] 2026-09-02 21:04:08.140898 | localhost | ok 2026-09-02 21:04:08.149743 | 2026-09-02 21:04:08.149848 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-02 21:04:08.185581 | localhost | ok 2026-09-02 21:04:08.190974 | 2026-09-02 21:04:08.191124 | TASK [upload-logs : Create log directories] 2026-09-02 21:04:08.695838 | localhost | changed 2026-09-02 21:04:08.698664 | 2026-09-02 21:04:08.698771 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-02 21:04:09.188774 | localhost -> localhost | ok: Runtime: 0:00:00.007206 2026-09-02 21:04:09.197722 | 2026-09-02 21:04:09.197970 | TASK [upload-logs : Upload logs to log server] 2026-09-02 21:04:09.753714 | localhost | Output suppressed because no_log was given 2026-09-02 21:04:09.757154 | 2026-09-02 21:04:09.757309 | LOOP [upload-logs : Compress console log and json output] 2026-09-02 21:04:09.815562 | localhost | skipping: Conditional result was False 2026-09-02 21:04:09.829969 | localhost | skipping: Conditional result was False 2026-09-02 21:04:09.837245 | 2026-09-02 21:04:09.837370 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-02 21:04:09.891503 | localhost | skipping: Conditional result was False 2026-09-02 21:04:09.891895 | 2026-09-02 21:04:09.896526 | localhost | skipping: Conditional result was False 2026-09-02 21:04:09.908449 | 2026-09-02 21:04:09.908666 | LOOP [upload-logs : Upload console log and json output]