2026-07-20 09:05:44.490141 | Job console starting 2026-07-20 09:05:44.512098 | Updating git repos 2026-07-20 09:05:44.575199 | Cloning repos into workspace 2026-07-20 09:05:44.660068 | Restoring repo states 2026-07-20 09:05:44.669816 | Merging changes 2026-07-20 09:05:45.572903 | Checking out repos 2026-07-20 09:05:45.664761 | Preparing playbooks 2026-07-20 09:05:47.134709 | Running Ansible setup 2026-07-20 09:05:52.703169 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-20 09:05:54.032550 | 2026-07-20 09:05:54.032783 | PLAY [Base pre] 2026-07-20 09:05:54.075451 | 2026-07-20 09:05:54.075655 | TASK [Setup log path fact] 2026-07-20 09:05:54.118900 | debian-bookworm | ok 2026-07-20 09:05:54.156019 | 2026-07-20 09:05:54.156217 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-20 09:05:54.205032 | debian-bookworm | ok 2026-07-20 09:05:54.223286 | 2026-07-20 09:05:54.223457 | TASK [emit-job-header : Print job information] 2026-07-20 09:05:54.269219 | # Job Information 2026-07-20 09:05:54.269426 | Ansible Version: 2.16.14 2026-07-20 09:05:54.269462 | Job: tox 2026-07-20 09:05:54.269493 | Pipeline: check 2026-07-20 09:05:54.269518 | Executor: 521e9411259a 2026-07-20 09:05:54.269540 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1255 2026-07-20 09:05:54.269563 | Event ID: 2a5fbed0-841a-11f1-8850-5dcb6d749181 2026-07-20 09:05:54.275035 | 2026-07-20 09:05:54.275176 | LOOP [emit-job-header : Print node information] 2026-07-20 09:05:54.526317 | debian-bookworm | ok: 2026-07-20 09:05:54.527522 | debian-bookworm | # Node Information 2026-07-20 09:05:54.527646 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-20 09:05:54.527678 | debian-bookworm | Hostname: debian 2026-07-20 09:05:54.527701 | debian-bookworm | Username: zuul 2026-07-20 09:05:54.527723 | debian-bookworm | Distro: Debian 12.15 2026-07-20 09:05:54.527747 | debian-bookworm | Provider: regiocloud-a 2026-07-20 09:05:54.527768 | debian-bookworm | Region: 2026-07-20 09:05:54.527789 | debian-bookworm | Label: debian-bookworm 2026-07-20 09:05:54.527809 | debian-bookworm | Product Name: OpenStack Nova 2026-07-20 09:05:54.527828 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4d:d5a5 2026-07-20 09:05:54.558943 | 2026-07-20 09:05:54.559136 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-20 09:05:55.384548 | debian-bookworm -> localhost | changed 2026-07-20 09:05:55.392829 | 2026-07-20 09:05:55.392992 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-20 09:05:57.120154 | debian-bookworm -> localhost | changed 2026-07-20 09:05:57.139989 | 2026-07-20 09:05:57.140159 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-20 09:05:57.688337 | debian-bookworm -> localhost | ok 2026-07-20 09:05:57.695862 | 2026-07-20 09:05:57.696012 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-20 09:05:57.755730 | debian-bookworm | ok 2026-07-20 09:05:57.796544 | debian-bookworm | included: /var/lib/zuul/builds/45dc5580e23d44eba0174e98ceba1983/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-20 09:05:57.802827 | 2026-07-20 09:05:57.802958 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-20 09:06:00.363185 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-20 09:06:00.363365 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/45dc5580e23d44eba0174e98ceba1983/work/45dc5580e23d44eba0174e98ceba1983_id_rsa 2026-07-20 09:06:00.363398 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/45dc5580e23d44eba0174e98ceba1983/work/45dc5580e23d44eba0174e98ceba1983_id_rsa.pub 2026-07-20 09:06:00.363422 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-20 09:06:00.363443 | debian-bookworm -> localhost | SHA256:myMabrQGJR58yuH0p56k9gXC1YT5nST6kBQCUiRtZUk zuul-build-sshkey 2026-07-20 09:06:00.363462 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-20 09:06:00.363490 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-20 09:06:00.363508 | debian-bookworm -> localhost | |+=+oE=. | 2026-07-20 09:06:00.363526 | debian-bookworm -> localhost | |..oo=o. . | 2026-07-20 09:06:00.363543 | debian-bookworm -> localhost | | o ..+.+ . | 2026-07-20 09:06:00.363560 | debian-bookworm -> localhost | | .*.* . o | 2026-07-20 09:06:00.363576 | debian-bookworm -> localhost | | =oO.o S | 2026-07-20 09:06:00.363609 | debian-bookworm -> localhost | | *.o.o o | 2026-07-20 09:06:00.363628 | debian-bookworm -> localhost | | oo+o + | 2026-07-20 09:06:00.363644 | debian-bookworm -> localhost | | .+== . . | 2026-07-20 09:06:00.363662 | debian-bookworm -> localhost | | ..=* | 2026-07-20 09:06:00.363679 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-20 09:06:00.363725 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.659713 2026-07-20 09:06:00.368939 | 2026-07-20 09:06:00.369016 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-20 09:06:00.385758 | debian-bookworm | ok 2026-07-20 09:06:00.394225 | debian-bookworm | included: /var/lib/zuul/builds/45dc5580e23d44eba0174e98ceba1983/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-20 09:06:00.401445 | 2026-07-20 09:06:00.401515 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-20 09:06:00.414216 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:06:00.418997 | 2026-07-20 09:06:00.419199 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-20 09:06:01.062925 | debian-bookworm | changed 2026-07-20 09:06:01.077876 | 2026-07-20 09:06:01.078051 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-20 09:06:01.342254 | debian-bookworm | ok 2026-07-20 09:06:01.349649 | 2026-07-20 09:06:01.349763 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-20 09:06:02.013048 | debian-bookworm | changed 2026-07-20 09:06:02.017436 | 2026-07-20 09:06:02.017510 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-20 09:06:02.638256 | debian-bookworm | changed 2026-07-20 09:06:02.644004 | 2026-07-20 09:06:02.644100 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-20 09:06:02.667455 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:06:02.673370 | 2026-07-20 09:06:02.673462 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-20 09:06:03.171622 | debian-bookworm -> localhost | changed 2026-07-20 09:06:03.182345 | 2026-07-20 09:06:03.182453 | TASK [add-build-sshkey : Add back temp key] 2026-07-20 09:06:03.511679 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/45dc5580e23d44eba0174e98ceba1983/work/45dc5580e23d44eba0174e98ceba1983_id_rsa (zuul-build-sshkey) 2026-07-20 09:06:03.511905 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008987 2026-07-20 09:06:03.522413 | 2026-07-20 09:06:03.522529 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-20 09:06:03.920954 | debian-bookworm | ok 2026-07-20 09:06:03.928351 | 2026-07-20 09:06:03.928491 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-20 09:06:03.952521 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:06:03.972814 | 2026-07-20 09:06:03.972959 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-20 09:06:04.386636 | debian-bookworm | ok 2026-07-20 09:06:04.406673 | 2026-07-20 09:06:04.406790 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-20 09:06:04.436411 | debian-bookworm | ok 2026-07-20 09:06:04.444177 | 2026-07-20 09:06:04.444292 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-20 09:06:04.710683 | debian-bookworm -> localhost | ok 2026-07-20 09:06:04.717040 | 2026-07-20 09:06:04.717145 | TASK [validate-host : Collect information about the host] 2026-07-20 09:06:05.859747 | debian-bookworm | ok 2026-07-20 09:06:05.871154 | 2026-07-20 09:06:05.871263 | TASK [validate-host : Sanitize hostname] 2026-07-20 09:06:05.978357 | debian-bookworm | ok 2026-07-20 09:06:05.983273 | 2026-07-20 09:06:05.983403 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-20 09:06:06.729202 | debian-bookworm -> localhost | changed 2026-07-20 09:06:06.735504 | 2026-07-20 09:06:06.735623 | TASK [validate-host : Collect information about zuul worker] 2026-07-20 09:06:07.140228 | debian-bookworm | ok 2026-07-20 09:06:07.144826 | 2026-07-20 09:06:07.144918 | TASK [validate-host : Write out all zuul information for each host] 2026-07-20 09:06:07.750181 | debian-bookworm -> localhost | changed 2026-07-20 09:06:07.759682 | 2026-07-20 09:06:07.759780 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-20 09:06:08.035124 | debian-bookworm | ok 2026-07-20 09:06:08.044049 | 2026-07-20 09:06:08.044179 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-20 09:06:08.988288 | debian-bookworm | changed: 2026-07-20 09:06:08.988639 | debian-bookworm | cd+++++++++ src/ 2026-07-20 09:06:08.988698 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-20 09:06:08.988735 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-20 09:06:08.988767 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-07-20 09:06:08.988798 | debian-bookworm | =7.0.3 2026-07-20 09:06:45.590175 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-07-20 09:06:45.630109 | debian-bookworm | Collecting colorama>=0.4.6 2026-07-20 09:06:45.641984 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-07-20 09:06:45.706713 | debian-bookworm | Collecting filelock>=3.25 2026-07-20 09:06:45.719744 | debian-bookworm | Downloading filelock-3.31.1-py3-none-any.whl (97 kB) 2026-07-20 09:06:45.732830 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 97.2/97.2 kB 11.8 MB/s eta 0:00:00 2026-07-20 09:06:45.792749 | debian-bookworm | Collecting packaging>=26 2026-07-20 09:06:45.804568 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-07-20 09:06:45.815623 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 11.2 MB/s eta 0:00:00 2026-07-20 09:06:45.866999 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-07-20 09:06:45.878361 | debian-bookworm | Downloading platformdirs-4.10.1-py3-none-any.whl (22 kB) 2026-07-20 09:06:45.934621 | debian-bookworm | Collecting pluggy>=1.6 2026-07-20 09:06:45.946510 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-07-20 09:06:45.988325 | debian-bookworm | Collecting pyproject-api>=1.10 2026-07-20 09:06:46.001209 | debian-bookworm | Downloading pyproject_api-1.10.1-py3-none-any.whl (12 kB) 2026-07-20 09:06:46.037111 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-07-20 09:06:46.048713 | debian-bookworm | Downloading python_discovery-1.4.4-py3-none-any.whl (34 kB) 2026-07-20 09:06:46.081482 | debian-bookworm | Collecting tomli-w>=1.2 2026-07-20 09:06:46.092871 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-07-20 09:06:46.229538 | debian-bookworm | Collecting virtualenv>=21.1 2026-07-20 09:06:46.241839 | debian-bookworm | Downloading virtualenv-21.6.1-py3-none-any.whl (5.5 MB) 2026-07-20 09:06:46.530223 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.5/5.5 MB 19.3 MB/s eta 0:00:00 2026-07-20 09:06:46.626494 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-07-20 09:06:46.637278 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-07-20 09:06:46.659912 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 27.5 MB/s eta 0:00:00 2026-07-20 09:06:46.777007 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-07-20 09:06:47.575845 | debian-bookworm | Successfully installed cachetools-7.1.4 colorama-0.4.6 distlib-0.4.3 filelock-3.31.1 packaging-26.2 platformdirs-4.10.1 pluggy-1.6.0 pyproject-api-1.10.1 python-discovery-1.4.4 tomli-w-1.2.0 tox-4.57.0 virtualenv-21.6.1 2026-07-20 09:06:47.978125 | debian-bookworm | ok: Runtime: 0:00:02.965549 2026-07-20 09:06:47.996414 | 2026-07-20 09:06:47.996710 | TASK [ensure-tox : Export installed tox_executable path] 2026-07-20 09:06:48.048986 | debian-bookworm | ok 2026-07-20 09:06:48.056365 | 2026-07-20 09:06:48.056504 | TASK [ensure-tox : Output tox version] 2026-07-20 09:06:48.634017 | 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-07-20 09:06:48.651405 | debian-bookworm | 4.57.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-20 09:06:49.091793 | debian-bookworm | ok: Runtime: 0:00:00.349157 2026-07-20 09:06:49.106315 | 2026-07-20 09:06:49.106508 | TASK [ensure-tox : Make global symlink] 2026-07-20 09:06:49.134333 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:06:49.144256 | 2026-07-20 09:06:49.144354 | PLAY RECAP 2026-07-20 09:06:49.144417 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-07-20 09:06:49.144445 | 2026-07-20 09:06:49.314946 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-07-20 09:06:49.316938 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-20 09:06:50.142025 | 2026-07-20 09:06:50.142235 | PLAY [all] 2026-07-20 09:06:50.162433 | 2026-07-20 09:06:50.162656 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-07-20 09:06:50.678467 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-20 09:06:50.747271 | debian-bookworm | ok: Runtime: 0:00:00.031187 2026-07-20 09:06:50.755650 | 2026-07-20 09:06:50.755828 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-07-20 09:06:51.290250 | debian-bookworm | ok: Runtime: 0:00:00.007880 2026-07-20 09:06:51.303453 | 2026-07-20 09:06:51.303682 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-07-20 09:06:51.604558 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-20 09:06:51.608479 | debian-bookworm | sudo: a password is required 2026-07-20 09:06:51.838475 | debian-bookworm | ok: Runtime: 0:00:00.024382 2026-07-20 09:06:51.859740 | 2026-07-20 09:06:51.859967 | TASK [tox : Check to see if the constraints file exists] 2026-07-20 09:06:51.896264 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:06:51.910421 | 2026-07-20 09:06:51.910626 | TASK [tox : Fail if constraints file is missing] 2026-07-20 09:06:51.938069 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:06:51.951422 | 2026-07-20 09:06:51.951649 | TASK [tox : Record file location] 2026-07-20 09:06:51.976476 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:06:51.986248 | 2026-07-20 09:06:51.986479 | TASK [tox : Install tox siblings] 2026-07-20 09:06:52.027149 | debian-bookworm | ok 2026-07-20 09:06:52.042171 | debian-bookworm | included: /var/lib/zuul/builds/45dc5580e23d44eba0174e98ceba1983/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-07-20 09:06:52.049094 | 2026-07-20 09:06:52.049218 | TASK [tox : Run tox without tests] 2026-07-20 09:06:53.364326 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-07-20 09:07:06.833741 | debian-bookworm | test: OK (14.20 seconds) 2026-07-20 09:07:06.833795 | debian-bookworm | congratulations :) (14.23 seconds) 2026-07-20 09:07:07.087846 | debian-bookworm | ok: Runtime: 0:00:14.551391 2026-07-20 09:07:07.104702 | 2026-07-20 09:07:07.104968 | TASK [tox : Create a tempfile to save tox showconfig] 2026-07-20 09:07:07.579297 | debian-bookworm | changed 2026-07-20 09:07:07.590076 | 2026-07-20 09:07:07.590240 | TASK [tox : Detect tox version] 2026-07-20 09:07:08.188705 | debian-bookworm | 4.57.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-20 09:07:08.625858 | debian-bookworm | ok: Runtime: 0:00:00.383997 2026-07-20 09:07:08.636905 | 2026-07-20 09:07:08.637121 | TASK [tox : Get tox envlist config] 2026-07-20 09:07:09.675009 | debian-bookworm | ok: Runtime: 0:00:00.386665 2026-07-20 09:07:09.681494 | 2026-07-20 09:07:09.681647 | TASK [tox : Install any sibling python packages] 2026-07-20 09:07:10.174936 | debian-bookworm | ok: No setup.cfg, no action needed 2026-07-20 09:07:10.181223 | 2026-07-20 09:07:10.181341 | TASK [tox : Remove tempfile] 2026-07-20 09:07:10.683577 | debian-bookworm | changed 2026-07-20 09:07:10.689536 | 2026-07-20 09:07:10.689665 | TASK [tox : Emit tox command] 2026-07-20 09:07:10.729689 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-07-20 09:07:10.735071 | 2026-07-20 09:07:10.735189 | TASK [tox : Run tox] 2026-07-20 09:07:11.366797 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-07-20 09:07:13.698626 | debian-bookworm | ...................................................2026-07-20 09:07:13 | INFO  | main:223 - Processing file /tmp/tmp710_jnuy/example.yml 2026-07-20 09:07:13.704685 | debian-bookworm | 2026-07-20 09:07:13 | INFO  | update_image:144 - Checking image Example 1.0 2026-07-20 09:07:13.704722 | debian-bookworm | 2026-07-20 09:07:13 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-07-20 09:07:13.705199 | debian-bookworm | 2026-07-20 09:07:13 | INFO  | update_image:178 - New URL is https://nbg1.your-objectstorage.com/osism/openstack-images/example-1.0/20260101-example-1.0.qcow2 2026-07-20 09:07:13.707453 | debian-bookworm | 2026-07-20 09:07:13 | INFO  | main:239 - Dry-run enabled, not writing /tmp/tmp710_jnuy/example.yml 2026-07-20 09:07:13.714706 | debian-bookworm | .2026-07-20 09:07:13 | INFO  | main:223 - Processing file /tmp/tmp0a00ed0e/example.yml 2026-07-20 09:07:13.717894 | debian-bookworm | 2026-07-20 09:07:13 | INFO  | update_image:144 - Checking image Example 1.0 2026-07-20 09:07:13.718242 | debian-bookworm | 2026-07-20 09:07:13 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:0000000000000000000000000000000000000000000000000000000000000000 2026-07-20 09:07:13.718260 | debian-bookworm | 2026-07-20 09:07:13 | INFO  | update_image:158 - Image Example 1.0 is up-to-date, nothing to do 2026-07-20 09:07:13.725276 | debian-bookworm | .2026-07-20 09:07:13 | INFO  | main:223 - Processing file /tmp/tmpzs47t84z/example.yml 2026-07-20 09:07:13.728507 | debian-bookworm | 2026-07-20 09:07:13 | INFO  | update_image:144 - Checking image Example 1.0 2026-07-20 09:07:13.728531 | debian-bookworm | 2026-07-20 09:07:13 | INFO  | update_image:147 - Checksum of current example.qcow2 is sha256:1111111111111111111111111111111111111111111111111111111111111111 2026-07-20 09:07:13.728865 | debian-bookworm | 2026-07-20 09:07:13 | INFO  | update_image:178 - New URL is https://nbg1.your-objectstorage.com/osism/openstack-images/example-1.0/20260101-example-1.0.qcow2 2026-07-20 09:07:13.732323 | debian-bookworm | . 2026-07-20 09:07:13.732351 | debian-bookworm | ---------------------------------------------------------------------- 2026-07-20 09:07:13.732362 | debian-bookworm | Ran 54 tests in 1.808s 2026-07-20 09:07:13.732367 | debian-bookworm | 2026-07-20 09:07:13.732373 | debian-bookworm | OK 2026-07-20 09:07:13.832714 | debian-bookworm | test: OK (2.51=setup[0.05]+cmd[2.46] seconds) 2026-07-20 09:07:13.832759 | debian-bookworm | congratulations :) (2.54 seconds) 2026-07-20 09:07:14.273295 | debian-bookworm | ok: Runtime: 0:00:02.860121 2026-07-20 09:07:14.280895 | 2026-07-20 09:07:14.281021 | TASK [tox : Look for output] 2026-07-20 09:07:14.712348 | debian-bookworm | ok 2026-07-20 09:07:14.730653 | 2026-07-20 09:07:14.730812 | TASK [tox : Return file comments to Zuul] 2026-07-20 09:07:14.765324 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:14.773749 | 2026-07-20 09:07:14.773857 | PLAY RECAP 2026-07-20 09:07:14.773916 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-07-20 09:07:14.773952 | 2026-07-20 09:07:14.936497 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-20 09:07:14.937703 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-20 09:07:15.914429 | 2026-07-20 09:07:15.914661 | PLAY [all] 2026-07-20 09:07:15.947194 | 2026-07-20 09:07:15.947407 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-07-20 09:07:16.044377 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:16.051578 | 2026-07-20 09:07:16.051820 | TASK [fetch-tox-output : Set tox log path for single node] 2026-07-20 09:07:16.092721 | debian-bookworm | ok 2026-07-20 09:07:16.098658 | 2026-07-20 09:07:16.098797 | TASK [fetch-tox-output : Ensure local tox dir] 2026-07-20 09:07:16.686678 | debian-bookworm -> localhost | changed 2026-07-20 09:07:16.695486 | 2026-07-20 09:07:16.695667 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-07-20 09:07:16.760911 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:16.767294 | 2026-07-20 09:07:16.767425 | TASK [fetch-tox-output : Set envlist fact] 2026-07-20 09:07:16.809744 | debian-bookworm | ok 2026-07-20 09:07:16.815920 | 2026-07-20 09:07:16.816035 | TASK [fetch-tox-output : Get tox version] 2026-07-20 09:07:17.570470 | 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-07-20 09:07:17.587678 | debian-bookworm | 4.57.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-20 09:07:17.847800 | debian-bookworm | ok: Runtime: 0:00:00.320499 2026-07-20 09:07:17.853872 | 2026-07-20 09:07:17.854007 | TASK [fetch-tox-output : Find all default environments] 2026-07-20 09:07:18.383748 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:18.390541 | 2026-07-20 09:07:18.390730 | TASK [fetch-tox-output : Set envlist fact] 2026-07-20 09:07:18.427037 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:18.433465 | 2026-07-20 09:07:18.433631 | TASK [fetch-tox-output : Find all default environments] 2026-07-20 09:07:18.963575 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:18.974550 | 2026-07-20 09:07:18.976147 | TASK [fetch-tox-output : Set envlist fact] 2026-07-20 09:07:19.022109 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:19.042084 | 2026-07-20 09:07:19.042238 | LOOP [fetch-tox-output : Copy tox logs] 2026-07-20 09:07:19.105328 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:19.119443 | 2026-07-20 09:07:19.119624 | LOOP [fetch-tox-output : Collect tox logs] 2026-07-20 09:07:19.939756 | debian-bookworm | changed: 2026-07-20 09:07:19.940245 | debian-bookworm | created directory /var/lib/zuul/builds/45dc5580e23d44eba0174e98ceba1983/work/logs/tox/test 2026-07-20 09:07:19.940347 | debian-bookworm | cd+++++++++ ./ 2026-07-20 09:07:19.940419 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-07-20 09:07:19.940501 | 2026-07-20 09:07:19.958909 | 2026-07-20 09:07:19.959075 | PLAY RECAP 2026-07-20 09:07:19.959163 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-07-20 09:07:19.959203 | 2026-07-20 09:07:20.145714 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-20 09:07:20.146942 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-20 09:07:20.947635 | 2026-07-20 09:07:20.947835 | PLAY [all] 2026-07-20 09:07:20.965203 | 2026-07-20 09:07:20.965357 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-07-20 09:07:21.205192 | debian-bookworm | changed: non-zero return code 2026-07-20 09:07:21.214299 | 2026-07-20 09:07:21.214508 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-07-20 09:07:21.241180 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:21.249334 | 2026-07-20 09:07:21.249497 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-07-20 09:07:21.280475 | 2026-07-20 09:07:21.280731 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-07-20 09:07:21.311746 | 2026-07-20 09:07:21.311950 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-07-20 09:07:21.335756 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:21.343342 | 2026-07-20 09:07:21.343479 | LOOP [fetch-subunit-output : Generate subunit file] 2026-07-20 09:07:21.377796 | 2026-07-20 09:07:21.378051 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-07-20 09:07:21.402548 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:21.415284 | 2026-07-20 09:07:21.415481 | TASK [fetch-subunit-output : Remove the temporary file] 2026-07-20 09:07:21.440681 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:21.454342 | 2026-07-20 09:07:21.454539 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-07-20 09:07:21.479667 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:21.491526 | 2026-07-20 09:07:21.491667 | PLAY RECAP 2026-07-20 09:07:21.491739 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-07-20 09:07:21.491776 | 2026-07-20 09:07:21.637956 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-20 09:07:21.639189 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-20 09:07:22.516424 | 2026-07-20 09:07:22.516680 | PLAY [Base post-fetch] 2026-07-20 09:07:22.533451 | 2026-07-20 09:07:22.533661 | TASK [diagnose-network : Ensure log directory exists] 2026-07-20 09:07:22.946460 | debian-bookworm | ok 2026-07-20 09:07:22.959049 | 2026-07-20 09:07:22.959266 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-07-20 09:07:34.695952 | debian-bookworm | ok: Runtime: 0:00:05.781360 2026-07-20 09:07:34.705779 | 2026-07-20 09:07:34.705936 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-07-20 09:07:40.729846 | debian-bookworm | ok: Runtime: 0:00:00.009031 2026-07-20 09:07:40.735698 | 2026-07-20 09:07:40.735837 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-07-20 09:07:46.756219 | debian-bookworm | ok: Runtime: 0:00:00.008148 2026-07-20 09:07:46.770920 | 2026-07-20 09:07:46.771069 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-20 09:07:46.827385 | debian-bookworm | skipping: Conditional result was False 2026-07-20 09:07:46.842490 | 2026-07-20 09:07:46.842748 | TASK [fetch-output : Set log path for single node] 2026-07-20 09:07:46.907675 | debian-bookworm | ok 2026-07-20 09:07:46.915031 | 2026-07-20 09:07:46.915189 | LOOP [fetch-output : Ensure local output dirs] 2026-07-20 09:07:47.230693 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/45dc5580e23d44eba0174e98ceba1983/work/logs" 2026-07-20 09:07:47.496265 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/45dc5580e23d44eba0174e98ceba1983/work/artifacts" 2026-07-20 09:07:47.774962 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/45dc5580e23d44eba0174e98ceba1983/work/docs" 2026-07-20 09:07:47.790741 | 2026-07-20 09:07:47.797314 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-20 09:07:48.636823 | debian-bookworm | changed: 2026-07-20 09:07:48.637186 | debian-bookworm | .d..t...... ./ 2026-07-20 09:07:48.637236 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-20 09:07:48.637267 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-20 09:07:48.637295 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-20 09:07:48.637321 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-20 09:07:48.637348 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-20 09:07:48.637418 | debian-bookworm | changed: All items complete 2026-07-20 09:07:48.637455 | 2026-07-20 09:07:49.216912 | debian-bookworm | changed: .d..t...... ./ 2026-07-20 09:07:49.798405 | debian-bookworm | changed: .d..t...... ./ 2026-07-20 09:07:49.834250 | 2026-07-20 09:07:49.834441 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-20 09:07:50.232747 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.007438 2026-07-20 09:07:50.564149 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008149 2026-07-20 09:07:50.602450 | 2026-07-20 09:07:50.602615 | PLAY RECAP 2026-07-20 09:07:50.602684 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-20 09:07:50.602714 | 2026-07-20 09:07:50.772909 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-20 09:07:50.775853 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-20 09:07:51.635653 | 2026-07-20 09:07:51.635858 | PLAY [Base post] 2026-07-20 09:07:51.650987 | 2026-07-20 09:07:51.651154 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-20 09:07:52.239248 | debian-bookworm | changed 2026-07-20 09:07:52.248875 | 2026-07-20 09:07:52.249010 | PLAY RECAP 2026-07-20 09:07:52.249092 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-20 09:07:52.249171 | 2026-07-20 09:07:52.384494 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-20 09:07:52.386135 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-20 09:07:53.189414 | 2026-07-20 09:07:53.189648 | PLAY [Base post-logs] 2026-07-20 09:07:53.202337 | 2026-07-20 09:07:53.202488 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-20 09:07:53.679072 | localhost | changed 2026-07-20 09:07:53.690995 | 2026-07-20 09:07:53.691195 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-20 09:07:53.728281 | localhost | ok 2026-07-20 09:07:53.732212 | 2026-07-20 09:07:53.732327 | TASK [Set zuul-log-path fact] 2026-07-20 09:07:53.761300 | localhost | ok 2026-07-20 09:07:53.777638 | 2026-07-20 09:07:53.777809 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-20 09:07:53.805538 | localhost | ok 2026-07-20 09:07:53.811727 | 2026-07-20 09:07:53.811877 | TASK [upload-logs : Create log directories] 2026-07-20 09:07:54.371737 | localhost | changed 2026-07-20 09:07:54.376696 | 2026-07-20 09:07:54.376853 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-20 09:07:54.982108 | localhost -> localhost | ok: Runtime: 0:00:00.008250 2026-07-20 09:07:54.989108 | 2026-07-20 09:07:54.989281 | TASK [upload-logs : Upload logs to log server] 2026-07-20 09:07:55.590321 | localhost | Output suppressed because no_log was given 2026-07-20 09:07:55.595685 | 2026-07-20 09:07:55.595882 | LOOP [upload-logs : Compress console log and json output] 2026-07-20 09:07:55.668044 | localhost | skipping: Conditional result was False 2026-07-20 09:07:55.673533 | localhost | skipping: Conditional result was False 2026-07-20 09:07:55.685673 | 2026-07-20 09:07:55.685872 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-20 09:07:55.743219 | localhost | skipping: Conditional result was False 2026-07-20 09:07:55.743905 | 2026-07-20 09:07:55.747249 | localhost | skipping: Conditional result was False 2026-07-20 09:07:55.757978 | 2026-07-20 09:07:55.758100 | LOOP [upload-logs : Upload console log and json output]