2026-08-25 03:02:33.521976 | Job console starting 2026-08-25 03:02:33.532204 | Updating git repos 2026-08-25 03:02:33.576015 | Cloning repos into workspace 2026-08-25 03:02:33.630419 | Restoring repo states 2026-08-25 03:02:33.643940 | Merging changes 2026-08-25 03:02:33.643963 | Checking out repos 2026-08-25 03:02:33.747278 | Preparing playbooks 2026-08-25 03:02:34.951046 | Running Ansible setup 2026-08-25 03:02:39.851696 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-25 03:02:40.580455 | 2026-08-25 03:02:40.580618 | PLAY [Base pre] 2026-08-25 03:02:40.597434 | 2026-08-25 03:02:40.597541 | TASK [Setup log path fact] 2026-08-25 03:02:40.616075 | debian-bookworm | ok 2026-08-25 03:02:40.632052 | 2026-08-25 03:02:40.632165 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-25 03:02:40.659714 | debian-bookworm | ok 2026-08-25 03:02:40.668929 | 2026-08-25 03:02:40.669015 | TASK [emit-job-header : Print job information] 2026-08-25 03:02:40.714417 | # Job Information 2026-08-25 03:02:40.714674 | Ansible Version: 2.16.14 2026-08-25 03:02:40.714734 | Job: tox 2026-08-25 03:02:40.714792 | Pipeline: periodic-daily 2026-08-25 03:02:40.714851 | Executor: 521e9411259a 2026-08-25 03:02:40.714890 | Triggered by: https://github.com/osism/openstack-image-manager 2026-08-25 03:02:40.714929 | Event ID: 9e4fde8f0fd84b8dbe31fa8149dae6d5 2026-08-25 03:02:40.722428 | 2026-08-25 03:02:40.722551 | LOOP [emit-job-header : Print node information] 2026-08-25 03:02:40.853499 | debian-bookworm | ok: 2026-08-25 03:02:40.853829 | debian-bookworm | # Node Information 2026-08-25 03:02:40.853875 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-25 03:02:40.853902 | debian-bookworm | Hostname: debian 2026-08-25 03:02:40.853925 | debian-bookworm | Username: zuul 2026-08-25 03:02:40.853945 | debian-bookworm | Distro: Debian 12.15 2026-08-25 03:02:40.853969 | debian-bookworm | Provider: regiocloud-a 2026-08-25 03:02:40.853990 | debian-bookworm | Region: 2026-08-25 03:02:40.854011 | debian-bookworm | Label: debian-bookworm 2026-08-25 03:02:40.854031 | debian-bookworm | Product Name: OpenStack Nova 2026-08-25 03:02:40.854050 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4f:9e2c 2026-08-25 03:02:40.873034 | 2026-08-25 03:02:40.873155 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-25 03:02:41.455350 | debian-bookworm -> localhost | changed 2026-08-25 03:02:41.465391 | 2026-08-25 03:02:41.465504 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-25 03:02:42.603193 | debian-bookworm -> localhost | changed 2026-08-25 03:02:42.619140 | 2026-08-25 03:02:42.619259 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-25 03:02:43.001422 | debian-bookworm -> localhost | ok 2026-08-25 03:02:43.015803 | 2026-08-25 03:02:43.015906 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-25 03:02:43.075723 | debian-bookworm | ok 2026-08-25 03:02:43.102450 | debian-bookworm | included: /var/lib/zuul/builds/0a088897ef2e4b0b90b4af915f600a9a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-25 03:02:43.126496 | 2026-08-25 03:02:43.126617 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-25 03:02:44.891326 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-25 03:02:44.891592 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0a088897ef2e4b0b90b4af915f600a9a/work/0a088897ef2e4b0b90b4af915f600a9a_id_rsa 2026-08-25 03:02:44.891644 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0a088897ef2e4b0b90b4af915f600a9a/work/0a088897ef2e4b0b90b4af915f600a9a_id_rsa.pub 2026-08-25 03:02:44.891673 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-25 03:02:44.891698 | debian-bookworm -> localhost | SHA256:Zrv4f/AqYcBv7udSxHgngbbvJ0IVOYMtFvIxsIX0dOk zuul-build-sshkey 2026-08-25 03:02:44.891722 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-25 03:02:44.891755 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-25 03:02:44.891778 | debian-bookworm -> localhost | | .+o*=.o | 2026-08-25 03:02:44.891800 | debian-bookworm -> localhost | | .B*+O | 2026-08-25 03:02:44.891821 | debian-bookworm -> localhost | | ..oo* = | 2026-08-25 03:02:44.891840 | debian-bookworm -> localhost | | o o E . | 2026-08-25 03:02:44.891860 | debian-bookworm -> localhost | | oS= o | 2026-08-25 03:02:44.891888 | debian-bookworm -> localhost | | o*.+ | 2026-08-25 03:02:44.891909 | debian-bookworm -> localhost | | =.+ o | 2026-08-25 03:02:44.891928 | debian-bookworm -> localhost | | .=.+ + | 2026-08-25 03:02:44.891949 | debian-bookworm -> localhost | | .o+B+= | 2026-08-25 03:02:44.891969 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-25 03:02:44.892015 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.407670 2026-08-25 03:02:44.901273 | 2026-08-25 03:02:44.901375 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-25 03:02:44.942923 | debian-bookworm | ok 2026-08-25 03:02:44.961204 | debian-bookworm | included: /var/lib/zuul/builds/0a088897ef2e4b0b90b4af915f600a9a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-25 03:02:44.977528 | 2026-08-25 03:02:44.977637 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-25 03:02:45.007088 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:02:45.016554 | 2026-08-25 03:02:45.016686 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-25 03:02:45.812272 | debian-bookworm | changed 2026-08-25 03:02:45.818454 | 2026-08-25 03:02:45.818549 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-25 03:02:46.140179 | debian-bookworm | ok 2026-08-25 03:02:46.149968 | 2026-08-25 03:02:46.150068 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-25 03:02:46.827478 | debian-bookworm | changed 2026-08-25 03:02:46.832648 | 2026-08-25 03:02:46.832741 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-25 03:02:47.525050 | debian-bookworm | changed 2026-08-25 03:02:47.532446 | 2026-08-25 03:02:47.532735 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-25 03:02:47.559451 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:02:47.565305 | 2026-08-25 03:02:47.565396 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-25 03:02:48.400113 | debian-bookworm -> localhost | changed 2026-08-25 03:02:48.410668 | 2026-08-25 03:02:48.410771 | TASK [add-build-sshkey : Add back temp key] 2026-08-25 03:02:49.096612 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0a088897ef2e4b0b90b4af915f600a9a/work/0a088897ef2e4b0b90b4af915f600a9a_id_rsa (zuul-build-sshkey) 2026-08-25 03:02:49.096836 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.029204 2026-08-25 03:02:49.104323 | 2026-08-25 03:02:49.104417 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-25 03:02:49.724027 | debian-bookworm | ok 2026-08-25 03:02:49.729027 | 2026-08-25 03:02:49.731131 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-25 03:02:49.761687 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:02:49.771809 | 2026-08-25 03:02:49.771909 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-25 03:02:50.243130 | debian-bookworm | ok 2026-08-25 03:02:50.287737 | 2026-08-25 03:02:50.287866 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-25 03:02:50.349090 | debian-bookworm | ok 2026-08-25 03:02:50.357901 | 2026-08-25 03:02:50.358000 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-25 03:02:50.867526 | debian-bookworm -> localhost | ok 2026-08-25 03:02:50.874573 | 2026-08-25 03:02:50.874668 | TASK [validate-host : Collect information about the host] 2026-08-25 03:02:52.124993 | debian-bookworm | ok 2026-08-25 03:02:52.138153 | 2026-08-25 03:02:52.138262 | TASK [validate-host : Sanitize hostname] 2026-08-25 03:02:52.298997 | debian-bookworm | ok 2026-08-25 03:02:52.308918 | 2026-08-25 03:02:52.309021 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-25 03:02:52.855489 | debian-bookworm -> localhost | changed 2026-08-25 03:02:52.864285 | 2026-08-25 03:02:52.864528 | TASK [validate-host : Collect information about zuul worker] 2026-08-25 03:02:53.236975 | debian-bookworm | ok 2026-08-25 03:02:53.241719 | 2026-08-25 03:02:53.241811 | TASK [validate-host : Write out all zuul information for each host] 2026-08-25 03:02:53.831400 | debian-bookworm -> localhost | changed 2026-08-25 03:02:53.840759 | 2026-08-25 03:02:53.840855 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-25 03:02:54.118704 | debian-bookworm | ok 2026-08-25 03:02:54.126905 | 2026-08-25 03:02:54.127081 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-25 03:02:55.007127 | debian-bookworm | changed: 2026-08-25 03:02:55.007306 | debian-bookworm | cd+++++++++ src/ 2026-08-25 03:02:55.007340 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-25 03:02:55.007364 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-25 03:02:55.007385 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-08-25 03:02:55.007404 | debian-bookworm | =7.0.3 2026-08-25 03:03:32.728682 | debian-bookworm | Downloading cachetools-7.1.7-py3-none-any.whl (16 kB) 2026-08-25 03:03:32.769059 | debian-bookworm | Collecting colorama>=0.4.6 2026-08-25 03:03:32.783089 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-08-25 03:03:32.849950 | debian-bookworm | Collecting filelock>=3.25 2026-08-25 03:03:32.864338 | debian-bookworm | Downloading filelock-3.32.4-py3-none-any.whl (99 kB) 2026-08-25 03:03:32.876415 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 99.9/99.9 kB 10.2 MB/s eta 0:00:00 2026-08-25 03:03:32.924115 | debian-bookworm | Collecting packaging>=26 2026-08-25 03:03:32.938084 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-08-25 03:03:32.956921 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 7.7 MB/s eta 0:00:00 2026-08-25 03:03:33.009724 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-08-25 03:03:33.023969 | debian-bookworm | Downloading platformdirs-4.11.4-py3-none-any.whl (23 kB) 2026-08-25 03:03:33.067591 | debian-bookworm | Collecting pluggy>=1.6 2026-08-25 03:03:33.081831 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-08-25 03:03:33.125272 | debian-bookworm | Collecting pyproject-api>=1.10 2026-08-25 03:03:33.139717 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-08-25 03:03:33.178202 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-08-25 03:03:33.192358 | debian-bookworm | Downloading python_discovery-1.5.3-py3-none-any.whl (38 kB) 2026-08-25 03:03:33.237766 | debian-bookworm | Collecting tomli-w>=1.2 2026-08-25 03:03:33.251598 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-08-25 03:03:33.306589 | debian-bookworm | Collecting typing-extensions>=4.15 2026-08-25 03:03:33.321085 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-08-25 03:03:33.329466 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 6.9 MB/s eta 0:00:00 2026-08-25 03:03:33.466647 | debian-bookworm | Collecting virtualenv>=21.1 2026-08-25 03:03:33.480949 | debian-bookworm | Downloading virtualenv-21.7.4-py3-none-any.whl (5.3 MB) 2026-08-25 03:03:33.792153 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 17.4 MB/s eta 0:00:00 2026-08-25 03:03:33.878974 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-08-25 03:03:33.893010 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-08-25 03:03:33.916283 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 23.0 MB/s eta 0:00:00 2026-08-25 03:03:34.020884 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-08-25 03:03:34.963673 | 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.4 pluggy-1.6.0 pyproject-api-1.11.0 python-discovery-1.5.3 tomli-w-1.2.0 tox-4.60.0 typing-extensions-4.16.0 virtualenv-21.7.4 2026-08-25 03:03:35.441872 | debian-bookworm | ok: Runtime: 0:00:03.317972 2026-08-25 03:03:35.449637 | 2026-08-25 03:03:35.449738 | TASK [ensure-tox : Export installed tox_executable path] 2026-08-25 03:03:35.533800 | debian-bookworm | ok 2026-08-25 03:03:35.551784 | 2026-08-25 03:03:35.551889 | TASK [ensure-tox : Output tox version] 2026-08-25 03:03:36.241712 | 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-25 03:03:36.260979 | debian-bookworm | 4.60.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-25 03:03:36.666562 | debian-bookworm | ok: Runtime: 0:00:00.346498 2026-08-25 03:03:36.674665 | 2026-08-25 03:03:36.674866 | TASK [ensure-tox : Make global symlink] 2026-08-25 03:03:36.711429 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:03:36.739865 | 2026-08-25 03:03:36.739957 | PLAY RECAP 2026-08-25 03:03:36.740013 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-08-25 03:03:36.741376 | 2026-08-25 03:03:37.205936 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-08-25 03:03:37.208092 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-08-25 03:03:38.705476 | 2026-08-25 03:03:38.705626 | PLAY [all] 2026-08-25 03:03:38.740513 | 2026-08-25 03:03:38.740661 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-08-25 03:03:39.219897 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-08-25 03:03:39.295316 | debian-bookworm | ok: Runtime: 0:00:00.035477 2026-08-25 03:03:39.299843 | 2026-08-25 03:03:39.299944 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-08-25 03:03:39.823779 | debian-bookworm | ok: Runtime: 0:00:00.008049 2026-08-25 03:03:39.829282 | 2026-08-25 03:03:39.829392 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-08-25 03:03:40.159308 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-08-25 03:03:40.159911 | debian-bookworm | sudo: a password is required 2026-08-25 03:03:40.390237 | debian-bookworm | ok: Runtime: 0:00:00.020041 2026-08-25 03:03:40.404064 | 2026-08-25 03:03:40.404162 | TASK [tox : Check to see if the constraints file exists] 2026-08-25 03:03:40.448033 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:03:40.454078 | 2026-08-25 03:03:40.454396 | TASK [tox : Fail if constraints file is missing] 2026-08-25 03:03:40.488965 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:03:40.495358 | 2026-08-25 03:03:40.495481 | TASK [tox : Record file location] 2026-08-25 03:03:40.519026 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:03:40.524637 | 2026-08-25 03:03:40.524741 | TASK [tox : Install tox siblings] 2026-08-25 03:03:40.555147 | debian-bookworm | ok 2026-08-25 03:03:40.579997 | debian-bookworm | included: /var/lib/zuul/builds/0a088897ef2e4b0b90b4af915f600a9a/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-08-25 03:03:40.587452 | 2026-08-25 03:03:40.587571 | TASK [tox : Run tox without tests] 2026-08-25 03:03:41.863982 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-08-25 03:03:54.931668 | debian-bookworm | test: OK (13.76 seconds) 2026-08-25 03:03:54.931731 | debian-bookworm | congratulations :) (13.79 seconds) 2026-08-25 03:03:55.128658 | debian-bookworm | ok: Runtime: 0:00:14.092853 2026-08-25 03:03:55.134956 | 2026-08-25 03:03:55.135057 | TASK [tox : Create a tempfile to save tox showconfig] 2026-08-25 03:03:55.581370 | debian-bookworm | changed 2026-08-25 03:03:55.585751 | 2026-08-25 03:03:55.585831 | TASK [tox : Detect tox version] 2026-08-25 03:03:56.148638 | debian-bookworm | 4.60.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-25 03:03:56.633226 | debian-bookworm | ok: Runtime: 0:00:00.343205 2026-08-25 03:03:56.639114 | 2026-08-25 03:03:56.639226 | TASK [tox : Get tox envlist config] 2026-08-25 03:03:57.702158 | debian-bookworm | ok: Runtime: 0:00:00.375868 2026-08-25 03:03:57.707922 | 2026-08-25 03:03:57.708023 | TASK [tox : Install any sibling python packages] 2026-08-25 03:03:58.117619 | debian-bookworm | ok: No setup.cfg, no action needed 2026-08-25 03:03:58.128639 | 2026-08-25 03:03:58.128747 | TASK [tox : Remove tempfile] 2026-08-25 03:03:58.861811 | debian-bookworm | changed 2026-08-25 03:03:58.879891 | 2026-08-25 03:03:58.879994 | TASK [tox : Emit tox command] 2026-08-25 03:03:58.958593 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-08-25 03:03:58.970627 | 2026-08-25 03:03:58.970736 | TASK [tox : Run tox] 2026-08-25 03:03:59.668354 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-08-25 03:04:00.228353 | debian-bookworm | ...........2026-08-25 03:04:00.227 | ERROR | contrib.check_updates:main:219 - operational failure: boom 2026-08-25 03:04:00.235963 | 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-25 03:04:00.236051 | debian-bookworm | warnings.warn( 2026-08-25 03:04:00.241128 | debian-bookworm | 2026-08-25 03:04:00.240 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 90 2026-08-25 03:04:00.241563 | debian-bookworm | 2026-08-25 03:04:00.241 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-08-25 03:04:00.241579 | debian-bookworm | 2026-08-25 03:04:00.241 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 10 2026-08-25 03:04:00.241598 | debian-bookworm | 2026-08-25 03:04:00.241 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-08-25 03:04:00.241610 | debian-bookworm | 2026-08-25 03:04:00.241 | 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-25 03:04:00.247751 | debian-bookworm | ..2026-08-25 03:04:00.247 | ERROR | openstack_image_manager.main:_download:714 - aria2c is not installed; cannot prefetch image 2026-08-25 03:04:00.251262 | debian-bookworm | .2026-08-25 03:04:00.250 | ERROR | openstack_image_manager.main:_download:745 - aria2c exited with rc=1 for http://x/y 2026-08-25 03:04:00.258221 | debian-bookworm | ..2026-08-25 03:04:00.257 | ERROR | openstack_image_manager.main:_download:739 - aria2c timed out downloading http://x/y 2026-08-25 03:04:00.265150 | debian-bookworm | ..2026-08-25 03:04:00.264 | 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-25 03:04:00.271702 | debian-bookworm | ..2026-08-25 03:04:00.271 | 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-25 03:04:00.278185 | debian-bookworm | ..2026-08-25 03:04:00.277 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: 404 2026-08-25 03:04:00.281230 | debian-bookworm | .2026-08-25 03:04:00.281 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: unreachable 2026-08-25 03:04:00.293638 | debian-bookworm | ...2026-08-25 03:04:00.293 | 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-25 03:04:00.296970 | debian-bookworm | .2026-08-25 03:04:00.296 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://x/y; skipping disk check 2026-08-25 03:04:00.300961 | debian-bookworm | .2026-08-25 03:04:00.300 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-25 03:04:00.301000 | debian-bookworm | 2026-08-25 03:04:00.300 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-25 03:04:00.602109 | debian-bookworm | 2026-08-25 03:04:00.601 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-25 03:04:00.604091 | debian-bookworm | 2026-08-25 03:04:00.603 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-25 03:04:00.604606 | debian-bookworm | 2026-08-25 03:04:00.604 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-08-25 03:04:00.613662 | debian-bookworm | .2026-08-25 03:04:00.613 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-25 03:04:00.614050 | debian-bookworm | 2026-08-25 03:04:00.613 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-25 03:04:00.615391 | debian-bookworm | 2026-08-25 03:04:00.615 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-25 03:04:00.615711 | debian-bookworm | 2026-08-25 03:04:00.615 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL file:///path/to/file.img 2026-08-25 03:04:00.615730 | debian-bookworm | 2026-08-25 03:04:00.615 | INFO | openstack_image_manager.main:import_image:539 - Importing local file '/path/to/file.img' as image Ubuntu 20.04 (1) 2026-08-25 03:04:00.622126 | debian-bookworm | .2026-08-25 03:04:00.621 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-25 03:04:00.622519 | debian-bookworm | 2026-08-25 03:04:00.622 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-25 03:04:00.622829 | debian-bookworm | 2026-08-25 03:04:00.622 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-25 03:04:00.623163 | debian-bookworm | 2026-08-25 03:04:00.622 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-25 03:04:00.623179 | debian-bookworm | 2026-08-25 03:04:00.623 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-25 03:04:00.623466 | debian-bookworm | 2026-08-25 03:04:00.623 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-25 03:04:00.623478 | debian-bookworm | 2026-08-25 03:04:00.623 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-25 03:04:00.624307 | debian-bookworm | 2026-08-25 03:04:00.624 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-08-25 03:04:00.629938 | debian-bookworm | .2026-08-25 03:04:00.629 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-25 03:04:00.630289 | debian-bookworm | 2026-08-25 03:04:00.629 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-25 03:04:00.630591 | debian-bookworm | 2026-08-25 03:04:00.630 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-25 03:04:00.630965 | debian-bookworm | 2026-08-25 03:04:00.630 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-25 03:04:00.631001 | debian-bookworm | 2026-08-25 03:04:00.630 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-25 03:04:00.631317 | debian-bookworm | 2026-08-25 03:04:00.630 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-25 03:04:00.631328 | debian-bookworm | 2026-08-25 03:04:00.630 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-25 03:04:00.631706 | debian-bookworm | 2026-08-25 03:04:00.631 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-08-25 03:04:00.631719 | debian-bookworm | 2026-08-25 03:04:00.631 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-08-25 03:04:00.906849 | debian-bookworm | 2026-08-25 03:04:00.906 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-25 03:04:00.909430 | debian-bookworm | 2026-08-25 03:04:00.909 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-25 03:04:00.909886 | debian-bookworm | 2026-08-25 03:04:00.909 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-08-25 03:04:00.942901 | debian-bookworm | ...2026-08-25 03:04:00.942 | ERROR | openstack_image_manager.main:main:366 - Skipping cleanup of outdated images because of previous errors 2026-08-25 03:04:01.032619 | debian-bookworm | .2026-08-25 03:04:01.032 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04' (removal candidate) 2026-08-25 03:04:01.037387 | debian-bookworm | .2026-08-25 03:04:01.037 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-08-25 03:04:01.037765 | debian-bookworm | 2026-08-25 03:04:01.037 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-08-25 03:04:01.037781 | debian-bookworm | 2026-08-25 03:04:01.037 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-08-25 03:04:01.038121 | debian-bookworm | 2026-08-25 03:04:01.037 | INFO | openstack_image_manager.main:manage_outdated_images:1430 - Deleting Ubuntu 20.04_2 2026-08-25 03:04:01.038809 | debian-bookworm | 2026-08-25 03:04:01.038 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-08-25 03:04:01.038828 | debian-bookworm | 2026-08-25 03:04:01.038 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-08-25 03:04:01.039201 | debian-bookworm | 2026-08-25 03:04:01.038 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-08-25 03:04:01.039213 | debian-bookworm | 2026-08-25 03:04:01.038 | 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-25 03:04:01.042474 | debian-bookworm | .2026-08-25 03:04:01.042 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-25 03:04:01.042497 | debian-bookworm | 2026-08-25 03:04:01.042 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-25 03:04:01.046312 | debian-bookworm | .2026-08-25 03:04:01.046 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-25 03:04:01.046336 | debian-bookworm | 2026-08-25 03:04:01.046 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-25 03:04:01.301739 | debian-bookworm | 2026-08-25 03:04:01.301 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-25 03:04:01.303906 | debian-bookworm | 2026-08-25 03:04:01.303 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.304447 | debian-bookworm | 2026-08-25 03:04:01.303 | ERROR | openstack_image_manager.main:_prefetch_import:655 - glance-direct import failed for Ubuntu 20.04 (1) 2026-08-25 03:04:01.304481 | debian-bookworm | 409 conflict on fixed id 2026-08-25 03:04:01.304530 | debian-bookworm | 2026-08-25 03:04:01.304 | ERROR | openstack_image_manager.main:_prefetch_import:658 - PREFETCH: glance-direct import failed for 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.312926 | debian-bookworm | .2026-08-25 03:04:01.312 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-25 03:04:01.312985 | debian-bookworm | 2026-08-25 03:04:01.312 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-25 03:04:01.668421 | debian-bookworm | 2026-08-25 03:04:01.667 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-25 03:04:01.671704 | debian-bookworm | 2026-08-25 03:04:01.671 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download failed for 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.685657 | debian-bookworm | .2026-08-25 03:04:01.685 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.686656 | debian-bookworm | 2026-08-25 03:04:01.686 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-25 03:04:01.687629 | debian-bookworm | 2026-08-25 03:04:01.687 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-08-25 03:04:01.689895 | debian-bookworm | 2026-08-25 03:04:01.689 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.690383 | debian-bookworm | 2026-08-25 03:04:01.690 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-08-25 03:04:01.690989 | debian-bookworm | 2026-08-25 03:04:01.690 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.691255 | debian-bookworm | 2026-08-25 03:04:01.691 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-25 03:04:01.691490 | debian-bookworm | 2026-08-25 03:04:01.691 | INFO | openstack_image_manager.main:process_image:1020 - Skipping required import of image 'Ubuntu 20.04 (1)', running in dry-run mode 2026-08-25 03:04:01.699534 | debian-bookworm | .2026-08-25 03:04:01.699 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (latest)' 2026-08-25 03:04:01.699927 | debian-bookworm | 2026-08-25 03:04:01.699 | 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-25 03:04:01.708918 | debian-bookworm | .2026-08-25 03:04:01.708 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-1' 2026-08-25 03:04:01.709652 | debian-bookworm | 2026-08-25 03:04:01.709 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-25 03:04:01.710666 | debian-bookworm | 2026-08-25 03:04:01.710 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-1' successfully completed, reloading images 2026-08-25 03:04:01.721552 | debian-bookworm | .2026-08-25 03:04:01.721 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(1)' 2026-08-25 03:04:01.721600 | debian-bookworm | 2026-08-25 03:04:01.721 | INFO | openstack_image_manager.main:process_image:1025 - Skipping image 'Ubuntu 20.04-(1)' (only importing the latest version from type multi) 2026-08-25 03:04:01.722155 | debian-bookworm | 2026-08-25 03:04:01.721 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(2)' 2026-08-25 03:04:01.725671 | debian-bookworm | 2026-08-25 03:04:01.725 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com2: 200 2026-08-25 03:04:01.726588 | debian-bookworm | 2026-08-25 03:04:01.726 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-(2)' successfully completed, reloading images 2026-08-25 03:04:01.749729 | debian-bookworm | ...2026-08-25 03:04:01.749 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.750268 | debian-bookworm | 2026-08-25 03:04:01.750 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-25 03:04:01.750618 | debian-bookworm | 2026-08-25 03:04:01.750 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.750635 | debian-bookworm | 2026-08-25 03:04:01.750 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-25 03:04:01.750935 | debian-bookworm | 2026-08-25 03:04:01.750 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04 (1) to Ubuntu 20.04 2026-08-25 03:04:01.756272 | debian-bookworm | .2026-08-25 03:04:01.756 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-08-25 03:04:01.756734 | debian-bookworm | 2026-08-25 03:04:01.756 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-25 03:04:01.757125 | debian-bookworm | 2026-08-25 03:04:01.756 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-08-25 03:04:01.757158 | debian-bookworm | 2026-08-25 03:04:01.756 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-25 03:04:01.757443 | debian-bookworm | 2026-08-25 03:04:01.757 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04-(1) to Ubuntu 20.04 2026-08-25 03:04:01.791226 | debian-bookworm | ..2026-08-25 03:04:01.790 | INFO | openstack_image_manager.main:set_properties:1063 - Checking parameters of 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.791589 | debian-bookworm | 2026-08-25 03:04:01.791 | INFO | openstack_image_manager.main:set_properties:1129 - Setting internal_version = 1 2026-08-25 03:04:01.791604 | debian-bookworm | 2026-08-25 03:04:01.791 | INFO | openstack_image_manager.main:set_properties:1132 - Setting image_original_user = ubuntu 2026-08-25 03:04:01.791610 | debian-bookworm | 2026-08-25 03:04:01.791 | INFO | openstack_image_manager.main:set_properties:1145 - Adding tag my_tag 2026-08-25 03:04:01.791927 | debian-bookworm | 2026-08-25 03:04:01.791 | INFO | openstack_image_manager.main:set_properties:1150 - Deleting tag fake_tag 2026-08-25 03:04:01.792267 | debian-bookworm | 2026-08-25 03:04:01.791 | INFO | openstack_image_manager.main:set_properties:1161 - Setting property image_build_date: 2021-01-01 != 2021-01-21 2026-08-25 03:04:01.792595 | debian-bookworm | 2026-08-25 03:04:01.792 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_distro: ubuntu 2026-08-25 03:04:01.792607 | debian-bookworm | 2026-08-25 03:04:01.792 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_version: 20.04 2026-08-25 03:04:01.792611 | debian-bookworm | 2026-08-25 03:04:01.792 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property image_source: http://url.com 2026-08-25 03:04:01.792616 | debian-bookworm | 2026-08-25 03:04:01.792 | INFO | openstack_image_manager.main:set_properties:1185 - Checking status of 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.792620 | debian-bookworm | 2026-08-25 03:04:01.792 | INFO | openstack_image_manager.main:set_properties:1190 - Deactivating image 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.792895 | debian-bookworm | 2026-08-25 03:04:01.792 | INFO | openstack_image_manager.main:set_properties:1197 - Checking visibility of 'Ubuntu 20.04 (1)' 2026-08-25 03:04:01.820449 | debian-bookworm | ...2026-08-25 03:04:01.820 | ERROR | openstack_image_manager.main:wait_for_image:814 - Image import timed out 2026-08-25 03:04:01.824773 | debian-bookworm | .2026-08-25 03:04:01.824 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-25 03:04:01.824798 | debian-bookworm | boom 2026-08-25 03:04:01.825145 | debian-bookworm | 2026-08-25 03:04:01.824 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-25 03:04:01.825159 | debian-bookworm | boom 2026-08-25 03:04:01.825473 | debian-bookworm | 2026-08-25 03:04:01.825 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-25 03:04:01.825484 | debian-bookworm | boom 2026-08-25 03:04:01.825488 | debian-bookworm | 2026-08-25 03:04:01.825 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-25 03:04:01.825492 | debian-bookworm | boom 2026-08-25 03:04:01.825496 | debian-bookworm | 2026-08-25 03:04:01.825 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-25 03:04:01.825500 | debian-bookworm | boom 2026-08-25 03:04:01.825505 | debian-bookworm | 2026-08-25 03:04:01.825 | ERROR | openstack_image_manager.main:wait_for_image:848 - Giving up on image after repeated errors 2026-08-25 03:04:01.830554 | debian-bookworm | .2026-08-25 03:04:01.830 | ERROR | openstack_image_manager.main:wait_for_image:837 - Image entered terminal state 'killed' 2026-08-25 03:04:01.834286 | debian-bookworm | .2026-08-25 03:04:01.833 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-25 03:04:01.834310 | debian-bookworm | 2026-08-25 03:04:01.834 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-25 03:04:01.834753 | debian-bookworm | 2026-08-25 03:04:01.834 | ERROR | openstack_image_manager.main:import_image:584 - Web-download import for image Ubuntu 20.04 (1) failed 2026-08-25 03:04:01.834784 | debian-bookworm | glance api error 2026-08-25 03:04:01.834790 | debian-bookworm | 2026-08-25 03:04:01.834 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-08-25 03:04:01.848255 | debian-bookworm | .......2026-08-25 03:04:01 | INFO  | main:223 - Processing file /tmp/tmpacyfed04/example.yml 2026-08-25 03:04:01.854458 | debian-bookworm | 2026-08-25 03:04:01 | INFO  | main:232 - Skipping disabled image Example 1.0 2026-08-25 03:04:01.862004 | debian-bookworm | .2026-08-25 03:04:01 | INFO  | main:223 - Processing file /tmp/tmpka_g0cua/example.yml 2026-08-25 03:04:01.865236 | debian-bookworm | 2026-08-25 03:04:01 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-25 03:04:01.865262 | debian-bookworm | 2026-08-25 03:04:01 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-08-25 03:04:01.865678 | debian-bookworm | 2026-08-25 03:04:01 | 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-25 03:04:01.868303 | debian-bookworm | 2026-08-25 03:04:01 | INFO  | main:242 - Dry-run enabled, not writing /tmp/tmpka_g0cua/example.yml 2026-08-25 03:04:01.875493 | debian-bookworm | .2026-08-25 03:04:01 | INFO  | main:223 - Processing file /tmp/tmpw6xlxzhq/example.yml 2026-08-25 03:04:01.878732 | debian-bookworm | 2026-08-25 03:04:01 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-25 03:04:01.878756 | debian-bookworm | 2026-08-25 03:04:01 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:0000000000000000000000000000000000000000000000000000000000000000 2026-08-25 03:04:01.879166 | debian-bookworm | 2026-08-25 03:04:01 | INFO  | update_image:158 - Image Example 1.0 is up-to-date, nothing to do 2026-08-25 03:04:01.885999 | debian-bookworm | .2026-08-25 03:04:01 | INFO  | main:223 - Processing file /tmp/tmpd0hngpa3/example.yml 2026-08-25 03:04:01.889081 | debian-bookworm | 2026-08-25 03:04:01 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-25 03:04:01.889104 | debian-bookworm | 2026-08-25 03:04:01 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-08-25 03:04:01.889456 | debian-bookworm | 2026-08-25 03:04:01 | 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-25 03:04:01.892591 | debian-bookworm | . 2026-08-25 03:04:01.892615 | debian-bookworm | ---------------------------------------------------------------------- 2026-08-25 03:04:01.893179 | debian-bookworm | Ran 74 tests in 1.688s 2026-08-25 03:04:01.893191 | debian-bookworm | 2026-08-25 03:04:01.893196 | debian-bookworm | OK 2026-08-25 03:04:02.031910 | debian-bookworm | test: OK (2.41=setup[0.05]+cmd[2.36] seconds) 2026-08-25 03:04:02.031993 | debian-bookworm | congratulations :) (2.44 seconds) 2026-08-25 03:04:02.557046 | debian-bookworm | ok: Runtime: 0:00:02.743276 2026-08-25 03:04:02.564449 | 2026-08-25 03:04:02.564560 | TASK [tox : Look for output] 2026-08-25 03:04:03.112574 | debian-bookworm | ok 2026-08-25 03:04:03.138225 | 2026-08-25 03:04:03.138370 | TASK [tox : Return file comments to Zuul] 2026-08-25 03:04:03.162863 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:03.171199 | 2026-08-25 03:04:03.171268 | PLAY RECAP 2026-08-25 03:04:03.171323 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-08-25 03:04:03.171351 | 2026-08-25 03:04:03.295975 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-08-25 03:04:03.297000 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-08-25 03:04:04.266471 | 2026-08-25 03:04:04.266621 | PLAY [all] 2026-08-25 03:04:04.287721 | 2026-08-25 03:04:04.287854 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-08-25 03:04:04.331925 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:04.339148 | 2026-08-25 03:04:04.339276 | TASK [fetch-tox-output : Set tox log path for single node] 2026-08-25 03:04:04.377769 | debian-bookworm | ok 2026-08-25 03:04:04.381915 | 2026-08-25 03:04:04.382007 | TASK [fetch-tox-output : Ensure local tox dir] 2026-08-25 03:04:05.263251 | debian-bookworm -> localhost | changed 2026-08-25 03:04:05.269738 | 2026-08-25 03:04:05.269826 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-08-25 03:04:05.383744 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:05.388835 | 2026-08-25 03:04:05.388933 | TASK [fetch-tox-output : Set envlist fact] 2026-08-25 03:04:05.467314 | debian-bookworm | ok 2026-08-25 03:04:05.472059 | 2026-08-25 03:04:05.472133 | TASK [fetch-tox-output : Get tox version] 2026-08-25 03:04:06.312483 | 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-25 03:04:06.331956 | debian-bookworm | 4.60.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-25 03:04:06.567251 | debian-bookworm | ok: Runtime: 0:00:00.333398 2026-08-25 03:04:06.572031 | 2026-08-25 03:04:06.572128 | TASK [fetch-tox-output : Find all default environments] 2026-08-25 03:04:07.122978 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:07.136350 | 2026-08-25 03:04:07.136494 | TASK [fetch-tox-output : Set envlist fact] 2026-08-25 03:04:07.169966 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:07.176068 | 2026-08-25 03:04:07.176170 | TASK [fetch-tox-output : Find all default environments] 2026-08-25 03:04:07.730853 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:07.736558 | 2026-08-25 03:04:07.736658 | TASK [fetch-tox-output : Set envlist fact] 2026-08-25 03:04:07.789894 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:07.796944 | 2026-08-25 03:04:07.797041 | LOOP [fetch-tox-output : Copy tox logs] 2026-08-25 03:04:07.889575 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:07.897005 | 2026-08-25 03:04:07.897129 | LOOP [fetch-tox-output : Collect tox logs] 2026-08-25 03:04:09.251454 | debian-bookworm | changed: 2026-08-25 03:04:09.251625 | debian-bookworm | created directory /var/lib/zuul/builds/0a088897ef2e4b0b90b4af915f600a9a/work/logs/tox/test 2026-08-25 03:04:09.251662 | debian-bookworm | cd+++++++++ ./ 2026-08-25 03:04:09.251684 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-08-25 03:04:09.251717 | 2026-08-25 03:04:09.261778 | 2026-08-25 03:04:09.261862 | PLAY RECAP 2026-08-25 03:04:09.261908 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-08-25 03:04:09.261928 | 2026-08-25 03:04:09.418924 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-08-25 03:04:09.420340 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-08-25 03:04:10.334117 | 2026-08-25 03:04:10.334256 | PLAY [all] 2026-08-25 03:04:10.364364 | 2026-08-25 03:04:10.364490 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-08-25 03:04:10.639046 | debian-bookworm | changed: non-zero return code 2026-08-25 03:04:10.646430 | 2026-08-25 03:04:10.646568 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-08-25 03:04:10.706321 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:10.715572 | 2026-08-25 03:04:10.716139 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-08-25 03:04:10.792256 | 2026-08-25 03:04:10.792414 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-08-25 03:04:10.851429 | 2026-08-25 03:04:10.851650 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-08-25 03:04:10.904956 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:10.916087 | 2026-08-25 03:04:10.916189 | LOOP [fetch-subunit-output : Generate subunit file] 2026-08-25 03:04:11.009127 | 2026-08-25 03:04:11.009293 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-08-25 03:04:11.045803 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:11.055709 | 2026-08-25 03:04:11.055810 | TASK [fetch-subunit-output : Remove the temporary file] 2026-08-25 03:04:11.081765 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:11.089736 | 2026-08-25 03:04:11.089831 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-08-25 03:04:11.124593 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:11.131670 | 2026-08-25 03:04:11.131739 | PLAY RECAP 2026-08-25 03:04:11.131781 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-08-25 03:04:11.131801 | 2026-08-25 03:04:11.264893 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-08-25 03:04:11.265936 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-25 03:04:12.177938 | 2026-08-25 03:04:12.178074 | PLAY [Base post-fetch] 2026-08-25 03:04:12.206968 | 2026-08-25 03:04:12.207100 | TASK [diagnose-network : Ensure log directory exists] 2026-08-25 03:04:12.628769 | debian-bookworm | ok 2026-08-25 03:04:12.635298 | 2026-08-25 03:04:12.635434 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-08-25 03:04:24.627296 | debian-bookworm | ok: Runtime: 0:00:06.056764 2026-08-25 03:04:24.632871 | 2026-08-25 03:04:24.632960 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-08-25 03:04:30.883919 | debian-bookworm | ok: Runtime: 0:00:00.008632 2026-08-25 03:04:30.890748 | 2026-08-25 03:04:30.891028 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-08-25 03:04:36.864795 | debian-bookworm | ok: Runtime: 0:00:00.004681 2026-08-25 03:04:36.888273 | 2026-08-25 03:04:36.888390 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-25 03:04:36.980525 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:36.986975 | 2026-08-25 03:04:36.987199 | TASK [fetch-output : Set log path for single node] 2026-08-25 03:04:37.026773 | debian-bookworm | ok 2026-08-25 03:04:37.031846 | 2026-08-25 03:04:37.031941 | LOOP [fetch-output : Ensure local output dirs] 2026-08-25 03:04:37.619509 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/0a088897ef2e4b0b90b4af915f600a9a/work/logs" 2026-08-25 03:04:38.451432 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0a088897ef2e4b0b90b4af915f600a9a/work/artifacts" 2026-08-25 03:04:38.988541 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0a088897ef2e4b0b90b4af915f600a9a/work/docs" 2026-08-25 03:04:39.008009 | 2026-08-25 03:04:39.008181 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-25 03:04:40.386648 | debian-bookworm | changed: 2026-08-25 03:04:40.386824 | debian-bookworm | .d..t...... ./ 2026-08-25 03:04:40.386970 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-25 03:04:40.386992 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-25 03:04:40.387010 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-25 03:04:40.387027 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-25 03:04:40.387045 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-25 03:04:40.387081 | debian-bookworm | changed: All items complete 2026-08-25 03:04:40.387104 | 2026-08-25 03:04:41.115969 | debian-bookworm | changed: .d..t...... ./ 2026-08-25 03:04:42.363694 | debian-bookworm | changed: .d..t...... ./ 2026-08-25 03:04:42.388764 | 2026-08-25 03:04:42.388888 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-25 03:04:42.446471 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:42.450394 | debian-bookworm | skipping: Conditional result was False 2026-08-25 03:04:42.467373 | 2026-08-25 03:04:42.467505 | PLAY RECAP 2026-08-25 03:04:42.467565 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-08-25 03:04:42.467591 | 2026-08-25 03:04:42.649433 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-25 03:04:42.650430 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-25 03:04:44.346730 | 2026-08-25 03:04:44.346958 | PLAY [Base post] 2026-08-25 03:04:44.373962 | 2026-08-25 03:04:44.374102 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-25 03:04:44.996078 | debian-bookworm | changed 2026-08-25 03:04:45.005207 | 2026-08-25 03:04:45.005297 | PLAY RECAP 2026-08-25 03:04:45.005347 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-25 03:04:45.005397 | 2026-08-25 03:04:45.095750 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-25 03:04:45.096637 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-25 03:04:46.979611 | 2026-08-25 03:04:46.979759 | PLAY [Base post-logs] 2026-08-25 03:04:47.021457 | 2026-08-25 03:04:47.021637 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-25 03:04:47.461355 | localhost | changed 2026-08-25 03:04:47.473821 | 2026-08-25 03:04:47.473959 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-25 03:04:47.498593 | localhost | ok 2026-08-25 03:04:47.501359 | 2026-08-25 03:04:47.501449 | TASK [Set zuul-log-path fact] 2026-08-25 03:04:47.535884 | localhost | ok 2026-08-25 03:04:47.545010 | 2026-08-25 03:04:47.545104 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-25 03:04:47.590224 | localhost | ok 2026-08-25 03:04:47.593275 | 2026-08-25 03:04:47.593364 | TASK [upload-logs : Create log directories] 2026-08-25 03:04:48.411055 | localhost | changed 2026-08-25 03:04:48.416331 | 2026-08-25 03:04:48.416435 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-25 03:04:49.212863 | localhost -> localhost | ok: Runtime: 0:00:00.006654 2026-08-25 03:04:49.216368 | 2026-08-25 03:04:49.216481 | TASK [upload-logs : Upload logs to log server] 2026-08-25 03:04:50.260493 | localhost | Output suppressed because no_log was given 2026-08-25 03:04:50.261925 | 2026-08-25 03:04:50.261999 | LOOP [upload-logs : Compress console log and json output] 2026-08-25 03:04:50.337836 | localhost | skipping: Conditional result was False 2026-08-25 03:04:50.358277 | localhost | skipping: Conditional result was False 2026-08-25 03:04:50.367987 | 2026-08-25 03:04:50.368087 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-25 03:04:50.466810 | localhost | skipping: Conditional result was False 2026-08-25 03:04:50.467083 | 2026-08-25 03:04:50.477979 | localhost | skipping: Conditional result was False 2026-08-25 03:04:50.494284 | 2026-08-25 03:04:50.494382 | LOOP [upload-logs : Upload console log and json output]