2026-09-05 03:03:05.052204 | Job console starting 2026-09-05 03:03:05.073838 | Updating git repos 2026-09-05 03:03:05.133043 | Cloning repos into workspace 2026-09-05 03:03:05.196375 | Restoring repo states 2026-09-05 03:03:05.208654 | Merging changes 2026-09-05 03:03:05.208672 | Checking out repos 2026-09-05 03:03:05.332349 | Preparing playbooks 2026-09-05 03:03:07.044510 | Running Ansible setup 2026-09-05 03:03:12.589727 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-05 03:03:13.639464 | 2026-09-05 03:03:13.639580 | PLAY [Base pre] 2026-09-05 03:03:13.655765 | 2026-09-05 03:03:13.655885 | TASK [Setup log path fact] 2026-09-05 03:03:13.674055 | debian-bookworm | ok 2026-09-05 03:03:13.690339 | 2026-09-05 03:03:13.690459 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-05 03:03:13.717917 | debian-bookworm | ok 2026-09-05 03:03:13.727322 | 2026-09-05 03:03:13.727419 | TASK [emit-job-header : Print job information] 2026-09-05 03:03:13.756217 | # Job Information 2026-09-05 03:03:13.756420 | Ansible Version: 2.16.14 2026-09-05 03:03:13.756455 | Job: tox 2026-09-05 03:03:13.756490 | Pipeline: periodic-daily 2026-09-05 03:03:13.756513 | Executor: 521e9411259a 2026-09-05 03:03:13.756534 | Triggered by: https://github.com/osism/openstack-image-manager 2026-09-05 03:03:13.756557 | Event ID: 28c29b0ca5a14531ae8ac1960f2ded6e 2026-09-05 03:03:13.761399 | 2026-09-05 03:03:13.761499 | LOOP [emit-job-header : Print node information] 2026-09-05 03:03:13.866501 | debian-bookworm | ok: 2026-09-05 03:03:13.866700 | debian-bookworm | # Node Information 2026-09-05 03:03:13.866732 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-05 03:03:13.866757 | debian-bookworm | Hostname: debian 2026-09-05 03:03:13.866779 | debian-bookworm | Username: zuul 2026-09-05 03:03:13.866799 | debian-bookworm | Distro: Debian 12.15 2026-09-05 03:03:13.866864 | debian-bookworm | Provider: regiocloud-a 2026-09-05 03:03:13.866889 | debian-bookworm | Region: 2026-09-05 03:03:13.866911 | debian-bookworm | Label: debian-bookworm 2026-09-05 03:03:13.866931 | debian-bookworm | Product Name: OpenStack Nova 2026-09-05 03:03:13.866950 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe66:5851 2026-09-05 03:03:13.886784 | 2026-09-05 03:03:13.887507 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-05 03:03:14.357392 | debian-bookworm -> localhost | changed 2026-09-05 03:03:14.363348 | 2026-09-05 03:03:14.363438 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-05 03:03:15.596725 | debian-bookworm -> localhost | changed 2026-09-05 03:03:15.605352 | 2026-09-05 03:03:15.605434 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-05 03:03:15.967544 | debian-bookworm -> localhost | ok 2026-09-05 03:03:15.972450 | 2026-09-05 03:03:15.972534 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-05 03:03:15.999837 | debian-bookworm | ok 2026-09-05 03:03:16.019444 | debian-bookworm | included: /var/lib/zuul/builds/91c625be70c4493dbf4543ba46473ddd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-05 03:03:16.034466 | 2026-09-05 03:03:16.034552 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-05 03:03:19.247339 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-05 03:03:19.247539 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/91c625be70c4493dbf4543ba46473ddd/work/91c625be70c4493dbf4543ba46473ddd_id_rsa 2026-09-05 03:03:19.247579 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/91c625be70c4493dbf4543ba46473ddd/work/91c625be70c4493dbf4543ba46473ddd_id_rsa.pub 2026-09-05 03:03:19.247606 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-05 03:03:19.247629 | debian-bookworm -> localhost | SHA256:vIU+Yg5XT38rQ/JkM7Cm/S6WsfE1VZcC3rJh1uBKe40 zuul-build-sshkey 2026-09-05 03:03:19.247653 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-05 03:03:19.247682 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-05 03:03:19.247704 | debian-bookworm -> localhost | | o. .| 2026-09-05 03:03:19.247726 | debian-bookworm -> localhost | | o +. .o| 2026-09-05 03:03:19.247746 | debian-bookworm -> localhost | | . B o. o| 2026-09-05 03:03:19.247765 | debian-bookworm -> localhost | | ...* * .| 2026-09-05 03:03:19.247785 | debian-bookworm -> localhost | | SooE . . | 2026-09-05 03:03:19.247808 | debian-bookworm -> localhost | | o =B.* o | 2026-09-05 03:03:19.247840 | debian-bookworm -> localhost | | . + ++.%.+.. | 2026-09-05 03:03:19.247860 | debian-bookworm -> localhost | | = ...* =. . | 2026-09-05 03:03:19.247881 | debian-bookworm -> localhost | | . . +oo. | 2026-09-05 03:03:19.247901 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-05 03:03:19.247946 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.570854 2026-09-05 03:03:19.256949 | 2026-09-05 03:03:19.257098 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-05 03:03:19.284601 | debian-bookworm | ok 2026-09-05 03:03:19.295223 | debian-bookworm | included: /var/lib/zuul/builds/91c625be70c4493dbf4543ba46473ddd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-05 03:03:19.303160 | 2026-09-05 03:03:19.303247 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-05 03:03:19.316076 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:03:19.322390 | 2026-09-05 03:03:19.322486 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-05 03:03:19.998175 | debian-bookworm | changed 2026-09-05 03:03:20.002537 | 2026-09-05 03:03:20.002610 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-05 03:03:20.266514 | debian-bookworm | ok 2026-09-05 03:03:20.273517 | 2026-09-05 03:03:20.273611 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-05 03:03:20.923391 | debian-bookworm | changed 2026-09-05 03:03:20.929648 | 2026-09-05 03:03:20.929741 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-05 03:03:21.616380 | debian-bookworm | changed 2026-09-05 03:03:21.622027 | 2026-09-05 03:03:21.622114 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-05 03:03:21.645165 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:03:21.652562 | 2026-09-05 03:03:21.652662 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-05 03:03:22.085409 | debian-bookworm -> localhost | changed 2026-09-05 03:03:22.096454 | 2026-09-05 03:03:22.096546 | TASK [add-build-sshkey : Add back temp key] 2026-09-05 03:03:22.408044 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/91c625be70c4493dbf4543ba46473ddd/work/91c625be70c4493dbf4543ba46473ddd_id_rsa (zuul-build-sshkey) 2026-09-05 03:03:22.408250 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011681 2026-09-05 03:03:22.415325 | 2026-09-05 03:03:22.415411 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-05 03:03:22.787601 | debian-bookworm | ok 2026-09-05 03:03:22.792810 | 2026-09-05 03:03:22.792921 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-05 03:03:22.816139 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:03:22.825677 | 2026-09-05 03:03:22.825758 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-05 03:03:23.264161 | debian-bookworm | ok 2026-09-05 03:03:23.278539 | 2026-09-05 03:03:23.278634 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-05 03:03:23.322798 | debian-bookworm | ok 2026-09-05 03:03:23.330975 | 2026-09-05 03:03:23.331081 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-05 03:03:23.596989 | debian-bookworm -> localhost | ok 2026-09-05 03:03:23.607690 | 2026-09-05 03:03:23.607805 | TASK [validate-host : Collect information about the host] 2026-09-05 03:03:24.758380 | debian-bookworm | ok 2026-09-05 03:03:24.821129 | 2026-09-05 03:03:24.821238 | TASK [validate-host : Sanitize hostname] 2026-09-05 03:03:25.164792 | debian-bookworm | ok 2026-09-05 03:03:25.175165 | 2026-09-05 03:03:25.175260 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-05 03:03:26.283936 | debian-bookworm -> localhost | changed 2026-09-05 03:03:26.289254 | 2026-09-05 03:03:26.289345 | TASK [validate-host : Collect information about zuul worker] 2026-09-05 03:03:26.978389 | debian-bookworm | ok 2026-09-05 03:03:26.986697 | 2026-09-05 03:03:26.986806 | TASK [validate-host : Write out all zuul information for each host] 2026-09-05 03:03:28.071810 | debian-bookworm -> localhost | changed 2026-09-05 03:03:28.079510 | 2026-09-05 03:03:28.079596 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-05 03:03:28.390516 | debian-bookworm | ok 2026-09-05 03:03:28.394808 | 2026-09-05 03:03:28.394967 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-05 03:03:29.444145 | debian-bookworm | changed: 2026-09-05 03:03:29.444285 | debian-bookworm | cd+++++++++ src/ 2026-09-05 03:03:29.444313 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-05 03:03:29.444332 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-05 03:03:29.444350 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-09-05 03:03:29.444366 | debian-bookworm | =7.0.3 2026-09-05 03:04:07.478279 | debian-bookworm | Downloading cachetools-7.1.8-py3-none-any.whl (16 kB) 2026-09-05 03:04:07.530857 | debian-bookworm | Collecting colorama>=0.4.6 2026-09-05 03:04:07.546735 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-09-05 03:04:07.638477 | debian-bookworm | Collecting filelock>=3.25 2026-09-05 03:04:07.653364 | debian-bookworm | Downloading filelock-3.32.5-py3-none-any.whl (100 kB) 2026-09-05 03:04:07.666776 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.0/100.0 kB 9.4 MB/s eta 0:00:00 2026-09-05 03:04:07.718200 | debian-bookworm | Collecting packaging>=26 2026-09-05 03:04:07.734025 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-09-05 03:04:07.750644 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 11.6 MB/s eta 0:00:00 2026-09-05 03:04:07.816036 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-09-05 03:04:07.831610 | debian-bookworm | Downloading platformdirs-4.11.7-py3-none-any.whl (23 kB) 2026-09-05 03:04:07.896397 | debian-bookworm | Collecting pluggy>=1.6 2026-09-05 03:04:07.911915 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-09-05 03:04:07.976027 | debian-bookworm | Collecting pyproject-api>=1.10 2026-09-05 03:04:07.990926 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-09-05 03:04:08.038272 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-09-05 03:04:08.052743 | debian-bookworm | Downloading python_discovery-1.6.0-py3-none-any.whl (38 kB) 2026-09-05 03:04:08.094590 | debian-bookworm | Collecting tomli-w>=1.2 2026-09-05 03:04:08.109458 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-09-05 03:04:08.169167 | debian-bookworm | Collecting typing-extensions>=4.15 2026-09-05 03:04:08.185036 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-09-05 03:04:08.198401 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 5.0 MB/s eta 0:00:00 2026-09-05 03:04:08.350176 | debian-bookworm | Collecting virtualenv>=21.1 2026-09-05 03:04:08.365441 | debian-bookworm | Downloading virtualenv-21.7.8-py3-none-any.whl (5.3 MB) 2026-09-05 03:04:08.702644 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 16.0 MB/s eta 0:00:00 2026-09-05 03:04:08.800235 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-09-05 03:04:08.815118 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-09-05 03:04:08.839446 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 22.0 MB/s eta 0:00:00 2026-09-05 03:04:08.945261 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-09-05 03:04:09.914899 | debian-bookworm | Successfully installed cachetools-7.1.8 colorama-0.4.6 distlib-0.4.3 filelock-3.32.5 packaging-26.3 platformdirs-4.11.7 pluggy-1.6.0 pyproject-api-1.11.0 python-discovery-1.6.0 tomli-w-1.2.0 tox-4.61.2 typing-extensions-4.16.0 virtualenv-21.7.8 2026-09-05 03:04:10.146988 | debian-bookworm | ok: Runtime: 0:00:03.695400 2026-09-05 03:04:10.151408 | 2026-09-05 03:04:10.151477 | TASK [ensure-tox : Export installed tox_executable path] 2026-09-05 03:04:10.178610 | debian-bookworm | ok 2026-09-05 03:04:10.183328 | 2026-09-05 03:04:10.183399 | TASK [ensure-tox : Output tox version] 2026-09-05 03:04:10.778451 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-09-05 03:04:10.798938 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-05 03:04:11.210737 | debian-bookworm | ok: Runtime: 0:00:00.416754 2026-09-05 03:04:11.216066 | 2026-09-05 03:04:11.216153 | TASK [ensure-tox : Make global symlink] 2026-09-05 03:04:11.229039 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:11.234961 | 2026-09-05 03:04:11.235025 | PLAY RECAP 2026-09-05 03:04:11.235119 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-09-05 03:04:11.235150 | 2026-09-05 03:04:11.324430 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-09-05 03:04:11.325387 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-05 03:04:12.092103 | 2026-09-05 03:04:12.092233 | PLAY [all] 2026-09-05 03:04:12.109009 | 2026-09-05 03:04:12.109132 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-09-05 03:04:12.592924 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-05 03:04:12.651843 | debian-bookworm | ok: Runtime: 0:00:00.032655 2026-09-05 03:04:12.658416 | 2026-09-05 03:04:12.658543 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-09-05 03:04:13.189351 | debian-bookworm | ok: Runtime: 0:00:00.009229 2026-09-05 03:04:13.196445 | 2026-09-05 03:04:13.196545 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-09-05 03:04:13.473191 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-09-05 03:04:13.476252 | debian-bookworm | sudo: a password is required 2026-09-05 03:04:13.730624 | debian-bookworm | ok: Runtime: 0:00:00.024114 2026-09-05 03:04:13.742994 | 2026-09-05 03:04:13.743092 | TASK [tox : Check to see if the constraints file exists] 2026-09-05 03:04:13.778351 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:13.784003 | 2026-09-05 03:04:13.784103 | TASK [tox : Fail if constraints file is missing] 2026-09-05 03:04:13.807302 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:13.812986 | 2026-09-05 03:04:13.813079 | TASK [tox : Record file location] 2026-09-05 03:04:13.836314 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:13.841624 | 2026-09-05 03:04:13.841708 | TASK [tox : Install tox siblings] 2026-09-05 03:04:13.860760 | debian-bookworm | ok 2026-09-05 03:04:13.869962 | debian-bookworm | included: /var/lib/zuul/builds/91c625be70c4493dbf4543ba46473ddd/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-09-05 03:04:13.875345 | 2026-09-05 03:04:13.875431 | TASK [tox : Run tox without tests] 2026-09-05 03:04:15.254350 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-09-05 03:04:30.007418 | debian-bookworm | test: OK (15.49 seconds) 2026-09-05 03:04:30.007513 | debian-bookworm | congratulations :) (15.53 seconds) 2026-09-05 03:04:30.415099 | debian-bookworm | ok: Runtime: 0:00:15.920764 2026-09-05 03:04:30.419643 | 2026-09-05 03:04:30.419718 | TASK [tox : Create a tempfile to save tox showconfig] 2026-09-05 03:04:30.826131 | debian-bookworm | changed 2026-09-05 03:04:30.831147 | 2026-09-05 03:04:30.831232 | TASK [tox : Detect tox version] 2026-09-05 03:04:31.463333 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-05 03:04:31.876928 | debian-bookworm | ok: Runtime: 0:00:00.376292 2026-09-05 03:04:31.882241 | 2026-09-05 03:04:31.882324 | TASK [tox : Get tox envlist config] 2026-09-05 03:04:32.917233 | debian-bookworm | ok: Runtime: 0:00:00.421626 2026-09-05 03:04:32.925071 | 2026-09-05 03:04:32.925169 | TASK [tox : Install any sibling python packages] 2026-09-05 03:04:33.392263 | debian-bookworm | ok: No setup.cfg, no action needed 2026-09-05 03:04:33.401930 | 2026-09-05 03:04:33.402066 | TASK [tox : Remove tempfile] 2026-09-05 03:04:33.896064 | debian-bookworm | changed 2026-09-05 03:04:33.907239 | 2026-09-05 03:04:33.907337 | TASK [tox : Emit tox command] 2026-09-05 03:04:33.965796 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-09-05 03:04:33.980975 | 2026-09-05 03:04:33.981082 | TASK [tox : Run tox] 2026-09-05 03:04:34.682522 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-09-05 03:04:35.313717 | debian-bookworm | ...........2026-09-05 03:04:35.313 | ERROR | contrib.check_updates:main:219 - operational failure: boom 2026-09-05 03:04:35.321513 | debian-bookworm | ......../home/zuul/src/github.com/osism/openstack-image-manager/.tox/test/lib/python3.11/site-packages/openstack/resource.py:874: RemovedInSDK50Warning: The _compute_attributes method is deprecated for removal 2026-09-05 03:04:35.321557 | debian-bookworm | warnings.warn( 2026-09-05 03:04:35.325257 | debian-bookworm | 2026-09-05 03:04:35.324 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 90 2026-09-05 03:04:35.325653 | debian-bookworm | 2026-09-05 03:04:35.325 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-05 03:04:35.325671 | debian-bookworm | 2026-09-05 03:04:35.325 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 10 2026-09-05 03:04:35.325689 | debian-bookworm | 2026-09-05 03:04:35.325 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-09-05 03:04:35.325701 | debian-bookworm | 2026-09-05 03:04:35.325 | WARNING | openstack_image_manager.main:check_image_age:1334 - Image 'Ubuntu 20.04 (1)' is 20 days older than the newest image in the definition 2026-09-05 03:04:35.331715 | debian-bookworm | ..2026-09-05 03:04:35.331 | ERROR | openstack_image_manager.main:_download:714 - aria2c is not installed; cannot prefetch image 2026-09-05 03:04:35.335508 | debian-bookworm | .2026-09-05 03:04:35.335 | ERROR | openstack_image_manager.main:_download:745 - aria2c exited with rc=1 for http://x/y 2026-09-05 03:04:35.342486 | debian-bookworm | ..2026-09-05 03:04:35.342 | ERROR | openstack_image_manager.main:_download:739 - aria2c timed out downloading http://x/y 2026-09-05 03:04:35.349787 | debian-bookworm | ..2026-09-05 03:04:35.349 | ERROR | openstack_image_manager.main:get_checksum_from_checksum_url:278 - Failed to fetch checksum file from https://url.com/image.qcow2.sha512: 404 2026-09-05 03:04:35.356190 | debian-bookworm | ..2026-09-05 03:04:35.355 | ERROR | openstack_image_manager.main:get_checksum_from_checksum_url:278 - Failed to fetch checksum file from https://url.com/image.qcow2.sha512: unreachable 2026-09-05 03:04:35.362995 | debian-bookworm | ..2026-09-05 03:04:35.362 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: 404 2026-09-05 03:04:35.366081 | debian-bookworm | .2026-09-05 03:04:35.365 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: unreachable 2026-09-05 03:04:35.376548 | debian-bookworm | ...2026-09-05 03:04:35.376 | ERROR | openstack_image_manager.main:_has_space_for_download:693 - Not enough disk to prefetch http://x/y: need ~2362232012 bytes, 1073741824 available at /tmp 2026-09-05 03:04:35.379957 | debian-bookworm | .2026-09-05 03:04:35.379 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://x/y; skipping disk check 2026-09-05 03:04:35.384165 | debian-bookworm | .2026-09-05 03:04:35.383 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-05 03:04:35.384190 | debian-bookworm | 2026-09-05 03:04:35.383 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-05 03:04:35.707580 | debian-bookworm | 2026-09-05 03:04:35.706 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-05 03:04:35.710507 | debian-bookworm | 2026-09-05 03:04:35.709 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-05 03:04:35.711305 | debian-bookworm | 2026-09-05 03:04:35.710 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-05 03:04:35.719946 | debian-bookworm | .2026-09-05 03:04:35.719 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-05 03:04:35.719988 | debian-bookworm | 2026-09-05 03:04:35.719 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-05 03:04:35.721592 | debian-bookworm | 2026-09-05 03:04:35.721 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-05 03:04:35.721624 | debian-bookworm | 2026-09-05 03:04:35.721 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL file:///path/to/file.img 2026-09-05 03:04:35.722178 | debian-bookworm | 2026-09-05 03:04:35.721 | INFO | openstack_image_manager.main:import_image:539 - Importing local file '/path/to/file.img' as image Ubuntu 20.04 (1) 2026-09-05 03:04:35.728168 | debian-bookworm | .2026-09-05 03:04:35.727 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-05 03:04:35.728202 | debian-bookworm | 2026-09-05 03:04:35.727 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-05 03:04:35.728687 | debian-bookworm | 2026-09-05 03:04:35.728 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-05 03:04:35.729121 | debian-bookworm | 2026-09-05 03:04:35.728 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-05 03:04:35.729143 | debian-bookworm | 2026-09-05 03:04:35.728 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-05 03:04:35.729497 | debian-bookworm | 2026-09-05 03:04:35.729 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-05 03:04:35.729515 | debian-bookworm | 2026-09-05 03:04:35.729 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-05 03:04:35.730271 | debian-bookworm | 2026-09-05 03:04:35.730 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-05 03:04:35.735986 | debian-bookworm | .2026-09-05 03:04:35.735 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-05 03:04:35.736018 | debian-bookworm | 2026-09-05 03:04:35.735 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-05 03:04:35.736417 | debian-bookworm | 2026-09-05 03:04:35.736 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-05 03:04:35.736888 | debian-bookworm | 2026-09-05 03:04:35.736 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-05 03:04:35.737282 | debian-bookworm | 2026-09-05 03:04:35.736 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-05 03:04:35.737304 | debian-bookworm | 2026-09-05 03:04:35.736 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-05 03:04:35.737309 | debian-bookworm | 2026-09-05 03:04:35.736 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-09-05 03:04:35.739084 | debian-bookworm | 2026-09-05 03:04:35.738 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-09-05 03:04:35.739110 | debian-bookworm | 2026-09-05 03:04:35.738 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-05 03:04:35.997154 | debian-bookworm | 2026-09-05 03:04:35.996 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-05 03:04:36.000078 | debian-bookworm | 2026-09-05 03:04:35.999 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.000571 | debian-bookworm | 2026-09-05 03:04:36.000 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.023722 | debian-bookworm | ...2026-09-05 03:04:36.023 | ERROR | openstack_image_manager.main:main:366 - Skipping cleanup of outdated images because of previous errors 2026-09-05 03:04:36.118120 | debian-bookworm | .2026-09-05 03:04:36.117 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04' (removal candidate) 2026-09-05 03:04:36.124889 | debian-bookworm | .2026-09-05 03:04:36.124 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-05 03:04:36.124926 | debian-bookworm | 2026-09-05 03:04:36.124 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-05 03:04:36.125656 | debian-bookworm | 2026-09-05 03:04:36.125 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-05 03:04:36.126113 | debian-bookworm | 2026-09-05 03:04:36.125 | INFO | openstack_image_manager.main:manage_outdated_images:1430 - Deleting Ubuntu 20.04_2 2026-09-05 03:04:36.126900 | debian-bookworm | 2026-09-05 03:04:36.126 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-09-05 03:04:36.126922 | debian-bookworm | 2026-09-05 03:04:36.126 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-09-05 03:04:36.127271 | debian-bookworm | 2026-09-05 03:04:36.126 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-09-05 03:04:36.127285 | debian-bookworm | 2026-09-05 03:04:36.126 | INFO | openstack_image_manager.main:manage_outdated_images:1433 - Image 'Ubuntu 20.04_2' will not be deleted, because 'keep' flag is True 2026-09-05 03:04:36.133048 | debian-bookworm | .2026-09-05 03:04:36.132 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-05 03:04:36.133106 | debian-bookworm | 2026-09-05 03:04:36.132 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-05 03:04:36.139667 | debian-bookworm | .2026-09-05 03:04:36.139 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-05 03:04:36.140075 | debian-bookworm | 2026-09-05 03:04:36.139 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-05 03:04:36.392220 | debian-bookworm | 2026-09-05 03:04:36.391 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-05 03:04:36.394808 | debian-bookworm | 2026-09-05 03:04:36.394 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.395476 | debian-bookworm | 2026-09-05 03:04:36.394 | ERROR | openstack_image_manager.main:_prefetch_import:655 - glance-direct import failed for Ubuntu 20.04 (1) 2026-09-05 03:04:36.395509 | debian-bookworm | 409 conflict on fixed id 2026-09-05 03:04:36.395523 | debian-bookworm | 2026-09-05 03:04:36.395 | ERROR | openstack_image_manager.main:_prefetch_import:658 - PREFETCH: glance-direct import failed for 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.404786 | debian-bookworm | .2026-09-05 03:04:36.404 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-05 03:04:36.404826 | debian-bookworm | 2026-09-05 03:04:36.404 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-05 03:04:36.669954 | debian-bookworm | 2026-09-05 03:04:36.669 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-09-05 03:04:36.673174 | debian-bookworm | 2026-09-05 03:04:36.672 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download failed for 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.685151 | debian-bookworm | .2026-09-05 03:04:36.684 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.685753 | debian-bookworm | 2026-09-05 03:04:36.685 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-05 03:04:36.686590 | debian-bookworm | 2026-09-05 03:04:36.686 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-05 03:04:36.688463 | debian-bookworm | 2026-09-05 03:04:36.688 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.688957 | debian-bookworm | 2026-09-05 03:04:36.688 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-09-05 03:04:36.689552 | debian-bookworm | 2026-09-05 03:04:36.689 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.689939 | debian-bookworm | 2026-09-05 03:04:36.689 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-05 03:04:36.689960 | debian-bookworm | 2026-09-05 03:04:36.689 | INFO | openstack_image_manager.main:process_image:1020 - Skipping required import of image 'Ubuntu 20.04 (1)', running in dry-run mode 2026-09-05 03:04:36.695609 | debian-bookworm | .2026-09-05 03:04:36.695 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (latest)' 2026-09-05 03:04:36.696052 | debian-bookworm | 2026-09-05 03:04:36.695 | ERROR | openstack_image_manager.main:process_image:948 - Could not find checksum for image 'Ubuntu 20.04', check the checksums_url or checksum_url 2026-09-05 03:04:36.702714 | debian-bookworm | .2026-09-05 03:04:36.702 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-1' 2026-09-05 03:04:36.703360 | debian-bookworm | 2026-09-05 03:04:36.702 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-09-05 03:04:36.703379 | debian-bookworm | 2026-09-05 03:04:36.703 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-1' successfully completed, reloading images 2026-09-05 03:04:36.708458 | debian-bookworm | .2026-09-05 03:04:36.708 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(1)' 2026-09-05 03:04:36.708484 | debian-bookworm | 2026-09-05 03:04:36.708 | INFO | openstack_image_manager.main:process_image:1025 - Skipping image 'Ubuntu 20.04-(1)' (only importing the latest version from type multi) 2026-09-05 03:04:36.708829 | debian-bookworm | 2026-09-05 03:04:36.708 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(2)' 2026-09-05 03:04:36.710105 | debian-bookworm | 2026-09-05 03:04:36.709 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com2: 200 2026-09-05 03:04:36.710638 | debian-bookworm | 2026-09-05 03:04:36.710 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-(2)' successfully completed, reloading images 2026-09-05 03:04:36.724954 | debian-bookworm | ...2026-09-05 03:04:36.724 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.725535 | debian-bookworm | 2026-09-05 03:04:36.724 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-05 03:04:36.725555 | debian-bookworm | 2026-09-05 03:04:36.725 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.725560 | debian-bookworm | 2026-09-05 03:04:36.725 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-05 03:04:36.725565 | debian-bookworm | 2026-09-05 03:04:36.725 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04 (1) to Ubuntu 20.04 2026-09-05 03:04:36.729374 | debian-bookworm | .2026-09-05 03:04:36.729 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-05 03:04:36.729878 | debian-bookworm | 2026-09-05 03:04:36.729 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-05 03:04:36.729896 | debian-bookworm | 2026-09-05 03:04:36.729 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-09-05 03:04:36.729912 | debian-bookworm | 2026-09-05 03:04:36.729 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-09-05 03:04:36.730224 | debian-bookworm | 2026-09-05 03:04:36.729 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04-(1) to Ubuntu 20.04 2026-09-05 03:04:36.767120 | debian-bookworm | ..2026-09-05 03:04:36.766 | INFO | openstack_image_manager.main:set_properties:1063 - Checking parameters of 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.767737 | debian-bookworm | 2026-09-05 03:04:36.766 | INFO | openstack_image_manager.main:set_properties:1129 - Setting internal_version = 1 2026-09-05 03:04:36.767755 | debian-bookworm | 2026-09-05 03:04:36.766 | INFO | openstack_image_manager.main:set_properties:1132 - Setting image_original_user = ubuntu 2026-09-05 03:04:36.767761 | debian-bookworm | 2026-09-05 03:04:36.766 | INFO | openstack_image_manager.main:set_properties:1145 - Adding tag my_tag 2026-09-05 03:04:36.767775 | debian-bookworm | 2026-09-05 03:04:36.767 | INFO | openstack_image_manager.main:set_properties:1150 - Deleting tag fake_tag 2026-09-05 03:04:36.768170 | debian-bookworm | 2026-09-05 03:04:36.767 | INFO | openstack_image_manager.main:set_properties:1161 - Setting property image_build_date: 2021-01-01 != 2021-01-21 2026-09-05 03:04:36.768511 | debian-bookworm | 2026-09-05 03:04:36.768 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_distro: ubuntu 2026-09-05 03:04:36.768524 | debian-bookworm | 2026-09-05 03:04:36.768 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_version: 20.04 2026-09-05 03:04:36.768529 | debian-bookworm | 2026-09-05 03:04:36.768 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property image_source: http://url.com 2026-09-05 03:04:36.768534 | debian-bookworm | 2026-09-05 03:04:36.768 | INFO | openstack_image_manager.main:set_properties:1185 - Checking status of 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.768538 | debian-bookworm | 2026-09-05 03:04:36.768 | INFO | openstack_image_manager.main:set_properties:1190 - Deactivating image 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.768962 | debian-bookworm | 2026-09-05 03:04:36.768 | INFO | openstack_image_manager.main:set_properties:1197 - Checking visibility of 'Ubuntu 20.04 (1)' 2026-09-05 03:04:36.801297 | debian-bookworm | ...2026-09-05 03:04:36.800 | ERROR | openstack_image_manager.main:wait_for_image:814 - Image import timed out 2026-09-05 03:04:36.805856 | debian-bookworm | .2026-09-05 03:04:36.805 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-05 03:04:36.805898 | debian-bookworm | boom 2026-09-05 03:04:36.806233 | debian-bookworm | 2026-09-05 03:04:36.806 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-05 03:04:36.806249 | debian-bookworm | boom 2026-09-05 03:04:36.806470 | debian-bookworm | 2026-09-05 03:04:36.806 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-05 03:04:36.806479 | debian-bookworm | boom 2026-09-05 03:04:36.806484 | debian-bookworm | 2026-09-05 03:04:36.806 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-05 03:04:36.806488 | debian-bookworm | boom 2026-09-05 03:04:36.806700 | debian-bookworm | 2026-09-05 03:04:36.806 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-09-05 03:04:36.806710 | debian-bookworm | boom 2026-09-05 03:04:36.806716 | debian-bookworm | 2026-09-05 03:04:36.806 | ERROR | openstack_image_manager.main:wait_for_image:848 - Giving up on image after repeated errors 2026-09-05 03:04:36.811157 | debian-bookworm | .2026-09-05 03:04:36.810 | ERROR | openstack_image_manager.main:wait_for_image:837 - Image entered terminal state 'killed' 2026-09-05 03:04:36.814806 | debian-bookworm | .2026-09-05 03:04:36.814 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-09-05 03:04:36.814850 | debian-bookworm | 2026-09-05 03:04:36.814 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-09-05 03:04:36.815155 | debian-bookworm | 2026-09-05 03:04:36.814 | ERROR | openstack_image_manager.main:import_image:584 - Web-download import for image Ubuntu 20.04 (1) failed 2026-09-05 03:04:36.815188 | debian-bookworm | glance api error 2026-09-05 03:04:36.815194 | debian-bookworm | 2026-09-05 03:04:36.814 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-09-05 03:04:36.829677 | debian-bookworm | .......2026-09-05 03:04:36 | INFO  | main:223 - Processing file /tmp/tmp88v6et18/example.yml 2026-09-05 03:04:36.836194 | debian-bookworm | 2026-09-05 03:04:36 | INFO  | main:232 - Skipping disabled image Example 1.0 2026-09-05 03:04:36.843791 | debian-bookworm | .2026-09-05 03:04:36 | INFO  | main:223 - Processing file /tmp/tmpk3bt6mj5/example.yml 2026-09-05 03:04:36.847053 | debian-bookworm | 2026-09-05 03:04:36 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-05 03:04:36.847079 | debian-bookworm | 2026-09-05 03:04:36 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-05 03:04:36.847513 | debian-bookworm | 2026-09-05 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-09-05 03:04:36.850195 | debian-bookworm | 2026-09-05 03:04:36 | INFO  | main:242 - Dry-run enabled, not writing /tmp/tmpk3bt6mj5/example.yml 2026-09-05 03:04:36.858509 | debian-bookworm | .2026-09-05 03:04:36 | INFO  | main:223 - Processing file /tmp/tmpcocppu_b/example.yml 2026-09-05 03:04:36.861626 | debian-bookworm | 2026-09-05 03:04:36 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-05 03:04:36.861649 | debian-bookworm | 2026-09-05 03:04:36 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:0000000000000000000000000000000000000000000000000000000000000000 2026-09-05 03:04:36.862105 | debian-bookworm | 2026-09-05 03:04:36 | INFO  | update_image:158 - Image Example 1.0 is up-to-date, nothing to do 2026-09-05 03:04:36.869223 | debian-bookworm | .2026-09-05 03:04:36 | INFO  | main:223 - Processing file /tmp/tmpq6qjdjfx/example.yml 2026-09-05 03:04:36.872111 | debian-bookworm | 2026-09-05 03:04:36 | INFO  | update_image:144 - Checking image Example 1.0 2026-09-05 03:04:36.872135 | debian-bookworm | 2026-09-05 03:04:36 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-09-05 03:04:36.872528 | debian-bookworm | 2026-09-05 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-09-05 03:04:36.875808 | debian-bookworm | . 2026-09-05 03:04:36.875886 | debian-bookworm | ---------------------------------------------------------------------- 2026-09-05 03:04:36.875896 | debian-bookworm | Ran 74 tests in 1.587s 2026-09-05 03:04:36.875901 | debian-bookworm | 2026-09-05 03:04:36.875906 | debian-bookworm | OK 2026-09-05 03:04:37.029315 | debian-bookworm | test: OK (2.40=setup[0.06]+cmd[2.34] seconds) 2026-09-05 03:04:37.029429 | debian-bookworm | congratulations :) (2.43 seconds) 2026-09-05 03:04:37.518503 | debian-bookworm | ok: Runtime: 0:00:02.786838 2026-09-05 03:04:37.526073 | 2026-09-05 03:04:37.526165 | TASK [tox : Look for output] 2026-09-05 03:04:37.971511 | debian-bookworm | ok 2026-09-05 03:04:37.981731 | 2026-09-05 03:04:37.981815 | TASK [tox : Return file comments to Zuul] 2026-09-05 03:04:38.043124 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:38.048408 | 2026-09-05 03:04:38.048473 | PLAY RECAP 2026-09-05 03:04:38.048512 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-05 03:04:38.048532 | 2026-09-05 03:04:38.196446 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-09-05 03:04:38.197254 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-05 03:04:39.006271 | 2026-09-05 03:04:39.006379 | PLAY [all] 2026-09-05 03:04:39.018389 | 2026-09-05 03:04:39.018480 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-09-05 03:04:39.051668 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:39.056209 | 2026-09-05 03:04:39.056313 | TASK [fetch-tox-output : Set tox log path for single node] 2026-09-05 03:04:39.082471 | debian-bookworm | ok 2026-09-05 03:04:39.086465 | 2026-09-05 03:04:39.086539 | TASK [fetch-tox-output : Ensure local tox dir] 2026-09-05 03:04:39.704084 | debian-bookworm -> localhost | changed 2026-09-05 03:04:39.713915 | 2026-09-05 03:04:39.714000 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-09-05 03:04:39.756674 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:39.761176 | 2026-09-05 03:04:39.761261 | TASK [fetch-tox-output : Set envlist fact] 2026-09-05 03:04:39.787731 | debian-bookworm | ok 2026-09-05 03:04:39.792347 | 2026-09-05 03:04:39.792429 | TASK [fetch-tox-output : Get tox version] 2026-09-05 03:04:40.496090 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-09-05 03:04:40.516653 | debian-bookworm | 4.61.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-09-05 03:04:40.816638 | debian-bookworm | ok: Runtime: 0:00:00.385663 2026-09-05 03:04:40.822337 | 2026-09-05 03:04:40.822488 | TASK [fetch-tox-output : Find all default environments] 2026-09-05 03:04:41.375279 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:41.380179 | 2026-09-05 03:04:41.380263 | TASK [fetch-tox-output : Set envlist fact] 2026-09-05 03:04:41.449015 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:41.453900 | 2026-09-05 03:04:41.453985 | TASK [fetch-tox-output : Find all default environments] 2026-09-05 03:04:42.003724 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:42.009171 | 2026-09-05 03:04:42.009264 | TASK [fetch-tox-output : Set envlist fact] 2026-09-05 03:04:42.056987 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:42.062975 | 2026-09-05 03:04:42.063061 | LOOP [fetch-tox-output : Copy tox logs] 2026-09-05 03:04:42.187615 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:42.203654 | 2026-09-05 03:04:42.203750 | LOOP [fetch-tox-output : Collect tox logs] 2026-09-05 03:04:43.234501 | debian-bookworm | changed: 2026-09-05 03:04:43.234645 | debian-bookworm | created directory /var/lib/zuul/builds/91c625be70c4493dbf4543ba46473ddd/work/logs/tox/test 2026-09-05 03:04:43.234673 | debian-bookworm | cd+++++++++ ./ 2026-09-05 03:04:43.234694 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-09-05 03:04:43.234717 | 2026-09-05 03:04:43.257533 | 2026-09-05 03:04:43.257618 | PLAY RECAP 2026-09-05 03:04:43.257746 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-09-05 03:04:43.257773 | 2026-09-05 03:04:43.357926 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-09-05 03:04:43.359089 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-05 03:04:44.271725 | 2026-09-05 03:04:44.271876 | PLAY [all] 2026-09-05 03:04:44.291442 | 2026-09-05 03:04:44.291552 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-09-05 03:04:44.505431 | debian-bookworm | changed: non-zero return code 2026-09-05 03:04:44.511103 | 2026-09-05 03:04:44.511203 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-09-05 03:04:44.535057 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:44.546228 | 2026-09-05 03:04:44.546325 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-09-05 03:04:44.579435 | 2026-09-05 03:04:44.579603 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-09-05 03:04:44.647457 | 2026-09-05 03:04:44.647640 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-09-05 03:04:44.675578 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:44.681175 | 2026-09-05 03:04:44.681276 | LOOP [fetch-subunit-output : Generate subunit file] 2026-09-05 03:04:44.715265 | 2026-09-05 03:04:44.715439 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-09-05 03:04:44.740455 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:44.747207 | 2026-09-05 03:04:44.747308 | TASK [fetch-subunit-output : Remove the temporary file] 2026-09-05 03:04:44.790318 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:44.796341 | 2026-09-05 03:04:44.796443 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-09-05 03:04:44.819435 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:04:44.826492 | 2026-09-05 03:04:44.826560 | PLAY RECAP 2026-09-05 03:04:44.826608 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-09-05 03:04:44.826634 | 2026-09-05 03:04:44.976351 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-09-05 03:04:44.978512 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-05 03:04:46.108974 | 2026-09-05 03:04:46.109102 | PLAY [Base post-fetch] 2026-09-05 03:04:46.143044 | 2026-09-05 03:04:46.143210 | TASK [diagnose-network : Ensure log directory exists] 2026-09-05 03:04:46.589818 | debian-bookworm | ok 2026-09-05 03:04:46.605812 | 2026-09-05 03:04:46.605942 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-09-05 03:04:58.297555 | debian-bookworm | ok: Runtime: 0:00:06.071664 2026-09-05 03:04:58.303701 | 2026-09-05 03:04:58.303800 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-09-05 03:05:04.284190 | debian-bookworm | ok: Runtime: 0:00:00.008762 2026-09-05 03:05:04.311347 | 2026-09-05 03:05:04.311467 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-09-05 03:05:10.383806 | debian-bookworm | ok: Runtime: 0:00:00.008464 2026-09-05 03:05:10.396673 | 2026-09-05 03:05:10.396793 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-05 03:05:10.461829 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:05:10.468035 | 2026-09-05 03:05:10.468167 | TASK [fetch-output : Set log path for single node] 2026-09-05 03:05:10.511400 | debian-bookworm | ok 2026-09-05 03:05:10.517151 | 2026-09-05 03:05:10.517272 | LOOP [fetch-output : Ensure local output dirs] 2026-09-05 03:05:10.929658 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/91c625be70c4493dbf4543ba46473ddd/work/logs" 2026-09-05 03:05:11.314789 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/91c625be70c4493dbf4543ba46473ddd/work/artifacts" 2026-09-05 03:05:11.664810 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/91c625be70c4493dbf4543ba46473ddd/work/docs" 2026-09-05 03:05:11.674526 | 2026-09-05 03:05:11.674644 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-05 03:05:12.546745 | debian-bookworm | changed: 2026-09-05 03:05:12.547013 | debian-bookworm | .d..t...... ./ 2026-09-05 03:05:12.547058 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-05 03:05:12.547083 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-05 03:05:12.547105 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-05 03:05:12.547126 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-05 03:05:12.547147 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-05 03:05:12.547188 | debian-bookworm | changed: All items complete 2026-09-05 03:05:12.547215 | 2026-09-05 03:05:13.090022 | debian-bookworm | changed: .d..t...... ./ 2026-09-05 03:05:13.703945 | debian-bookworm | changed: .d..t...... ./ 2026-09-05 03:05:13.724943 | 2026-09-05 03:05:13.725034 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-05 03:05:13.762509 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:05:13.771854 | debian-bookworm | skipping: Conditional result was False 2026-09-05 03:05:13.786419 | 2026-09-05 03:05:13.786485 | PLAY RECAP 2026-09-05 03:05:13.786531 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-09-05 03:05:13.786556 | 2026-09-05 03:05:13.879285 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-05 03:05:13.880283 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-05 03:05:14.670703 | 2026-09-05 03:05:14.670824 | PLAY [Base post] 2026-09-05 03:05:14.684709 | 2026-09-05 03:05:14.684825 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-05 03:05:15.275395 | debian-bookworm | changed 2026-09-05 03:05:15.281831 | 2026-09-05 03:05:15.281941 | PLAY RECAP 2026-09-05 03:05:15.282000 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-05 03:05:15.282060 | 2026-09-05 03:05:15.377826 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-05 03:05:15.378770 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-05 03:05:16.321070 | 2026-09-05 03:05:16.321201 | PLAY [Base post-logs] 2026-09-05 03:05:16.342219 | 2026-09-05 03:05:16.342344 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-05 03:05:16.848445 | localhost | changed 2026-09-05 03:05:16.857504 | 2026-09-05 03:05:16.857618 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-05 03:05:16.882255 | localhost | ok 2026-09-05 03:05:16.886466 | 2026-09-05 03:05:16.886796 | TASK [Set zuul-log-path fact] 2026-09-05 03:05:16.901401 | localhost | ok 2026-09-05 03:05:16.908625 | 2026-09-05 03:05:16.908749 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-05 03:05:16.933014 | localhost | ok 2026-09-05 03:05:16.936063 | 2026-09-05 03:05:16.936202 | TASK [upload-logs : Create log directories] 2026-09-05 03:05:17.418603 | localhost | changed 2026-09-05 03:05:17.421252 | 2026-09-05 03:05:17.421338 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-05 03:05:17.839925 | localhost -> localhost | ok: Runtime: 0:00:00.003183 2026-09-05 03:05:17.843374 | 2026-09-05 03:05:17.843464 | TASK [upload-logs : Upload logs to log server] 2026-09-05 03:05:18.471401 | localhost | Output suppressed because no_log was given 2026-09-05 03:05:18.473267 | 2026-09-05 03:05:18.473358 | LOOP [upload-logs : Compress console log and json output] 2026-09-05 03:05:18.577422 | localhost | skipping: Conditional result was False 2026-09-05 03:05:18.590024 | localhost | skipping: Conditional result was False 2026-09-05 03:05:18.600347 | 2026-09-05 03:05:18.600438 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-05 03:05:18.661389 | localhost | skipping: Conditional result was False 2026-09-05 03:05:18.661615 | 2026-09-05 03:05:18.666131 | localhost | skipping: Conditional result was False 2026-09-05 03:05:18.676380 | 2026-09-05 03:05:18.676470 | LOOP [upload-logs : Upload console log and json output]