2026-08-23 01:05:27.505720 | Job console starting 2026-08-23 01:05:27.519571 | Updating git repos 2026-08-23 01:05:27.537542 | Cloning repos into workspace 2026-08-23 01:05:27.592745 | Restoring repo states 2026-08-23 01:05:27.601802 | Merging changes 2026-08-23 01:05:28.224054 | Checking out repos 2026-08-23 01:05:28.325179 | Preparing playbooks 2026-08-23 01:05:29.420967 | Running Ansible setup 2026-08-23 01:05:35.426229 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-23 01:05:36.329789 | 2026-08-23 01:05:36.329941 | PLAY [Base pre] 2026-08-23 01:05:36.352563 | 2026-08-23 01:05:36.352690 | TASK [Setup log path fact] 2026-08-23 01:05:36.382872 | debian-bookworm | ok 2026-08-23 01:05:36.429288 | 2026-08-23 01:05:36.429463 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-23 01:05:36.498229 | debian-bookworm | ok 2026-08-23 01:05:36.508325 | 2026-08-23 01:05:36.508475 | TASK [emit-job-header : Print job information] 2026-08-23 01:05:36.548650 | # Job Information 2026-08-23 01:05:36.548818 | Ansible Version: 2.16.14 2026-08-23 01:05:36.548853 | Job: tox 2026-08-23 01:05:36.548887 | Pipeline: check 2026-08-23 01:05:36.548909 | Executor: 521e9411259a 2026-08-23 01:05:36.548929 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1249 2026-08-23 01:05:36.548951 | Event ID: a9e8ffc0-9e8e-11f1-88e2-9fc2beaac6bb 2026-08-23 01:05:36.553900 | 2026-08-23 01:05:36.553998 | LOOP [emit-job-header : Print node information] 2026-08-23 01:05:36.670313 | debian-bookworm | ok: 2026-08-23 01:05:36.670566 | debian-bookworm | # Node Information 2026-08-23 01:05:36.670605 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-23 01:05:36.670722 | debian-bookworm | Hostname: debian 2026-08-23 01:05:36.670745 | debian-bookworm | Username: zuul 2026-08-23 01:05:36.670767 | debian-bookworm | Distro: Debian 12.15 2026-08-23 01:05:36.670791 | debian-bookworm | Provider: regiocloud-a 2026-08-23 01:05:36.670813 | debian-bookworm | Region: 2026-08-23 01:05:36.671432 | debian-bookworm | Label: debian-bookworm 2026-08-23 01:05:36.671503 | debian-bookworm | Product Name: OpenStack Nova 2026-08-23 01:05:36.671530 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4a:dc4f 2026-08-23 01:05:36.698347 | 2026-08-23 01:05:36.698485 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-23 01:05:37.658986 | debian-bookworm -> localhost | changed 2026-08-23 01:05:37.666780 | 2026-08-23 01:05:37.666915 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-23 01:05:38.916177 | debian-bookworm -> localhost | changed 2026-08-23 01:05:38.930515 | 2026-08-23 01:05:38.930636 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-23 01:05:39.358469 | debian-bookworm -> localhost | ok 2026-08-23 01:05:39.377353 | 2026-08-23 01:05:39.377584 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-23 01:05:39.425795 | debian-bookworm | ok 2026-08-23 01:05:39.448299 | debian-bookworm | included: /var/lib/zuul/builds/0da71412732c4638ac85e19b0eaddadc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-23 01:05:39.454937 | 2026-08-23 01:05:39.455066 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-23 01:05:40.573993 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-23 01:05:40.574269 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0da71412732c4638ac85e19b0eaddadc/work/0da71412732c4638ac85e19b0eaddadc_id_rsa 2026-08-23 01:05:40.574310 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0da71412732c4638ac85e19b0eaddadc/work/0da71412732c4638ac85e19b0eaddadc_id_rsa.pub 2026-08-23 01:05:40.574338 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-23 01:05:40.574363 | debian-bookworm -> localhost | SHA256:BSl43ak2oFGGKj1jzs7oBs9mj790/l0TjnHT9S57N4c zuul-build-sshkey 2026-08-23 01:05:40.574423 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-23 01:05:40.574464 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-23 01:05:40.574488 | debian-bookworm -> localhost | | +o..o . | 2026-08-23 01:05:40.574510 | debian-bookworm -> localhost | | +.+ o.o | 2026-08-23 01:05:40.574531 | debian-bookworm -> localhost | | . . + o .. .| 2026-08-23 01:05:40.574553 | debian-bookworm -> localhost | |. * . +. . ..| 2026-08-23 01:05:40.574573 | debian-bookworm -> localhost | | = o .S.. + . .| 2026-08-23 01:05:40.574601 | debian-bookworm -> localhost | |. o = o . | 2026-08-23 01:05:40.574622 | debian-bookworm -> localhost | |.* . . . + ...| 2026-08-23 01:05:40.574642 | debian-bookworm -> localhost | |..Bo o . . .E++| 2026-08-23 01:05:40.574663 | debian-bookworm -> localhost | |o+.o+.... . ..+| 2026-08-23 01:05:40.574682 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-23 01:05:40.574735 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.303535 2026-08-23 01:05:40.582744 | 2026-08-23 01:05:40.582927 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-23 01:05:40.614715 | debian-bookworm | ok 2026-08-23 01:05:40.631686 | debian-bookworm | included: /var/lib/zuul/builds/0da71412732c4638ac85e19b0eaddadc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-23 01:05:40.642128 | 2026-08-23 01:05:40.642245 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-23 01:05:40.668913 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:05:40.676954 | 2026-08-23 01:05:40.677117 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-23 01:05:41.403554 | debian-bookworm | changed 2026-08-23 01:05:41.421224 | 2026-08-23 01:05:41.421465 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-23 01:05:41.692551 | debian-bookworm | ok 2026-08-23 01:05:41.698998 | 2026-08-23 01:05:41.699112 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-23 01:05:42.404935 | debian-bookworm | changed 2026-08-23 01:05:42.415305 | 2026-08-23 01:05:42.415511 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-23 01:05:43.054062 | debian-bookworm | changed 2026-08-23 01:05:43.063223 | 2026-08-23 01:05:43.063421 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-23 01:05:43.088661 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:05:43.098444 | 2026-08-23 01:05:43.098579 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-23 01:05:43.749565 | debian-bookworm -> localhost | changed 2026-08-23 01:05:43.762586 | 2026-08-23 01:05:43.762732 | TASK [add-build-sshkey : Add back temp key] 2026-08-23 01:05:44.257160 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0da71412732c4638ac85e19b0eaddadc/work/0da71412732c4638ac85e19b0eaddadc_id_rsa (zuul-build-sshkey) 2026-08-23 01:05:44.257556 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018375 2026-08-23 01:05:44.273478 | 2026-08-23 01:05:44.273625 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-23 01:05:44.806283 | debian-bookworm | ok 2026-08-23 01:05:44.812057 | 2026-08-23 01:05:44.812172 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-23 01:05:44.837168 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:05:44.847852 | 2026-08-23 01:05:44.847967 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-23 01:05:45.300995 | debian-bookworm | ok 2026-08-23 01:05:45.314251 | 2026-08-23 01:05:45.314419 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-23 01:05:45.383520 | debian-bookworm | ok 2026-08-23 01:05:45.393756 | 2026-08-23 01:05:45.393931 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-23 01:05:45.840213 | debian-bookworm -> localhost | ok 2026-08-23 01:05:45.855751 | 2026-08-23 01:05:45.856031 | TASK [validate-host : Collect information about the host] 2026-08-23 01:05:47.104204 | debian-bookworm | ok 2026-08-23 01:05:47.117312 | 2026-08-23 01:05:47.117460 | TASK [validate-host : Sanitize hostname] 2026-08-23 01:05:47.384879 | debian-bookworm | ok 2026-08-23 01:05:47.394656 | 2026-08-23 01:05:47.394886 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-23 01:05:48.131755 | debian-bookworm -> localhost | changed 2026-08-23 01:05:48.139298 | 2026-08-23 01:05:48.139480 | TASK [validate-host : Collect information about zuul worker] 2026-08-23 01:05:48.594567 | debian-bookworm | ok 2026-08-23 01:05:48.599889 | 2026-08-23 01:05:48.600011 | TASK [validate-host : Write out all zuul information for each host] 2026-08-23 01:05:49.992914 | debian-bookworm -> localhost | changed 2026-08-23 01:05:50.016180 | 2026-08-23 01:05:50.016492 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-23 01:05:50.394802 | debian-bookworm | ok 2026-08-23 01:05:50.417298 | 2026-08-23 01:05:50.417493 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-23 01:05:52.206360 | debian-bookworm | changed: 2026-08-23 01:05:52.206611 | debian-bookworm | cd+++++++++ src/ 2026-08-23 01:05:52.206646 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-23 01:05:52.206670 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-23 01:05:52.206691 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-08-23 01:05:52.206710 | debian-bookworm | =7.0.3 2026-08-23 01:06:31.578600 | debian-bookworm | Downloading cachetools-7.1.7-py3-none-any.whl (16 kB) 2026-08-23 01:06:31.620756 | debian-bookworm | Collecting colorama>=0.4.6 2026-08-23 01:06:31.635121 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-08-23 01:06:31.703893 | debian-bookworm | Collecting filelock>=3.25 2026-08-23 01:06:31.718456 | debian-bookworm | Downloading filelock-3.32.3-py3-none-any.whl (98 kB) 2026-08-23 01:06:31.732573 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.9/98.9 kB 11.4 MB/s eta 0:00:00 2026-08-23 01:06:31.794404 | debian-bookworm | Collecting packaging>=26 2026-08-23 01:06:31.808623 | debian-bookworm | Downloading packaging-26.3-py3-none-any.whl (129 kB) 2026-08-23 01:06:31.818412 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 130.0/130.0 kB 18.0 MB/s eta 0:00:00 2026-08-23 01:06:31.875287 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-08-23 01:06:31.889676 | debian-bookworm | Downloading platformdirs-4.11.3-py3-none-any.whl (23 kB) 2026-08-23 01:06:31.946254 | debian-bookworm | Collecting pluggy>=1.6 2026-08-23 01:06:31.960674 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-08-23 01:06:32.009390 | debian-bookworm | Collecting pyproject-api>=1.10 2026-08-23 01:06:32.024757 | debian-bookworm | Downloading pyproject_api-1.11.0-py3-none-any.whl (13 kB) 2026-08-23 01:06:32.069641 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-08-23 01:06:32.085769 | debian-bookworm | Downloading python_discovery-1.5.2-py3-none-any.whl (38 kB) 2026-08-23 01:06:32.136613 | debian-bookworm | Collecting tomli-w>=1.2 2026-08-23 01:06:32.151688 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-08-23 01:06:32.252275 | debian-bookworm | Collecting typing-extensions>=4.15 2026-08-23 01:06:32.266632 | debian-bookworm | Downloading typing_extensions-4.16.0-py3-none-any.whl (45 kB) 2026-08-23 01:06:32.275175 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.6/45.6 kB 6.4 MB/s eta 0:00:00 2026-08-23 01:06:32.410925 | debian-bookworm | Collecting virtualenv>=21.1 2026-08-23 01:06:32.426786 | debian-bookworm | Downloading virtualenv-21.7.4-py3-none-any.whl (5.3 MB) 2026-08-23 01:06:32.556299 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.3/5.3 MB 42.7 MB/s eta 0:00:00 2026-08-23 01:06:32.655640 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-08-23 01:06:32.671538 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-08-23 01:06:32.690451 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 33.9 MB/s eta 0:00:00 2026-08-23 01:06:32.799275 | debian-bookworm | Installing collected packages: distlib, typing-extensions, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-08-23 01:06:33.738393 | debian-bookworm | Successfully installed cachetools-7.1.7 colorama-0.4.6 distlib-0.4.3 filelock-3.32.3 packaging-26.3 platformdirs-4.11.3 pluggy-1.6.0 pyproject-api-1.11.0 python-discovery-1.5.2 tomli-w-1.2.0 tox-4.60.0 typing-extensions-4.16.0 virtualenv-21.7.4 2026-08-23 01:06:34.190552 | debian-bookworm | ok: Runtime: 0:00:03.242625 2026-08-23 01:06:34.204730 | 2026-08-23 01:06:34.204956 | TASK [ensure-tox : Export installed tox_executable path] 2026-08-23 01:06:34.249911 | debian-bookworm | ok 2026-08-23 01:06:34.257798 | 2026-08-23 01:06:34.258019 | TASK [ensure-tox : Output tox version] 2026-08-23 01:06:34.794409 | 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-23 01:06:34.812292 | debian-bookworm | 4.60.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-23 01:06:35.296096 | debian-bookworm | ok: Runtime: 0:00:00.305879 2026-08-23 01:06:35.305444 | 2026-08-23 01:06:35.305676 | TASK [ensure-tox : Make global symlink] 2026-08-23 01:06:35.343278 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:06:35.351557 | 2026-08-23 01:06:35.351654 | PLAY RECAP 2026-08-23 01:06:35.351713 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-08-23 01:06:35.351740 | 2026-08-23 01:06:35.551846 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-08-23 01:06:35.553704 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-08-23 01:06:36.540779 | 2026-08-23 01:06:36.540982 | PLAY [all] 2026-08-23 01:06:36.559446 | 2026-08-23 01:06:36.559648 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-08-23 01:06:37.218153 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-08-23 01:06:37.598225 | debian-bookworm | ok: Runtime: 0:00:00.027080 2026-08-23 01:06:37.604890 | 2026-08-23 01:06:37.605047 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-08-23 01:06:38.165656 | debian-bookworm | ok: Runtime: 0:00:00.005995 2026-08-23 01:06:38.172963 | 2026-08-23 01:06:38.173114 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-08-23 01:06:38.565083 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-08-23 01:06:38.566279 | debian-bookworm | sudo: a password is required 2026-08-23 01:06:38.752724 | debian-bookworm | ok: Runtime: 0:00:00.015303 2026-08-23 01:06:38.762400 | 2026-08-23 01:06:38.762518 | TASK [tox : Check to see if the constraints file exists] 2026-08-23 01:06:38.816653 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:06:38.822607 | 2026-08-23 01:06:38.822771 | TASK [tox : Fail if constraints file is missing] 2026-08-23 01:06:38.856305 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:06:38.862241 | 2026-08-23 01:06:38.862369 | TASK [tox : Record file location] 2026-08-23 01:06:38.885642 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:06:38.891220 | 2026-08-23 01:06:38.891312 | TASK [tox : Install tox siblings] 2026-08-23 01:06:38.911881 | debian-bookworm | ok 2026-08-23 01:06:38.921791 | debian-bookworm | included: /var/lib/zuul/builds/0da71412732c4638ac85e19b0eaddadc/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-08-23 01:06:38.927939 | 2026-08-23 01:06:38.928033 | TASK [tox : Run tox without tests] 2026-08-23 01:06:40.068590 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-08-23 01:06:53.666911 | debian-bookworm | test: OK (14.14 seconds) 2026-08-23 01:06:53.666976 | debian-bookworm | congratulations :) (14.17 seconds) 2026-08-23 01:06:53.979169 | debian-bookworm | ok: Runtime: 0:00:14.496295 2026-08-23 01:06:53.985102 | 2026-08-23 01:06:53.985241 | TASK [tox : Create a tempfile to save tox showconfig] 2026-08-23 01:06:54.398638 | debian-bookworm | changed 2026-08-23 01:06:54.431664 | 2026-08-23 01:06:54.431785 | TASK [tox : Detect tox version] 2026-08-23 01:06:55.088688 | debian-bookworm | 4.60.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-23 01:06:55.486613 | debian-bookworm | ok: Runtime: 0:00:00.356921 2026-08-23 01:06:55.495697 | 2026-08-23 01:06:55.495814 | TASK [tox : Get tox envlist config] 2026-08-23 01:06:56.525876 | debian-bookworm | ok: Runtime: 0:00:00.416685 2026-08-23 01:06:56.531569 | 2026-08-23 01:06:56.531663 | TASK [tox : Install any sibling python packages] 2026-08-23 01:06:57.185965 | debian-bookworm | ok: No setup.cfg, no action needed 2026-08-23 01:06:57.195008 | 2026-08-23 01:06:57.195140 | TASK [tox : Remove tempfile] 2026-08-23 01:06:57.761460 | debian-bookworm | changed 2026-08-23 01:06:57.769840 | 2026-08-23 01:06:57.769955 | TASK [tox : Emit tox command] 2026-08-23 01:06:57.842465 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-08-23 01:06:57.857590 | 2026-08-23 01:06:57.857787 | TASK [tox : Run tox] 2026-08-23 01:06:58.528002 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-08-23 01:06:59.107008 | debian-bookworm | ...........2026-08-23 01:06:59.106 | ERROR | contrib.check_updates:main:219 - operational failure: boom 2026-08-23 01:06:59.114523 | 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-23 01:06:59.114562 | debian-bookworm | warnings.warn( 2026-08-23 01:06:59.119243 | debian-bookworm | 2026-08-23 01:06:59.118 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 90 2026-08-23 01:06:59.119578 | debian-bookworm | 2026-08-23 01:06:59.119 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-08-23 01:06:59.119619 | debian-bookworm | 2026-08-23 01:06:59.119 | INFO | openstack_image_manager.main:check_image_age:1283 - Checking for openstack images of age 10 2026-08-23 01:06:59.119637 | debian-bookworm | 2026-08-23 01:06:59.119 | INFO | openstack_image_manager.main:check_image_age:1328 - Image 'Ubuntu 20.04 (1)' was created on 2021-01-01 2026-08-23 01:06:59.119996 | debian-bookworm | 2026-08-23 01:06:59.119 | 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-23 01:06:59.125594 | debian-bookworm | ..2026-08-23 01:06:59.125 | ERROR | openstack_image_manager.main:_download:714 - aria2c is not installed; cannot prefetch image 2026-08-23 01:06:59.129155 | debian-bookworm | .2026-08-23 01:06:59.128 | ERROR | openstack_image_manager.main:_download:745 - aria2c exited with rc=1 for http://x/y 2026-08-23 01:06:59.136075 | debian-bookworm | ..2026-08-23 01:06:59.135 | ERROR | openstack_image_manager.main:_download:739 - aria2c timed out downloading http://x/y 2026-08-23 01:06:59.142860 | debian-bookworm | ..2026-08-23 01:06:59.142 | 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-23 01:06:59.149335 | debian-bookworm | ..2026-08-23 01:06:59.149 | 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-23 01:06:59.155910 | debian-bookworm | ..2026-08-23 01:06:59.155 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: 404 2026-08-23 01:06:59.158974 | debian-bookworm | .2026-08-23 01:06:59.158 | ERROR | openstack_image_manager.main:get_checksum_from_checksums_url:250 - Failed to fetch checksums file from https://url.com/SHA512SUMS: unreachable 2026-08-23 01:06:59.169470 | debian-bookworm | ...2026-08-23 01:06:59.169 | 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-23 01:06:59.172729 | debian-bookworm | .2026-08-23 01:06:59.172 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://x/y; skipping disk check 2026-08-23 01:06:59.176699 | debian-bookworm | .2026-08-23 01:06:59.176 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-23 01:06:59.176724 | debian-bookworm | 2026-08-23 01:06:59.176 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-23 01:06:59.443852 | debian-bookworm | 2026-08-23 01:06:59.443 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-23 01:06:59.446029 | debian-bookworm | 2026-08-23 01:06:59.445 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-23 01:06:59.446512 | debian-bookworm | 2026-08-23 01:06:59.446 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-08-23 01:06:59.456350 | debian-bookworm | .2026-08-23 01:06:59.456 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-23 01:06:59.456386 | debian-bookworm | 2026-08-23 01:06:59.456 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-23 01:06:59.457747 | debian-bookworm | 2026-08-23 01:06:59.457 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-23 01:06:59.458165 | debian-bookworm | 2026-08-23 01:06:59.457 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL file:///path/to/file.img 2026-08-23 01:06:59.458190 | debian-bookworm | 2026-08-23 01:06:59.457 | INFO | openstack_image_manager.main:import_image:539 - Importing local file '/path/to/file.img' as image Ubuntu 20.04 (1) 2026-08-23 01:06:59.464961 | debian-bookworm | .2026-08-23 01:06:59.464 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-23 01:06:59.464990 | debian-bookworm | 2026-08-23 01:06:59.464 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-23 01:06:59.465505 | debian-bookworm | 2026-08-23 01:06:59.465 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-23 01:06:59.465853 | debian-bookworm | 2026-08-23 01:06:59.465 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-23 01:06:59.466198 | debian-bookworm | 2026-08-23 01:06:59.465 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-23 01:06:59.466220 | debian-bookworm | 2026-08-23 01:06:59.465 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-23 01:06:59.466228 | debian-bookworm | 2026-08-23 01:06:59.466 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-23 01:06:59.466807 | debian-bookworm | 2026-08-23 01:06:59.466 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-08-23 01:06:59.472565 | debian-bookworm | .2026-08-23 01:06:59.472 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-23 01:06:59.472590 | debian-bookworm | 2026-08-23 01:06:59.472 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-23 01:06:59.473177 | debian-bookworm | 2026-08-23 01:06:59.472 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-23 01:06:59.473569 | debian-bookworm | 2026-08-23 01:06:59.473 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-23 01:06:59.473592 | debian-bookworm | 2026-08-23 01:06:59.473 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-23 01:06:59.473907 | debian-bookworm | 2026-08-23 01:06:59.473 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-23 01:06:59.473943 | debian-bookworm | 2026-08-23 01:06:59.473 | INFO | openstack_image_manager.main:wait_for_image:834 - Waiting for image to leave queued state... 2026-08-23 01:06:59.474667 | debian-bookworm | 2026-08-23 01:06:59.474 | ERROR | openstack_image_manager.main:wait_for_image:830 - Image seems stuck in queued state 2026-08-23 01:06:59.475029 | debian-bookworm | 2026-08-23 01:06:59.474 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-08-23 01:06:59.719198 | debian-bookworm | 2026-08-23 01:06:59.718 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-23 01:06:59.722238 | debian-bookworm | 2026-08-23 01:06:59.721 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-23 01:06:59.722880 | debian-bookworm | 2026-08-23 01:06:59.722 | INFO | openstack_image_manager.main:_prefetch_import:666 - PREFETCH: glance-direct import succeeded for 'Ubuntu 20.04 (1)' 2026-08-23 01:06:59.742326 | debian-bookworm | ...2026-08-23 01:06:59.741 | ERROR | openstack_image_manager.main:main:366 - Skipping cleanup of outdated images because of previous errors 2026-08-23 01:06:59.832848 | debian-bookworm | .2026-08-23 01:06:59.832 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04' (removal candidate) 2026-08-23 01:06:59.837414 | debian-bookworm | .2026-08-23 01:06:59.837 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-08-23 01:06:59.837443 | debian-bookworm | 2026-08-23 01:06:59.837 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-08-23 01:06:59.837916 | debian-bookworm | 2026-08-23 01:06:59.837 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-08-23 01:06:59.838340 | debian-bookworm | 2026-08-23 01:06:59.837 | INFO | openstack_image_manager.main:manage_outdated_images:1430 - Deleting Ubuntu 20.04_2 2026-08-23 01:06:59.838763 | debian-bookworm | 2026-08-23 01:06:59.838 | INFO | openstack_image_manager.main:manage_outdated_images:1378 - Processing image 'Ubuntu 20.04_2' (removal candidate) 2026-08-23 01:06:59.839137 | debian-bookworm | 2026-08-23 01:06:59.838 | INFO | openstack_image_manager.main:manage_outdated_images:1418 - Deactivating image 'Ubuntu 20.04_2' 2026-08-23 01:06:59.839154 | debian-bookworm | 2026-08-23 01:06:59.838 | INFO | openstack_image_manager.main:manage_outdated_images:1421 - Setting visibility of 'Ubuntu 20.04_2' to 'community' 2026-08-23 01:06:59.839159 | debian-bookworm | 2026-08-23 01:06:59.838 | 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-23 01:06:59.842492 | debian-bookworm | .2026-08-23 01:06:59.842 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-23 01:06:59.842516 | debian-bookworm | 2026-08-23 01:06:59.842 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-23 01:06:59.846232 | debian-bookworm | .2026-08-23 01:06:59.845 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-23 01:06:59.846259 | debian-bookworm | 2026-08-23 01:06:59.846 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-23 01:07:00.112303 | debian-bookworm | 2026-08-23 01:07:00.111 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-23 01:07:00.117652 | debian-bookworm | 2026-08-23 01:07:00.117 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download ok for 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.118226 | debian-bookworm | 2026-08-23 01:07:00.117 | ERROR | openstack_image_manager.main:_prefetch_import:655 - glance-direct import failed for Ubuntu 20.04 (1) 2026-08-23 01:07:00.118270 | debian-bookworm | 409 conflict on fixed id 2026-08-23 01:07:00.118585 | debian-bookworm | 2026-08-23 01:07:00.118 | ERROR | openstack_image_manager.main:_prefetch_import:658 - PREFETCH: glance-direct import failed for 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.130625 | debian-bookworm | .2026-08-23 01:07:00.130 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-23 01:07:00.131111 | debian-bookworm | 2026-08-23 01:07:00.130 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-23 01:07:00.368515 | debian-bookworm | 2026-08-23 01:07:00.367 | WARNING | openstack_image_manager.main:_has_space_for_download:684 - No Content-Length for http://url.com; skipping disk check 2026-08-23 01:07:00.372206 | debian-bookworm | 2026-08-23 01:07:00.371 | INFO | openstack_image_manager.main:_prefetch_import:636 - PREFETCH: aria2 download failed for 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.384805 | debian-bookworm | .2026-08-23 01:07:00.384 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.385746 | debian-bookworm | 2026-08-23 01:07:00.385 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-23 01:07:00.387102 | debian-bookworm | 2026-08-23 01:07:00.386 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-08-23 01:07:00.389200 | debian-bookworm | 2026-08-23 01:07:00.388 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.389764 | debian-bookworm | 2026-08-23 01:07:00.389 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04 (1)' successfully completed, reloading images 2026-08-23 01:07:00.390457 | debian-bookworm | 2026-08-23 01:07:00.390 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.390907 | debian-bookworm | 2026-08-23 01:07:00.390 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-23 01:07:00.391347 | debian-bookworm | 2026-08-23 01:07:00.390 | INFO | openstack_image_manager.main:process_image:1020 - Skipping required import of image 'Ubuntu 20.04 (1)', running in dry-run mode 2026-08-23 01:07:00.399878 | debian-bookworm | .2026-08-23 01:07:00.395 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04 (latest)' 2026-08-23 01:07:00.399904 | debian-bookworm | 2026-08-23 01:07:00.396 | 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-23 01:07:00.401507 | debian-bookworm | .2026-08-23 01:07:00.401 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-1' 2026-08-23 01:07:00.402158 | debian-bookworm | 2026-08-23 01:07:00.401 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com: 200 2026-08-23 01:07:00.402948 | debian-bookworm | 2026-08-23 01:07:00.402 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-1' successfully completed, reloading images 2026-08-23 01:07:00.408575 | debian-bookworm | .2026-08-23 01:07:00.408 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(1)' 2026-08-23 01:07:00.409067 | debian-bookworm | 2026-08-23 01:07:00.408 | INFO | openstack_image_manager.main:process_image:1025 - Skipping image 'Ubuntu 20.04-(1)' (only importing the latest version from type multi) 2026-08-23 01:07:00.409091 | debian-bookworm | 2026-08-23 01:07:00.408 | INFO | openstack_image_manager.main:process_image:886 - Processing image 'Ubuntu 20.04-(2)' 2026-08-23 01:07:00.411487 | debian-bookworm | 2026-08-23 01:07:00.411 | INFO | openstack_image_manager.main:process_image:992 - Tested URL http://url.com2: 200 2026-08-23 01:07:00.412310 | debian-bookworm | 2026-08-23 01:07:00.412 | INFO | openstack_image_manager.main:process_image:1014 - Import of 'Ubuntu 20.04-(2)' successfully completed, reloading images 2026-08-23 01:07:00.427977 | debian-bookworm | ...2026-08-23 01:07:00.427 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.428033 | debian-bookworm | 2026-08-23 01:07:00.427 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-23 01:07:00.428575 | debian-bookworm | 2026-08-23 01:07:00.428 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.428802 | debian-bookworm | 2026-08-23 01:07:00.428 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-23 01:07:00.429256 | debian-bookworm | 2026-08-23 01:07:00.429 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04 (1) to Ubuntu 20.04 2026-08-23 01:07:00.433099 | debian-bookworm | .2026-08-23 01:07:00.432 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-08-23 01:07:00.433703 | debian-bookworm | 2026-08-23 01:07:00.433 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-23 01:07:00.434142 | debian-bookworm | 2026-08-23 01:07:00.433 | INFO | openstack_image_manager.main:rename_images:1262 - Renaming old latest 'Ubuntu 20.04' to 'Ubuntu 20.04-(1)' 2026-08-23 01:07:00.434587 | debian-bookworm | 2026-08-23 01:07:00.434 | INFO | openstack_image_manager.main:rename_images:1265 - Renaming imported image 'Ubuntu 20.04' to 'Ubuntu 20.04' 2026-08-23 01:07:00.434607 | debian-bookworm | 2026-08-23 01:07:00.434 | INFO | openstack_image_manager.main:rename_images:1272 - Renaming Ubuntu 20.04-(1) to Ubuntu 20.04 2026-08-23 01:07:00.471700 | debian-bookworm | ..2026-08-23 01:07:00.471 | INFO | openstack_image_manager.main:set_properties:1063 - Checking parameters of 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.472241 | debian-bookworm | 2026-08-23 01:07:00.471 | INFO | openstack_image_manager.main:set_properties:1129 - Setting internal_version = 1 2026-08-23 01:07:00.472282 | debian-bookworm | 2026-08-23 01:07:00.471 | INFO | openstack_image_manager.main:set_properties:1132 - Setting image_original_user = ubuntu 2026-08-23 01:07:00.472296 | debian-bookworm | 2026-08-23 01:07:00.472 | INFO | openstack_image_manager.main:set_properties:1145 - Adding tag my_tag 2026-08-23 01:07:00.472980 | debian-bookworm | 2026-08-23 01:07:00.472 | INFO | openstack_image_manager.main:set_properties:1150 - Deleting tag fake_tag 2026-08-23 01:07:00.473730 | debian-bookworm | 2026-08-23 01:07:00.473 | INFO | openstack_image_manager.main:set_properties:1161 - Setting property image_build_date: 2021-01-01 != 2021-01-21 2026-08-23 01:07:00.474546 | debian-bookworm | 2026-08-23 01:07:00.474 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_distro: ubuntu 2026-08-23 01:07:00.475001 | debian-bookworm | 2026-08-23 01:07:00.474 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property os_version: 20.04 2026-08-23 01:07:00.475368 | debian-bookworm | 2026-08-23 01:07:00.475 | INFO | openstack_image_manager.main:set_properties:1178 - Setting property image_source: http://url.com 2026-08-23 01:07:00.475716 | debian-bookworm | 2026-08-23 01:07:00.475 | INFO | openstack_image_manager.main:set_properties:1185 - Checking status of 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.475744 | debian-bookworm | 2026-08-23 01:07:00.475 | INFO | openstack_image_manager.main:set_properties:1190 - Deactivating image 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.476419 | debian-bookworm | 2026-08-23 01:07:00.476 | INFO | openstack_image_manager.main:set_properties:1197 - Checking visibility of 'Ubuntu 20.04 (1)' 2026-08-23 01:07:00.506883 | debian-bookworm | ...2026-08-23 01:07:00.506 | ERROR | openstack_image_manager.main:wait_for_image:814 - Image import timed out 2026-08-23 01:07:00.511572 | debian-bookworm | .2026-08-23 01:07:00.511 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-23 01:07:00.511670 | debian-bookworm | boom 2026-08-23 01:07:00.512146 | debian-bookworm | 2026-08-23 01:07:00.511 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-23 01:07:00.512174 | debian-bookworm | boom 2026-08-23 01:07:00.512468 | debian-bookworm | 2026-08-23 01:07:00.512 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-23 01:07:00.512492 | debian-bookworm | boom 2026-08-23 01:07:00.512831 | debian-bookworm | 2026-08-23 01:07:00.512 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-23 01:07:00.512895 | debian-bookworm | boom 2026-08-23 01:07:00.513374 | debian-bookworm | 2026-08-23 01:07:00.512 | ERROR | openstack_image_manager.main:wait_for_image:846 - Exception while importing image 2026-08-23 01:07:00.513434 | debian-bookworm | boom 2026-08-23 01:07:00.513447 | debian-bookworm | 2026-08-23 01:07:00.512 | ERROR | openstack_image_manager.main:wait_for_image:848 - Giving up on image after repeated errors 2026-08-23 01:07:00.518253 | debian-bookworm | .2026-08-23 01:07:00.518 | ERROR | openstack_image_manager.main:wait_for_image:837 - Image entered terminal state 'killed' 2026-08-23 01:07:00.522219 | debian-bookworm | .2026-08-23 01:07:00.522 | INFO | openstack_image_manager.main:import_image:518 - Importing image Ubuntu 20.04 (1) 2026-08-23 01:07:00.522561 | debian-bookworm | 2026-08-23 01:07:00.522 | INFO | openstack_image_manager.main:import_image:519 - Importing from URL http://url.com 2026-08-23 01:07:00.522592 | debian-bookworm | 2026-08-23 01:07:00.522 | ERROR | openstack_image_manager.main:import_image:584 - Web-download import for image Ubuntu 20.04 (1) failed 2026-08-23 01:07:00.522631 | debian-bookworm | glance api error 2026-08-23 01:07:00.522899 | debian-bookworm | 2026-08-23 01:07:00.522 | ERROR | openstack_image_manager.main:import_image:602 - PREFETCH: fallback triggered for 'Ubuntu 20.04 (1)' (web-download failed) 2026-08-23 01:07:00.538417 | debian-bookworm | .......2026-08-23 01:07:00 | INFO  | main:223 - Processing file /tmp/tmp48zqf55b/example.yml 2026-08-23 01:07:00.544575 | debian-bookworm | 2026-08-23 01:07:00 | INFO  | main:232 - Skipping disabled image Example 1.0 2026-08-23 01:07:00.552381 | debian-bookworm | .2026-08-23 01:07:00 | INFO  | main:223 - Processing file /tmp/tmpy_mp7_od/example.yml 2026-08-23 01:07:00.555706 | debian-bookworm | 2026-08-23 01:07:00 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-23 01:07:00.556169 | debian-bookworm | 2026-08-23 01:07:00 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-08-23 01:07:00.556211 | debian-bookworm | 2026-08-23 01:07:00 | 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-23 01:07:00.558856 | debian-bookworm | 2026-08-23 01:07:00 | INFO  | main:242 - Dry-run enabled, not writing /tmp/tmpy_mp7_od/example.yml 2026-08-23 01:07:00.566260 | debian-bookworm | .2026-08-23 01:07:00 | INFO  | main:223 - Processing file /tmp/tmp4h9t34ww/example.yml 2026-08-23 01:07:00.569507 | debian-bookworm | 2026-08-23 01:07:00 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-23 01:07:00.569954 | debian-bookworm | 2026-08-23 01:07:00 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:0000000000000000000000000000000000000000000000000000000000000000 2026-08-23 01:07:00.569995 | debian-bookworm | 2026-08-23 01:07:00 | INFO  | update_image:158 - Image Example 1.0 is up-to-date, nothing to do 2026-08-23 01:07:00.577159 | debian-bookworm | .2026-08-23 01:07:00 | INFO  | main:223 - Processing file /tmp/tmpvymqbfpm/example.yml 2026-08-23 01:07:00.580286 | debian-bookworm | 2026-08-23 01:07:00 | INFO  | update_image:144 - Checking image Example 1.0 2026-08-23 01:07:00.580603 | debian-bookworm | 2026-08-23 01:07:00 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-08-23 01:07:00.580639 | debian-bookworm | 2026-08-23 01:07:00 | 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-23 01:07:00.583989 | debian-bookworm | . 2026-08-23 01:07:00.584021 | debian-bookworm | ---------------------------------------------------------------------- 2026-08-23 01:07:00.584032 | debian-bookworm | Ran 74 tests in 1.500s 2026-08-23 01:07:00.584043 | debian-bookworm | 2026-08-23 01:07:00.584052 | debian-bookworm | OK 2026-08-23 01:07:00.707666 | debian-bookworm | test: OK (2.22=setup[0.05]+cmd[2.17] seconds) 2026-08-23 01:07:00.707741 | debian-bookworm | congratulations :) (2.25 seconds) 2026-08-23 01:07:00.901693 | debian-bookworm | ok: Runtime: 0:00:02.603126 2026-08-23 01:07:00.909774 | 2026-08-23 01:07:00.909900 | TASK [tox : Look for output] 2026-08-23 01:07:01.489270 | debian-bookworm | ok 2026-08-23 01:07:01.505580 | 2026-08-23 01:07:01.505723 | TASK [tox : Return file comments to Zuul] 2026-08-23 01:07:01.540832 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:01.560801 | 2026-08-23 01:07:01.560917 | PLAY RECAP 2026-08-23 01:07:01.560973 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-08-23 01:07:01.560998 | 2026-08-23 01:07:01.797670 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-08-23 01:07:01.799020 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-08-23 01:07:02.778269 | 2026-08-23 01:07:02.778517 | PLAY [all] 2026-08-23 01:07:02.803840 | 2026-08-23 01:07:02.804022 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-08-23 01:07:02.860449 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:02.867724 | 2026-08-23 01:07:02.867918 | TASK [fetch-tox-output : Set tox log path for single node] 2026-08-23 01:07:02.913260 | debian-bookworm | ok 2026-08-23 01:07:02.921717 | 2026-08-23 01:07:02.921855 | TASK [fetch-tox-output : Ensure local tox dir] 2026-08-23 01:07:03.602771 | debian-bookworm -> localhost | changed 2026-08-23 01:07:03.611702 | 2026-08-23 01:07:03.611833 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-08-23 01:07:03.681256 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:03.687925 | 2026-08-23 01:07:03.688055 | TASK [fetch-tox-output : Set envlist fact] 2026-08-23 01:07:03.749090 | debian-bookworm | ok 2026-08-23 01:07:03.755529 | 2026-08-23 01:07:03.755644 | TASK [fetch-tox-output : Get tox version] 2026-08-23 01:07:04.563697 | 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-23 01:07:04.582691 | debian-bookworm | 4.60.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-08-23 01:07:04.794888 | debian-bookworm | ok: Runtime: 0:00:00.336474 2026-08-23 01:07:04.805970 | 2026-08-23 01:07:04.806131 | TASK [fetch-tox-output : Find all default environments] 2026-08-23 01:07:05.351709 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:05.359444 | 2026-08-23 01:07:05.359597 | TASK [fetch-tox-output : Set envlist fact] 2026-08-23 01:07:05.394906 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:05.406851 | 2026-08-23 01:07:05.408834 | TASK [fetch-tox-output : Find all default environments] 2026-08-23 01:07:05.941186 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:05.956438 | 2026-08-23 01:07:05.956589 | TASK [fetch-tox-output : Set envlist fact] 2026-08-23 01:07:05.991648 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:06.004282 | 2026-08-23 01:07:06.004458 | LOOP [fetch-tox-output : Copy tox logs] 2026-08-23 01:07:06.105725 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:06.119051 | 2026-08-23 01:07:06.119220 | LOOP [fetch-tox-output : Collect tox logs] 2026-08-23 01:07:07.140631 | debian-bookworm | changed: 2026-08-23 01:07:07.140903 | debian-bookworm | created directory /var/lib/zuul/builds/0da71412732c4638ac85e19b0eaddadc/work/logs/tox/test 2026-08-23 01:07:07.140947 | debian-bookworm | cd+++++++++ ./ 2026-08-23 01:07:07.140979 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-08-23 01:07:07.141019 | 2026-08-23 01:07:07.151147 | 2026-08-23 01:07:07.151258 | PLAY RECAP 2026-08-23 01:07:07.151315 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-08-23 01:07:07.151361 | 2026-08-23 01:07:07.344124 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-08-23 01:07:07.345301 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-08-23 01:07:08.398929 | 2026-08-23 01:07:08.399125 | PLAY [all] 2026-08-23 01:07:08.416177 | 2026-08-23 01:07:08.416365 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-08-23 01:07:08.659691 | debian-bookworm | changed: non-zero return code 2026-08-23 01:07:08.665807 | 2026-08-23 01:07:08.665937 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-08-23 01:07:08.691414 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:08.698072 | 2026-08-23 01:07:08.698204 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-08-23 01:07:08.734813 | 2026-08-23 01:07:08.736198 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-08-23 01:07:08.771482 | 2026-08-23 01:07:08.771746 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-08-23 01:07:08.797849 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:08.804795 | 2026-08-23 01:07:08.804911 | LOOP [fetch-subunit-output : Generate subunit file] 2026-08-23 01:07:08.834440 | 2026-08-23 01:07:08.834637 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-08-23 01:07:08.859324 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:08.872707 | 2026-08-23 01:07:08.872836 | TASK [fetch-subunit-output : Remove the temporary file] 2026-08-23 01:07:08.900000 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:08.906607 | 2026-08-23 01:07:08.906720 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-08-23 01:07:08.961209 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:08.968163 | 2026-08-23 01:07:08.968251 | PLAY RECAP 2026-08-23 01:07:08.968306 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-08-23 01:07:08.968365 | 2026-08-23 01:07:09.175414 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-08-23 01:07:09.177291 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-23 01:07:10.051483 | 2026-08-23 01:07:10.051683 | PLAY [Base post-fetch] 2026-08-23 01:07:10.083134 | 2026-08-23 01:07:10.083308 | TASK [diagnose-network : Ensure log directory exists] 2026-08-23 01:07:10.507954 | debian-bookworm | ok 2026-08-23 01:07:10.529165 | 2026-08-23 01:07:10.529911 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-08-23 01:07:22.977496 | debian-bookworm | ok: Runtime: 0:00:06.406439 2026-08-23 01:07:22.986205 | 2026-08-23 01:07:22.986371 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-08-23 01:07:29.049050 | debian-bookworm | ok: Runtime: 0:00:00.008572 2026-08-23 01:07:29.058801 | 2026-08-23 01:07:29.059053 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-08-23 01:07:35.144463 | debian-bookworm | ok: Runtime: 0:00:00.009659 2026-08-23 01:07:35.159227 | 2026-08-23 01:07:35.159369 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-23 01:07:35.215592 | debian-bookworm | skipping: Conditional result was False 2026-08-23 01:07:35.226615 | 2026-08-23 01:07:35.226749 | TASK [fetch-output : Set log path for single node] 2026-08-23 01:07:35.282720 | debian-bookworm | ok 2026-08-23 01:07:35.288791 | 2026-08-23 01:07:35.288916 | LOOP [fetch-output : Ensure local output dirs] 2026-08-23 01:07:35.629414 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/0da71412732c4638ac85e19b0eaddadc/work/logs" 2026-08-23 01:07:35.924558 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0da71412732c4638ac85e19b0eaddadc/work/artifacts" 2026-08-23 01:07:36.212436 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0da71412732c4638ac85e19b0eaddadc/work/docs" 2026-08-23 01:07:36.243603 | 2026-08-23 01:07:36.244366 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-23 01:07:37.071351 | debian-bookworm | changed: 2026-08-23 01:07:37.071735 | debian-bookworm | .d..t...... ./ 2026-08-23 01:07:37.071797 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-23 01:07:37.071838 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-23 01:07:37.071875 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-23 01:07:37.071910 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-23 01:07:37.071944 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-23 01:07:37.072007 | debian-bookworm | changed: All items complete 2026-08-23 01:07:37.072048 | 2026-08-23 01:07:37.657820 | debian-bookworm | changed: .d..t...... ./ 2026-08-23 01:07:38.224227 | debian-bookworm | changed: .d..t...... ./ 2026-08-23 01:07:38.241204 | 2026-08-23 01:07:38.241366 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-23 01:07:38.622432 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.013914 2026-08-23 01:07:38.934781 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010915 2026-08-23 01:07:38.958099 | 2026-08-23 01:07:38.958278 | PLAY RECAP 2026-08-23 01:07:38.958393 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-23 01:07:38.958439 | 2026-08-23 01:07:39.142895 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-23 01:07:39.146284 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-23 01:07:39.989048 | 2026-08-23 01:07:39.989254 | PLAY [Base post] 2026-08-23 01:07:40.006418 | 2026-08-23 01:07:40.006591 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-23 01:07:40.604268 | debian-bookworm | changed 2026-08-23 01:07:40.611255 | 2026-08-23 01:07:40.611403 | PLAY RECAP 2026-08-23 01:07:40.611470 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-23 01:07:40.611530 | 2026-08-23 01:07:40.756998 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-23 01:07:40.758244 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-23 01:07:41.585804 | 2026-08-23 01:07:41.586011 | PLAY [Base post-logs] 2026-08-23 01:07:41.597729 | 2026-08-23 01:07:41.597932 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-23 01:07:42.092252 | localhost | changed 2026-08-23 01:07:42.107240 | 2026-08-23 01:07:42.107494 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-23 01:07:42.135043 | localhost | ok 2026-08-23 01:07:42.139944 | 2026-08-23 01:07:42.140083 | TASK [Set zuul-log-path fact] 2026-08-23 01:07:42.157621 | localhost | ok 2026-08-23 01:07:42.171985 | 2026-08-23 01:07:42.172154 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-23 01:07:42.246072 | localhost | ok 2026-08-23 01:07:42.254150 | 2026-08-23 01:07:42.254280 | TASK [upload-logs : Create log directories] 2026-08-23 01:07:42.836356 | localhost | changed 2026-08-23 01:07:42.842511 | 2026-08-23 01:07:42.842687 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-23 01:07:43.389530 | localhost -> localhost | ok: Runtime: 0:00:00.006041 2026-08-23 01:07:43.394234 | 2026-08-23 01:07:43.394385 | TASK [upload-logs : Upload logs to log server] 2026-08-23 01:07:44.014452 | localhost | Output suppressed because no_log was given 2026-08-23 01:07:44.019505 | 2026-08-23 01:07:44.019701 | LOOP [upload-logs : Compress console log and json output] 2026-08-23 01:07:44.085092 | localhost | skipping: Conditional result was False 2026-08-23 01:07:44.093496 | localhost | skipping: Conditional result was False 2026-08-23 01:07:44.103629 | 2026-08-23 01:07:44.103911 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-23 01:07:44.156372 | localhost | skipping: Conditional result was False 2026-08-23 01:07:44.156684 | 2026-08-23 01:07:44.163580 | localhost | skipping: Conditional result was False 2026-08-23 01:07:44.178432 | 2026-08-23 01:07:44.178685 | LOOP [upload-logs : Upload console log and json output]