2026-09-06 03:02:47.485077 | Job console starting 2026-09-06 03:02:47.494169 | Updating git repos 2026-09-06 03:02:47.526602 | Cloning repos into workspace 2026-09-06 03:02:47.573597 | Restoring repo states 2026-09-06 03:02:47.580469 | Merging changes 2026-09-06 03:02:47.580482 | Checking out repos 2026-09-06 03:02:47.622848 | Preparing playbooks 2026-09-06 03:02:48.526718 | Running Ansible setup 2026-09-06 03:02:52.698131 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-06 03:02:53.536888 | 2026-09-06 03:02:53.537044 | PLAY [Base pre] 2026-09-06 03:02:53.555674 | 2026-09-06 03:02:53.555800 | TASK [Setup log path fact] 2026-09-06 03:02:53.585810 | debian-bookworm | ok 2026-09-06 03:02:53.602684 | 2026-09-06 03:02:53.602809 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-06 03:02:53.644566 | debian-bookworm | ok 2026-09-06 03:02:53.661969 | 2026-09-06 03:02:53.662126 | TASK [emit-job-header : Print job information] 2026-09-06 03:02:53.718183 | # Job Information 2026-09-06 03:02:53.718449 | Ansible Version: 2.16.14 2026-09-06 03:02:53.718508 | Job: tox 2026-09-06 03:02:53.718562 | Pipeline: periodic-daily 2026-09-06 03:02:53.718599 | Executor: 521e9411259a 2026-09-06 03:02:53.718632 | Triggered by: https://github.com/osism/openstack-image-manager 2026-09-06 03:02:53.718669 | Event ID: eeb07045d1324b3d8e9c9696e80a0397 2026-09-06 03:02:53.727492 | 2026-09-06 03:02:53.727641 | LOOP [emit-job-header : Print node information] 2026-09-06 03:02:53.861452 | debian-bookworm | ok: 2026-09-06 03:02:53.861704 | debian-bookworm | # Node Information 2026-09-06 03:02:53.861757 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-06 03:02:53.861795 | debian-bookworm | Hostname: debian 2026-09-06 03:02:53.861829 | debian-bookworm | Username: zuul 2026-09-06 03:02:53.861861 | debian-bookworm | Distro: Debian 12.15 2026-09-06 03:02:53.861896 | debian-bookworm | Provider: regiocloud-a 2026-09-06 03:02:53.861927 | debian-bookworm | Region: 2026-09-06 03:02:53.861960 | debian-bookworm | Label: debian-bookworm 2026-09-06 03:02:53.862220 | debian-bookworm | Product Name: OpenStack Nova 2026-09-06 03:02:53.862271 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7b:8d96 2026-09-06 03:02:53.875619 | 2026-09-06 03:02:53.875742 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-06 03:02:54.362225 | debian-bookworm -> localhost | changed 2026-09-06 03:02:54.391098 | 2026-09-06 03:02:54.391310 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-06 03:02:55.527352 | debian-bookworm -> localhost | changed 2026-09-06 03:02:55.545186 | 2026-09-06 03:02:55.545423 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-06 03:02:55.906518 | debian-bookworm -> localhost | ok 2026-09-06 03:02:55.913125 | 2026-09-06 03:02:55.913247 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-06 03:02:55.934681 | debian-bookworm | ok 2026-09-06 03:02:55.950296 | debian-bookworm | included: /var/lib/zuul/builds/5b63d11ddb37423290bb61b449bd5457/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-06 03:02:55.956864 | 2026-09-06 03:02:55.956967 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-06 03:02:56.942028 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-06 03:02:56.942290 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/5b63d11ddb37423290bb61b449bd5457/work/5b63d11ddb37423290bb61b449bd5457_id_rsa 2026-09-06 03:02:56.942333 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/5b63d11ddb37423290bb61b449bd5457/work/5b63d11ddb37423290bb61b449bd5457_id_rsa.pub 2026-09-06 03:02:56.942360 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-06 03:02:56.942384 | debian-bookworm -> localhost | SHA256:T3d6JCfy36aR0vDUbpTmW5Vbg7YTNp48M5AQ/7AEXao zuul-build-sshkey 2026-09-06 03:02:56.942405 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-06 03:02:56.942438 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-06 03:02:56.942460 | debian-bookworm -> localhost | | o. .. | 2026-09-06 03:02:56.942482 | debian-bookworm -> localhost | | +.. | 2026-09-06 03:02:56.942503 | debian-bookworm -> localhost | | . = | 2026-09-06 03:02:56.942523 | debian-bookworm -> localhost | | + = ..o| 2026-09-06 03:02:56.942544 | debian-bookworm -> localhost | | SEo+=B===| 2026-09-06 03:02:56.942573 | debian-bookworm -> localhost | | o +*%B+=| 2026-09-06 03:02:56.942595 | debian-bookworm -> localhost | | . +X*o+| 2026-09-06 03:02:56.942615 | debian-bookworm -> localhost | | +=++| 2026-09-06 03:02:56.942635 | debian-bookworm -> localhost | | o+.| 2026-09-06 03:02:56.942655 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-06 03:02:56.942703 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.486899 2026-09-06 03:02:56.949742 | 2026-09-06 03:02:56.949861 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-06 03:02:56.978158 | debian-bookworm | ok 2026-09-06 03:02:56.988579 | debian-bookworm | included: /var/lib/zuul/builds/5b63d11ddb37423290bb61b449bd5457/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-06 03:02:56.997075 | 2026-09-06 03:02:56.997190 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-06 03:02:57.021551 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:02:57.029564 | 2026-09-06 03:02:57.029686 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-06 03:02:57.727285 | debian-bookworm | changed 2026-09-06 03:02:57.732811 | 2026-09-06 03:02:57.732920 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-06 03:02:58.029623 | debian-bookworm | ok 2026-09-06 03:02:58.035903 | 2026-09-06 03:02:58.036023 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-06 03:02:58.661350 | debian-bookworm | changed 2026-09-06 03:02:58.668827 | 2026-09-06 03:02:58.668966 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-06 03:02:59.312792 | debian-bookworm | changed 2026-09-06 03:02:59.322292 | 2026-09-06 03:02:59.322448 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-06 03:02:59.346226 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:02:59.352534 | 2026-09-06 03:02:59.352642 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-06 03:02:59.803417 | debian-bookworm -> localhost | changed 2026-09-06 03:02:59.818879 | 2026-09-06 03:02:59.818995 | TASK [add-build-sshkey : Add back temp key] 2026-09-06 03:03:00.152482 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/5b63d11ddb37423290bb61b449bd5457/work/5b63d11ddb37423290bb61b449bd5457_id_rsa (zuul-build-sshkey) 2026-09-06 03:03:00.152741 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010477 2026-09-06 03:03:00.160787 | 2026-09-06 03:03:00.160904 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-06 03:03:00.641824 | debian-bookworm | ok 2026-09-06 03:03:00.649312 | 2026-09-06 03:03:00.649448 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-06 03:03:00.704862 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:03:00.718191 | 2026-09-06 03:03:00.719561 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-06 03:03:01.170770 | debian-bookworm | ok 2026-09-06 03:03:01.190191 | 2026-09-06 03:03:01.190343 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-06 03:03:01.229853 | debian-bookworm | ok 2026-09-06 03:03:01.238427 | 2026-09-06 03:03:01.238538 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-06 03:03:01.537216 | debian-bookworm -> localhost | ok 2026-09-06 03:03:01.545386 | 2026-09-06 03:03:01.545574 | TASK [validate-host : Collect information about the host] 2026-09-06 03:03:02.827432 | debian-bookworm | ok 2026-09-06 03:03:02.842511 | 2026-09-06 03:03:02.842637 | TASK [validate-host : Sanitize hostname] 2026-09-06 03:03:03.026415 | debian-bookworm | ok 2026-09-06 03:03:03.031409 | 2026-09-06 03:03:03.031548 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-06 03:03:04.099834 | debian-bookworm -> localhost | changed 2026-09-06 03:03:04.104589 | 2026-09-06 03:03:04.104679 | TASK [validate-host : Collect information about zuul worker] 2026-09-06 03:03:04.598161 | debian-bookworm | ok 2026-09-06 03:03:04.602244 | 2026-09-06 03:03:04.602324 | TASK [validate-host : Write out all zuul information for each host] 2026-09-06 03:03:05.415958 | debian-bookworm -> localhost | changed 2026-09-06 03:03:05.424826 | 2026-09-06 03:03:05.424921 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-06 03:03:05.692767 | debian-bookworm | ok 2026-09-06 03:03:05.705927 | 2026-09-06 03:03:05.706035 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-06 03:03:06.735103 | debian-bookworm | changed: 2026-09-06 03:03:06.735260 | debian-bookworm | cd+++++++++ src/ 2026-09-06 03:03:06.735291 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-06 03:03:06.735313 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-06 03:03:06.735332 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-09-06 03:03:06.735350 | debian-bookworm | =7.0.3 2026-09-06 03:03:43.669482 | debian-bookworm | Downloading cachetools-7.1.8-py3-none-any.whl (16 kB) 2026-09-06 03:03:43.712618 | debian-bookworm | Collecting colorama>=0.4.6 2026-09-06 03:03:43.724142 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-09-06 03:03:43.800796 | debian-bookworm | Collecting filelock>=3.25 2026-09-06 03:03:43.812993 | debian-bookworm | Downloading filelock-3.32.5-py3-none-any.whl (100 kB) 2026-09-06 03:03:43.828377 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.0/100.0 kB 8.0 MB/s eta 0:00:00 2026-09-06 03:03:43.893466 | debian-bookworm | Collecting packaging>=26 2026-09-06 03:03:43.905348 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-09-06 03:03:43.924758 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 7.6 MB/s eta 0:00:00 2026-09-06 03:03:43.979358 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-09-06 03:03:43.990321 | debian-bookworm | Downloading platformdirs-4.11.7-py3-none-any.whl (23 kB) 2026-09-06 03:03:44.031489 | debian-bookworm | Collecting pluggy>=1.6 2026-09-06 03:03:44.042564 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-09-06 03:03:44.091936 | debian-bookworm | Collecting pyproject-api>=1.10 2026-09-06 03:03:44.103449 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-09-06 03:03:44.155364 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-09-06 03:03:44.167528 | debian-bookworm | Downloading python_discovery-1.6.0-py3-none-any.whl (38 kB) 2026-09-06 03:03:44.204800 | debian-bookworm | Collecting tomli-w>=1.2 2026-09-06 03:03:44.215758 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-09-06 03:03:44.273661 | debian-bookworm | Collecting typing-extensions>=4.15 2026-09-06 03:03:44.284940 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-09-06 03:03:44.293056 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 6.7 MB/s eta 0:00:00 2026-09-06 03:03:44.447576 | debian-bookworm | Collecting virtualenv>=21.1 2026-09-06 03:03:44.458896 | debian-bookworm | Downloading virtualenv-21.7.8-py3-none-any.whl (5.3 MB) 2026-09-06 03:03:44.724308 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 20.3 MB/s eta 0:00:00 2026-09-06 03:03:44.834229 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-09-06 03:03:44.846685 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-09-06 03:03:44.867410 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 26.2 MB/s eta 0:00:00 2026-09-06 03:03:44.970932 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-09-06 03:03:45.973243 | 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-06 03:03:46.488312 | debian-bookworm | ok: Runtime: 0:00:03.448177 2026-09-06 03:03:46.497954 | 2026-09-06 03:03:46.498037 | TASK [ensure-tox : Export installed tox_executable path] 2026-09-06 03:03:46.540500 | debian-bookworm | ok 2026-09-06 03:03:46.548539 | 2026-09-06 03:03:46.548626 | TASK [ensure-tox : Output tox version] 2026-09-06 03:03:47.123541 | 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-06 03:03:47.143044 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-06 03:03:47.572845 | debian-bookworm | ok: Runtime: 0:00:00.372017 2026-09-06 03:03:47.577865 | 2026-09-06 03:03:47.577947 | TASK [ensure-tox : Make global symlink] 2026-09-06 03:03:47.623084 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:03:47.631463 | 2026-09-06 03:03:47.631533 | PLAY RECAP 2026-09-06 03:03:47.631590 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-09-06 03:03:47.631620 | 2026-09-06 03:03:47.802287 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-09-06 03:03:47.803145 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-06 03:03:48.639203 | 2026-09-06 03:03:48.639415 | PLAY [all] 2026-09-06 03:03:48.684115 | 2026-09-06 03:03:48.684341 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-09-06 03:03:49.367504 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-06 03:03:49.761739 | debian-bookworm | ok: Runtime: 0:00:00.025227 2026-09-06 03:03:49.767205 | 2026-09-06 03:03:49.767299 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-09-06 03:03:50.327595 | debian-bookworm | ok: Runtime: 0:00:00.004989 2026-09-06 03:03:50.333768 | 2026-09-06 03:03:50.333969 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-09-06 03:03:50.694007 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-06 03:03:50.695987 | debian-bookworm | sudo: a password is required 2026-09-06 03:03:50.862549 | debian-bookworm | ok: Runtime: 0:00:00.025332 2026-09-06 03:03:50.873032 | 2026-09-06 03:03:50.873131 | TASK [tox : Check to see if the constraints file exists] 2026-09-06 03:03:50.947978 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:03:50.955569 | 2026-09-06 03:03:50.955819 | TASK [tox : Fail if constraints file is missing] 2026-09-06 03:03:50.979094 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:03:50.988306 | 2026-09-06 03:03:50.988429 | TASK [tox : Record file location] 2026-09-06 03:03:51.043275 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:03:51.048874 | 2026-09-06 03:03:51.048979 | TASK [tox : Install tox siblings] 2026-09-06 03:03:51.088269 | debian-bookworm | ok 2026-09-06 03:03:51.100832 | debian-bookworm | included: /var/lib/zuul/builds/5b63d11ddb37423290bb61b449bd5457/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-09-06 03:03:51.125352 | 2026-09-06 03:03:51.125483 | TASK [tox : Run tox without tests] 2026-09-06 03:03:52.499611 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-09-06 03:04:06.788184 | debian-bookworm | test: OK (15.01 seconds) 2026-09-06 03:04:06.788237 | debian-bookworm | congratulations :) (15.04 seconds) 2026-09-06 03:04:07.165937 | debian-bookworm | ok: Runtime: 0:00:15.408810 2026-09-06 03:04:07.170980 | 2026-09-06 03:04:07.171063 | TASK [tox : Create a tempfile to save tox showconfig] 2026-09-06 03:04:07.544913 | debian-bookworm | changed 2026-09-06 03:04:07.549896 | 2026-09-06 03:04:07.549981 | TASK [tox : Detect tox version] 2026-09-06 03:04:08.170983 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-06 03:04:08.578487 | debian-bookworm | ok: Runtime: 0:00:00.373497 2026-09-06 03:04:08.583834 | 2026-09-06 03:04:08.583921 | TASK [tox : Get tox envlist config] 2026-09-06 03:04:09.623618 | debian-bookworm | ok: Runtime: 0:00:00.481969 2026-09-06 03:04:09.632097 | 2026-09-06 03:04:09.632234 | TASK [tox : Install any sibling python packages] 2026-09-06 03:04:10.120656 | debian-bookworm | ok: No setup.cfg, no action needed 2026-09-06 03:04:10.125985 | 2026-09-06 03:04:10.126075 | TASK [tox : Remove tempfile] 2026-09-06 03:04:10.580410 | debian-bookworm | changed 2026-09-06 03:04:10.586106 | 2026-09-06 03:04:10.586195 | TASK [tox : Emit tox command] 2026-09-06 03:04:10.644107 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-09-06 03:04:10.660462 | 2026-09-06 03:04:10.660571 | TASK [tox : Run tox] 2026-09-06 03:04:11.282103 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-09-06 03:04:11.874194 | debian-bookworm | ...........2026-09-06 03:04:11.873 | ERROR | contrib.check_updates:main:219 - operational failure: boom 2026-09-06 03:04:11.881759 | 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-06 03:04:11.881807 | debian-bookworm | warnings.warn( 2026-09-06 03:04:11.885055 | debian-bookworm | 2026-09-06 03:04:11.884 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 90 2026-09-06 03:04:11.885322 | debian-bookworm | 2026-09-06 03:04:11.884 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-06 03:04:11.885341 | debian-bookworm | 2026-09-06 03:04:11.885 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 10 2026-09-06 03:04:11.885360 | debian-bookworm | 2026-09-06 03:04:11.885 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-06 03:04:11.885372 | debian-bookworm | 2026-09-06 03:04:11.885 | 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-06 03:04:11.891223 | debian-bookworm | ..2026-09-06 03:04:11.890 | ERROR | openstack_image_manager.main:_download:714 - aria2c is not installed; cannot prefetch image 2026-09-06 03:04:11.895092 | debian-bookworm | .2026-09-06 03:04:11.894 | ERROR | openstack_image_manager.main:_download:745 - aria2c exited with rc=1 for http://x/y 2026-09-06 03:04:11.901809 | debian-bookworm | ..2026-09-06 03:04:11.901 | ERROR | openstack_image_manager.main:_download:739 - aria2c timed out downloading http://x/y 2026-09-06 03:04:11.908479 | debian-bookworm | ..2026-09-06 03:04:11.908 | 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-06 03:04:11.914564 | debian-bookworm | ..2026-09-06 03:04:11.914 | 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-06 03:04:11.921222 | debian-bookworm | ..2026-09-06 03:04:11.920 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: 404 2026-09-06 03:04:11.924002 | debian-bookworm | .2026-09-06 03:04:11.923 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: unreachable 2026-09-06 03:04:11.934378 | debian-bookworm | ...2026-09-06 03:04:11.933 | 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-06 03:04:11.937423 | debian-bookworm | .2026-09-06 03:04:11.937 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://x/y; skipping disk check 2026-09-06 03:04:11.941491 | debian-bookworm | .2026-09-06 03:04:11.940 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 03:04:11.941534 | debian-bookworm | 2026-09-06 03:04:11.941 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 03:04:12.253512 | debian-bookworm | 2026-09-06 03:04:12.252 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-06 03:04:12.256119 | debian-bookworm | 2026-09-06 03:04:12.255 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-06 03:04:12.256884 | debian-bookworm | 2026-09-06 03:04:12.255 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-06 03:04:12.265417 | debian-bookworm | .2026-09-06 03:04:12.264 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 03:04:12.265477 | debian-bookworm | 2026-09-06 03:04:12.265 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 03:04:12.266515 | debian-bookworm | 2026-09-06 03:04:12.265 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 03:04:12.266570 | debian-bookworm | 2026-09-06 03:04:12.265 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL file:///path/to/file.img 2026-09-06 03:04:12.267216 | debian-bookworm | 2026-09-06 03:04:12.266 | INFO | openstack_image_manager.main:import_image:539 - Importing local file '/path/to/file.img' as image Ubuntu 20.04 (1) 2026-09-06 03:04:12.274110 | debian-bookworm | .2026-09-06 03:04:12.273 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 03:04:12.274162 | debian-bookworm | 2026-09-06 03:04:12.273 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 03:04:12.274505 | debian-bookworm | 2026-09-06 03:04:12.274 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 03:04:12.274719 | debian-bookworm | 2026-09-06 03:04:12.274 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 03:04:12.274733 | debian-bookworm | 2026-09-06 03:04:12.274 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 03:04:12.274739 | debian-bookworm | 2026-09-06 03:04:12.274 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 03:04:12.274744 | debian-bookworm | 2026-09-06 03:04:12.274 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 03:04:12.275345 | debian-bookworm | 2026-09-06 03:04:12.275 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-06 03:04:12.280928 | debian-bookworm | .2026-09-06 03:04:12.280 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 03:04:12.281030 | debian-bookworm | 2026-09-06 03:04:12.280 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 03:04:12.281773 | debian-bookworm | 2026-09-06 03:04:12.280 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 03:04:12.281823 | debian-bookworm | 2026-09-06 03:04:12.281 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 03:04:12.281844 | debian-bookworm | 2026-09-06 03:04:12.281 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 03:04:12.281861 | debian-bookworm | 2026-09-06 03:04:12.281 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 03:04:12.281879 | debian-bookworm | 2026-09-06 03:04:12.281 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-06 03:04:12.283150 | debian-bookworm | 2026-09-06 03:04:12.282 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-06 03:04:12.283194 | debian-bookworm | 2026-09-06 03:04:12.282 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-06 03:04:12.533943 | debian-bookworm | 2026-09-06 03:04:12.533 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-06 03:04:12.535476 | debian-bookworm | 2026-09-06 03:04:12.535 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-06 03:04:12.535772 | debian-bookworm | 2026-09-06 03:04:12.535 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-06 03:04:12.553237 | debian-bookworm | ...2026-09-06 03:04:12.552 | ERROR | openstack_image_manager.main:main:366 - Skipping cleanup of outdated images because of previous errors 2026-09-06 03:04:12.648558 | debian-bookworm | .2026-09-06 03:04:12.648 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04' (removal candidate) 2026-09-06 03:04:12.653431 | debian-bookworm | .2026-09-06 03:04:12.653 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-06 03:04:12.653475 | debian-bookworm | 2026-09-06 03:04:12.653 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-06 03:04:12.653794 | debian-bookworm | 2026-09-06 03:04:12.653 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-06 03:04:12.654072 | debian-bookworm | 2026-09-06 03:04:12.653 | INFO | openstack_image_manager.main:manage_outdated_images:1430 - Deleting Ubuntu 20.04_2 2026-09-06 03:04:12.654697 | debian-bookworm | 2026-09-06 03:04:12.654 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-06 03:04:12.654894 | debian-bookworm | 2026-09-06 03:04:12.654 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-06 03:04:12.654910 | debian-bookworm | 2026-09-06 03:04:12.654 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-06 03:04:12.654918 | debian-bookworm | 2026-09-06 03:04:12.654 | 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-06 03:04:12.658338 | debian-bookworm | .2026-09-06 03:04:12.658 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 03:04:12.658362 | debian-bookworm | 2026-09-06 03:04:12.658 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 03:04:12.661941 | debian-bookworm | .2026-09-06 03:04:12.661 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 03:04:12.661981 | debian-bookworm | 2026-09-06 03:04:12.661 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 03:04:12.899605 | debian-bookworm | 2026-09-06 03:04:12.899 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-06 03:04:12.901709 | debian-bookworm | 2026-09-06 03:04:12.901 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-06 03:04:12.902059 | debian-bookworm | 2026-09-06 03:04:12.901 | ERROR | openstack_image_manager.main:_prefetch_import:655 - glance-direct import failed for Ubuntu 20.04 (1) 2026-09-06 03:04:12.902078 | debian-bookworm | 409 conflict on fixed id 2026-09-06 03:04:12.902085 | debian-bookworm | 2026-09-06 03:04:12.901 | ERROR | openstack_image_manager.main:_prefetch_import:658 - PREFETCH: glance-direct import failed for 'Ubuntu 20.04 (1)' 2026-09-06 03:04:12.909463 | debian-bookworm | .2026-09-06 03:04:12.909 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 03:04:12.909509 | debian-bookworm | 2026-09-06 03:04:12.909 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 03:04:13.154440 | debian-bookworm | 2026-09-06 03:04:13.153 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-06 03:04:13.155685 | debian-bookworm | 2026-09-06 03:04:13.155 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download failed for 'Ubuntu 20.04 (1)' 2026-09-06 03:04:13.161779 | debian-bookworm | .2026-09-06 03:04:13.161 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-06 03:04:13.162204 | debian-bookworm | 2026-09-06 03:04:13.161 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-06 03:04:13.162476 | debian-bookworm | 2026-09-06 03:04:13.162 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-06 03:04:13.163751 | debian-bookworm | 2026-09-06 03:04:13.163 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-06 03:04:13.164046 | debian-bookworm | 2026-09-06 03:04:13.163 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-06 03:04:13.164295 | debian-bookworm | 2026-09-06 03:04:13.164 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-06 03:04:13.164313 | debian-bookworm | 2026-09-06 03:04:13.164 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-06 03:04:13.164320 | debian-bookworm | 2026-09-06 03:04:13.164 | INFO | openstack_image_manager.main:process_image:1020 - Skipping required import of image 'Ubuntu 20.04 (1)', running in dry-run mode 2026-09-06 03:04:13.168567 | debian-bookworm | .2026-09-06 03:04:13.168 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (latest)' 2026-09-06 03:04:13.169056 | debian-bookworm | 2026-09-06 03:04:13.168 | 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-06 03:04:13.174688 | debian-bookworm | .2026-09-06 03:04:13.174 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-1' 2026-09-06 03:04:13.175157 | debian-bookworm | 2026-09-06 03:04:13.174 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-06 03:04:13.175386 | debian-bookworm | 2026-09-06 03:04:13.175 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-1' successfully completed, reloading images 2026-09-06 03:04:13.180832 | debian-bookworm | .2026-09-06 03:04:13.180 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(1)' 2026-09-06 03:04:13.181158 | debian-bookworm | 2026-09-06 03:04:13.180 | INFO | openstack_image_manager.main:process_image:1025 - Skipping image 'Ubuntu 20.04-(1)' (only importing the latest version from type multi) 2026-09-06 03:04:13.181178 | debian-bookworm | 2026-09-06 03:04:13.180 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(2)' 2026-09-06 03:04:13.182129 | debian-bookworm | 2026-09-06 03:04:13.181 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com2: 200 2026-09-06 03:04:13.182500 | debian-bookworm | 2026-09-06 03:04:13.182 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-(2)' successfully completed, reloading images 2026-09-06 03:04:13.201299 | debian-bookworm | ...2026-09-06 03:04:13.200 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-06 03:04:13.201656 | debian-bookworm | 2026-09-06 03:04:13.201 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-06 03:04:13.201676 | debian-bookworm | 2026-09-06 03:04:13.201 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-06 03:04:13.201683 | debian-bookworm | 2026-09-06 03:04:13.201 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-06 03:04:13.201985 | debian-bookworm | 2026-09-06 03:04:13.201 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04 (1) to Ubuntu 20.04 2026-09-06 03:04:13.205329 | debian-bookworm | .2026-09-06 03:04:13.204 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-06 03:04:13.205657 | debian-bookworm | 2026-09-06 03:04:13.205 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-06 03:04:13.205679 | debian-bookworm | 2026-09-06 03:04:13.205 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-06 03:04:13.205693 | debian-bookworm | 2026-09-06 03:04:13.205 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-06 03:04:13.205900 | debian-bookworm | 2026-09-06 03:04:13.205 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04-(1) to Ubuntu 20.04 2026-09-06 03:04:13.239389 | debian-bookworm | ..2026-09-06 03:04:13.238 | INFO | openstack_image_manager.main:set_properties:1063 - Checking parameters of 'Ubuntu 20.04 (1)' 2026-09-06 03:04:13.240064 | debian-bookworm | 2026-09-06 03:04:13.239 | INFO | openstack_image_manager.main:set_properties:1129 - Setting internal_version = 1 2026-09-06 03:04:13.240103 | debian-bookworm | 2026-09-06 03:04:13.239 | INFO | openstack_image_manager.main:set_properties:1132 - Setting image_original_user = ubuntu 2026-09-06 03:04:13.240118 | debian-bookworm | 2026-09-06 03:04:13.239 | INFO | openstack_image_manager.main:set_properties:1145 - Adding tag my_tag 2026-09-06 03:04:13.240139 | debian-bookworm | 2026-09-06 03:04:13.239 | INFO | openstack_image_manager.main:set_properties:1150 - Deleting tag fake_tag 2026-09-06 03:04:13.240153 | debian-bookworm | 2026-09-06 03:04:13.239 | INFO | openstack_image_manager.main:set_properties:1161 - Setting property image_build_date: 2021-01-01 != 2021-01-21 2026-09-06 03:04:13.240652 | debian-bookworm | 2026-09-06 03:04:13.240 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_distro: ubuntu 2026-09-06 03:04:13.240684 | debian-bookworm | 2026-09-06 03:04:13.240 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_version: 20.04 2026-09-06 03:04:13.240692 | debian-bookworm | 2026-09-06 03:04:13.240 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property image_source: http://url.com 2026-09-06 03:04:13.240700 | debian-bookworm | 2026-09-06 03:04:13.240 | INFO | openstack_image_manager.main:set_properties:1185 - Checking status of 'Ubuntu 20.04 (1)' 2026-09-06 03:04:13.240707 | debian-bookworm | 2026-09-06 03:04:13.240 | INFO | openstack_image_manager.main:set_properties:1190 - Deactivating image 'Ubuntu 20.04 (1)' 2026-09-06 03:04:13.241125 | debian-bookworm | 2026-09-06 03:04:13.240 | INFO | openstack_image_manager.main:set_properties:1197 - Checking visibility of 'Ubuntu 20.04 (1)' 2026-09-06 03:04:13.270224 | debian-bookworm | ...2026-09-06 03:04:13.269 | ERROR | openstack_image_manager.main:wait_for_image:814 - Image import timed out 2026-09-06 03:04:13.274222 | debian-bookworm | .2026-09-06 03:04:13.273 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-06 03:04:13.274260 | debian-bookworm | boom 2026-09-06 03:04:13.274494 | debian-bookworm | 2026-09-06 03:04:13.274 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-06 03:04:13.274505 | debian-bookworm | boom 2026-09-06 03:04:13.274510 | debian-bookworm | 2026-09-06 03:04:13.274 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-06 03:04:13.274514 | debian-bookworm | boom 2026-09-06 03:04:13.274673 | debian-bookworm | 2026-09-06 03:04:13.274 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-06 03:04:13.274682 | debian-bookworm | boom 2026-09-06 03:04:13.274687 | debian-bookworm | 2026-09-06 03:04:13.274 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-06 03:04:13.274690 | debian-bookworm | boom 2026-09-06 03:04:13.274831 | debian-bookworm | 2026-09-06 03:04:13.274 | ERROR | openstack_image_manager.main:wait_for_image:848 - Giving up on image after repeated errors 2026-09-06 03:04:13.279048 | debian-bookworm | .2026-09-06 03:04:13.278 | ERROR | openstack_image_manager.main:wait_for_image:837 - Image entered terminal state 'killed' 2026-09-06 03:04:13.282479 | debian-bookworm | .2026-09-06 03:04:13.282 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-06 03:04:13.282528 | debian-bookworm | 2026-09-06 03:04:13.282 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-06 03:04:13.282905 | debian-bookworm | 2026-09-06 03:04:13.282 | ERROR | openstack_image_manager.main:import_image:584 - Web-download import for image Ubuntu 20.04 (1) failed 2026-09-06 03:04:13.282992 | debian-bookworm | glance api error 2026-09-06 03:04:13.283006 | debian-bookworm | 2026-09-06 03:04:13.282 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-06 03:04:13.297035 | debian-bookworm | .......2026-09-06 03:04:13 | INFO  | main:223 - Processing file /tmp/tmpczxftfwo/example.yml 2026-09-06 03:04:13.304304 | debian-bookworm | 2026-09-06 03:04:13 | INFO  | main:232 - Skipping disabled image Example 1.0 2026-09-06 03:04:13.313870 | debian-bookworm | .2026-09-06 03:04:13 | INFO  | main:223 - Processing file /tmp/tmp4vt1fqep/example.yml 2026-09-06 03:04:13.318847 | debian-bookworm | 2026-09-06 03:04:13 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-06 03:04:13.318900 | debian-bookworm | 2026-09-06 03:04:13 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-06 03:04:13.319485 | debian-bookworm | 2026-09-06 03:04:13 | 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-06 03:04:13.322137 | debian-bookworm | 2026-09-06 03:04:13 | INFO  | main:242 - Dry-run enabled, not writing /tmp/tmp4vt1fqep/example.yml 2026-09-06 03:04:13.331135 | debian-bookworm | .2026-09-06 03:04:13 | INFO  | main:223 - Processing file /tmp/tmp0km7qzy8/example.yml 2026-09-06 03:04:13.334846 | debian-bookworm | 2026-09-06 03:04:13 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-06 03:04:13.335152 | debian-bookworm | 2026-09-06 03:04:13 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:0000000000000000000000000000000000000000000000000000000000000000 2026-09-06 03:04:13.335169 | debian-bookworm | 2026-09-06 03:04:13 | INFO  | update_image:158 - Image Example 1.0 is up-to-date, nothing to do 2026-09-06 03:04:13.343391 | debian-bookworm | .2026-09-06 03:04:13 | INFO  | main:223 - Processing file /tmp/tmpeecsojx6/example.yml 2026-09-06 03:04:13.347388 | debian-bookworm | 2026-09-06 03:04:13 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-06 03:04:13.347790 | debian-bookworm | 2026-09-06 03:04:13 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-06 03:04:13.347827 | debian-bookworm | 2026-09-06 03:04:13 | 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-06 03:04:13.351935 | debian-bookworm | . 2026-09-06 03:04:13.351983 | debian-bookworm | ---------------------------------------------------------------------- 2026-09-06 03:04:13.352021 | debian-bookworm | Ran 74 tests in 1.501s 2026-09-06 03:04:13.352027 | debian-bookworm | 2026-09-06 03:04:13.352032 | debian-bookworm | OK 2026-09-06 03:04:13.480542 | debian-bookworm | test: OK (2.25=setup[0.05]+cmd[2.20] seconds) 2026-09-06 03:04:13.480585 | debian-bookworm | congratulations :) (2.28 seconds) 2026-09-06 03:04:13.702535 | debian-bookworm | ok: Runtime: 0:00:02.586887 2026-09-06 03:04:13.708571 | 2026-09-06 03:04:13.708645 | TASK [tox : Look for output] 2026-09-06 03:04:14.055113 | debian-bookworm | ok 2026-09-06 03:04:14.065780 | 2026-09-06 03:04:14.065869 | TASK [tox : Return file comments to Zuul] 2026-09-06 03:04:14.119621 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:14.125801 | 2026-09-06 03:04:14.125867 | PLAY RECAP 2026-09-06 03:04:14.125914 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-06 03:04:14.125938 | 2026-09-06 03:04:14.243757 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-06 03:04:14.244779 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-06 03:04:14.849821 | 2026-09-06 03:04:14.849929 | PLAY [all] 2026-09-06 03:04:14.875015 | 2026-09-06 03:04:14.875120 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-09-06 03:04:14.965327 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:14.970031 | 2026-09-06 03:04:14.970132 | TASK [fetch-tox-output : Set tox log path for single node] 2026-09-06 03:04:15.028549 | debian-bookworm | ok 2026-09-06 03:04:15.032921 | 2026-09-06 03:04:15.033006 | TASK [fetch-tox-output : Ensure local tox dir] 2026-09-06 03:04:15.838429 | debian-bookworm -> localhost | changed 2026-09-06 03:04:15.847697 | 2026-09-06 03:04:15.847793 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-09-06 03:04:15.946136 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:15.969751 | 2026-09-06 03:04:15.969878 | TASK [fetch-tox-output : Set envlist fact] 2026-09-06 03:04:16.055402 | debian-bookworm | ok 2026-09-06 03:04:16.071327 | 2026-09-06 03:04:16.071782 | TASK [fetch-tox-output : Get tox version] 2026-09-06 03:04:17.229370 | 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-06 03:04:17.247956 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-06 03:04:17.663408 | debian-bookworm | ok: Runtime: 0:00:00.314236 2026-09-06 03:04:17.668008 | 2026-09-06 03:04:17.668089 | TASK [fetch-tox-output : Find all default environments] 2026-09-06 03:04:18.212936 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:18.217614 | 2026-09-06 03:04:18.217703 | TASK [fetch-tox-output : Set envlist fact] 2026-09-06 03:04:18.240299 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:18.244755 | 2026-09-06 03:04:18.244832 | TASK [fetch-tox-output : Find all default environments] 2026-09-06 03:04:18.785028 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:18.797869 | 2026-09-06 03:04:18.798021 | TASK [fetch-tox-output : Set envlist fact] 2026-09-06 03:04:18.843575 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:18.849893 | 2026-09-06 03:04:18.849979 | LOOP [fetch-tox-output : Copy tox logs] 2026-09-06 03:04:18.900808 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:18.911352 | 2026-09-06 03:04:18.911480 | LOOP [fetch-tox-output : Collect tox logs] 2026-09-06 03:04:19.586646 | debian-bookworm | changed: 2026-09-06 03:04:19.586812 | debian-bookworm | created directory /var/lib/zuul/builds/5b63d11ddb37423290bb61b449bd5457/work/logs/tox/test 2026-09-06 03:04:19.586939 | debian-bookworm | cd+++++++++ ./ 2026-09-06 03:04:19.586967 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-09-06 03:04:19.586996 | 2026-09-06 03:04:19.597939 | 2026-09-06 03:04:19.598014 | PLAY RECAP 2026-09-06 03:04:19.598061 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-09-06 03:04:19.598086 | 2026-09-06 03:04:19.745437 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-06 03:04:19.747260 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-06 03:04:20.801305 | 2026-09-06 03:04:20.801434 | PLAY [all] 2026-09-06 03:04:20.815667 | 2026-09-06 03:04:20.815787 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-09-06 03:04:20.976321 | debian-bookworm | changed: non-zero return code 2026-09-06 03:04:20.987787 | 2026-09-06 03:04:20.987902 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-09-06 03:04:21.021098 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:21.027173 | 2026-09-06 03:04:21.027281 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-09-06 03:04:21.056876 | 2026-09-06 03:04:21.057043 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-09-06 03:04:21.106784 | 2026-09-06 03:04:21.108001 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-09-06 03:04:21.151729 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:21.156498 | 2026-09-06 03:04:21.156591 | LOOP [fetch-subunit-output : Generate subunit file] 2026-09-06 03:04:21.193873 | 2026-09-06 03:04:21.194029 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-09-06 03:04:21.216436 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:21.221000 | 2026-09-06 03:04:21.221083 | TASK [fetch-subunit-output : Remove the temporary file] 2026-09-06 03:04:21.235258 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:21.239772 | 2026-09-06 03:04:21.239845 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-09-06 03:04:21.252162 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:21.257020 | 2026-09-06 03:04:21.257071 | PLAY RECAP 2026-09-06 03:04:21.257109 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-09-06 03:04:21.257136 | 2026-09-06 03:04:21.335468 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-06 03:04:21.336959 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-06 03:04:22.077814 | 2026-09-06 03:04:22.077941 | PLAY [Base post-fetch] 2026-09-06 03:04:22.110628 | 2026-09-06 03:04:22.110742 | TASK [diagnose-network : Ensure log directory exists] 2026-09-06 03:04:22.620515 | debian-bookworm | ok 2026-09-06 03:04:22.626989 | 2026-09-06 03:04:22.630768 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-09-06 03:04:34.468651 | debian-bookworm | ok: Runtime: 0:00:05.929342 2026-09-06 03:04:34.485483 | 2026-09-06 03:04:34.485579 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-09-06 03:04:40.661625 | debian-bookworm | ok: Runtime: 0:00:00.004890 2026-09-06 03:04:40.666001 | 2026-09-06 03:04:40.666088 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-09-06 03:04:46.585492 | debian-bookworm | ok: Runtime: 0:00:00.004761 2026-09-06 03:04:46.595272 | 2026-09-06 03:04:46.595376 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-06 03:04:46.673257 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:46.678724 | 2026-09-06 03:04:46.678825 | TASK [fetch-output : Set log path for single node] 2026-09-06 03:04:46.738140 | debian-bookworm | ok 2026-09-06 03:04:46.744128 | 2026-09-06 03:04:46.744238 | LOOP [fetch-output : Ensure local output dirs] 2026-09-06 03:04:47.364635 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/5b63d11ddb37423290bb61b449bd5457/work/logs" 2026-09-06 03:04:47.778033 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5b63d11ddb37423290bb61b449bd5457/work/artifacts" 2026-09-06 03:04:48.227048 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5b63d11ddb37423290bb61b449bd5457/work/docs" 2026-09-06 03:04:48.236129 | 2026-09-06 03:04:48.236248 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-06 03:04:48.962194 | debian-bookworm | changed: 2026-09-06 03:04:48.962391 | debian-bookworm | .d..t...... ./ 2026-09-06 03:04:48.962426 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-06 03:04:48.962450 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-06 03:04:48.962471 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-06 03:04:48.962491 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-06 03:04:48.962512 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-06 03:04:48.962548 | debian-bookworm | changed: All items complete 2026-09-06 03:04:48.962571 | 2026-09-06 03:04:49.628606 | debian-bookworm | changed: .d..t...... ./ 2026-09-06 03:04:50.182496 | debian-bookworm | changed: .d..t...... ./ 2026-09-06 03:04:50.201891 | 2026-09-06 03:04:50.201990 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-06 03:04:50.231844 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:50.235975 | debian-bookworm | skipping: Conditional result was False 2026-09-06 03:04:50.254989 | 2026-09-06 03:04:50.255064 | PLAY RECAP 2026-09-06 03:04:50.255114 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-09-06 03:04:50.255139 | 2026-09-06 03:04:50.400084 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-06 03:04:50.401051 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-06 03:04:51.132304 | 2026-09-06 03:04:51.132425 | PLAY [Base post] 2026-09-06 03:04:51.147053 | 2026-09-06 03:04:51.147159 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-06 03:04:51.707464 | debian-bookworm | changed 2026-09-06 03:04:51.731097 | 2026-09-06 03:04:51.731198 | PLAY RECAP 2026-09-06 03:04:51.731289 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-06 03:04:51.731359 | 2026-09-06 03:04:51.900500 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-06 03:04:51.901468 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-06 03:04:52.823165 | 2026-09-06 03:04:52.823346 | PLAY [Base post-logs] 2026-09-06 03:04:52.835058 | 2026-09-06 03:04:52.835158 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-06 03:04:53.363500 | localhost | changed 2026-09-06 03:04:53.371187 | 2026-09-06 03:04:53.371307 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-06 03:04:53.404994 | localhost | ok 2026-09-06 03:04:53.407626 | 2026-09-06 03:04:53.407693 | TASK [Set zuul-log-path fact] 2026-09-06 03:04:53.420881 | localhost | ok 2026-09-06 03:04:53.426725 | 2026-09-06 03:04:53.426798 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-06 03:04:53.460290 | localhost | ok 2026-09-06 03:04:53.462456 | 2026-09-06 03:04:53.462524 | TASK [upload-logs : Create log directories] 2026-09-06 03:04:54.129524 | localhost | changed 2026-09-06 03:04:54.131640 | 2026-09-06 03:04:54.131711 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-06 03:04:54.650689 | localhost -> localhost | ok: Runtime: 0:00:00.002994 2026-09-06 03:04:54.653646 | 2026-09-06 03:04:54.653725 | TASK [upload-logs : Upload logs to log server] 2026-09-06 03:04:55.370004 | localhost | Output suppressed because no_log was given 2026-09-06 03:04:55.379835 | 2026-09-06 03:04:55.379927 | LOOP [upload-logs : Compress console log and json output] 2026-09-06 03:04:55.456477 | localhost | skipping: Conditional result was False 2026-09-06 03:04:55.463316 | localhost | skipping: Conditional result was False 2026-09-06 03:04:55.468512 | 2026-09-06 03:04:55.468830 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-06 03:04:55.520833 | localhost | skipping: Conditional result was False 2026-09-06 03:04:55.521023 | 2026-09-06 03:04:55.526566 | localhost | skipping: Conditional result was False 2026-09-06 03:04:55.536970 | 2026-09-06 03:04:55.537060 | LOOP [upload-logs : Upload console log and json output]