2026-08-29 03:02:59.630390 | Job console starting 2026-08-29 03:02:59.644021 | Updating git repos 2026-08-29 03:02:59.708493 | Cloning repos into workspace 2026-08-29 03:02:59.755943 | Restoring repo states 2026-08-29 03:02:59.781454 | Merging changes 2026-08-29 03:02:59.781481 | Checking out repos 2026-08-29 03:02:59.921131 | Preparing playbooks 2026-08-29 03:03:01.700712 | Running Ansible setup 2026-08-29 03:03:10.392842 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-29 03:03:12.169512 | 2026-08-29 03:03:12.169642 | PLAY [Base pre] 2026-08-29 03:03:12.219613 | 2026-08-29 03:03:12.219762 | TASK [Setup log path fact] 2026-08-29 03:03:12.259313 | debian-bookworm | ok 2026-08-29 03:03:12.304072 | 2026-08-29 03:03:12.304212 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-29 03:03:12.331806 | debian-bookworm | ok 2026-08-29 03:03:12.340765 | 2026-08-29 03:03:12.340860 | TASK [emit-job-header : Print job information] 2026-08-29 03:03:12.408910 | # Job Information 2026-08-29 03:03:12.409059 | Ansible Version: 2.16.14 2026-08-29 03:03:12.409088 | Job: tox 2026-08-29 03:03:12.409115 | Pipeline: periodic-daily 2026-08-29 03:03:12.409134 | Executor: 521e9411259a 2026-08-29 03:03:12.409152 | Triggered by: https://github.com/osism/openstack-image-manager 2026-08-29 03:03:12.409170 | Event ID: ad68ecdd1958421e821402e88e9a5eeb 2026-08-29 03:03:12.413351 | 2026-08-29 03:03:12.413432 | LOOP [emit-job-header : Print node information] 2026-08-29 03:03:12.681425 | debian-bookworm | ok: 2026-08-29 03:03:12.682380 | debian-bookworm | # Node Information 2026-08-29 03:03:12.682461 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-29 03:03:12.682490 | debian-bookworm | Hostname: debian 2026-08-29 03:03:12.682514 | debian-bookworm | Username: zuul 2026-08-29 03:03:12.682536 | debian-bookworm | Distro: Debian 12.15 2026-08-29 03:03:12.682705 | debian-bookworm | Provider: regiocloud-a 2026-08-29 03:03:12.682734 | debian-bookworm | Region: 2026-08-29 03:03:12.682760 | debian-bookworm | Label: debian-bookworm 2026-08-29 03:03:12.682781 | debian-bookworm | Product Name: OpenStack Nova 2026-08-29 03:03:12.682800 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe12:59d2 2026-08-29 03:03:12.700464 | 2026-08-29 03:03:12.700603 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-29 03:03:13.942329 | debian-bookworm -> localhost | changed 2026-08-29 03:03:13.948579 | 2026-08-29 03:03:13.948694 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-29 03:03:15.436098 | debian-bookworm -> localhost | changed 2026-08-29 03:03:15.450889 | 2026-08-29 03:03:15.451019 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-29 03:03:16.033359 | debian-bookworm -> localhost | ok 2026-08-29 03:03:16.038890 | 2026-08-29 03:03:16.038989 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-29 03:03:16.060119 | debian-bookworm | ok 2026-08-29 03:03:16.103701 | debian-bookworm | included: /var/lib/zuul/builds/da853f618e9b4515a4b986958808e3e9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-29 03:03:16.127515 | 2026-08-29 03:03:16.127627 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-29 03:03:17.631327 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-29 03:03:17.631522 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/da853f618e9b4515a4b986958808e3e9/work/da853f618e9b4515a4b986958808e3e9_id_rsa 2026-08-29 03:03:17.631555 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/da853f618e9b4515a4b986958808e3e9/work/da853f618e9b4515a4b986958808e3e9_id_rsa.pub 2026-08-29 03:03:17.631578 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-29 03:03:17.631599 | debian-bookworm -> localhost | SHA256:3KP6+9EsELsTjUBbIOQntFYLVly26HydKJZ7jkCUb14 zuul-build-sshkey 2026-08-29 03:03:17.631618 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-29 03:03:17.631647 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-29 03:03:17.631677 | debian-bookworm -> localhost | | .*o=o+ | 2026-08-29 03:03:17.631697 | debian-bookworm -> localhost | | + B.* . | 2026-08-29 03:03:17.631714 | debian-bookworm -> localhost | | B * o | 2026-08-29 03:03:17.631730 | debian-bookworm -> localhost | | o * + O . | 2026-08-29 03:03:17.631746 | debian-bookworm -> localhost | | . O E * | 2026-08-29 03:03:17.631768 | debian-bookworm -> localhost | | . + = = + | 2026-08-29 03:03:17.631785 | debian-bookworm -> localhost | | . o = o o | 2026-08-29 03:03:17.631801 | debian-bookworm -> localhost | | . = . o | 2026-08-29 03:03:17.631817 | debian-bookworm -> localhost | | o.+o. | 2026-08-29 03:03:17.631833 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-29 03:03:17.631874 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.468110 2026-08-29 03:03:17.637385 | 2026-08-29 03:03:17.637479 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-29 03:03:17.664746 | debian-bookworm | ok 2026-08-29 03:03:17.673359 | debian-bookworm | included: /var/lib/zuul/builds/da853f618e9b4515a4b986958808e3e9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-29 03:03:17.681182 | 2026-08-29 03:03:17.681271 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-29 03:03:17.718808 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:03:17.725538 | 2026-08-29 03:03:17.725638 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-29 03:03:18.403303 | debian-bookworm | changed 2026-08-29 03:03:18.414759 | 2026-08-29 03:03:18.414904 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-29 03:03:18.688628 | debian-bookworm | ok 2026-08-29 03:03:18.695716 | 2026-08-29 03:03:18.695822 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-29 03:03:19.520212 | debian-bookworm | changed 2026-08-29 03:03:19.524763 | 2026-08-29 03:03:19.524843 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-29 03:03:20.240589 | debian-bookworm | changed 2026-08-29 03:03:20.245307 | 2026-08-29 03:03:20.245389 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-29 03:03:20.278362 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:03:20.283610 | 2026-08-29 03:03:20.283762 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-29 03:03:20.935975 | debian-bookworm -> localhost | changed 2026-08-29 03:03:20.950147 | 2026-08-29 03:03:20.950253 | TASK [add-build-sshkey : Add back temp key] 2026-08-29 03:03:21.632177 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/da853f618e9b4515a4b986958808e3e9/work/da853f618e9b4515a4b986958808e3e9_id_rsa (zuul-build-sshkey) 2026-08-29 03:03:21.632411 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016016 2026-08-29 03:03:21.640535 | 2026-08-29 03:03:21.640646 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-29 03:03:22.024730 | debian-bookworm | ok 2026-08-29 03:03:22.029886 | 2026-08-29 03:03:22.029983 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-29 03:03:22.053260 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:03:22.063213 | 2026-08-29 03:03:22.063314 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-29 03:03:22.524083 | debian-bookworm | ok 2026-08-29 03:03:22.536187 | 2026-08-29 03:03:22.536290 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-29 03:03:22.594894 | debian-bookworm | ok 2026-08-29 03:03:22.599994 | 2026-08-29 03:03:22.600085 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-29 03:03:22.962203 | debian-bookworm -> localhost | ok 2026-08-29 03:03:22.968101 | 2026-08-29 03:03:22.968188 | TASK [validate-host : Collect information about the host] 2026-08-29 03:03:24.289396 | debian-bookworm | ok 2026-08-29 03:03:24.309120 | 2026-08-29 03:03:24.309244 | TASK [validate-host : Sanitize hostname] 2026-08-29 03:03:24.501786 | debian-bookworm | ok 2026-08-29 03:03:24.506224 | 2026-08-29 03:03:24.506324 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-29 03:03:25.045337 | debian-bookworm -> localhost | changed 2026-08-29 03:03:25.051188 | 2026-08-29 03:03:25.051283 | TASK [validate-host : Collect information about zuul worker] 2026-08-29 03:03:25.657905 | debian-bookworm | ok 2026-08-29 03:03:25.662472 | 2026-08-29 03:03:25.662569 | TASK [validate-host : Write out all zuul information for each host] 2026-08-29 03:03:26.249237 | debian-bookworm -> localhost | changed 2026-08-29 03:03:26.262547 | 2026-08-29 03:03:26.262688 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-29 03:03:26.537908 | debian-bookworm | ok 2026-08-29 03:03:26.544051 | 2026-08-29 03:03:26.544146 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-29 03:03:27.385495 | debian-bookworm | changed: 2026-08-29 03:03:27.386326 | debian-bookworm | cd+++++++++ src/ 2026-08-29 03:03:27.386440 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-29 03:03:27.386473 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-29 03:03:27.386499 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-08-29 03:03:27.386523 | debian-bookworm | =7.0.3 2026-08-29 03:04:07.792124 | debian-bookworm | Downloading cachetools-7.1.7-py3-none-any.whl (16 kB) 2026-08-29 03:04:07.834348 | debian-bookworm | Collecting colorama>=0.4.6 2026-08-29 03:04:07.845813 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-08-29 03:04:07.914998 | debian-bookworm | Collecting filelock>=3.25 2026-08-29 03:04:07.926376 | debian-bookworm | Downloading filelock-3.32.4-py3-none-any.whl (99 kB) 2026-08-29 03:04:07.939788 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 99.9/99.9 kB 8.8 MB/s eta 0:00:00 2026-08-29 03:04:07.986983 | debian-bookworm | Collecting packaging>=26 2026-08-29 03:04:07.998735 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-08-29 03:04:08.014232 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 9.9 MB/s eta 0:00:00 2026-08-29 03:04:08.070375 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-08-29 03:04:08.083669 | debian-bookworm | Downloading platformdirs-4.11.5-py3-none-any.whl (23 kB) 2026-08-29 03:04:08.149238 | debian-bookworm | Collecting pluggy>=1.6 2026-08-29 03:04:08.161051 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-08-29 03:04:08.215966 | debian-bookworm | Collecting pyproject-api>=1.10 2026-08-29 03:04:08.228349 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-08-29 03:04:08.277850 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-08-29 03:04:08.289478 | debian-bookworm | Downloading python_discovery-1.6.0-py3-none-any.whl (38 kB) 2026-08-29 03:04:08.326461 | debian-bookworm | Collecting tomli-w>=1.2 2026-08-29 03:04:08.337743 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-08-29 03:04:08.394430 | debian-bookworm | Collecting typing-extensions>=4.15 2026-08-29 03:04:08.405638 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-08-29 03:04:08.413898 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 6.9 MB/s eta 0:00:00 2026-08-29 03:04:08.551670 | debian-bookworm | Collecting virtualenv>=21.1 2026-08-29 03:04:08.562962 | debian-bookworm | Downloading virtualenv-21.7.7-py3-none-any.whl (5.3 MB) 2026-08-29 03:04:08.814145 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 21.8 MB/s eta 0:00:00 2026-08-29 03:04:08.912924 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-08-29 03:04:08.925107 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-08-29 03:04:08.951476 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 23.5 MB/s eta 0:00:00 2026-08-29 03:04:09.065161 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-08-29 03:04:09.995341 | debian-bookworm | Successfully installed cachetools-7.1.7 colorama-0.4.6 distlib-0.4.3 filelock-3.32.4 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.1 typing-extensions-4.16.0 virtualenv-21.7.7 2026-08-29 03:04:10.601032 | debian-bookworm | ok: Runtime: 0:00:03.255333 2026-08-29 03:04:10.623682 | 2026-08-29 03:04:10.623800 | TASK [ensure-tox : Export installed tox_executable path] 2026-08-29 03:04:10.677090 | debian-bookworm | ok 2026-08-29 03:04:10.681881 | 2026-08-29 03:04:10.681972 | TASK [ensure-tox : Output tox version] 2026-08-29 03:04:11.370339 | 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-29 03:04:11.390328 | debian-bookworm | 4.61.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-29 03:04:11.772295 | debian-bookworm | ok: Runtime: 0:00:00.369068 2026-08-29 03:04:11.784546 | 2026-08-29 03:04:11.784670 | TASK [ensure-tox : Make global symlink] 2026-08-29 03:04:11.840531 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:11.847700 | 2026-08-29 03:04:11.847782 | PLAY RECAP 2026-08-29 03:04:11.847833 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-08-29 03:04:11.847857 | 2026-08-29 03:04:12.111650 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-08-29 03:04:12.112708 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-08-29 03:04:13.467146 | 2026-08-29 03:04:13.467284 | PLAY [all] 2026-08-29 03:04:13.497910 | 2026-08-29 03:04:13.498049 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-08-29 03:04:14.462822 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-08-29 03:04:14.557558 | debian-bookworm | ok: Runtime: 0:00:00.031687 2026-08-29 03:04:14.563443 | 2026-08-29 03:04:14.563562 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-08-29 03:04:15.114147 | debian-bookworm | ok: Runtime: 0:00:00.007601 2026-08-29 03:04:15.120069 | 2026-08-29 03:04:15.120178 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-08-29 03:04:15.482687 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-08-29 03:04:15.486543 | debian-bookworm | sudo: a password is required 2026-08-29 03:04:15.688320 | debian-bookworm | ok: Runtime: 0:00:00.025830 2026-08-29 03:04:15.696526 | 2026-08-29 03:04:15.696633 | TASK [tox : Check to see if the constraints file exists] 2026-08-29 03:04:15.742270 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:15.755279 | 2026-08-29 03:04:15.755436 | TASK [tox : Fail if constraints file is missing] 2026-08-29 03:04:15.780180 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:15.786069 | 2026-08-29 03:04:15.786172 | TASK [tox : Record file location] 2026-08-29 03:04:15.811531 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:15.817037 | 2026-08-29 03:04:15.817133 | TASK [tox : Install tox siblings] 2026-08-29 03:04:15.836991 | debian-bookworm | ok 2026-08-29 03:04:15.847760 | debian-bookworm | included: /var/lib/zuul/builds/da853f618e9b4515a4b986958808e3e9/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-08-29 03:04:15.859117 | 2026-08-29 03:04:15.859388 | TASK [tox : Run tox without tests] 2026-08-29 03:04:17.084008 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-08-29 03:04:29.526311 | debian-bookworm | test: OK (13.06 seconds) 2026-08-29 03:04:29.526388 | debian-bookworm | congratulations :) (13.09 seconds) 2026-08-29 03:04:29.900927 | debian-bookworm | ok: Runtime: 0:00:13.437111 2026-08-29 03:04:29.907242 | 2026-08-29 03:04:29.907340 | TASK [tox : Create a tempfile to save tox showconfig] 2026-08-29 03:04:30.597651 | debian-bookworm | changed 2026-08-29 03:04:30.619870 | 2026-08-29 03:04:30.619993 | TASK [tox : Detect tox version] 2026-08-29 03:04:31.175204 | debian-bookworm | 4.61.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-29 03:04:31.692378 | debian-bookworm | ok: Runtime: 0:00:00.296929 2026-08-29 03:04:31.698357 | 2026-08-29 03:04:31.698457 | TASK [tox : Get tox envlist config] 2026-08-29 03:04:32.752170 | debian-bookworm | ok: Runtime: 0:00:00.353462 2026-08-29 03:04:32.757748 | 2026-08-29 03:04:32.757848 | TASK [tox : Install any sibling python packages] 2026-08-29 03:04:33.225293 | debian-bookworm | ok: No setup.cfg, no action needed 2026-08-29 03:04:33.235354 | 2026-08-29 03:04:33.235458 | TASK [tox : Remove tempfile] 2026-08-29 03:04:33.694426 | debian-bookworm | changed 2026-08-29 03:04:33.705911 | 2026-08-29 03:04:33.706025 | TASK [tox : Emit tox command] 2026-08-29 03:04:33.781017 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-08-29 03:04:33.786927 | 2026-08-29 03:04:33.787038 | TASK [tox : Run tox] 2026-08-29 03:04:34.420746 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-08-29 03:04:34.935396 | debian-bookworm | ...........2026-08-29 03:04:34.935 | ERROR | contrib.check_updates:main:219 - operational failure: boom 2026-08-29 03:04:34.942342 | 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-29 03:04:34.942373 | debian-bookworm | warnings.warn( 2026-08-29 03:04:34.945602 | debian-bookworm | 2026-08-29 03:04:34.945 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 90 2026-08-29 03:04:34.945998 | debian-bookworm | 2026-08-29 03:04:34.945 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-08-29 03:04:34.946013 | debian-bookworm | 2026-08-29 03:04:34.945 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 10 2026-08-29 03:04:34.946029 | debian-bookworm | 2026-08-29 03:04:34.945 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-08-29 03:04:34.946040 | debian-bookworm | 2026-08-29 03:04:34.945 | 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-29 03:04:34.951887 | debian-bookworm | ..2026-08-29 03:04:34.951 | ERROR | openstack_image_manager.main:_download:714 - aria2c is not installed; cannot prefetch image 2026-08-29 03:04:34.955422 | debian-bookworm | .2026-08-29 03:04:34.955 | ERROR | openstack_image_manager.main:_download:745 - aria2c exited with rc=1 for http://x/y 2026-08-29 03:04:34.961933 | debian-bookworm | ..2026-08-29 03:04:34.961 | ERROR | openstack_image_manager.main:_download:739 - aria2c timed out downloading http://x/y 2026-08-29 03:04:34.968711 | debian-bookworm | ..2026-08-29 03:04:34.968 | 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-29 03:04:34.974759 | debian-bookworm | ..2026-08-29 03:04:34.974 | 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-29 03:04:34.981362 | debian-bookworm | ..2026-08-29 03:04:34.981 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: 404 2026-08-29 03:04:34.984253 | debian-bookworm | .2026-08-29 03:04:34.984 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: unreachable 2026-08-29 03:04:34.994210 | debian-bookworm | ...2026-08-29 03:04:34.993 | 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-29 03:04:34.997664 | debian-bookworm | .2026-08-29 03:04:34.997 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://x/y; skipping disk check 2026-08-29 03:04:35.001737 | debian-bookworm | .2026-08-29 03:04:35.001 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-29 03:04:35.001755 | debian-bookworm | 2026-08-29 03:04:35.001 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-29 03:04:35.282750 | debian-bookworm | 2026-08-29 03:04:35.282 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-29 03:04:35.285013 | debian-bookworm | 2026-08-29 03:04:35.284 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-29 03:04:35.285535 | debian-bookworm | 2026-08-29 03:04:35.285 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-08-29 03:04:35.295351 | debian-bookworm | .2026-08-29 03:04:35.295 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-29 03:04:35.295386 | debian-bookworm | 2026-08-29 03:04:35.295 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-29 03:04:35.296857 | debian-bookworm | 2026-08-29 03:04:35.296 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-29 03:04:35.296884 | debian-bookworm | 2026-08-29 03:04:35.296 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL file:///path/to/file.img 2026-08-29 03:04:35.297482 | debian-bookworm | 2026-08-29 03:04:35.296 | INFO | openstack_image_manager.main:import_image:539 - Importing local file '/path/to/file.img' as image Ubuntu 20.04 (1) 2026-08-29 03:04:35.303889 | debian-bookworm | .2026-08-29 03:04:35.303 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-29 03:04:35.303913 | debian-bookworm | 2026-08-29 03:04:35.303 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-29 03:04:35.304298 | debian-bookworm | 2026-08-29 03:04:35.304 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-29 03:04:35.304888 | debian-bookworm | 2026-08-29 03:04:35.304 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-29 03:04:35.304905 | debian-bookworm | 2026-08-29 03:04:35.304 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-29 03:04:35.305420 | debian-bookworm | 2026-08-29 03:04:35.304 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-29 03:04:35.305501 | debian-bookworm | 2026-08-29 03:04:35.304 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-29 03:04:35.306004 | debian-bookworm | 2026-08-29 03:04:35.305 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-08-29 03:04:35.311241 | debian-bookworm | .2026-08-29 03:04:35.311 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-29 03:04:35.311259 | debian-bookworm | 2026-08-29 03:04:35.311 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-29 03:04:35.311708 | debian-bookworm | 2026-08-29 03:04:35.311 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-29 03:04:35.312015 | debian-bookworm | 2026-08-29 03:04:35.311 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-29 03:04:35.312232 | debian-bookworm | 2026-08-29 03:04:35.311 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-29 03:04:35.312242 | debian-bookworm | 2026-08-29 03:04:35.312 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-29 03:04:35.312247 | debian-bookworm | 2026-08-29 03:04:35.312 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-29 03:04:35.313808 | debian-bookworm | 2026-08-29 03:04:35.313 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-08-29 03:04:35.314145 | debian-bookworm | 2026-08-29 03:04:35.313 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-08-29 03:04:35.560649 | debian-bookworm | 2026-08-29 03:04:35.560 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-29 03:04:35.562824 | debian-bookworm | 2026-08-29 03:04:35.562 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-29 03:04:35.563232 | debian-bookworm | 2026-08-29 03:04:35.562 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-08-29 03:04:35.583308 | debian-bookworm | ...2026-08-29 03:04:35.583 | ERROR | openstack_image_manager.main:main:366 - Skipping cleanup of outdated images because of previous errors 2026-08-29 03:04:35.667009 | debian-bookworm | .2026-08-29 03:04:35.666 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04' (removal candidate) 2026-08-29 03:04:35.671392 | debian-bookworm | .2026-08-29 03:04:35.671 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-08-29 03:04:35.671765 | debian-bookworm | 2026-08-29 03:04:35.671 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-08-29 03:04:35.672077 | debian-bookworm | 2026-08-29 03:04:35.671 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-08-29 03:04:35.672391 | debian-bookworm | 2026-08-29 03:04:35.672 | INFO | openstack_image_manager.main:manage_outdated_images:1430 - Deleting Ubuntu 20.04_2 2026-08-29 03:04:35.672928 | debian-bookworm | 2026-08-29 03:04:35.672 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-08-29 03:04:35.673281 | debian-bookworm | 2026-08-29 03:04:35.672 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-08-29 03:04:35.673295 | debian-bookworm | 2026-08-29 03:04:35.672 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-08-29 03:04:35.673299 | debian-bookworm | 2026-08-29 03:04:35.672 | 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-29 03:04:35.678803 | debian-bookworm | .2026-08-29 03:04:35.678 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-29 03:04:35.679125 | debian-bookworm | 2026-08-29 03:04:35.678 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-29 03:04:35.684798 | debian-bookworm | .2026-08-29 03:04:35.684 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-29 03:04:35.684816 | debian-bookworm | 2026-08-29 03:04:35.684 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-29 03:04:35.921509 | debian-bookworm | 2026-08-29 03:04:35.920 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-29 03:04:35.923117 | debian-bookworm | 2026-08-29 03:04:35.922 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-29 03:04:35.923592 | debian-bookworm | 2026-08-29 03:04:35.923 | ERROR | openstack_image_manager.main:_prefetch_import:655 - glance-direct import failed for Ubuntu 20.04 (1) 2026-08-29 03:04:35.923620 | debian-bookworm | 409 conflict on fixed id 2026-08-29 03:04:35.923631 | debian-bookworm | 2026-08-29 03:04:35.923 | ERROR | openstack_image_manager.main:_prefetch_import:658 - PREFETCH: glance-direct import failed for 'Ubuntu 20.04 (1)' 2026-08-29 03:04:35.931763 | debian-bookworm | .2026-08-29 03:04:35.931 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-29 03:04:35.932013 | debian-bookworm | 2026-08-29 03:04:35.931 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-29 03:04:36.492813 | debian-bookworm | 2026-08-29 03:04:36.492 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-29 03:04:36.495146 | debian-bookworm | 2026-08-29 03:04:36.494 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download failed for 'Ubuntu 20.04 (1)' 2026-08-29 03:04:36.504742 | debian-bookworm | .2026-08-29 03:04:36.504 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-29 03:04:36.505340 | debian-bookworm | 2026-08-29 03:04:36.505 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-29 03:04:36.506179 | debian-bookworm | 2026-08-29 03:04:36.505 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-08-29 03:04:36.507609 | debian-bookworm | 2026-08-29 03:04:36.507 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-29 03:04:36.508032 | debian-bookworm | 2026-08-29 03:04:36.507 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-08-29 03:04:36.508389 | debian-bookworm | 2026-08-29 03:04:36.507 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-29 03:04:36.508403 | debian-bookworm | 2026-08-29 03:04:36.508 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-29 03:04:36.508408 | debian-bookworm | 2026-08-29 03:04:36.508 | INFO | openstack_image_manager.main:process_image:1020 - Skipping required import of image 'Ubuntu 20.04 (1)', running in dry-run mode 2026-08-29 03:04:36.512828 | debian-bookworm | .2026-08-29 03:04:36.512 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (latest)' 2026-08-29 03:04:36.512846 | debian-bookworm | 2026-08-29 03:04:36.512 | 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-29 03:04:36.518016 | debian-bookworm | .2026-08-29 03:04:36.517 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-1' 2026-08-29 03:04:36.518402 | debian-bookworm | 2026-08-29 03:04:36.518 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-29 03:04:36.518760 | debian-bookworm | 2026-08-29 03:04:36.518 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-1' successfully completed, reloading images 2026-08-29 03:04:36.523564 | debian-bookworm | .2026-08-29 03:04:36.523 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(1)' 2026-08-29 03:04:36.523583 | debian-bookworm | 2026-08-29 03:04:36.523 | INFO | openstack_image_manager.main:process_image:1025 - Skipping image 'Ubuntu 20.04-(1)' (only importing the latest version from type multi) 2026-08-29 03:04:36.523591 | debian-bookworm | 2026-08-29 03:04:36.523 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(2)' 2026-08-29 03:04:36.524608 | debian-bookworm | 2026-08-29 03:04:36.524 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com2: 200 2026-08-29 03:04:36.525104 | debian-bookworm | 2026-08-29 03:04:36.524 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-(2)' successfully completed, reloading images 2026-08-29 03:04:36.537864 | debian-bookworm | ...2026-08-29 03:04:36.537 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-08-29 03:04:36.538424 | debian-bookworm | 2026-08-29 03:04:36.537 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-29 03:04:36.538438 | debian-bookworm | 2026-08-29 03:04:36.538 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-08-29 03:04:36.538443 | debian-bookworm | 2026-08-29 03:04:36.538 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-29 03:04:36.538447 | debian-bookworm | 2026-08-29 03:04:36.538 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04 (1) to Ubuntu 20.04 2026-08-29 03:04:36.541731 | debian-bookworm | .2026-08-29 03:04:36.541 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-08-29 03:04:36.542159 | debian-bookworm | 2026-08-29 03:04:36.541 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-29 03:04:36.542171 | debian-bookworm | 2026-08-29 03:04:36.541 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-08-29 03:04:36.542182 | debian-bookworm | 2026-08-29 03:04:36.542 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-29 03:04:36.542647 | debian-bookworm | 2026-08-29 03:04:36.542 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04-(1) to Ubuntu 20.04 2026-08-29 03:04:36.574012 | debian-bookworm | ..2026-08-29 03:04:36.573 | INFO | openstack_image_manager.main:set_properties:1063 - Checking parameters of 'Ubuntu 20.04 (1)' 2026-08-29 03:04:36.574524 | debian-bookworm | 2026-08-29 03:04:36.573 | INFO | openstack_image_manager.main:set_properties:1129 - Setting internal_version = 1 2026-08-29 03:04:36.574539 | debian-bookworm | 2026-08-29 03:04:36.573 | INFO | openstack_image_manager.main:set_properties:1132 - Setting image_original_user = ubuntu 2026-08-29 03:04:36.574543 | debian-bookworm | 2026-08-29 03:04:36.573 | INFO | openstack_image_manager.main:set_properties:1145 - Adding tag my_tag 2026-08-29 03:04:36.574552 | debian-bookworm | 2026-08-29 03:04:36.574 | INFO | openstack_image_manager.main:set_properties:1150 - Deleting tag fake_tag 2026-08-29 03:04:36.574904 | debian-bookworm | 2026-08-29 03:04:36.574 | INFO | openstack_image_manager.main:set_properties:1161 - Setting property image_build_date: 2021-01-01 != 2021-01-21 2026-08-29 03:04:36.575230 | debian-bookworm | 2026-08-29 03:04:36.574 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_distro: ubuntu 2026-08-29 03:04:36.575242 | debian-bookworm | 2026-08-29 03:04:36.574 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_version: 20.04 2026-08-29 03:04:36.575246 | debian-bookworm | 2026-08-29 03:04:36.574 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property image_source: http://url.com 2026-08-29 03:04:36.575250 | debian-bookworm | 2026-08-29 03:04:36.575 | INFO | openstack_image_manager.main:set_properties:1185 - Checking status of 'Ubuntu 20.04 (1)' 2026-08-29 03:04:36.575254 | debian-bookworm | 2026-08-29 03:04:36.575 | INFO | openstack_image_manager.main:set_properties:1190 - Deactivating image 'Ubuntu 20.04 (1)' 2026-08-29 03:04:36.575556 | debian-bookworm | 2026-08-29 03:04:36.575 | INFO | openstack_image_manager.main:set_properties:1197 - Checking visibility of 'Ubuntu 20.04 (1)' 2026-08-29 03:04:36.603474 | debian-bookworm | ...2026-08-29 03:04:36.603 | ERROR | openstack_image_manager.main:wait_for_image:814 - Image import timed out 2026-08-29 03:04:36.607762 | debian-bookworm | .2026-08-29 03:04:36.607 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-29 03:04:36.607786 | debian-bookworm | boom 2026-08-29 03:04:36.608119 | debian-bookworm | 2026-08-29 03:04:36.607 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-29 03:04:36.608130 | debian-bookworm | boom 2026-08-29 03:04:36.608135 | debian-bookworm | 2026-08-29 03:04:36.607 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-29 03:04:36.608139 | debian-bookworm | boom 2026-08-29 03:04:36.608389 | debian-bookworm | 2026-08-29 03:04:36.608 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-29 03:04:36.608399 | debian-bookworm | boom 2026-08-29 03:04:36.608404 | debian-bookworm | 2026-08-29 03:04:36.608 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-29 03:04:36.608408 | debian-bookworm | boom 2026-08-29 03:04:36.608412 | debian-bookworm | 2026-08-29 03:04:36.608 | ERROR | openstack_image_manager.main:wait_for_image:848 - Giving up on image after repeated errors 2026-08-29 03:04:36.612611 | debian-bookworm | .2026-08-29 03:04:36.612 | ERROR | openstack_image_manager.main:wait_for_image:837 - Image entered terminal state 'killed' 2026-08-29 03:04:36.616160 | debian-bookworm | .2026-08-29 03:04:36.615 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-29 03:04:36.616178 | debian-bookworm | 2026-08-29 03:04:36.615 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-29 03:04:36.616596 | debian-bookworm | 2026-08-29 03:04:36.616 | ERROR | openstack_image_manager.main:import_image:584 - Web-download import for image Ubuntu 20.04 (1) failed 2026-08-29 03:04:36.616621 | debian-bookworm | glance api error 2026-08-29 03:04:36.616626 | debian-bookworm | 2026-08-29 03:04:36.616 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-08-29 03:04:36.629796 | debian-bookworm | .......2026-08-29 03:04:36 | INFO  | main:223 - Processing file /tmp/tmp45ob8cws/example.yml 2026-08-29 03:04:36.635488 | debian-bookworm | 2026-08-29 03:04:36 | INFO  | main:232 - Skipping disabled image Example 1.0 2026-08-29 03:04:36.642503 | debian-bookworm | .2026-08-29 03:04:36 | INFO  | main:223 - Processing file /tmp/tmpavx0tc0r/example.yml 2026-08-29 03:04:36.645594 | debian-bookworm | 2026-08-29 03:04:36 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-29 03:04:36.645614 | debian-bookworm | 2026-08-29 03:04:36 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-08-29 03:04:36.646035 | debian-bookworm | 2026-08-29 03:04:36 | 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-29 03:04:36.648024 | debian-bookworm | 2026-08-29 03:04:36 | INFO  | main:242 - Dry-run enabled, not writing /tmp/tmpavx0tc0r/example.yml 2026-08-29 03:04:36.654977 | debian-bookworm | .2026-08-29 03:04:36 | INFO  | main:223 - Processing file /tmp/tmpqomrg3kf/example.yml 2026-08-29 03:04:36.657994 | debian-bookworm | 2026-08-29 03:04:36 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-29 03:04:36.658311 | debian-bookworm | 2026-08-29 03:04:36 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:0000000000000000000000000000000000000000000000000000000000000000 2026-08-29 03:04:36.658325 | debian-bookworm | 2026-08-29 03:04:36 | INFO  | update_image:158 - Image Example 1.0 is up-to-date, nothing to do 2026-08-29 03:04:36.665173 | debian-bookworm | .2026-08-29 03:04:36 | INFO  | main:223 - Processing file /tmp/tmpfyfdwy0g/example.yml 2026-08-29 03:04:36.668165 | debian-bookworm | 2026-08-29 03:04:36 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-29 03:04:36.668184 | debian-bookworm | 2026-08-29 03:04:36 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-08-29 03:04:36.668569 | debian-bookworm | 2026-08-29 03:04:36 | 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-29 03:04:36.670967 | debian-bookworm | . 2026-08-29 03:04:36.670983 | debian-bookworm | ---------------------------------------------------------------------- 2026-08-29 03:04:36.670988 | debian-bookworm | Ran 74 tests in 1.759s 2026-08-29 03:04:36.670992 | debian-bookworm | 2026-08-29 03:04:36.671316 | debian-bookworm | OK 2026-08-29 03:04:36.765523 | debian-bookworm | test: OK (2.39=setup[0.05]+cmd[2.34] seconds) 2026-08-29 03:04:36.765581 | debian-bookworm | congratulations :) (2.42 seconds) 2026-08-29 03:04:36.886265 | debian-bookworm | ok: Runtime: 0:00:02.732941 2026-08-29 03:04:36.893974 | 2026-08-29 03:04:36.894093 | TASK [tox : Look for output] 2026-08-29 03:04:37.290847 | debian-bookworm | ok 2026-08-29 03:04:37.306532 | 2026-08-29 03:04:37.306782 | TASK [tox : Return file comments to Zuul] 2026-08-29 03:04:37.343643 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:37.355792 | 2026-08-29 03:04:37.355918 | PLAY RECAP 2026-08-29 03:04:37.355989 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-08-29 03:04:37.356019 | 2026-08-29 03:04:37.524924 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-08-29 03:04:37.527967 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-08-29 03:04:38.391017 | 2026-08-29 03:04:38.391222 | PLAY [all] 2026-08-29 03:04:38.408629 | 2026-08-29 03:04:38.408810 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-08-29 03:04:38.465937 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:38.477512 | 2026-08-29 03:04:38.477741 | TASK [fetch-tox-output : Set tox log path for single node] 2026-08-29 03:04:38.531268 | debian-bookworm | ok 2026-08-29 03:04:38.536977 | 2026-08-29 03:04:38.537111 | TASK [fetch-tox-output : Ensure local tox dir] 2026-08-29 03:04:39.079537 | debian-bookworm -> localhost | changed 2026-08-29 03:04:39.090259 | 2026-08-29 03:04:39.090414 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-08-29 03:04:39.145439 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:39.152331 | 2026-08-29 03:04:39.152472 | TASK [fetch-tox-output : Set envlist fact] 2026-08-29 03:04:39.193528 | debian-bookworm | ok 2026-08-29 03:04:39.199864 | 2026-08-29 03:04:39.199981 | TASK [fetch-tox-output : Get tox version] 2026-08-29 03:04:39.967239 | 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-29 03:04:39.986692 | debian-bookworm | 4.61.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-29 03:04:40.257461 | debian-bookworm | ok: Runtime: 0:00:00.354440 2026-08-29 03:04:40.266341 | 2026-08-29 03:04:40.266673 | TASK [fetch-tox-output : Find all default environments] 2026-08-29 03:04:40.870109 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:40.877266 | 2026-08-29 03:04:40.877423 | TASK [fetch-tox-output : Set envlist fact] 2026-08-29 03:04:40.952956 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:40.963374 | 2026-08-29 03:04:40.963530 | TASK [fetch-tox-output : Find all default environments] 2026-08-29 03:04:41.517468 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:41.532793 | 2026-08-29 03:04:41.533510 | TASK [fetch-tox-output : Set envlist fact] 2026-08-29 03:04:41.627174 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:41.639656 | 2026-08-29 03:04:41.639838 | LOOP [fetch-tox-output : Copy tox logs] 2026-08-29 03:04:41.815941 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:41.840024 | 2026-08-29 03:04:41.840198 | LOOP [fetch-tox-output : Collect tox logs] 2026-08-29 03:04:43.833627 | debian-bookworm | changed: 2026-08-29 03:04:43.833818 | debian-bookworm | created directory /var/lib/zuul/builds/da853f618e9b4515a4b986958808e3e9/work/logs/tox/test 2026-08-29 03:04:43.833851 | debian-bookworm | cd+++++++++ ./ 2026-08-29 03:04:43.833876 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-08-29 03:04:43.833905 | 2026-08-29 03:04:43.841070 | 2026-08-29 03:04:43.841165 | PLAY RECAP 2026-08-29 03:04:43.841217 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-08-29 03:04:43.841241 | 2026-08-29 03:04:44.121319 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-08-29 03:04:44.122279 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-08-29 03:04:44.943735 | 2026-08-29 03:04:44.943867 | PLAY [all] 2026-08-29 03:04:44.975464 | 2026-08-29 03:04:44.975619 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-08-29 03:04:45.492480 | debian-bookworm | changed: non-zero return code 2026-08-29 03:04:45.497044 | 2026-08-29 03:04:45.497142 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-08-29 03:04:45.540878 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:45.545850 | 2026-08-29 03:04:45.545955 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-08-29 03:04:45.594705 | 2026-08-29 03:04:45.594944 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-08-29 03:04:45.644363 | 2026-08-29 03:04:45.644527 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-08-29 03:04:45.677983 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:45.683128 | 2026-08-29 03:04:45.683234 | LOOP [fetch-subunit-output : Generate subunit file] 2026-08-29 03:04:45.721718 | 2026-08-29 03:04:45.721884 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-08-29 03:04:45.745641 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:45.750406 | 2026-08-29 03:04:45.750492 | TASK [fetch-subunit-output : Remove the temporary file] 2026-08-29 03:04:45.773293 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:45.778446 | 2026-08-29 03:04:45.778534 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-08-29 03:04:45.811409 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:04:45.816838 | 2026-08-29 03:04:45.816906 | PLAY RECAP 2026-08-29 03:04:45.816946 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-08-29 03:04:45.816967 | 2026-08-29 03:04:46.056205 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-08-29 03:04:46.057471 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-29 03:04:47.051162 | 2026-08-29 03:04:47.051328 | PLAY [Base post-fetch] 2026-08-29 03:04:47.077039 | 2026-08-29 03:04:47.077189 | TASK [diagnose-network : Ensure log directory exists] 2026-08-29 03:04:47.434326 | debian-bookworm | ok 2026-08-29 03:04:47.442145 | 2026-08-29 03:04:47.442292 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-08-29 03:04:59.494456 | debian-bookworm | ok: Runtime: 0:00:05.913787 2026-08-29 03:04:59.503827 | 2026-08-29 03:04:59.503980 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-08-29 03:05:05.721750 | debian-bookworm | ok: Runtime: 0:00:00.007930 2026-08-29 03:05:05.726097 | 2026-08-29 03:05:05.726189 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-08-29 03:05:11.621815 | debian-bookworm | ok: Runtime: 0:00:00.008273 2026-08-29 03:05:11.640936 | 2026-08-29 03:05:11.641093 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-29 03:05:11.686006 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:05:11.692477 | 2026-08-29 03:05:11.692598 | TASK [fetch-output : Set log path for single node] 2026-08-29 03:05:11.732726 | debian-bookworm | ok 2026-08-29 03:05:11.738114 | 2026-08-29 03:05:11.738209 | LOOP [fetch-output : Ensure local output dirs] 2026-08-29 03:05:12.091793 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/da853f618e9b4515a4b986958808e3e9/work/logs" 2026-08-29 03:05:12.525750 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/da853f618e9b4515a4b986958808e3e9/work/artifacts" 2026-08-29 03:05:13.105462 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/da853f618e9b4515a4b986958808e3e9/work/docs" 2026-08-29 03:05:13.123743 | 2026-08-29 03:05:13.123861 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-29 03:05:14.138648 | debian-bookworm | changed: 2026-08-29 03:05:14.145246 | debian-bookworm | .d..t...... ./ 2026-08-29 03:05:14.145323 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-29 03:05:14.145352 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-29 03:05:14.145375 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-29 03:05:14.145397 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-29 03:05:14.145417 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-29 03:05:14.145470 | debian-bookworm | changed: All items complete 2026-08-29 03:05:14.145497 | 2026-08-29 03:05:14.860641 | debian-bookworm | changed: .d..t...... ./ 2026-08-29 03:05:15.455323 | debian-bookworm | changed: .d..t...... ./ 2026-08-29 03:05:15.470816 | 2026-08-29 03:05:15.473481 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-29 03:05:15.518429 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:05:15.527709 | debian-bookworm | skipping: Conditional result was False 2026-08-29 03:05:15.542948 | 2026-08-29 03:05:15.543020 | PLAY RECAP 2026-08-29 03:05:15.543063 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-08-29 03:05:15.543084 | 2026-08-29 03:05:15.769967 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-29 03:05:15.770982 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-29 03:05:16.516335 | 2026-08-29 03:05:16.516478 | PLAY [Base post] 2026-08-29 03:05:16.539018 | 2026-08-29 03:05:16.539151 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-29 03:05:17.243275 | debian-bookworm | changed 2026-08-29 03:05:17.262666 | 2026-08-29 03:05:17.262798 | PLAY RECAP 2026-08-29 03:05:17.262929 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-29 03:05:17.262994 | 2026-08-29 03:05:17.511754 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-29 03:05:17.512757 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-29 03:05:18.450413 | 2026-08-29 03:05:18.451614 | PLAY [Base post-logs] 2026-08-29 03:05:18.461812 | 2026-08-29 03:05:18.461925 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-29 03:05:19.127537 | localhost | changed 2026-08-29 03:05:19.140791 | 2026-08-29 03:05:19.140960 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-29 03:05:19.181264 | localhost | ok 2026-08-29 03:05:19.184572 | 2026-08-29 03:05:19.184661 | TASK [Set zuul-log-path fact] 2026-08-29 03:05:19.199908 | localhost | ok 2026-08-29 03:05:19.209229 | 2026-08-29 03:05:19.209329 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-29 03:05:19.239592 | localhost | ok 2026-08-29 03:05:19.242589 | 2026-08-29 03:05:19.242687 | TASK [upload-logs : Create log directories] 2026-08-29 03:05:20.157964 | localhost | changed 2026-08-29 03:05:20.163535 | 2026-08-29 03:05:20.163661 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-29 03:05:21.487814 | localhost -> localhost | ok: Runtime: 0:00:00.018881 2026-08-29 03:05:21.492589 | 2026-08-29 03:05:21.492695 | TASK [upload-logs : Upload logs to log server] 2026-08-29 03:05:23.071121 | localhost | Output suppressed because no_log was given 2026-08-29 03:05:23.073832 | 2026-08-29 03:05:23.073937 | LOOP [upload-logs : Compress console log and json output] 2026-08-29 03:05:23.172280 | localhost | skipping: Conditional result was False 2026-08-29 03:05:23.184444 | localhost | skipping: Conditional result was False 2026-08-29 03:05:23.194942 | 2026-08-29 03:05:23.195070 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-29 03:05:23.270129 | localhost | skipping: Conditional result was False 2026-08-29 03:05:23.270413 | 2026-08-29 03:05:23.298702 | localhost | skipping: Conditional result was False 2026-08-29 03:05:23.304605 | 2026-08-29 03:05:23.304758 | LOOP [upload-logs : Upload console log and json output]