2026-09-01 03:02:48.401468 | Job console starting 2026-09-01 03:02:48.429823 | Updating git repos 2026-09-01 03:02:48.463771 | Cloning repos into workspace 2026-09-01 03:02:48.497038 | Restoring repo states 2026-09-01 03:02:48.517000 | Merging changes 2026-09-01 03:02:48.517021 | Checking out repos 2026-09-01 03:02:48.548327 | Preparing playbooks 2026-09-01 03:02:49.391573 | Running Ansible setup 2026-09-01 03:02:53.776302 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-01 03:02:54.615453 | 2026-09-01 03:02:54.616268 | PLAY [Base pre] 2026-09-01 03:02:54.642869 | 2026-09-01 03:02:54.643018 | TASK [Setup log path fact] 2026-09-01 03:02:54.684600 | debian-bookworm | ok 2026-09-01 03:02:54.702523 | 2026-09-01 03:02:54.702665 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 03:02:54.731089 | debian-bookworm | ok 2026-09-01 03:02:54.741211 | 2026-09-01 03:02:54.741318 | TASK [emit-job-header : Print job information] 2026-09-01 03:02:54.780089 | # Job Information 2026-09-01 03:02:54.780252 | Ansible Version: 2.16.14 2026-09-01 03:02:54.780287 | Job: tox 2026-09-01 03:02:54.780321 | Pipeline: periodic-daily 2026-09-01 03:02:54.780343 | Executor: 521e9411259a 2026-09-01 03:02:54.780365 | Triggered by: https://github.com/osism/openstack-image-manager 2026-09-01 03:02:54.780387 | Event ID: 45f9320fa1c041ebbd811769d0144840 2026-09-01 03:02:54.785302 | 2026-09-01 03:02:54.785423 | LOOP [emit-job-header : Print node information] 2026-09-01 03:02:54.942735 | debian-bookworm | ok: 2026-09-01 03:02:54.943188 | debian-bookworm | # Node Information 2026-09-01 03:02:54.943244 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-01 03:02:54.943278 | debian-bookworm | Hostname: debian 2026-09-01 03:02:54.943306 | debian-bookworm | Username: zuul 2026-09-01 03:02:54.943333 | debian-bookworm | Distro: Debian 12.15 2026-09-01 03:02:54.943364 | debian-bookworm | Provider: regiocloud-a 2026-09-01 03:02:54.943393 | debian-bookworm | Region: 2026-09-01 03:02:54.943421 | debian-bookworm | Label: debian-bookworm 2026-09-01 03:02:54.943448 | debian-bookworm | Product Name: OpenStack Nova 2026-09-01 03:02:54.943473 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fea9:795d 2026-09-01 03:02:54.957336 | 2026-09-01 03:02:54.957498 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-01 03:02:55.442097 | debian-bookworm -> localhost | changed 2026-09-01 03:02:55.449682 | 2026-09-01 03:02:55.449803 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-01 03:02:56.486945 | debian-bookworm -> localhost | changed 2026-09-01 03:02:56.503755 | 2026-09-01 03:02:56.503879 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-01 03:02:56.783196 | debian-bookworm -> localhost | ok 2026-09-01 03:02:56.797543 | 2026-09-01 03:02:56.797704 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-01 03:02:56.822470 | debian-bookworm | ok 2026-09-01 03:02:56.839185 | debian-bookworm | included: /var/lib/zuul/builds/daa3b37036414eb5853b2bfe3a50d752/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-01 03:02:56.845593 | 2026-09-01 03:02:56.845687 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-01 03:02:58.221411 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-01 03:02:58.221687 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/daa3b37036414eb5853b2bfe3a50d752/work/daa3b37036414eb5853b2bfe3a50d752_id_rsa 2026-09-01 03:02:58.221741 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/daa3b37036414eb5853b2bfe3a50d752/work/daa3b37036414eb5853b2bfe3a50d752_id_rsa.pub 2026-09-01 03:02:58.221778 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-01 03:02:58.221812 | debian-bookworm -> localhost | SHA256:uiJU/BvHmO3o+nMefzdWxqlTOYo8iIpW8FmTNsha/nE zuul-build-sshkey 2026-09-01 03:02:58.221843 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-01 03:02:58.221887 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-01 03:02:58.221916 | debian-bookworm -> localhost | | | 2026-09-01 03:02:58.221946 | debian-bookworm -> localhost | | | 2026-09-01 03:02:58.221973 | debian-bookworm -> localhost | | .. . . | 2026-09-01 03:02:58.222000 | debian-bookworm -> localhost | | .o+ * | 2026-09-01 03:02:58.222028 | debian-bookworm -> localhost | | .*.+=S .o| 2026-09-01 03:02:58.222059 | debian-bookworm -> localhost | | .. ==o+E =+| 2026-09-01 03:02:58.222106 | debian-bookworm -> localhost | | . . o*= o . +o.| 2026-09-01 03:02:58.222135 | debian-bookworm -> localhost | | ..o ++o+ +.++ | 2026-09-01 03:02:58.222164 | debian-bookworm -> localhost | | .o.*=+. ...o.. | 2026-09-01 03:02:58.222191 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-01 03:02:58.222275 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.872524 2026-09-01 03:02:58.231529 | 2026-09-01 03:02:58.231654 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-01 03:02:58.261310 | debian-bookworm | ok 2026-09-01 03:02:58.272910 | debian-bookworm | included: /var/lib/zuul/builds/daa3b37036414eb5853b2bfe3a50d752/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-01 03:02:58.281187 | 2026-09-01 03:02:58.281290 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-01 03:02:58.294409 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:02:58.300847 | 2026-09-01 03:02:58.300947 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-01 03:02:58.971625 | debian-bookworm | changed 2026-09-01 03:02:58.980285 | 2026-09-01 03:02:58.980413 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-01 03:02:59.253436 | debian-bookworm | ok 2026-09-01 03:02:59.266722 | 2026-09-01 03:02:59.266925 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-01 03:02:59.968944 | debian-bookworm | changed 2026-09-01 03:02:59.976246 | 2026-09-01 03:02:59.976369 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-01 03:03:00.634264 | debian-bookworm | changed 2026-09-01 03:03:00.644680 | 2026-09-01 03:03:00.644802 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-01 03:03:00.668809 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:03:00.674929 | 2026-09-01 03:03:00.675038 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-01 03:03:01.137402 | debian-bookworm -> localhost | changed 2026-09-01 03:03:01.158384 | 2026-09-01 03:03:01.158534 | TASK [add-build-sshkey : Add back temp key] 2026-09-01 03:03:01.497430 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/daa3b37036414eb5853b2bfe3a50d752/work/daa3b37036414eb5853b2bfe3a50d752_id_rsa (zuul-build-sshkey) 2026-09-01 03:03:01.497840 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017827 2026-09-01 03:03:01.511861 | 2026-09-01 03:03:01.512005 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-01 03:03:01.945117 | debian-bookworm | ok 2026-09-01 03:03:01.953976 | 2026-09-01 03:03:01.954154 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-01 03:03:01.978753 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:03:01.992074 | 2026-09-01 03:03:01.992177 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-01 03:03:02.415107 | debian-bookworm | ok 2026-09-01 03:03:02.435274 | 2026-09-01 03:03:02.435414 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-01 03:03:02.490169 | debian-bookworm | ok 2026-09-01 03:03:02.495742 | 2026-09-01 03:03:02.495862 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-01 03:03:02.823245 | debian-bookworm -> localhost | ok 2026-09-01 03:03:02.835717 | 2026-09-01 03:03:02.835875 | TASK [validate-host : Collect information about the host] 2026-09-01 03:03:03.981459 | debian-bookworm | ok 2026-09-01 03:03:03.994153 | 2026-09-01 03:03:03.994277 | TASK [validate-host : Sanitize hostname] 2026-09-01 03:03:04.283721 | debian-bookworm | ok 2026-09-01 03:03:04.289552 | 2026-09-01 03:03:04.289667 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-01 03:03:05.372713 | debian-bookworm -> localhost | changed 2026-09-01 03:03:05.379911 | 2026-09-01 03:03:05.380129 | TASK [validate-host : Collect information about zuul worker] 2026-09-01 03:03:05.844560 | debian-bookworm | ok 2026-09-01 03:03:05.849534 | 2026-09-01 03:03:05.849651 | TASK [validate-host : Write out all zuul information for each host] 2026-09-01 03:03:06.468288 | debian-bookworm -> localhost | changed 2026-09-01 03:03:06.503544 | 2026-09-01 03:03:06.503676 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-01 03:03:06.801241 | debian-bookworm | ok 2026-09-01 03:03:06.807061 | 2026-09-01 03:03:06.807191 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-01 03:03:07.719573 | debian-bookworm | changed: 2026-09-01 03:03:07.719867 | debian-bookworm | cd+++++++++ src/ 2026-09-01 03:03:07.719926 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-01 03:03:07.719970 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-01 03:03:07.720009 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-09-01 03:03:07.720044 | debian-bookworm | =7.0.3 2026-09-01 03:03:46.045422 | debian-bookworm | Downloading cachetools-7.1.8-py3-none-any.whl (16 kB) 2026-09-01 03:03:46.113145 | debian-bookworm | Collecting colorama>=0.4.6 2026-09-01 03:03:46.125838 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-09-01 03:03:46.217534 | debian-bookworm | Collecting filelock>=3.25 2026-09-01 03:03:46.230058 | debian-bookworm | Downloading filelock-3.32.5-py3-none-any.whl (100 kB) 2026-09-01 03:03:46.242499 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.0/100.0 kB 10.7 MB/s eta 0:00:00 2026-09-01 03:03:46.310417 | debian-bookworm | Collecting packaging>=26 2026-09-01 03:03:46.322929 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-09-01 03:03:46.344673 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 6.8 MB/s eta 0:00:00 2026-09-01 03:03:46.413922 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-09-01 03:03:46.426151 | debian-bookworm | Downloading platformdirs-4.11.5-py3-none-any.whl (23 kB) 2026-09-01 03:03:46.475369 | debian-bookworm | Collecting pluggy>=1.6 2026-09-01 03:03:46.487280 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-09-01 03:03:46.530916 | debian-bookworm | Collecting pyproject-api>=1.10 2026-09-01 03:03:46.543760 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-09-01 03:03:46.597192 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-09-01 03:03:46.609755 | debian-bookworm | Downloading python_discovery-1.6.0-py3-none-any.whl (38 kB) 2026-09-01 03:03:46.653038 | debian-bookworm | Collecting tomli-w>=1.2 2026-09-01 03:03:46.665242 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-09-01 03:03:46.726260 | debian-bookworm | Collecting typing-extensions>=4.15 2026-09-01 03:03:46.738364 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-09-01 03:03:46.747490 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 6.3 MB/s eta 0:00:00 2026-09-01 03:03:46.889435 | debian-bookworm | Collecting virtualenv>=21.1 2026-09-01 03:03:46.901421 | debian-bookworm | Downloading virtualenv-21.7.7-py3-none-any.whl (5.3 MB) 2026-09-01 03:03:47.155762 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 21.4 MB/s eta 0:00:00 2026-09-01 03:03:47.251204 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-09-01 03:03:47.263306 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-09-01 03:03:47.284340 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 26.6 MB/s eta 0:00:00 2026-09-01 03:03:47.389370 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-09-01 03:03:48.302512 | 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.5 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.7 2026-09-01 03:03:48.893245 | debian-bookworm | ok: Runtime: 0:00:03.327107 2026-09-01 03:03:48.898898 | 2026-09-01 03:03:48.898989 | TASK [ensure-tox : Export installed tox_executable path] 2026-09-01 03:03:48.929914 | debian-bookworm | ok 2026-09-01 03:03:48.936362 | 2026-09-01 03:03:48.936469 | TASK [ensure-tox : Output tox version] 2026-09-01 03:03:49.553914 | 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-01 03:03:49.573923 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-01 03:03:49.990449 | debian-bookworm | ok: Runtime: 0:00:00.386475 2026-09-01 03:03:49.995445 | 2026-09-01 03:03:49.995554 | TASK [ensure-tox : Make global symlink] 2026-09-01 03:03:50.018477 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:03:50.023889 | 2026-09-01 03:03:50.023949 | PLAY RECAP 2026-09-01 03:03:50.023991 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-09-01 03:03:50.024012 | 2026-09-01 03:03:50.293139 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-09-01 03:03:50.293963 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-01 03:03:51.118204 | 2026-09-01 03:03:51.118317 | PLAY [all] 2026-09-01 03:03:51.132673 | 2026-09-01 03:03:51.132774 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-09-01 03:03:51.606570 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-01 03:03:51.671195 | debian-bookworm | ok: Runtime: 0:00:00.033041 2026-09-01 03:03:51.675755 | 2026-09-01 03:03:51.675845 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-09-01 03:03:52.215141 | debian-bookworm | ok: Runtime: 0:00:00.006463 2026-09-01 03:03:52.224764 | 2026-09-01 03:03:52.224854 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-09-01 03:03:52.618181 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-01 03:03:52.622015 | debian-bookworm | sudo: a password is required 2026-09-01 03:03:52.783587 | debian-bookworm | ok: Runtime: 0:00:00.025072 2026-09-01 03:03:52.791730 | 2026-09-01 03:03:52.791808 | TASK [tox : Check to see if the constraints file exists] 2026-09-01 03:03:52.814527 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:03:52.819427 | 2026-09-01 03:03:52.819505 | TASK [tox : Fail if constraints file is missing] 2026-09-01 03:03:52.842032 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:03:52.846889 | 2026-09-01 03:03:52.846970 | TASK [tox : Record file location] 2026-09-01 03:03:52.879837 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:03:52.884425 | 2026-09-01 03:03:52.884511 | TASK [tox : Install tox siblings] 2026-09-01 03:03:52.912399 | debian-bookworm | ok 2026-09-01 03:03:52.923510 | debian-bookworm | included: /var/lib/zuul/builds/daa3b37036414eb5853b2bfe3a50d752/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-09-01 03:03:52.933211 | 2026-09-01 03:03:52.933309 | TASK [tox : Run tox without tests] 2026-09-01 03:03:54.313966 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-09-01 03:04:08.869768 | debian-bookworm | test: OK (15.34 seconds) 2026-09-01 03:04:08.869852 | debian-bookworm | congratulations :) (15.37 seconds) 2026-09-01 03:04:09.011720 | debian-bookworm | ok: Runtime: 0:00:15.739741 2026-09-01 03:04:09.016020 | 2026-09-01 03:04:09.016120 | TASK [tox : Create a tempfile to save tox showconfig] 2026-09-01 03:04:09.417149 | debian-bookworm | changed 2026-09-01 03:04:09.421944 | 2026-09-01 03:04:09.422026 | TASK [tox : Detect tox version] 2026-09-01 03:04:10.001816 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-01 03:04:10.448509 | debian-bookworm | ok: Runtime: 0:00:00.358407 2026-09-01 03:04:10.453675 | 2026-09-01 03:04:10.453816 | TASK [tox : Get tox envlist config] 2026-09-01 03:04:11.480925 | debian-bookworm | ok: Runtime: 0:00:00.411579 2026-09-01 03:04:11.486373 | 2026-09-01 03:04:11.486466 | TASK [tox : Install any sibling python packages] 2026-09-01 03:04:11.898914 | debian-bookworm | ok: No setup.cfg, no action needed 2026-09-01 03:04:11.905736 | 2026-09-01 03:04:11.905832 | TASK [tox : Remove tempfile] 2026-09-01 03:04:12.354884 | debian-bookworm | changed 2026-09-01 03:04:12.359932 | 2026-09-01 03:04:12.360014 | TASK [tox : Emit tox command] 2026-09-01 03:04:12.387525 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-09-01 03:04:12.392563 | 2026-09-01 03:04:12.392669 | TASK [tox : Run tox] 2026-09-01 03:04:13.061667 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-09-01 03:04:13.747330 | debian-bookworm | ...........2026-09-01 03:04:13.746 | ERROR | contrib.check_updates:main:219 - operational failure: boom 2026-09-01 03:04:13.755419 | 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-01 03:04:13.755472 | debian-bookworm | warnings.warn( 2026-09-01 03:04:13.759207 | debian-bookworm | 2026-09-01 03:04:13.758 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 90 2026-09-01 03:04:13.759561 | debian-bookworm | 2026-09-01 03:04:13.759 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-01 03:04:13.759576 | debian-bookworm | 2026-09-01 03:04:13.759 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 10 2026-09-01 03:04:13.759914 | debian-bookworm | 2026-09-01 03:04:13.759 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-01 03:04:13.759933 | debian-bookworm | 2026-09-01 03:04:13.759 | 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-01 03:04:13.766061 | debian-bookworm | ..2026-09-01 03:04:13.765 | ERROR | openstack_image_manager.main:_download:714 - aria2c is not installed; cannot prefetch image 2026-09-01 03:04:13.770096 | debian-bookworm | .2026-09-01 03:04:13.769 | ERROR | openstack_image_manager.main:_download:745 - aria2c exited with rc=1 for http://x/y 2026-09-01 03:04:13.777206 | debian-bookworm | ..2026-09-01 03:04:13.776 | ERROR | openstack_image_manager.main:_download:739 - aria2c timed out downloading http://x/y 2026-09-01 03:04:13.784460 | debian-bookworm | ..2026-09-01 03:04:13.784 | 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-01 03:04:13.790740 | debian-bookworm | ..2026-09-01 03:04:13.790 | 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-01 03:04:13.797552 | debian-bookworm | ..2026-09-01 03:04:13.797 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: 404 2026-09-01 03:04:13.800690 | debian-bookworm | .2026-09-01 03:04:13.800 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: unreachable 2026-09-01 03:04:13.811021 | debian-bookworm | ...2026-09-01 03:04:13.810 | 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-01 03:04:13.814376 | debian-bookworm | .2026-09-01 03:04:13.814 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://x/y; skipping disk check 2026-09-01 03:04:13.818578 | debian-bookworm | .2026-09-01 03:04:13.818 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-01 03:04:13.818602 | debian-bookworm | 2026-09-01 03:04:13.818 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-01 03:04:14.172692 | debian-bookworm | 2026-09-01 03:04:14.169 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-01 03:04:14.176618 | debian-bookworm | 2026-09-01 03:04:14.175 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-01 03:04:14.179012 | debian-bookworm | 2026-09-01 03:04:14.177 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-01 03:04:14.189205 | debian-bookworm | .2026-09-01 03:04:14.188 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-01 03:04:14.190043 | debian-bookworm | 2026-09-01 03:04:14.189 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-01 03:04:14.191247 | debian-bookworm | 2026-09-01 03:04:14.190 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-01 03:04:14.196998 | debian-bookworm | 2026-09-01 03:04:14.191 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL file:///path/to/file.img 2026-09-01 03:04:14.197039 | debian-bookworm | 2026-09-01 03:04:14.191 | INFO | openstack_image_manager.main:import_image:539 - Importing local file '/path/to/file.img' as image Ubuntu 20.04 (1) 2026-09-01 03:04:14.202164 | debian-bookworm | .2026-09-01 03:04:14.199 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-01 03:04:14.202194 | debian-bookworm | 2026-09-01 03:04:14.199 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-01 03:04:14.202201 | debian-bookworm | 2026-09-01 03:04:14.199 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-01 03:04:14.202208 | debian-bookworm | 2026-09-01 03:04:14.200 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-01 03:04:14.202234 | debian-bookworm | 2026-09-01 03:04:14.200 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-01 03:04:14.202243 | debian-bookworm | 2026-09-01 03:04:14.200 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-01 03:04:14.202249 | debian-bookworm | 2026-09-01 03:04:14.200 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-01 03:04:14.202266 | debian-bookworm | 2026-09-01 03:04:14.200 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-01 03:04:14.207768 | debian-bookworm | .2026-09-01 03:04:14.207 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-01 03:04:14.208245 | debian-bookworm | 2026-09-01 03:04:14.207 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-01 03:04:14.208999 | debian-bookworm | 2026-09-01 03:04:14.208 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-01 03:04:14.209664 | debian-bookworm | 2026-09-01 03:04:14.209 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-01 03:04:14.210061 | debian-bookworm | 2026-09-01 03:04:14.209 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-01 03:04:14.210451 | debian-bookworm | 2026-09-01 03:04:14.210 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-01 03:04:14.210680 | debian-bookworm | 2026-09-01 03:04:14.210 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-01 03:04:14.213107 | debian-bookworm | 2026-09-01 03:04:14.212 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-01 03:04:14.213583 | debian-bookworm | 2026-09-01 03:04:14.213 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-01 03:04:14.459864 | debian-bookworm | 2026-09-01 03:04:14.458 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-01 03:04:14.463158 | debian-bookworm | 2026-09-01 03:04:14.462 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-01 03:04:14.464391 | debian-bookworm | 2026-09-01 03:04:14.463 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-01 03:04:14.489935 | debian-bookworm | ...2026-09-01 03:04:14.489 | ERROR | openstack_image_manager.main:main:366 - Skipping cleanup of outdated images because of previous errors 2026-09-01 03:04:14.584032 | debian-bookworm | .2026-09-01 03:04:14.583 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04' (removal candidate) 2026-09-01 03:04:14.589753 | debian-bookworm | .2026-09-01 03:04:14.589 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-01 03:04:14.590295 | debian-bookworm | 2026-09-01 03:04:14.589 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-01 03:04:14.590827 | debian-bookworm | 2026-09-01 03:04:14.590 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-01 03:04:14.591440 | debian-bookworm | 2026-09-01 03:04:14.591 | INFO | openstack_image_manager.main:manage_outdated_images:1430 - Deleting Ubuntu 20.04_2 2026-09-01 03:04:14.592484 | debian-bookworm | 2026-09-01 03:04:14.592 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-01 03:04:14.592914 | debian-bookworm | 2026-09-01 03:04:14.592 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-01 03:04:14.593193 | debian-bookworm | 2026-09-01 03:04:14.592 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-01 03:04:14.593223 | debian-bookworm | 2026-09-01 03:04:14.592 | 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-01 03:04:14.598470 | debian-bookworm | .2026-09-01 03:04:14.598 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-01 03:04:14.598948 | debian-bookworm | 2026-09-01 03:04:14.598 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-01 03:04:14.604771 | debian-bookworm | .2026-09-01 03:04:14.604 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-01 03:04:14.605184 | debian-bookworm | 2026-09-01 03:04:14.604 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-01 03:04:14.855022 | debian-bookworm | 2026-09-01 03:04:14.854 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-01 03:04:14.858305 | debian-bookworm | 2026-09-01 03:04:14.857 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-01 03:04:14.859155 | debian-bookworm | 2026-09-01 03:04:14.858 | ERROR | openstack_image_manager.main:_prefetch_import:655 - glance-direct import failed for Ubuntu 20.04 (1) 2026-09-01 03:04:14.859226 | debian-bookworm | 409 conflict on fixed id 2026-09-01 03:04:14.859780 | debian-bookworm | 2026-09-01 03:04:14.859 | ERROR | openstack_image_manager.main:_prefetch_import:658 - PREFETCH: glance-direct import failed for 'Ubuntu 20.04 (1)' 2026-09-01 03:04:14.869993 | debian-bookworm | .2026-09-01 03:04:14.869 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-01 03:04:14.870679 | debian-bookworm | 2026-09-01 03:04:14.870 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-01 03:04:15.134828 | debian-bookworm | 2026-09-01 03:04:15.134 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-01 03:04:15.138666 | debian-bookworm | 2026-09-01 03:04:15.137 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download failed for 'Ubuntu 20.04 (1)' 2026-09-01 03:04:15.150071 | debian-bookworm | .2026-09-01 03:04:15.149 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-01 03:04:15.151073 | debian-bookworm | 2026-09-01 03:04:15.150 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-01 03:04:15.152287 | debian-bookworm | 2026-09-01 03:04:15.151 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-01 03:04:15.154297 | debian-bookworm | 2026-09-01 03:04:15.153 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-01 03:04:15.154901 | debian-bookworm | 2026-09-01 03:04:15.154 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-01 03:04:15.155635 | debian-bookworm | 2026-09-01 03:04:15.155 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-01 03:04:15.156067 | debian-bookworm | 2026-09-01 03:04:15.155 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-01 03:04:15.156487 | debian-bookworm | 2026-09-01 03:04:15.156 | INFO | openstack_image_manager.main:process_image:1020 - Skipping required import of image 'Ubuntu 20.04 (1)', running in dry-run mode 2026-09-01 03:04:15.161371 | debian-bookworm | .2026-09-01 03:04:15.161 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (latest)' 2026-09-01 03:04:15.161764 | debian-bookworm | 2026-09-01 03:04:15.161 | 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-01 03:04:15.167766 | debian-bookworm | .2026-09-01 03:04:15.167 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-1' 2026-09-01 03:04:15.168456 | debian-bookworm | 2026-09-01 03:04:15.168 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-01 03:04:15.169309 | debian-bookworm | 2026-09-01 03:04:15.169 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-1' successfully completed, reloading images 2026-09-01 03:04:15.174764 | debian-bookworm | .2026-09-01 03:04:15.174 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(1)' 2026-09-01 03:04:15.177123 | debian-bookworm | 2026-09-01 03:04:15.174 | INFO | openstack_image_manager.main:process_image:1025 - Skipping image 'Ubuntu 20.04-(1)' (only importing the latest version from type multi) 2026-09-01 03:04:15.177153 | debian-bookworm | 2026-09-01 03:04:15.174 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(2)' 2026-09-01 03:04:15.177158 | debian-bookworm | 2026-09-01 03:04:15.176 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com2: 200 2026-09-01 03:04:15.177163 | debian-bookworm | 2026-09-01 03:04:15.176 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-(2)' successfully completed, reloading images 2026-09-01 03:04:15.192354 | debian-bookworm | ...2026-09-01 03:04:15.192 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-01 03:04:15.193040 | debian-bookworm | 2026-09-01 03:04:15.192 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-01 03:04:15.193555 | debian-bookworm | 2026-09-01 03:04:15.193 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-01 03:04:15.193821 | debian-bookworm | 2026-09-01 03:04:15.193 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-01 03:04:15.194291 | debian-bookworm | 2026-09-01 03:04:15.194 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04 (1) to Ubuntu 20.04 2026-09-01 03:04:15.198384 | debian-bookworm | .2026-09-01 03:04:15.198 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-01 03:04:15.199021 | debian-bookworm | 2026-09-01 03:04:15.198 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-01 03:04:15.199507 | debian-bookworm | 2026-09-01 03:04:15.199 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-01 03:04:15.199889 | debian-bookworm | 2026-09-01 03:04:15.199 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-01 03:04:15.200150 | debian-bookworm | 2026-09-01 03:04:15.199 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04-(1) to Ubuntu 20.04 2026-09-01 03:04:15.236041 | debian-bookworm | ..2026-09-01 03:04:15.235 | INFO | openstack_image_manager.main:set_properties:1063 - Checking parameters of 'Ubuntu 20.04 (1)' 2026-09-01 03:04:15.236533 | debian-bookworm | 2026-09-01 03:04:15.236 | INFO | openstack_image_manager.main:set_properties:1129 - Setting internal_version = 1 2026-09-01 03:04:15.237000 | debian-bookworm | 2026-09-01 03:04:15.236 | INFO | openstack_image_manager.main:set_properties:1132 - Setting image_original_user = ubuntu 2026-09-01 03:04:15.237022 | debian-bookworm | 2026-09-01 03:04:15.236 | INFO | openstack_image_manager.main:set_properties:1145 - Adding tag my_tag 2026-09-01 03:04:15.237630 | debian-bookworm | 2026-09-01 03:04:15.237 | INFO | openstack_image_manager.main:set_properties:1150 - Deleting tag fake_tag 2026-09-01 03:04:15.238318 | debian-bookworm | 2026-09-01 03:04:15.238 | INFO | openstack_image_manager.main:set_properties:1161 - Setting property image_build_date: 2021-01-01 != 2021-01-21 2026-09-01 03:04:15.238928 | debian-bookworm | 2026-09-01 03:04:15.238 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_distro: ubuntu 2026-09-01 03:04:15.239306 | debian-bookworm | 2026-09-01 03:04:15.239 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_version: 20.04 2026-09-01 03:04:15.239567 | debian-bookworm | 2026-09-01 03:04:15.239 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property image_source: http://url.com 2026-09-01 03:04:15.239581 | debian-bookworm | 2026-09-01 03:04:15.239 | INFO | openstack_image_manager.main:set_properties:1185 - Checking status of 'Ubuntu 20.04 (1)' 2026-09-01 03:04:15.239586 | debian-bookworm | 2026-09-01 03:04:15.239 | INFO | openstack_image_manager.main:set_properties:1190 - Deactivating image 'Ubuntu 20.04 (1)' 2026-09-01 03:04:15.240267 | debian-bookworm | 2026-09-01 03:04:15.240 | INFO | openstack_image_manager.main:set_properties:1197 - Checking visibility of 'Ubuntu 20.04 (1)' 2026-09-01 03:04:15.274001 | debian-bookworm | ...2026-09-01 03:04:15.273 | ERROR | openstack_image_manager.main:wait_for_image:814 - Image import timed out 2026-09-01 03:04:15.279294 | debian-bookworm | .2026-09-01 03:04:15.278 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-01 03:04:15.279335 | debian-bookworm | boom 2026-09-01 03:04:15.280029 | debian-bookworm | 2026-09-01 03:04:15.279 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-01 03:04:15.280053 | debian-bookworm | boom 2026-09-01 03:04:15.280580 | debian-bookworm | 2026-09-01 03:04:15.280 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-01 03:04:15.280603 | debian-bookworm | boom 2026-09-01 03:04:15.280607 | debian-bookworm | 2026-09-01 03:04:15.280 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-01 03:04:15.280612 | debian-bookworm | boom 2026-09-01 03:04:15.281074 | debian-bookworm | 2026-09-01 03:04:15.280 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-01 03:04:15.281092 | debian-bookworm | boom 2026-09-01 03:04:15.281098 | debian-bookworm | 2026-09-01 03:04:15.280 | ERROR | openstack_image_manager.main:wait_for_image:848 - Giving up on image after repeated errors 2026-09-01 03:04:15.285976 | debian-bookworm | .2026-09-01 03:04:15.285 | ERROR | openstack_image_manager.main:wait_for_image:837 - Image entered terminal state 'killed' 2026-09-01 03:04:15.290429 | debian-bookworm | .2026-09-01 03:04:15.290 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-01 03:04:15.290842 | debian-bookworm | 2026-09-01 03:04:15.290 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-01 03:04:15.291119 | debian-bookworm | 2026-09-01 03:04:15.290 | ERROR | openstack_image_manager.main:import_image:584 - Web-download import for image Ubuntu 20.04 (1) failed 2026-09-01 03:04:15.291153 | debian-bookworm | glance api error 2026-09-01 03:04:15.291159 | debian-bookworm | 2026-09-01 03:04:15.290 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-01 03:04:15.309882 | debian-bookworm | .......2026-09-01 03:04:15 | INFO  | main:223 - Processing file /tmp/tmpubq3l55l/example.yml 2026-09-01 03:04:15.317570 | debian-bookworm | 2026-09-01 03:04:15 | INFO  | main:232 - Skipping disabled image Example 1.0 2026-09-01 03:04:15.327369 | debian-bookworm | .2026-09-01 03:04:15 | INFO  | main:223 - Processing file /tmp/tmpchjcrwlv/example.yml 2026-09-01 03:04:15.332585 | debian-bookworm | 2026-09-01 03:04:15 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-01 03:04:15.333292 | debian-bookworm | 2026-09-01 03:04:15 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-01 03:04:15.333322 | debian-bookworm | 2026-09-01 03:04:15 | 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-01 03:04:15.336983 | debian-bookworm | 2026-09-01 03:04:15 | INFO  | main:242 - Dry-run enabled, not writing /tmp/tmpchjcrwlv/example.yml 2026-09-01 03:04:15.345576 | debian-bookworm | .2026-09-01 03:04:15 | INFO  | main:223 - Processing file /tmp/tmpnbxejlap/example.yml 2026-09-01 03:04:15.349747 | debian-bookworm | 2026-09-01 03:04:15 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-01 03:04:15.350273 | debian-bookworm | 2026-09-01 03:04:15 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:0000000000000000000000000000000000000000000000000000000000000000 2026-09-01 03:04:15.350301 | debian-bookworm | 2026-09-01 03:04:15 | INFO  | update_image:158 - Image Example 1.0 is up-to-date, nothing to do 2026-09-01 03:04:15.359374 | debian-bookworm | .2026-09-01 03:04:15 | INFO  | main:223 - Processing file /tmp/tmpzkywcuwa/example.yml 2026-09-01 03:04:15.365344 | debian-bookworm | 2026-09-01 03:04:15 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-01 03:04:15.365979 | debian-bookworm | 2026-09-01 03:04:15 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-01 03:04:15.366043 | debian-bookworm | 2026-09-01 03:04:15 | 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-01 03:04:15.371781 | debian-bookworm | . 2026-09-01 03:04:15.371828 | debian-bookworm | ---------------------------------------------------------------------- 2026-09-01 03:04:15.371845 | debian-bookworm | Ran 74 tests in 1.649s 2026-09-01 03:04:15.371852 | debian-bookworm | 2026-09-01 03:04:15.371859 | debian-bookworm | OK 2026-09-01 03:04:15.544693 | debian-bookworm | test: OK (2.53=setup[0.05]+cmd[2.48] seconds) 2026-09-01 03:04:15.544802 | debian-bookworm | congratulations :) (2.57 seconds) 2026-09-01 03:04:15.925169 | debian-bookworm | ok: Runtime: 0:00:02.924743 2026-09-01 03:04:15.941832 | 2026-09-01 03:04:15.941971 | TASK [tox : Look for output] 2026-09-01 03:04:16.361156 | debian-bookworm | ok 2026-09-01 03:04:16.377651 | 2026-09-01 03:04:16.377746 | TASK [tox : Return file comments to Zuul] 2026-09-01 03:04:16.422211 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:16.432894 | 2026-09-01 03:04:16.432971 | PLAY RECAP 2026-09-01 03:04:16.433030 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-01 03:04:16.433059 | 2026-09-01 03:04:16.528894 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-01 03:04:16.531067 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-01 03:04:17.476901 | 2026-09-01 03:04:17.477025 | PLAY [all] 2026-09-01 03:04:17.502238 | 2026-09-01 03:04:17.502418 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-09-01 03:04:17.556354 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:17.563192 | 2026-09-01 03:04:17.563311 | TASK [fetch-tox-output : Set tox log path for single node] 2026-09-01 03:04:17.593692 | debian-bookworm | ok 2026-09-01 03:04:17.598897 | 2026-09-01 03:04:17.598984 | TASK [fetch-tox-output : Ensure local tox dir] 2026-09-01 03:04:18.308544 | debian-bookworm -> localhost | changed 2026-09-01 03:04:18.323187 | 2026-09-01 03:04:18.323292 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-09-01 03:04:18.411240 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:18.430457 | 2026-09-01 03:04:18.430602 | TASK [fetch-tox-output : Set envlist fact] 2026-09-01 03:04:18.510341 | debian-bookworm | ok 2026-09-01 03:04:18.517544 | 2026-09-01 03:04:18.517641 | TASK [fetch-tox-output : Get tox version] 2026-09-01 03:04:19.664657 | 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-01 03:04:19.685009 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-01 03:04:20.070467 | debian-bookworm | ok: Runtime: 0:00:00.367335 2026-09-01 03:04:20.075732 | 2026-09-01 03:04:20.075819 | TASK [fetch-tox-output : Find all default environments] 2026-09-01 03:04:20.600894 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:20.614751 | 2026-09-01 03:04:20.614927 | TASK [fetch-tox-output : Set envlist fact] 2026-09-01 03:04:20.655803 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:20.670574 | 2026-09-01 03:04:20.670676 | TASK [fetch-tox-output : Find all default environments] 2026-09-01 03:04:21.203134 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:21.208661 | 2026-09-01 03:04:21.208762 | TASK [fetch-tox-output : Set envlist fact] 2026-09-01 03:04:21.231677 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:21.237454 | 2026-09-01 03:04:21.237539 | LOOP [fetch-tox-output : Copy tox logs] 2026-09-01 03:04:21.272757 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:21.286282 | 2026-09-01 03:04:21.286375 | LOOP [fetch-tox-output : Collect tox logs] 2026-09-01 03:04:22.000205 | debian-bookworm | changed: 2026-09-01 03:04:22.000367 | debian-bookworm | created directory /var/lib/zuul/builds/daa3b37036414eb5853b2bfe3a50d752/work/logs/tox/test 2026-09-01 03:04:22.000403 | debian-bookworm | cd+++++++++ ./ 2026-09-01 03:04:22.000429 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-09-01 03:04:22.000461 | 2026-09-01 03:04:22.018821 | 2026-09-01 03:04:22.018951 | PLAY RECAP 2026-09-01 03:04:22.019018 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-09-01 03:04:22.019053 | 2026-09-01 03:04:22.107068 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-01 03:04:22.108010 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-01 03:04:22.757700 | 2026-09-01 03:04:22.757813 | PLAY [all] 2026-09-01 03:04:22.771229 | 2026-09-01 03:04:22.771336 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-09-01 03:04:22.983126 | debian-bookworm | changed: non-zero return code 2026-09-01 03:04:22.993477 | 2026-09-01 03:04:22.993591 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-09-01 03:04:23.006842 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:23.013355 | 2026-09-01 03:04:23.013473 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-09-01 03:04:23.044165 | 2026-09-01 03:04:23.044319 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-09-01 03:04:23.069832 | 2026-09-01 03:04:23.069986 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-09-01 03:04:23.088564 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:23.095344 | 2026-09-01 03:04:23.095462 | LOOP [fetch-subunit-output : Generate subunit file] 2026-09-01 03:04:23.130280 | 2026-09-01 03:04:23.130457 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-09-01 03:04:23.153700 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:23.159285 | 2026-09-01 03:04:23.159375 | TASK [fetch-subunit-output : Remove the temporary file] 2026-09-01 03:04:23.182408 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:23.187999 | 2026-09-01 03:04:23.188115 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-09-01 03:04:23.209227 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:23.215053 | 2026-09-01 03:04:23.215146 | PLAY RECAP 2026-09-01 03:04:23.215197 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-09-01 03:04:23.215225 | 2026-09-01 03:04:23.314109 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-01 03:04:23.315059 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-01 03:04:24.164619 | 2026-09-01 03:04:24.164739 | PLAY [Base post-fetch] 2026-09-01 03:04:24.177394 | 2026-09-01 03:04:24.177496 | TASK [diagnose-network : Ensure log directory exists] 2026-09-01 03:04:24.568699 | debian-bookworm | ok 2026-09-01 03:04:24.576061 | 2026-09-01 03:04:24.576207 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-09-01 03:04:36.389642 | debian-bookworm | ok: Runtime: 0:00:05.965393 2026-09-01 03:04:36.398919 | 2026-09-01 03:04:36.399051 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-09-01 03:04:42.475451 | debian-bookworm | ok: Runtime: 0:00:00.009293 2026-09-01 03:04:42.481631 | 2026-09-01 03:04:42.481760 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-09-01 03:04:48.537021 | debian-bookworm | ok: Runtime: 0:00:00.009752 2026-09-01 03:04:48.549548 | 2026-09-01 03:04:48.549690 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-01 03:04:48.655792 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:48.665423 | 2026-09-01 03:04:48.665576 | TASK [fetch-output : Set log path for single node] 2026-09-01 03:04:48.724528 | debian-bookworm | ok 2026-09-01 03:04:48.729810 | 2026-09-01 03:04:48.729923 | LOOP [fetch-output : Ensure local output dirs] 2026-09-01 03:04:49.037569 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/daa3b37036414eb5853b2bfe3a50d752/work/logs" 2026-09-01 03:04:49.300115 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/daa3b37036414eb5853b2bfe3a50d752/work/artifacts" 2026-09-01 03:04:49.563221 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/daa3b37036414eb5853b2bfe3a50d752/work/docs" 2026-09-01 03:04:49.574492 | 2026-09-01 03:04:49.574596 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-01 03:04:50.494215 | debian-bookworm | changed: 2026-09-01 03:04:50.494404 | debian-bookworm | .d..t...... ./ 2026-09-01 03:04:50.494441 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-01 03:04:50.494465 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-01 03:04:50.494496 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-01 03:04:50.494519 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-01 03:04:50.494540 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-01 03:04:50.494582 | debian-bookworm | changed: All items complete 2026-09-01 03:04:50.494608 | 2026-09-01 03:04:50.993678 | debian-bookworm | changed: .d..t...... ./ 2026-09-01 03:04:51.589720 | debian-bookworm | changed: .d..t...... ./ 2026-09-01 03:04:51.602716 | 2026-09-01 03:04:51.602805 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-01 03:04:51.653715 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:51.663743 | debian-bookworm | skipping: Conditional result was False 2026-09-01 03:04:51.677665 | 2026-09-01 03:04:51.677738 | PLAY RECAP 2026-09-01 03:04:51.677789 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-09-01 03:04:51.677814 | 2026-09-01 03:04:51.857760 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-01 03:04:51.858732 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 03:04:52.952285 | 2026-09-01 03:04:52.952417 | PLAY [Base post] 2026-09-01 03:04:52.984745 | 2026-09-01 03:04:52.984875 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-01 03:04:53.630090 | debian-bookworm | changed 2026-09-01 03:04:53.638387 | 2026-09-01 03:04:53.638481 | PLAY RECAP 2026-09-01 03:04:53.638542 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-01 03:04:53.638604 | 2026-09-01 03:04:53.779699 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-01 03:04:53.780536 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-01 03:04:54.957401 | 2026-09-01 03:04:54.957510 | PLAY [Base post-logs] 2026-09-01 03:04:54.966813 | 2026-09-01 03:04:54.966937 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-01 03:04:55.405322 | localhost | changed 2026-09-01 03:04:55.414460 | 2026-09-01 03:04:55.414599 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-01 03:04:55.463800 | localhost | ok 2026-09-01 03:04:55.466787 | 2026-09-01 03:04:55.467179 | TASK [Set zuul-log-path fact] 2026-09-01 03:04:55.491688 | localhost | ok 2026-09-01 03:04:55.499206 | 2026-09-01 03:04:55.499312 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-01 03:04:55.536532 | localhost | ok 2026-09-01 03:04:55.540868 | 2026-09-01 03:04:55.540984 | TASK [upload-logs : Create log directories] 2026-09-01 03:04:55.964100 | localhost | changed 2026-09-01 03:04:55.966567 | 2026-09-01 03:04:55.966651 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-01 03:04:56.342564 | localhost -> localhost | ok: Runtime: 0:00:00.003850 2026-09-01 03:04:56.346042 | 2026-09-01 03:04:56.346159 | TASK [upload-logs : Upload logs to log server] 2026-09-01 03:04:56.805895 | localhost | Output suppressed because no_log was given 2026-09-01 03:04:56.807298 | 2026-09-01 03:04:56.807372 | LOOP [upload-logs : Compress console log and json output] 2026-09-01 03:04:56.853011 | localhost | skipping: Conditional result was False 2026-09-01 03:04:56.858631 | localhost | skipping: Conditional result was False 2026-09-01 03:04:56.861957 | 2026-09-01 03:04:56.862032 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-01 03:04:56.908829 | localhost | skipping: Conditional result was False 2026-09-01 03:04:56.909013 | 2026-09-01 03:04:56.912755 | localhost | skipping: Conditional result was False 2026-09-01 03:04:56.916711 | 2026-09-01 03:04:56.916789 | LOOP [upload-logs : Upload console log and json output]