2026-08-16 01:03:56.371453 | Job console starting 2026-08-16 01:03:56.394538 | Updating git repos 2026-08-16 01:03:56.430763 | Cloning repos into workspace 2026-08-16 01:03:56.494512 | Restoring repo states 2026-08-16 01:03:56.516517 | Merging changes 2026-08-16 01:03:57.699142 | Checking out repos 2026-08-16 01:03:57.769508 | Preparing playbooks 2026-08-16 01:03:59.028720 | Running Ansible setup 2026-08-16 01:04:04.331958 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-16 01:04:05.639352 | 2026-08-16 01:04:05.639482 | PLAY [Base pre] 2026-08-16 01:04:05.662787 | 2026-08-16 01:04:05.663003 | TASK [Setup log path fact] 2026-08-16 01:04:05.713050 | debian-bookworm | ok 2026-08-16 01:04:05.744851 | 2026-08-16 01:04:05.747830 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-16 01:04:05.872214 | debian-bookworm | ok 2026-08-16 01:04:05.889622 | 2026-08-16 01:04:05.889732 | TASK [emit-job-header : Print job information] 2026-08-16 01:04:05.948848 | # Job Information 2026-08-16 01:04:05.949081 | Ansible Version: 2.16.14 2026-08-16 01:04:05.949118 | Job: tox 2026-08-16 01:04:05.949145 | Pipeline: check 2026-08-16 01:04:05.949164 | Executor: 521e9411259a 2026-08-16 01:04:05.949181 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1249 2026-08-16 01:04:05.949200 | Event ID: 4d625d80-990e-11f1-9e43-ef50dbbec22d 2026-08-16 01:04:05.954058 | 2026-08-16 01:04:05.954152 | LOOP [emit-job-header : Print node information] 2026-08-16 01:04:06.325803 | debian-bookworm | ok: 2026-08-16 01:04:06.326042 | debian-bookworm | # Node Information 2026-08-16 01:04:06.326082 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-16 01:04:06.326110 | debian-bookworm | Hostname: debian 2026-08-16 01:04:06.326134 | debian-bookworm | Username: zuul 2026-08-16 01:04:06.326156 | debian-bookworm | Distro: Debian 12.15 2026-08-16 01:04:06.326180 | debian-bookworm | Provider: regiocloud-a 2026-08-16 01:04:06.326201 | debian-bookworm | Region: 2026-08-16 01:04:06.326223 | debian-bookworm | Label: debian-bookworm 2026-08-16 01:04:06.326243 | debian-bookworm | Product Name: OpenStack Nova 2026-08-16 01:04:06.326263 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fedc:51da 2026-08-16 01:04:06.349052 | 2026-08-16 01:04:06.349164 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-16 01:04:07.158978 | debian-bookworm -> localhost | changed 2026-08-16 01:04:07.166046 | 2026-08-16 01:04:07.166151 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-16 01:04:09.155969 | debian-bookworm -> localhost | changed 2026-08-16 01:04:09.166818 | 2026-08-16 01:04:09.167014 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-16 01:04:09.519519 | debian-bookworm -> localhost | ok 2026-08-16 01:04:09.527355 | 2026-08-16 01:04:09.527446 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-16 01:04:09.565933 | debian-bookworm | ok 2026-08-16 01:04:09.594067 | debian-bookworm | included: /var/lib/zuul/builds/48d4daa425d942d1ba938d8ef1de3208/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-16 01:04:09.599222 | 2026-08-16 01:04:09.618957 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-16 01:04:11.785677 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-16 01:04:11.785934 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/48d4daa425d942d1ba938d8ef1de3208/work/48d4daa425d942d1ba938d8ef1de3208_id_rsa 2026-08-16 01:04:11.785975 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/48d4daa425d942d1ba938d8ef1de3208/work/48d4daa425d942d1ba938d8ef1de3208_id_rsa.pub 2026-08-16 01:04:11.786002 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-16 01:04:11.786028 | debian-bookworm -> localhost | SHA256:NCntqVG9VRYJB5vwd+bGMfuDCSVtpjEf80lPS5DGlzI zuul-build-sshkey 2026-08-16 01:04:11.786050 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-16 01:04:11.786081 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-16 01:04:11.786104 | debian-bookworm -> localhost | | . ++*o.| 2026-08-16 01:04:11.786125 | debian-bookworm -> localhost | | . o o.Eoo | 2026-08-16 01:04:11.786145 | debian-bookworm -> localhost | | . * .+*B==+| 2026-08-16 01:04:11.786164 | debian-bookworm -> localhost | | = o oX.**B| 2026-08-16 01:04:11.786184 | debian-bookworm -> localhost | | . S .o . ==| 2026-08-16 01:04:11.786209 | debian-bookworm -> localhost | | o . o..| 2026-08-16 01:04:11.786229 | debian-bookworm -> localhost | | . o ..| 2026-08-16 01:04:11.786248 | debian-bookworm -> localhost | | .| 2026-08-16 01:04:11.786269 | debian-bookworm -> localhost | | | 2026-08-16 01:04:11.786289 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-16 01:04:11.786341 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.407677 2026-08-16 01:04:11.806590 | 2026-08-16 01:04:11.806699 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-16 01:04:11.903351 | debian-bookworm | ok 2026-08-16 01:04:11.921006 | debian-bookworm | included: /var/lib/zuul/builds/48d4daa425d942d1ba938d8ef1de3208/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-16 01:04:11.964158 | 2026-08-16 01:04:11.964269 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-16 01:04:12.008269 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:04:12.019083 | 2026-08-16 01:04:12.019205 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-16 01:04:12.741521 | debian-bookworm | changed 2026-08-16 01:04:12.749837 | 2026-08-16 01:04:12.749941 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-16 01:04:13.070215 | debian-bookworm | ok 2026-08-16 01:04:13.077689 | 2026-08-16 01:04:13.077829 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-16 01:04:13.856605 | debian-bookworm | changed 2026-08-16 01:04:13.862044 | 2026-08-16 01:04:13.862142 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-16 01:04:14.582132 | debian-bookworm | changed 2026-08-16 01:04:14.597427 | 2026-08-16 01:04:14.597596 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-16 01:04:14.632075 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:04:14.642027 | 2026-08-16 01:04:14.642178 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-16 01:04:15.176966 | debian-bookworm -> localhost | changed 2026-08-16 01:04:15.208804 | 2026-08-16 01:04:15.208925 | TASK [add-build-sshkey : Add back temp key] 2026-08-16 01:04:15.735623 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/48d4daa425d942d1ba938d8ef1de3208/work/48d4daa425d942d1ba938d8ef1de3208_id_rsa (zuul-build-sshkey) 2026-08-16 01:04:15.735877 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.029598 2026-08-16 01:04:15.745022 | 2026-08-16 01:04:15.745116 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-16 01:04:16.167369 | debian-bookworm | ok 2026-08-16 01:04:16.172547 | 2026-08-16 01:04:16.172641 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-16 01:04:16.196212 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:04:16.210800 | 2026-08-16 01:04:16.210916 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-16 01:04:16.650549 | debian-bookworm | ok 2026-08-16 01:04:16.671562 | 2026-08-16 01:04:16.671683 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-16 01:04:16.708403 | debian-bookworm | ok 2026-08-16 01:04:16.716261 | 2026-08-16 01:04:16.716359 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-16 01:04:17.090088 | debian-bookworm -> localhost | ok 2026-08-16 01:04:17.104188 | 2026-08-16 01:04:17.104297 | TASK [validate-host : Collect information about the host] 2026-08-16 01:04:18.246859 | debian-bookworm | ok 2026-08-16 01:04:18.267788 | 2026-08-16 01:04:18.267940 | TASK [validate-host : Sanitize hostname] 2026-08-16 01:04:18.422769 | debian-bookworm | ok 2026-08-16 01:04:18.427490 | 2026-08-16 01:04:18.427576 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-16 01:04:18.895896 | debian-bookworm -> localhost | changed 2026-08-16 01:04:18.902554 | 2026-08-16 01:04:18.902668 | TASK [validate-host : Collect information about zuul worker] 2026-08-16 01:04:19.305636 | debian-bookworm | ok 2026-08-16 01:04:19.310240 | 2026-08-16 01:04:19.310336 | TASK [validate-host : Write out all zuul information for each host] 2026-08-16 01:04:19.863754 | debian-bookworm -> localhost | changed 2026-08-16 01:04:19.885227 | 2026-08-16 01:04:19.885383 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-16 01:04:20.120427 | debian-bookworm | ok 2026-08-16 01:04:20.128944 | 2026-08-16 01:04:20.129061 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-16 01:04:21.049152 | debian-bookworm | changed: 2026-08-16 01:04:21.049347 | debian-bookworm | cd+++++++++ src/ 2026-08-16 01:04:21.049382 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-16 01:04:21.049406 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-16 01:04:21.049427 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-08-16 01:04:21.049447 | debian-bookworm | =7.0.3 2026-08-16 01:04:58.487136 | debian-bookworm | Downloading cachetools-7.1.7-py3-none-any.whl (16 kB) 2026-08-16 01:04:58.541577 | debian-bookworm | Collecting colorama>=0.4.6 2026-08-16 01:04:58.555953 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-08-16 01:04:58.625877 | debian-bookworm | Collecting filelock>=3.25 2026-08-16 01:04:58.639901 | debian-bookworm | Downloading filelock-3.32.3-py3-none-any.whl (98 kB) 2026-08-16 01:04:58.652917 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.9/98.9 kB 9.1 MB/s eta 0:00:00 2026-08-16 01:04:58.698955 | debian-bookworm | Collecting packaging>=26 2026-08-16 01:04:58.713021 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-08-16 01:04:58.796394 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 1.5 MB/s eta 0:00:00 2026-08-16 01:04:58.862491 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-08-16 01:04:58.876449 | debian-bookworm | Downloading platformdirs-4.11.3-py3-none-any.whl (23 kB) 2026-08-16 01:04:58.916988 | debian-bookworm | Collecting pluggy>=1.6 2026-08-16 01:04:58.930924 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-08-16 01:04:58.971566 | debian-bookworm | Collecting pyproject-api>=1.10 2026-08-16 01:04:58.985942 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-08-16 01:04:59.023537 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-08-16 01:04:59.039515 | debian-bookworm | Downloading python_discovery-1.5.2-py3-none-any.whl (38 kB) 2026-08-16 01:04:59.088987 | debian-bookworm | Collecting tomli-w>=1.2 2026-08-16 01:04:59.103459 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-08-16 01:04:59.163047 | debian-bookworm | Collecting typing-extensions>=4.15 2026-08-16 01:04:59.177908 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-08-16 01:04:59.186191 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 7.1 MB/s eta 0:00:00 2026-08-16 01:04:59.326039 | debian-bookworm | Collecting virtualenv>=21.1 2026-08-16 01:04:59.340865 | debian-bookworm | Downloading virtualenv-21.7.4-py3-none-any.whl (5.3 MB) 2026-08-16 01:04:59.689081 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 15.4 MB/s eta 0:00:00 2026-08-16 01:04:59.781033 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-08-16 01:04:59.795440 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-08-16 01:04:59.821447 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 20.6 MB/s eta 0:00:00 2026-08-16 01:04:59.930754 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-08-16 01:05:00.880475 | debian-bookworm | Successfully installed cachetools-7.1.7 colorama-0.4.6 distlib-0.4.3 filelock-3.32.3 packaging-26.3 platformdirs-4.11.3 pluggy-1.6.0 pyproject-api-1.11.0 python-discovery-1.5.2 tomli-w-1.2.0 tox-4.60.0 typing-extensions-4.16.0 virtualenv-21.7.4 2026-08-16 01:05:01.110662 | debian-bookworm | ok: Runtime: 0:00:03.551390 2026-08-16 01:05:01.116750 | 2026-08-16 01:05:01.116839 | TASK [ensure-tox : Export installed tox_executable path] 2026-08-16 01:05:01.167338 | debian-bookworm | ok 2026-08-16 01:05:01.177078 | 2026-08-16 01:05:01.177185 | TASK [ensure-tox : Output tox version] 2026-08-16 01:05:01.783634 | 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-08-16 01:05:01.803071 | debian-bookworm | 4.60.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-16 01:05:02.228938 | debian-bookworm | ok: Runtime: 0:00:00.369547 2026-08-16 01:05:02.234507 | 2026-08-16 01:05:02.234596 | TASK [ensure-tox : Make global symlink] 2026-08-16 01:05:02.278813 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:02.286759 | 2026-08-16 01:05:02.286876 | PLAY RECAP 2026-08-16 01:05:02.286932 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-08-16 01:05:02.286957 | 2026-08-16 01:05:02.413442 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-08-16 01:05:02.414506 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-08-16 01:05:03.771487 | 2026-08-16 01:05:03.771633 | PLAY [all] 2026-08-16 01:05:03.796537 | 2026-08-16 01:05:03.796674 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-08-16 01:05:04.494086 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-08-16 01:05:04.832761 | debian-bookworm | ok: Runtime: 0:00:00.031137 2026-08-16 01:05:04.838305 | 2026-08-16 01:05:04.838426 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-08-16 01:05:05.381772 | debian-bookworm | ok: Runtime: 0:00:00.008169 2026-08-16 01:05:05.386920 | 2026-08-16 01:05:05.387009 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-08-16 01:05:05.788830 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-08-16 01:05:05.790218 | debian-bookworm | sudo: a password is required 2026-08-16 01:05:05.955380 | debian-bookworm | ok: Runtime: 0:00:00.020203 2026-08-16 01:05:05.974398 | 2026-08-16 01:05:05.974514 | TASK [tox : Check to see if the constraints file exists] 2026-08-16 01:05:06.048374 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:06.057262 | 2026-08-16 01:05:06.057375 | TASK [tox : Fail if constraints file is missing] 2026-08-16 01:05:06.113620 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:06.119706 | 2026-08-16 01:05:06.119835 | TASK [tox : Record file location] 2026-08-16 01:05:06.194056 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:06.201885 | 2026-08-16 01:05:06.202071 | TASK [tox : Install tox siblings] 2026-08-16 01:05:06.258229 | debian-bookworm | ok 2026-08-16 01:05:06.274498 | debian-bookworm | included: /var/lib/zuul/builds/48d4daa425d942d1ba938d8ef1de3208/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-08-16 01:05:06.286897 | 2026-08-16 01:05:06.288615 | TASK [tox : Run tox without tests] 2026-08-16 01:05:07.776157 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-08-16 01:05:21.461288 | debian-bookworm | test: OK (14.39 seconds) 2026-08-16 01:05:21.461447 | debian-bookworm | congratulations :) (14.42 seconds) 2026-08-16 01:05:21.883364 | debian-bookworm | ok: Runtime: 0:00:14.761335 2026-08-16 01:05:21.889064 | 2026-08-16 01:05:21.889192 | TASK [tox : Create a tempfile to save tox showconfig] 2026-08-16 01:05:22.652118 | debian-bookworm | changed 2026-08-16 01:05:22.657114 | 2026-08-16 01:05:22.657206 | TASK [tox : Detect tox version] 2026-08-16 01:05:23.315115 | debian-bookworm | 4.60.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-16 01:05:23.712897 | debian-bookworm | ok: Runtime: 0:00:00.352775 2026-08-16 01:05:23.719017 | 2026-08-16 01:05:23.719140 | TASK [tox : Get tox envlist config] 2026-08-16 01:05:24.776955 | debian-bookworm | ok: Runtime: 0:00:00.429298 2026-08-16 01:05:24.783142 | 2026-08-16 01:05:24.783253 | TASK [tox : Install any sibling python packages] 2026-08-16 01:05:25.465069 | debian-bookworm | ok: No setup.cfg, no action needed 2026-08-16 01:05:25.471788 | 2026-08-16 01:05:25.471884 | TASK [tox : Remove tempfile] 2026-08-16 01:05:26.178786 | debian-bookworm | changed 2026-08-16 01:05:26.190131 | 2026-08-16 01:05:26.190228 | TASK [tox : Emit tox command] 2026-08-16 01:05:26.243337 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-08-16 01:05:26.247999 | 2026-08-16 01:05:26.248105 | TASK [tox : Run tox] 2026-08-16 01:05:26.995535 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-08-16 01:05:27.586892 | debian-bookworm | ...........2026-08-16 01:05:27.586 | ERROR | contrib.check_updates:main:219 - operational failure: boom 2026-08-16 01:05:27.594561 | 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-08-16 01:05:27.594601 | debian-bookworm | warnings.warn( 2026-08-16 01:05:27.599419 | debian-bookworm | 2026-08-16 01:05:27.599 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 90 2026-08-16 01:05:27.599743 | debian-bookworm | 2026-08-16 01:05:27.599 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-08-16 01:05:27.599759 | debian-bookworm | 2026-08-16 01:05:27.599 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 10 2026-08-16 01:05:27.600087 | debian-bookworm | 2026-08-16 01:05:27.599 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-08-16 01:05:27.600107 | debian-bookworm | 2026-08-16 01:05:27.599 | 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-08-16 01:05:27.605904 | debian-bookworm | ..2026-08-16 01:05:27.605 | ERROR | openstack_image_manager.main:_download:714 - aria2c is not installed; cannot prefetch image 2026-08-16 01:05:27.609459 | debian-bookworm | .2026-08-16 01:05:27.609 | ERROR | openstack_image_manager.main:_download:745 - aria2c exited with rc=1 for http://x/y 2026-08-16 01:05:27.616341 | debian-bookworm | ..2026-08-16 01:05:27.616 | ERROR | openstack_image_manager.main:_download:739 - aria2c timed out downloading http://x/y 2026-08-16 01:05:27.623210 | debian-bookworm | ..2026-08-16 01:05:27.622 | 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-08-16 01:05:27.629977 | debian-bookworm | ..2026-08-16 01:05:27.629 | 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-08-16 01:05:27.636512 | debian-bookworm | ..2026-08-16 01:05:27.636 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: 404 2026-08-16 01:05:27.639605 | debian-bookworm | .2026-08-16 01:05:27.639 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: unreachable 2026-08-16 01:05:27.650098 | debian-bookworm | ...2026-08-16 01:05:27.649 | 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-08-16 01:05:27.653347 | debian-bookworm | .2026-08-16 01:05:27.653 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://x/y; skipping disk check 2026-08-16 01:05:27.657309 | debian-bookworm | .2026-08-16 01:05:27.657 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-16 01:05:27.657682 | debian-bookworm | 2026-08-16 01:05:27.657 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-16 01:05:27.898657 | debian-bookworm | 2026-08-16 01:05:27.897 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-16 01:05:27.901056 | debian-bookworm | 2026-08-16 01:05:27.900 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-16 01:05:27.901553 | debian-bookworm | 2026-08-16 01:05:27.901 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-08-16 01:05:27.912024 | debian-bookworm | .2026-08-16 01:05:27.911 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-16 01:05:27.912063 | debian-bookworm | 2026-08-16 01:05:27.911 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-16 01:05:27.913595 | debian-bookworm | 2026-08-16 01:05:27.913 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-16 01:05:27.913999 | debian-bookworm | 2026-08-16 01:05:27.913 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL file:///path/to/file.img 2026-08-16 01:05:27.914022 | debian-bookworm | 2026-08-16 01:05:27.913 | INFO | openstack_image_manager.main:import_image:539 - Importing local file '/path/to/file.img' as image Ubuntu 20.04 (1) 2026-08-16 01:05:27.921431 | debian-bookworm | .2026-08-16 01:05:27.921 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-16 01:05:27.921459 | debian-bookworm | 2026-08-16 01:05:27.921 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-16 01:05:27.921940 | debian-bookworm | 2026-08-16 01:05:27.921 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-16 01:05:27.922269 | debian-bookworm | 2026-08-16 01:05:27.922 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-16 01:05:27.922630 | debian-bookworm | 2026-08-16 01:05:27.922 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-16 01:05:27.922648 | debian-bookworm | 2026-08-16 01:05:27.922 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-16 01:05:27.922654 | debian-bookworm | 2026-08-16 01:05:27.922 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-16 01:05:27.923347 | debian-bookworm | 2026-08-16 01:05:27.923 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-08-16 01:05:27.929131 | debian-bookworm | .2026-08-16 01:05:27.928 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-16 01:05:27.929156 | debian-bookworm | 2026-08-16 01:05:27.929 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-16 01:05:27.929625 | debian-bookworm | 2026-08-16 01:05:27.929 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-16 01:05:27.929996 | debian-bookworm | 2026-08-16 01:05:27.929 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-16 01:05:27.930334 | debian-bookworm | 2026-08-16 01:05:27.929 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-16 01:05:27.930348 | debian-bookworm | 2026-08-16 01:05:27.930 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-16 01:05:27.930352 | debian-bookworm | 2026-08-16 01:05:27.930 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-16 01:05:27.930844 | debian-bookworm | 2026-08-16 01:05:27.930 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-08-16 01:05:27.930862 | debian-bookworm | 2026-08-16 01:05:27.930 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-08-16 01:05:28.182989 | debian-bookworm | 2026-08-16 01:05:28.182 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-16 01:05:28.185464 | debian-bookworm | 2026-08-16 01:05:28.185 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.185950 | debian-bookworm | 2026-08-16 01:05:28.185 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.207906 | debian-bookworm | ...2026-08-16 01:05:28.207 | ERROR | openstack_image_manager.main:main:366 - Skipping cleanup of outdated images because of previous errors 2026-08-16 01:05:28.292513 | debian-bookworm | .2026-08-16 01:05:28.292 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04' (removal candidate) 2026-08-16 01:05:28.297312 | debian-bookworm | .2026-08-16 01:05:28.297 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-08-16 01:05:28.297495 | debian-bookworm | 2026-08-16 01:05:28.297 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-08-16 01:05:28.297804 | debian-bookworm | 2026-08-16 01:05:28.297 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-08-16 01:05:28.298124 | debian-bookworm | 2026-08-16 01:05:28.298 | INFO | openstack_image_manager.main:manage_outdated_images:1430 - Deleting Ubuntu 20.04_2 2026-08-16 01:05:28.298825 | debian-bookworm | 2026-08-16 01:05:28.298 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-08-16 01:05:28.298942 | debian-bookworm | 2026-08-16 01:05:28.298 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-08-16 01:05:28.299061 | debian-bookworm | 2026-08-16 01:05:28.298 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-08-16 01:05:28.299070 | debian-bookworm | 2026-08-16 01:05:28.298 | INFO | openstack_image_manager.main:manage_outdated_images:1433 - Image 'Ubuntu 20.04_2' will not be deleted, because 'keep' flag is True 2026-08-16 01:05:28.302545 | debian-bookworm | .2026-08-16 01:05:28.302 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-16 01:05:28.302570 | debian-bookworm | 2026-08-16 01:05:28.302 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-16 01:05:28.306359 | debian-bookworm | .2026-08-16 01:05:28.306 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-16 01:05:28.306506 | debian-bookworm | 2026-08-16 01:05:28.306 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-16 01:05:28.546121 | debian-bookworm | 2026-08-16 01:05:28.545 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-16 01:05:28.549287 | debian-bookworm | 2026-08-16 01:05:28.548 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.549816 | debian-bookworm | 2026-08-16 01:05:28.549 | ERROR | openstack_image_manager.main:_prefetch_import:655 - glance-direct import failed for Ubuntu 20.04 (1) 2026-08-16 01:05:28.549847 | debian-bookworm | 409 conflict on fixed id 2026-08-16 01:05:28.549861 | debian-bookworm | 2026-08-16 01:05:28.549 | ERROR | openstack_image_manager.main:_prefetch_import:658 - PREFETCH: glance-direct import failed for 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.557733 | debian-bookworm | .2026-08-16 01:05:28.557 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-16 01:05:28.558081 | debian-bookworm | 2026-08-16 01:05:28.557 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-16 01:05:28.797822 | debian-bookworm | 2026-08-16 01:05:28.797 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-16 01:05:28.800291 | debian-bookworm | 2026-08-16 01:05:28.799 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download failed for 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.813690 | debian-bookworm | .2026-08-16 01:05:28.813 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.814330 | debian-bookworm | 2026-08-16 01:05:28.814 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-16 01:05:28.815343 | debian-bookworm | 2026-08-16 01:05:28.815 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-08-16 01:05:28.817216 | debian-bookworm | 2026-08-16 01:05:28.816 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.817633 | debian-bookworm | 2026-08-16 01:05:28.817 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-08-16 01:05:28.817994 | debian-bookworm | 2026-08-16 01:05:28.817 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.818311 | debian-bookworm | 2026-08-16 01:05:28.818 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-16 01:05:28.818327 | debian-bookworm | 2026-08-16 01:05:28.818 | INFO | openstack_image_manager.main:process_image:1020 - Skipping required import of image 'Ubuntu 20.04 (1)', running in dry-run mode 2026-08-16 01:05:28.823794 | debian-bookworm | .2026-08-16 01:05:28.823 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (latest)' 2026-08-16 01:05:28.823822 | debian-bookworm | 2026-08-16 01:05:28.823 | 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-08-16 01:05:28.828887 | debian-bookworm | .2026-08-16 01:05:28.828 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-1' 2026-08-16 01:05:28.829244 | debian-bookworm | 2026-08-16 01:05:28.829 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-16 01:05:28.829746 | debian-bookworm | 2026-08-16 01:05:28.829 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-1' successfully completed, reloading images 2026-08-16 01:05:28.834863 | debian-bookworm | .2026-08-16 01:05:28.834 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(1)' 2026-08-16 01:05:28.835218 | debian-bookworm | 2026-08-16 01:05:28.834 | INFO | openstack_image_manager.main:process_image:1025 - Skipping image 'Ubuntu 20.04-(1)' (only importing the latest version from type multi) 2026-08-16 01:05:28.835235 | debian-bookworm | 2026-08-16 01:05:28.834 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(2)' 2026-08-16 01:05:28.836237 | debian-bookworm | 2026-08-16 01:05:28.836 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com2: 200 2026-08-16 01:05:28.836826 | debian-bookworm | 2026-08-16 01:05:28.836 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-(2)' successfully completed, reloading images 2026-08-16 01:05:28.851487 | debian-bookworm | ...2026-08-16 01:05:28.851 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.851807 | debian-bookworm | 2026-08-16 01:05:28.851 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-16 01:05:28.852107 | debian-bookworm | 2026-08-16 01:05:28.851 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.852119 | debian-bookworm | 2026-08-16 01:05:28.851 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-16 01:05:28.852125 | debian-bookworm | 2026-08-16 01:05:28.852 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04 (1) to Ubuntu 20.04 2026-08-16 01:05:28.855662 | debian-bookworm | .2026-08-16 01:05:28.855 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-08-16 01:05:28.856022 | debian-bookworm | 2026-08-16 01:05:28.855 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-16 01:05:28.856328 | debian-bookworm | 2026-08-16 01:05:28.855 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-08-16 01:05:28.856353 | debian-bookworm | 2026-08-16 01:05:28.856 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-16 01:05:28.856358 | debian-bookworm | 2026-08-16 01:05:28.856 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04-(1) to Ubuntu 20.04 2026-08-16 01:05:28.889545 | debian-bookworm | ..2026-08-16 01:05:28.889 | INFO | openstack_image_manager.main:set_properties:1063 - Checking parameters of 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.889901 | debian-bookworm | 2026-08-16 01:05:28.889 | INFO | openstack_image_manager.main:set_properties:1129 - Setting internal_version = 1 2026-08-16 01:05:28.889917 | debian-bookworm | 2026-08-16 01:05:28.889 | INFO | openstack_image_manager.main:set_properties:1132 - Setting image_original_user = ubuntu 2026-08-16 01:05:28.889923 | debian-bookworm | 2026-08-16 01:05:28.889 | INFO | openstack_image_manager.main:set_properties:1145 - Adding tag my_tag 2026-08-16 01:05:28.890225 | debian-bookworm | 2026-08-16 01:05:28.889 | INFO | openstack_image_manager.main:set_properties:1150 - Deleting tag fake_tag 2026-08-16 01:05:28.890543 | debian-bookworm | 2026-08-16 01:05:28.890 | INFO | openstack_image_manager.main:set_properties:1161 - Setting property image_build_date: 2021-01-01 != 2021-01-21 2026-08-16 01:05:28.890842 | debian-bookworm | 2026-08-16 01:05:28.890 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_distro: ubuntu 2026-08-16 01:05:28.890855 | debian-bookworm | 2026-08-16 01:05:28.890 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_version: 20.04 2026-08-16 01:05:28.890862 | debian-bookworm | 2026-08-16 01:05:28.890 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property image_source: http://url.com 2026-08-16 01:05:28.891136 | debian-bookworm | 2026-08-16 01:05:28.890 | INFO | openstack_image_manager.main:set_properties:1185 - Checking status of 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.891149 | debian-bookworm | 2026-08-16 01:05:28.890 | INFO | openstack_image_manager.main:set_properties:1190 - Deactivating image 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.891467 | debian-bookworm | 2026-08-16 01:05:28.891 | INFO | openstack_image_manager.main:set_properties:1197 - Checking visibility of 'Ubuntu 20.04 (1)' 2026-08-16 01:05:28.919933 | debian-bookworm | ...2026-08-16 01:05:28.919 | ERROR | openstack_image_manager.main:wait_for_image:814 - Image import timed out 2026-08-16 01:05:28.924355 | debian-bookworm | .2026-08-16 01:05:28.924 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-16 01:05:28.924401 | debian-bookworm | boom 2026-08-16 01:05:28.924912 | debian-bookworm | 2026-08-16 01:05:28.924 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-16 01:05:28.924926 | debian-bookworm | boom 2026-08-16 01:05:28.924930 | debian-bookworm | 2026-08-16 01:05:28.924 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-16 01:05:28.924935 | debian-bookworm | boom 2026-08-16 01:05:28.924939 | debian-bookworm | 2026-08-16 01:05:28.924 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-16 01:05:28.924943 | debian-bookworm | boom 2026-08-16 01:05:28.925310 | debian-bookworm | 2026-08-16 01:05:28.924 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-16 01:05:28.925319 | debian-bookworm | boom 2026-08-16 01:05:28.925325 | debian-bookworm | 2026-08-16 01:05:28.924 | ERROR | openstack_image_manager.main:wait_for_image:848 - Giving up on image after repeated errors 2026-08-16 01:05:28.929560 | debian-bookworm | .2026-08-16 01:05:28.929 | ERROR | openstack_image_manager.main:wait_for_image:837 - Image entered terminal state 'killed' 2026-08-16 01:05:28.934179 | debian-bookworm | .2026-08-16 01:05:28.933 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-16 01:05:28.934205 | debian-bookworm | 2026-08-16 01:05:28.934 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-16 01:05:28.934576 | debian-bookworm | 2026-08-16 01:05:28.934 | ERROR | openstack_image_manager.main:import_image:584 - Web-download import for image Ubuntu 20.04 (1) failed 2026-08-16 01:05:28.934608 | debian-bookworm | glance api error 2026-08-16 01:05:28.934614 | debian-bookworm | 2026-08-16 01:05:28.934 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-08-16 01:05:28.949665 | debian-bookworm | .......2026-08-16 01:05:28 | INFO  | main:223 - Processing file /tmp/tmp543vbpo3/example.yml 2026-08-16 01:05:28.956015 | debian-bookworm | 2026-08-16 01:05:28 | INFO  | main:232 - Skipping disabled image Example 1.0 2026-08-16 01:05:28.963345 | debian-bookworm | .2026-08-16 01:05:28 | INFO  | main:223 - Processing file /tmp/tmptran3cnf/example.yml 2026-08-16 01:05:28.966665 | debian-bookworm | 2026-08-16 01:05:28 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-16 01:05:28.966689 | debian-bookworm | 2026-08-16 01:05:28 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-08-16 01:05:28.966697 | debian-bookworm | 2026-08-16 01:05:28 | INFO  | update_image:178 - New URL is https://nbg1.your-objectstorage.com/osism/openstack-images/example-1.0/20260101-example-1.0.qcow2 2026-08-16 01:05:28.969437 | debian-bookworm | 2026-08-16 01:05:28 | INFO  | main:242 - Dry-run enabled, not writing /tmp/tmptran3cnf/example.yml 2026-08-16 01:05:28.977045 | debian-bookworm | .2026-08-16 01:05:28 | INFO  | main:223 - Processing file /tmp/tmpy16d9y3o/example.yml 2026-08-16 01:05:28.980223 | debian-bookworm | 2026-08-16 01:05:28 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-16 01:05:28.980247 | debian-bookworm | 2026-08-16 01:05:28 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:0000000000000000000000000000000000000000000000000000000000000000 2026-08-16 01:05:28.980901 | debian-bookworm | 2026-08-16 01:05:28 | INFO  | update_image:158 - Image Example 1.0 is up-to-date, nothing to do 2026-08-16 01:05:28.987790 | debian-bookworm | .2026-08-16 01:05:28 | INFO  | main:223 - Processing file /tmp/tmpybwd2oyn/example.yml 2026-08-16 01:05:28.991038 | debian-bookworm | 2026-08-16 01:05:28 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-16 01:05:28.991064 | debian-bookworm | 2026-08-16 01:05:28 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-08-16 01:05:28.991086 | debian-bookworm | 2026-08-16 01:05:28 | INFO  | update_image:178 - New URL is https://nbg1.your-objectstorage.com/osism/openstack-images/example-1.0/20260101-example-1.0.qcow2 2026-08-16 01:05:28.994974 | debian-bookworm | . 2026-08-16 01:05:28.995035 | debian-bookworm | ---------------------------------------------------------------------- 2026-08-16 01:05:28.995050 | debian-bookworm | Ran 74 tests in 1.432s 2026-08-16 01:05:28.995063 | debian-bookworm | 2026-08-16 01:05:28.995075 | debian-bookworm | OK 2026-08-16 01:05:29.143045 | debian-bookworm | test: OK (2.19=setup[0.05]+cmd[2.14] seconds) 2026-08-16 01:05:29.143151 | debian-bookworm | congratulations :) (2.23 seconds) 2026-08-16 01:05:29.310870 | debian-bookworm | ok: Runtime: 0:00:02.567390 2026-08-16 01:05:29.318041 | 2026-08-16 01:05:29.318138 | TASK [tox : Look for output] 2026-08-16 01:05:29.908266 | debian-bookworm | ok 2026-08-16 01:05:29.956026 | 2026-08-16 01:05:29.956155 | TASK [tox : Return file comments to Zuul] 2026-08-16 01:05:30.040304 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:30.047770 | 2026-08-16 01:05:30.047857 | PLAY RECAP 2026-08-16 01:05:30.047910 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-08-16 01:05:30.047936 | 2026-08-16 01:05:30.259187 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-08-16 01:05:30.260288 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-08-16 01:05:32.323814 | 2026-08-16 01:05:32.323941 | PLAY [all] 2026-08-16 01:05:32.373551 | 2026-08-16 01:05:32.373675 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-08-16 01:05:32.437418 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:32.445472 | 2026-08-16 01:05:32.445600 | TASK [fetch-tox-output : Set tox log path for single node] 2026-08-16 01:05:32.502609 | debian-bookworm | ok 2026-08-16 01:05:32.506905 | 2026-08-16 01:05:32.507003 | TASK [fetch-tox-output : Ensure local tox dir] 2026-08-16 01:05:33.651167 | debian-bookworm -> localhost | changed 2026-08-16 01:05:33.657761 | 2026-08-16 01:05:33.657860 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-08-16 01:05:33.748061 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:33.753392 | 2026-08-16 01:05:33.753499 | TASK [fetch-tox-output : Set envlist fact] 2026-08-16 01:05:33.829896 | debian-bookworm | ok 2026-08-16 01:05:33.836891 | 2026-08-16 01:05:33.836982 | TASK [fetch-tox-output : Get tox version] 2026-08-16 01:05:34.690314 | 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-08-16 01:05:34.712844 | debian-bookworm | 4.60.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-16 01:05:34.919437 | debian-bookworm | ok: Runtime: 0:00:00.350360 2026-08-16 01:05:34.928348 | 2026-08-16 01:05:34.928439 | TASK [fetch-tox-output : Find all default environments] 2026-08-16 01:05:35.482680 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:35.488072 | 2026-08-16 01:05:35.488198 | TASK [fetch-tox-output : Set envlist fact] 2026-08-16 01:05:35.531986 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:35.537582 | 2026-08-16 01:05:35.537681 | TASK [fetch-tox-output : Find all default environments] 2026-08-16 01:05:36.141741 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:36.148128 | 2026-08-16 01:05:36.148235 | TASK [fetch-tox-output : Set envlist fact] 2026-08-16 01:05:36.181412 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:36.187394 | 2026-08-16 01:05:36.187486 | LOOP [fetch-tox-output : Copy tox logs] 2026-08-16 01:05:36.309243 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:36.319656 | 2026-08-16 01:05:36.319778 | LOOP [fetch-tox-output : Collect tox logs] 2026-08-16 01:05:37.757465 | debian-bookworm | changed: 2026-08-16 01:05:37.757638 | debian-bookworm | created directory /var/lib/zuul/builds/48d4daa425d942d1ba938d8ef1de3208/work/logs/tox/test 2026-08-16 01:05:37.757680 | debian-bookworm | cd+++++++++ ./ 2026-08-16 01:05:37.757732 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-08-16 01:05:37.757763 | 2026-08-16 01:05:37.771057 | 2026-08-16 01:05:37.771146 | PLAY RECAP 2026-08-16 01:05:37.771194 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-08-16 01:05:37.771219 | 2026-08-16 01:05:38.169373 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-08-16 01:05:38.170206 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-08-16 01:05:39.738623 | 2026-08-16 01:05:39.739312 | PLAY [all] 2026-08-16 01:05:39.780800 | 2026-08-16 01:05:39.782130 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-08-16 01:05:40.118494 | debian-bookworm | changed: non-zero return code 2026-08-16 01:05:40.130657 | 2026-08-16 01:05:40.130802 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-08-16 01:05:40.171026 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:40.176802 | 2026-08-16 01:05:40.176908 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-08-16 01:05:40.220388 | 2026-08-16 01:05:40.220635 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-08-16 01:05:40.270974 | 2026-08-16 01:05:40.271152 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-08-16 01:05:40.304440 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:40.310828 | 2026-08-16 01:05:40.310960 | LOOP [fetch-subunit-output : Generate subunit file] 2026-08-16 01:05:40.340580 | 2026-08-16 01:05:40.340788 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-08-16 01:05:40.365240 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:40.371144 | 2026-08-16 01:05:40.371251 | TASK [fetch-subunit-output : Remove the temporary file] 2026-08-16 01:05:40.404411 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:40.410226 | 2026-08-16 01:05:40.410323 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-08-16 01:05:40.443523 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:05:40.454986 | 2026-08-16 01:05:40.455064 | PLAY RECAP 2026-08-16 01:05:40.455117 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-08-16 01:05:40.455143 | 2026-08-16 01:05:40.628879 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-08-16 01:05:40.629731 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-16 01:05:41.607551 | 2026-08-16 01:05:41.607694 | PLAY [Base post-fetch] 2026-08-16 01:05:41.636841 | 2026-08-16 01:05:41.636975 | TASK [diagnose-network : Ensure log directory exists] 2026-08-16 01:05:42.070096 | debian-bookworm | ok 2026-08-16 01:05:42.087963 | 2026-08-16 01:05:42.090189 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-08-16 01:05:53.964010 | debian-bookworm | ok: Runtime: 0:00:06.222791 2026-08-16 01:05:53.973901 | 2026-08-16 01:05:53.974264 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-08-16 01:06:00.059934 | debian-bookworm | ok: Runtime: 0:00:00.009469 2026-08-16 01:06:00.069832 | 2026-08-16 01:06:00.069996 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-08-16 01:06:06.232667 | debian-bookworm | ok: Runtime: 0:00:00.010213 2026-08-16 01:06:06.245048 | 2026-08-16 01:06:06.245195 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-16 01:06:06.359974 | debian-bookworm | skipping: Conditional result was False 2026-08-16 01:06:06.367538 | 2026-08-16 01:06:06.367730 | TASK [fetch-output : Set log path for single node] 2026-08-16 01:06:06.459581 | debian-bookworm | ok 2026-08-16 01:06:06.466022 | 2026-08-16 01:06:06.466149 | LOOP [fetch-output : Ensure local output dirs] 2026-08-16 01:06:07.221155 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/48d4daa425d942d1ba938d8ef1de3208/work/logs" 2026-08-16 01:06:07.584861 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/48d4daa425d942d1ba938d8ef1de3208/work/artifacts" 2026-08-16 01:06:08.049946 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/48d4daa425d942d1ba938d8ef1de3208/work/docs" 2026-08-16 01:06:08.061920 | 2026-08-16 01:06:08.062031 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-16 01:06:08.937243 | debian-bookworm | changed: 2026-08-16 01:06:08.937407 | debian-bookworm | .d..t...... ./ 2026-08-16 01:06:08.937434 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-16 01:06:08.937454 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-16 01:06:08.937472 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-16 01:06:08.937489 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-16 01:06:08.937506 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-16 01:06:08.937536 | debian-bookworm | changed: All items complete 2026-08-16 01:06:08.937555 | 2026-08-16 01:06:09.653613 | debian-bookworm | changed: .d..t...... ./ 2026-08-16 01:06:10.272364 | debian-bookworm | changed: .d..t...... ./ 2026-08-16 01:06:10.302201 | 2026-08-16 01:06:10.302447 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-16 01:06:10.784831 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.005390 2026-08-16 01:06:11.075473 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.018060 2026-08-16 01:06:11.089878 | 2026-08-16 01:06:11.090062 | PLAY RECAP 2026-08-16 01:06:11.090117 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-16 01:06:11.090142 | 2026-08-16 01:06:11.194204 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-16 01:06:11.195330 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-16 01:06:11.971741 | 2026-08-16 01:06:11.971884 | PLAY [Base post] 2026-08-16 01:06:11.985331 | 2026-08-16 01:06:11.985446 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-16 01:06:12.523565 | debian-bookworm | changed 2026-08-16 01:06:12.533828 | 2026-08-16 01:06:12.533952 | PLAY RECAP 2026-08-16 01:06:12.534037 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-16 01:06:12.534124 | 2026-08-16 01:06:12.627078 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-16 01:06:12.628108 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-16 01:06:13.487105 | 2026-08-16 01:06:13.487241 | PLAY [Base post-logs] 2026-08-16 01:06:13.504198 | 2026-08-16 01:06:13.504396 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-16 01:06:14.029998 | localhost | changed 2026-08-16 01:06:14.048184 | 2026-08-16 01:06:14.048322 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-16 01:06:14.086150 | localhost | ok 2026-08-16 01:06:14.090187 | 2026-08-16 01:06:14.090285 | TASK [Set zuul-log-path fact] 2026-08-16 01:06:14.115576 | localhost | ok 2026-08-16 01:06:14.124128 | 2026-08-16 01:06:14.124233 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-16 01:06:14.148847 | localhost | ok 2026-08-16 01:06:14.152041 | 2026-08-16 01:06:14.152336 | TASK [upload-logs : Create log directories] 2026-08-16 01:06:14.707713 | localhost | changed 2026-08-16 01:06:14.710797 | 2026-08-16 01:06:14.711019 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-16 01:06:15.434338 | localhost -> localhost | ok: Runtime: 0:00:00.008479 2026-08-16 01:06:15.439075 | 2026-08-16 01:06:15.439221 | TASK [upload-logs : Upload logs to log server] 2026-08-16 01:06:16.261102 | localhost | Output suppressed because no_log was given 2026-08-16 01:06:16.263157 | 2026-08-16 01:06:16.263274 | LOOP [upload-logs : Compress console log and json output] 2026-08-16 01:06:16.325780 | localhost | skipping: Conditional result was False 2026-08-16 01:06:16.349430 | localhost | skipping: Conditional result was False 2026-08-16 01:06:16.359713 | 2026-08-16 01:06:16.359873 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-16 01:06:16.411422 | localhost | skipping: Conditional result was False 2026-08-16 01:06:16.411734 | 2026-08-16 01:06:16.420235 | localhost | skipping: Conditional result was False 2026-08-16 01:06:16.433983 | 2026-08-16 01:06:16.434120 | LOOP [upload-logs : Upload console log and json output]