2025-10-18 03:09:13.701965 | Job console starting 2025-10-18 03:09:13.711464 | Updating git repos 2025-10-18 03:09:13.740667 | Cloning repos into workspace 2025-10-18 03:09:13.811832 | Restoring repo states 2025-10-18 03:09:13.818123 | Merging changes 2025-10-18 03:09:13.818138 | Checking out repos 2025-10-18 03:09:13.862606 | Preparing playbooks 2025-10-18 03:09:14.914081 | Running Ansible setup 2025-10-18 03:09:19.980640 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-18 03:09:20.638812 | 2025-10-18 03:09:20.638946 | PLAY [Base pre] 2025-10-18 03:09:20.650874 | 2025-10-18 03:09:20.650960 | TASK [Setup log path fact] 2025-10-18 03:09:20.678403 | debian-bookworm | ok 2025-10-18 03:09:20.690707 | 2025-10-18 03:09:20.690804 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-18 03:09:20.708342 | debian-bookworm | ok 2025-10-18 03:09:20.715850 | 2025-10-18 03:09:20.715925 | TASK [emit-job-header : Print job information] 2025-10-18 03:09:20.743759 | # Job Information 2025-10-18 03:09:20.743898 | Ansible Version: 2.16.14 2025-10-18 03:09:20.743933 | Job: tox 2025-10-18 03:09:20.743967 | Pipeline: periodic-daily 2025-10-18 03:09:20.743991 | Executor: 521e9411259a 2025-10-18 03:09:20.744029 | Triggered by: https://github.com/osism/openstack-image-manager 2025-10-18 03:09:20.744053 | Event ID: 5ea0b779559a401384d6d4e931e36ec7 2025-10-18 03:09:20.748531 | 2025-10-18 03:09:20.748608 | LOOP [emit-job-header : Print node information] 2025-10-18 03:09:20.841185 | debian-bookworm | ok: 2025-10-18 03:09:20.841425 | debian-bookworm | # Node Information 2025-10-18 03:09:20.841482 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-18 03:09:20.841524 | debian-bookworm | Hostname: debian 2025-10-18 03:09:20.841560 | debian-bookworm | Username: zuul 2025-10-18 03:09:20.841595 | debian-bookworm | Distro: Debian 12.12 2025-10-18 03:09:20.841634 | debian-bookworm | Provider: regiocloud-a 2025-10-18 03:09:20.841670 | debian-bookworm | Region: 2025-10-18 03:09:20.841706 | debian-bookworm | Label: debian-bookworm 2025-10-18 03:09:20.841739 | debian-bookworm | Product Name: OpenStack Nova 2025-10-18 03:09:20.841770 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe62:d9cf 2025-10-18 03:09:20.856644 | 2025-10-18 03:09:20.856736 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-18 03:09:21.259366 | debian-bookworm -> localhost | changed 2025-10-18 03:09:21.264916 | 2025-10-18 03:09:21.264998 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-18 03:09:22.090385 | debian-bookworm -> localhost | changed 2025-10-18 03:09:22.098725 | 2025-10-18 03:09:22.098808 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-18 03:09:22.303891 | debian-bookworm -> localhost | ok 2025-10-18 03:09:22.308941 | 2025-10-18 03:09:22.309043 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-18 03:09:22.335730 | debian-bookworm | ok 2025-10-18 03:09:22.349208 | debian-bookworm | included: /var/lib/zuul/builds/847264ee02624a799fcdbdc1a459782f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-18 03:09:22.353911 | 2025-10-18 03:09:22.353978 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-18 03:09:23.837962 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-18 03:09:23.838204 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/847264ee02624a799fcdbdc1a459782f/work/847264ee02624a799fcdbdc1a459782f_id_rsa 2025-10-18 03:09:23.838251 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/847264ee02624a799fcdbdc1a459782f/work/847264ee02624a799fcdbdc1a459782f_id_rsa.pub 2025-10-18 03:09:23.838285 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-18 03:09:23.838318 | debian-bookworm -> localhost | SHA256:ya+S0cm2LASd0mJK9NKkE9dwL4S7AOYQV2Q4DD32O1I zuul-build-sshkey 2025-10-18 03:09:23.838346 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-18 03:09:23.838384 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-18 03:09:23.838412 | debian-bookworm -> localhost | |o=.+=+o | 2025-10-18 03:09:23.838439 | debian-bookworm -> localhost | |ooX.+o.. | 2025-10-18 03:09:23.838464 | debian-bookworm -> localhost | |++ @ +... | 2025-10-18 03:09:23.838488 | debian-bookworm -> localhost | | .* E +o . | 2025-10-18 03:09:23.838513 | debian-bookworm -> localhost | | . B * oS. | 2025-10-18 03:09:23.838542 | debian-bookworm -> localhost | | o + o =. | 2025-10-18 03:09:23.838568 | debian-bookworm -> localhost | | . o = .. | 2025-10-18 03:09:23.838593 | debian-bookworm -> localhost | | + o. | 2025-10-18 03:09:23.838619 | debian-bookworm -> localhost | | o. | 2025-10-18 03:09:23.838646 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-18 03:09:23.838705 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.072807 2025-10-18 03:09:23.845915 | 2025-10-18 03:09:23.846023 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-18 03:09:23.877668 | debian-bookworm | ok 2025-10-18 03:09:23.895064 | debian-bookworm | included: /var/lib/zuul/builds/847264ee02624a799fcdbdc1a459782f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-18 03:09:23.905229 | 2025-10-18 03:09:23.905309 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-18 03:09:23.928524 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:09:23.936917 | 2025-10-18 03:09:23.937038 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-18 03:09:24.569404 | debian-bookworm | changed 2025-10-18 03:09:24.583457 | 2025-10-18 03:09:24.583612 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-18 03:09:24.852375 | debian-bookworm | ok 2025-10-18 03:09:24.862265 | 2025-10-18 03:09:24.862390 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-18 03:09:25.557828 | debian-bookworm | changed 2025-10-18 03:09:25.578348 | 2025-10-18 03:09:25.578942 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-18 03:09:26.310697 | debian-bookworm | changed 2025-10-18 03:09:26.318471 | 2025-10-18 03:09:26.318560 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-18 03:09:26.344691 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:09:26.350450 | 2025-10-18 03:09:26.350536 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-18 03:09:26.860002 | debian-bookworm -> localhost | changed 2025-10-18 03:09:26.889967 | 2025-10-18 03:09:26.890124 | TASK [add-build-sshkey : Add back temp key] 2025-10-18 03:09:27.241059 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/847264ee02624a799fcdbdc1a459782f/work/847264ee02624a799fcdbdc1a459782f_id_rsa (zuul-build-sshkey) 2025-10-18 03:09:27.241263 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012626 2025-10-18 03:09:27.248875 | 2025-10-18 03:09:27.248967 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-18 03:09:27.644715 | debian-bookworm | ok 2025-10-18 03:09:27.649486 | 2025-10-18 03:09:27.649573 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-18 03:09:27.675926 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:09:27.686060 | 2025-10-18 03:09:27.686152 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-18 03:09:28.109308 | debian-bookworm | ok 2025-10-18 03:09:28.131209 | 2025-10-18 03:09:28.131316 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-18 03:09:28.169026 | debian-bookworm | ok 2025-10-18 03:09:28.173786 | 2025-10-18 03:09:28.173869 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-18 03:09:28.418801 | debian-bookworm -> localhost | ok 2025-10-18 03:09:28.424776 | 2025-10-18 03:09:28.424863 | TASK [validate-host : Collect information about the host] 2025-10-18 03:09:29.563789 | debian-bookworm | ok 2025-10-18 03:09:29.583884 | 2025-10-18 03:09:29.584109 | TASK [validate-host : Sanitize hostname] 2025-10-18 03:09:29.736899 | debian-bookworm | ok 2025-10-18 03:09:29.794907 | 2025-10-18 03:09:29.795050 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-18 03:09:30.825296 | debian-bookworm -> localhost | changed 2025-10-18 03:09:30.833902 | 2025-10-18 03:09:30.834090 | TASK [validate-host : Collect information about zuul worker] 2025-10-18 03:09:31.294575 | debian-bookworm | ok 2025-10-18 03:09:31.300005 | 2025-10-18 03:09:31.300129 | TASK [validate-host : Write out all zuul information for each host] 2025-10-18 03:09:31.971633 | debian-bookworm -> localhost | changed 2025-10-18 03:09:31.988050 | 2025-10-18 03:09:31.988185 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-18 03:09:32.295459 | debian-bookworm | ok 2025-10-18 03:09:32.301484 | 2025-10-18 03:09:32.301597 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-18 03:09:33.293310 | debian-bookworm | changed: 2025-10-18 03:09:33.293525 | debian-bookworm | cd+++++++++ src/ 2025-10-18 03:09:33.293744 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-18 03:09:33.293778 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-18 03:09:33.293802 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-10-18 03:09:33.293824 | debian-bookworm | =6.2 2025-10-18 03:09:57.184086 | debian-bookworm | Downloading cachetools-6.2.1-py3-none-any.whl (11 kB) 2025-10-18 03:09:57.239100 | debian-bookworm | Collecting chardet>=5.2 2025-10-18 03:09:57.253487 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-10-18 03:09:57.278220 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 10.5 MB/s eta 0:00:00 2025-10-18 03:09:57.336611 | debian-bookworm | Collecting colorama>=0.4.6 2025-10-18 03:09:57.351235 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-10-18 03:09:57.432962 | debian-bookworm | Collecting filelock>=3.20 2025-10-18 03:09:57.446983 | debian-bookworm | Downloading filelock-3.20.0-py3-none-any.whl (16 kB) 2025-10-18 03:09:57.512216 | debian-bookworm | Collecting packaging>=25 2025-10-18 03:09:57.526823 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-18 03:09:57.547978 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 3.9 MB/s eta 0:00:00 2025-10-18 03:09:57.609707 | debian-bookworm | Collecting platformdirs>=4.5 2025-10-18 03:09:57.623933 | debian-bookworm | Downloading platformdirs-4.5.0-py3-none-any.whl (18 kB) 2025-10-18 03:09:57.684402 | debian-bookworm | Collecting pluggy>=1.6 2025-10-18 03:09:57.698506 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-18 03:09:57.752116 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-10-18 03:09:57.767279 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2025-10-18 03:09:57.902061 | debian-bookworm | Collecting virtualenv>=20.34 2025-10-18 03:09:57.916500 | debian-bookworm | Downloading virtualenv-20.35.3-py3-none-any.whl (6.0 MB) 2025-10-18 03:09:58.243518 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 18.6 MB/s eta 0:00:00 2025-10-18 03:09:58.358309 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-10-18 03:09:58.372208 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-10-18 03:09:58.397391 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 21.8 MB/s eta 0:00:00 2025-10-18 03:09:58.567886 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-10-18 03:09:59.564963 | debian-bookworm | Successfully installed cachetools-6.2.1 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.20.0 packaging-25.0 platformdirs-4.5.0 pluggy-1.6.0 pyproject-api-1.10.0 tox-4.31.0 virtualenv-20.35.3 2025-10-18 03:09:59.966531 | debian-bookworm | ok: Runtime: 0:00:03.457840 2025-10-18 03:09:59.972238 | 2025-10-18 03:09:59.972333 | TASK [ensure-tox : Export installed tox_executable path] 2025-10-18 03:10:00.021734 | debian-bookworm | ok 2025-10-18 03:10:00.029453 | 2025-10-18 03:10:00.029556 | TASK [ensure-tox : Output tox version] 2025-10-18 03:10:00.671957 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-10-18 03:10:00.690897 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-18 03:10:01.092749 | debian-bookworm | ok: Runtime: 0:00:00.334284 2025-10-18 03:10:01.097399 | 2025-10-18 03:10:01.097475 | TASK [ensure-tox : Make global symlink] 2025-10-18 03:10:01.138799 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:01.146416 | 2025-10-18 03:10:01.146478 | PLAY RECAP 2025-10-18 03:10:01.146522 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-10-18 03:10:01.146542 | 2025-10-18 03:10:01.292987 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-10-18 03:10:01.293985 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-18 03:10:01.905836 | 2025-10-18 03:10:01.905988 | PLAY [all] 2025-10-18 03:10:01.917594 | 2025-10-18 03:10:01.917694 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-18 03:10:02.311298 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-18 03:10:02.448874 | debian-bookworm | ok: Runtime: 0:00:00.028819 2025-10-18 03:10:02.453314 | 2025-10-18 03:10:02.453404 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-18 03:10:02.977573 | debian-bookworm | ok: Runtime: 0:00:00.006730 2025-10-18 03:10:02.982239 | 2025-10-18 03:10:02.982324 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-18 03:10:03.251749 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-10-18 03:10:03.251915 | debian-bookworm | sudo: a password is required 2025-10-18 03:10:03.507504 | debian-bookworm | ok: Runtime: 0:00:00.022766 2025-10-18 03:10:03.523100 | 2025-10-18 03:10:03.523214 | TASK [tox : Check to see if the constraints file exists] 2025-10-18 03:10:03.557500 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:03.567047 | 2025-10-18 03:10:03.567166 | TASK [tox : Fail if constraints file is missing] 2025-10-18 03:10:03.592133 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:03.597711 | 2025-10-18 03:10:03.597803 | TASK [tox : Record file location] 2025-10-18 03:10:03.620492 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:03.625794 | 2025-10-18 03:10:03.625884 | TASK [tox : Install tox siblings] 2025-10-18 03:10:03.644784 | debian-bookworm | ok 2025-10-18 03:10:03.652699 | debian-bookworm | included: /var/lib/zuul/builds/847264ee02624a799fcdbdc1a459782f/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-10-18 03:10:03.658005 | 2025-10-18 03:10:03.658085 | TASK [tox : Run tox without tests] 2025-10-18 03:10:04.906602 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-10-18 03:10:18.935454 | debian-bookworm | test: OK (14.68 seconds) 2025-10-18 03:10:18.935582 | debian-bookworm | congratulations :) (14.73 seconds) 2025-10-18 03:10:19.213458 | debian-bookworm | ok: Runtime: 0:00:15.061122 2025-10-18 03:10:19.222817 | 2025-10-18 03:10:19.222964 | TASK [tox : Create a tempfile to save tox showconfig] 2025-10-18 03:10:19.762866 | debian-bookworm | changed 2025-10-18 03:10:19.768354 | 2025-10-18 03:10:19.768449 | TASK [tox : Get tox envlist config] 2025-10-18 03:10:20.826200 | debian-bookworm | ok: Runtime: 0:00:00.345933 2025-10-18 03:10:20.831413 | 2025-10-18 03:10:20.831504 | TASK [tox : Install any sibling python packages] 2025-10-18 03:10:21.596340 | debian-bookworm | ok: No setup.cfg, no action needed 2025-10-18 03:10:21.601431 | 2025-10-18 03:10:21.601520 | TASK [tox : Remove tempfile] 2025-10-18 03:10:22.037561 | debian-bookworm | changed 2025-10-18 03:10:22.055061 | 2025-10-18 03:10:22.055182 | TASK [tox : Emit tox command] 2025-10-18 03:10:22.123369 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-10-18 03:10:22.131987 | 2025-10-18 03:10:22.132094 | TASK [tox : Run tox] 2025-10-18 03:10:22.809651 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-10-18 03:10:23.515478 | debian-bookworm | ............... 2025-10-18 03:10:23.515588 | debian-bookworm | ---------------------------------------------------------------------- 2025-10-18 03:10:23.515607 | debian-bookworm | Ran 15 tests in 0.214s 2025-10-18 03:10:23.515613 | debian-bookworm | 2025-10-18 03:10:23.515621 | debian-bookworm | OK 2025-10-18 03:10:23.639287 | debian-bookworm | test: OK (0.87=setup[0.05]+cmd[0.82] seconds) 2025-10-18 03:10:23.639415 | debian-bookworm | congratulations :) (0.90 seconds) 2025-10-18 03:10:23.766412 | debian-bookworm | ok: Runtime: 0:00:01.209840 2025-10-18 03:10:23.775972 | 2025-10-18 03:10:23.776079 | TASK [tox : Look for output] 2025-10-18 03:10:24.215282 | debian-bookworm | ok 2025-10-18 03:10:24.230432 | 2025-10-18 03:10:24.230544 | TASK [tox : Return file comments to Zuul] 2025-10-18 03:10:24.264499 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:24.271752 | 2025-10-18 03:10:24.271820 | PLAY RECAP 2025-10-18 03:10:24.271869 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-10-18 03:10:24.271894 | 2025-10-18 03:10:24.370251 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-10-18 03:10:24.371482 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-18 03:10:25.258278 | 2025-10-18 03:10:25.258411 | PLAY [all] 2025-10-18 03:10:25.280579 | 2025-10-18 03:10:25.280707 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-10-18 03:10:25.354550 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:25.360058 | 2025-10-18 03:10:25.360177 | TASK [fetch-tox-output : Set tox log path for single node] 2025-10-18 03:10:25.397367 | debian-bookworm | ok 2025-10-18 03:10:25.402385 | 2025-10-18 03:10:25.402476 | TASK [fetch-tox-output : Ensure local tox dir] 2025-10-18 03:10:25.847342 | debian-bookworm -> localhost | changed 2025-10-18 03:10:25.854662 | 2025-10-18 03:10:25.854764 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-10-18 03:10:25.898090 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:25.904331 | 2025-10-18 03:10:25.904442 | TASK [fetch-tox-output : Set envlist fact] 2025-10-18 03:10:25.943057 | debian-bookworm | ok 2025-10-18 03:10:25.948441 | 2025-10-18 03:10:25.948538 | TASK [fetch-tox-output : Get tox version] 2025-10-18 03:10:26.613977 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-10-18 03:10:26.632666 | debian-bookworm | 4.31.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-10-18 03:10:26.978370 | debian-bookworm | ok: Runtime: 0:00:00.299257 2025-10-18 03:10:26.984509 | 2025-10-18 03:10:26.984612 | TASK [fetch-tox-output : Find all default environments] 2025-10-18 03:10:27.554075 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:27.558997 | 2025-10-18 03:10:27.559100 | TASK [fetch-tox-output : Set envlist fact] 2025-10-18 03:10:27.602280 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:27.606802 | 2025-10-18 03:10:27.606904 | TASK [fetch-tox-output : Find all default environments] 2025-10-18 03:10:28.142890 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:28.157982 | 2025-10-18 03:10:28.158139 | TASK [fetch-tox-output : Set envlist fact] 2025-10-18 03:10:28.193043 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:28.205412 | 2025-10-18 03:10:28.205541 | LOOP [fetch-tox-output : Copy tox logs] 2025-10-18 03:10:28.248692 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:28.263663 | 2025-10-18 03:10:28.263800 | LOOP [fetch-tox-output : Collect tox logs] 2025-10-18 03:10:28.996310 | debian-bookworm | changed: 2025-10-18 03:10:28.996463 | debian-bookworm | created directory /var/lib/zuul/builds/847264ee02624a799fcdbdc1a459782f/work/logs/tox/test 2025-10-18 03:10:28.996498 | debian-bookworm | cd+++++++++ ./ 2025-10-18 03:10:28.996523 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-10-18 03:10:28.996551 | 2025-10-18 03:10:29.009750 | 2025-10-18 03:10:29.009824 | PLAY RECAP 2025-10-18 03:10:29.009865 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-10-18 03:10:29.009885 | 2025-10-18 03:10:29.121420 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-10-18 03:10:29.122346 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-18 03:10:29.954994 | 2025-10-18 03:10:29.955123 | PLAY [all] 2025-10-18 03:10:29.970515 | 2025-10-18 03:10:29.970631 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-18 03:10:30.242014 | debian-bookworm | changed: non-zero return code 2025-10-18 03:10:30.247353 | 2025-10-18 03:10:30.247471 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-18 03:10:30.271110 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:30.276565 | 2025-10-18 03:10:30.276662 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-18 03:10:30.305330 | 2025-10-18 03:10:30.305489 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-18 03:10:30.344272 | 2025-10-18 03:10:30.344529 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-18 03:10:30.368485 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:30.374669 | 2025-10-18 03:10:30.374767 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-18 03:10:30.418683 | 2025-10-18 03:10:30.419011 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-18 03:10:30.455680 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:30.461714 | 2025-10-18 03:10:30.461816 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-18 03:10:30.484980 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:30.492318 | 2025-10-18 03:10:30.492411 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-18 03:10:30.515154 | debian-bookworm | skipping: Conditional result was False 2025-10-18 03:10:30.521125 | 2025-10-18 03:10:30.521189 | PLAY RECAP 2025-10-18 03:10:30.521237 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-18 03:10:30.521262 | 2025-10-18 03:10:30.657441 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-18 03:10:30.658921 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-18 03:10:31.422305 | 2025-10-18 03:10:31.422416 | PLAY [Base post] 2025-10-18 03:10:31.433440 | 2025-10-18 03:10:31.433537 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-18 03:10:32.019706 | debian-bookworm | changed 2025-10-18 03:10:32.024572 | 2025-10-18 03:10:32.024643 | PLAY RECAP 2025-10-18 03:10:32.024691 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-18 03:10:32.024746 | 2025-10-18 03:10:32.186476 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-18 03:10:32.187498 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-18 03:10:33.242235 | 2025-10-18 03:10:33.242388 | PLAY [Base post-logs] 2025-10-18 03:10:33.260978 | 2025-10-18 03:10:33.261082 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-18 03:10:33.758880 | localhost | changed 2025-10-18 03:10:33.766344 | 2025-10-18 03:10:33.766452 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-18 03:10:33.811122 | localhost | ok 2025-10-18 03:10:33.813469 | 2025-10-18 03:10:33.813539 | TASK [Set zuul-log-path fact] 2025-10-18 03:10:33.837307 | localhost | ok 2025-10-18 03:10:33.843956 | 2025-10-18 03:10:33.844041 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-18 03:10:33.891370 | localhost | ok 2025-10-18 03:10:33.893817 | 2025-10-18 03:10:33.893893 | TASK [upload-logs : Create log directories] 2025-10-18 03:10:34.615582 | localhost | changed 2025-10-18 03:10:34.617668 | 2025-10-18 03:10:34.617744 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-18 03:10:35.124979 | localhost -> localhost | ok: Runtime: 0:00:00.007588 2025-10-18 03:10:35.127917 | 2025-10-18 03:10:35.127999 | TASK [upload-logs : Upload logs to log server] 2025-10-18 03:10:35.556178 | localhost | Output suppressed because no_log was given 2025-10-18 03:10:35.557524 | 2025-10-18 03:10:35.557596 | LOOP [upload-logs : Compress console log and json output] 2025-10-18 03:10:35.607675 | localhost | skipping: Conditional result was False 2025-10-18 03:10:35.612696 | localhost | skipping: Conditional result was False 2025-10-18 03:10:35.622403 | 2025-10-18 03:10:35.622483 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-18 03:10:35.671779 | localhost | skipping: Conditional result was False 2025-10-18 03:10:35.671975 | 2025-10-18 03:10:35.672563 | localhost | skipping: Conditional result was False 2025-10-18 03:10:35.676892 | 2025-10-18 03:10:35.676983 | LOOP [upload-logs : Upload console log and json output]