2025-09-11 09:34:40.389559 | Job console starting 2025-09-11 09:34:40.421657 | Updating git repos 2025-09-11 09:34:40.480667 | Cloning repos into workspace 2025-09-11 09:34:40.565204 | Restoring repo states 2025-09-11 09:34:40.578443 | Merging changes 2025-09-11 09:34:41.121317 | Checking out repos 2025-09-11 09:34:41.196062 | Preparing playbooks 2025-09-11 09:34:41.924940 | Running Ansible setup 2025-09-11 09:34:46.993432 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-11 09:34:47.996571 | 2025-09-11 09:34:47.996737 | PLAY [Base pre] 2025-09-11 09:34:48.025601 | 2025-09-11 09:34:48.025766 | TASK [Setup log path fact] 2025-09-11 09:34:48.055032 | debian-bookworm | ok 2025-09-11 09:34:48.087338 | 2025-09-11 09:34:48.087515 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-11 09:34:48.137623 | debian-bookworm | ok 2025-09-11 09:34:48.155769 | 2025-09-11 09:34:48.155962 | TASK [emit-job-header : Print job information] 2025-09-11 09:34:48.206455 | # Job Information 2025-09-11 09:34:48.206648 | Ansible Version: 2.16.14 2025-09-11 09:34:48.206684 | Job: tox 2025-09-11 09:34:48.206719 | Pipeline: check 2025-09-11 09:34:48.206743 | Executor: 521e9411259a 2025-09-11 09:34:48.206764 | Triggered by: https://github.com/osism/openstack-image-manager/pull/954 2025-09-11 09:34:48.206786 | Event ID: 856bcdf0-8ef2-11f0-85a8-efd0a7ce97b6 2025-09-11 09:34:48.216095 | 2025-09-11 09:34:48.216234 | LOOP [emit-job-header : Print node information] 2025-09-11 09:34:48.364876 | debian-bookworm | ok: 2025-09-11 09:34:48.365168 | debian-bookworm | # Node Information 2025-09-11 09:34:48.365214 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-11 09:34:48.365241 | debian-bookworm | Hostname: debian 2025-09-11 09:34:48.365264 | debian-bookworm | Username: zuul 2025-09-11 09:34:48.365286 | debian-bookworm | Distro: Debian 12.12 2025-09-11 09:34:48.365310 | debian-bookworm | Provider: regiocloud-a 2025-09-11 09:34:48.365332 | debian-bookworm | Region: 2025-09-11 09:34:48.365353 | debian-bookworm | Label: debian-bookworm 2025-09-11 09:34:48.365373 | debian-bookworm | Product Name: OpenStack Nova 2025-09-11 09:34:48.365394 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe99:ecc6 2025-09-11 09:34:48.385925 | 2025-09-11 09:34:48.386067 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-11 09:34:48.946419 | debian-bookworm -> localhost | changed 2025-09-11 09:34:48.955685 | 2025-09-11 09:34:48.955822 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-11 09:34:50.490565 | debian-bookworm -> localhost | changed 2025-09-11 09:34:50.501628 | 2025-09-11 09:34:50.501766 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-11 09:34:50.904011 | debian-bookworm -> localhost | ok 2025-09-11 09:34:50.910560 | 2025-09-11 09:34:50.910697 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-11 09:34:50.951329 | debian-bookworm | ok 2025-09-11 09:34:50.974468 | debian-bookworm | included: /var/lib/zuul/builds/85e3c9e9b1914d918e1ea8ec3b5f5fc0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-11 09:34:50.981838 | 2025-09-11 09:34:50.981990 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-11 09:34:53.093326 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-11 09:34:53.093631 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/85e3c9e9b1914d918e1ea8ec3b5f5fc0/work/85e3c9e9b1914d918e1ea8ec3b5f5fc0_id_rsa 2025-09-11 09:34:53.093698 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/85e3c9e9b1914d918e1ea8ec3b5f5fc0/work/85e3c9e9b1914d918e1ea8ec3b5f5fc0_id_rsa.pub 2025-09-11 09:34:53.093743 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-11 09:34:53.093786 | debian-bookworm -> localhost | SHA256:kjxS7A1q1ee9ZFJMMDw06UCaE06TAEd5Jfq84pVzcMo zuul-build-sshkey 2025-09-11 09:34:53.093825 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-11 09:34:53.093893 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-11 09:34:53.093935 | debian-bookworm -> localhost | | .o+o=+oo=o. | 2025-09-11 09:34:53.093973 | debian-bookworm -> localhost | | ..=oB. += | 2025-09-11 09:34:53.094008 | debian-bookworm -> localhost | | ..X .o..o | 2025-09-11 09:34:53.094043 | debian-bookworm -> localhost | | O = o.o | 2025-09-11 09:34:53.094076 | debian-bookworm -> localhost | | + X S o + | 2025-09-11 09:34:53.094118 | debian-bookworm -> localhost | | . o O + . | 2025-09-11 09:34:53.094155 | debian-bookworm -> localhost | | . E . . | 2025-09-11 09:34:53.094190 | debian-bookworm -> localhost | | . o o | 2025-09-11 09:34:53.094226 | debian-bookworm -> localhost | | . | 2025-09-11 09:34:53.094261 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-11 09:34:53.094335 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.506531 2025-09-11 09:34:53.104027 | 2025-09-11 09:34:53.104135 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-11 09:34:53.138435 | debian-bookworm | ok 2025-09-11 09:34:53.151253 | debian-bookworm | included: /var/lib/zuul/builds/85e3c9e9b1914d918e1ea8ec3b5f5fc0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-11 09:34:53.160768 | 2025-09-11 09:34:53.160865 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-11 09:34:53.183703 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:34:53.189466 | 2025-09-11 09:34:53.189552 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-11 09:34:53.803052 | debian-bookworm | changed 2025-09-11 09:34:53.812129 | 2025-09-11 09:34:53.812266 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-11 09:34:54.071755 | debian-bookworm | ok 2025-09-11 09:34:54.083580 | 2025-09-11 09:34:54.083749 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-11 09:34:54.688176 | debian-bookworm | changed 2025-09-11 09:34:54.697119 | 2025-09-11 09:34:54.697225 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-11 09:34:55.305154 | debian-bookworm | changed 2025-09-11 09:34:55.310332 | 2025-09-11 09:34:55.310424 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-11 09:34:55.347085 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:34:55.352773 | 2025-09-11 09:34:55.352872 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-11 09:34:55.840932 | debian-bookworm -> localhost | changed 2025-09-11 09:34:55.862138 | 2025-09-11 09:34:55.862335 | TASK [add-build-sshkey : Add back temp key] 2025-09-11 09:34:56.177196 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/85e3c9e9b1914d918e1ea8ec3b5f5fc0/work/85e3c9e9b1914d918e1ea8ec3b5f5fc0_id_rsa (zuul-build-sshkey) 2025-09-11 09:34:56.177805 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015389 2025-09-11 09:34:56.194471 | 2025-09-11 09:34:56.194609 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-11 09:34:56.524959 | debian-bookworm | ok 2025-09-11 09:34:56.529721 | 2025-09-11 09:34:56.529808 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-11 09:34:56.555669 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:34:56.565368 | 2025-09-11 09:34:56.565457 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-11 09:34:56.957019 | debian-bookworm | ok 2025-09-11 09:34:56.972253 | 2025-09-11 09:34:56.972363 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-11 09:34:57.009658 | debian-bookworm | ok 2025-09-11 09:34:57.015049 | 2025-09-11 09:34:57.015139 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-11 09:34:57.291514 | debian-bookworm -> localhost | ok 2025-09-11 09:34:57.304695 | 2025-09-11 09:34:57.304822 | TASK [validate-host : Collect information about the host] 2025-09-11 09:34:58.294290 | debian-bookworm | ok 2025-09-11 09:34:58.312348 | 2025-09-11 09:34:58.312492 | TASK [validate-host : Sanitize hostname] 2025-09-11 09:34:58.427137 | debian-bookworm | ok 2025-09-11 09:34:58.476003 | 2025-09-11 09:34:58.476110 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-11 09:34:59.049581 | debian-bookworm -> localhost | changed 2025-09-11 09:34:59.061560 | 2025-09-11 09:34:59.061924 | TASK [validate-host : Collect information about zuul worker] 2025-09-11 09:34:59.579734 | debian-bookworm | ok 2025-09-11 09:34:59.588128 | 2025-09-11 09:34:59.588263 | TASK [validate-host : Write out all zuul information for each host] 2025-09-11 09:35:00.189495 | debian-bookworm -> localhost | changed 2025-09-11 09:35:00.199440 | 2025-09-11 09:35:00.199559 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-11 09:35:00.498275 | debian-bookworm | ok 2025-09-11 09:35:00.507004 | 2025-09-11 09:35:00.507132 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-11 09:35:01.488314 | debian-bookworm | changed: 2025-09-11 09:35:01.488598 | debian-bookworm | cd+++++++++ src/ 2025-09-11 09:35:01.488640 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-11 09:35:01.488664 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-11 09:35:01.488687 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-09-11 09:35:01.488707 | debian-bookworm | =6.1 2025-09-11 09:35:24.390479 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-09-11 09:35:24.436997 | debian-bookworm | Collecting chardet>=5.2 2025-09-11 09:35:24.445387 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-09-11 09:35:24.467097 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 12.1 MB/s eta 0:00:00 2025-09-11 09:35:24.514201 | debian-bookworm | Collecting colorama>=0.4.6 2025-09-11 09:35:24.523922 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-11 09:35:24.585079 | debian-bookworm | Collecting filelock>=3.18 2025-09-11 09:35:24.592536 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-09-11 09:35:24.634306 | debian-bookworm | Collecting packaging>=25 2025-09-11 09:35:24.641840 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-11 09:35:24.648906 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 12.2 MB/s eta 0:00:00 2025-09-11 09:35:24.688574 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-09-11 09:35:24.695827 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-09-11 09:35:24.729422 | debian-bookworm | Collecting pluggy>=1.6 2025-09-11 09:35:24.736701 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-11 09:35:24.768482 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-09-11 09:35:24.775720 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-09-11 09:35:24.895061 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-09-11 09:35:24.903238 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-09-11 09:35:25.151773 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 24.4 MB/s eta 0:00:00 2025-09-11 09:35:25.249875 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-09-11 09:35:25.257414 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-09-11 09:35:25.334750 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 6.4 MB/s eta 0:00:00 2025-09-11 09:35:25.467014 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-09-11 09:35:26.230114 | debian-bookworm | Successfully installed cachetools-6.2.0 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.19.1 packaging-25.0 platformdirs-4.4.0 pluggy-1.6.0 pyproject-api-1.9.1 tox-4.30.2 virtualenv-20.34.0 2025-09-11 09:35:26.421907 | debian-bookworm | ok: Runtime: 0:00:02.656483 2025-09-11 09:35:26.427996 | 2025-09-11 09:35:26.428113 | TASK [ensure-tox : Export installed tox_executable path] 2025-09-11 09:35:26.479168 | debian-bookworm | ok 2025-09-11 09:35:26.484086 | 2025-09-11 09:35:26.484199 | TASK [ensure-tox : Output tox version] 2025-09-11 09:35:26.946514 | 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-09-11 09:35:26.963903 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-11 09:35:27.040130 | debian-bookworm | ok: Runtime: 0:00:00.260285 2025-09-11 09:35:27.046228 | 2025-09-11 09:35:27.046340 | TASK [ensure-tox : Make global symlink] 2025-09-11 09:35:27.070571 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:27.077305 | 2025-09-11 09:35:27.077395 | PLAY RECAP 2025-09-11 09:35:27.077452 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-09-11 09:35:27.077478 | 2025-09-11 09:35:27.221256 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-09-11 09:35:27.222358 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-11 09:35:28.009249 | 2025-09-11 09:35:28.009414 | PLAY [all] 2025-09-11 09:35:28.025052 | 2025-09-11 09:35:28.025195 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-09-11 09:35:28.485834 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-11 09:35:28.552855 | debian-bookworm | ok: Runtime: 0:00:00.023863 2025-09-11 09:35:28.560958 | 2025-09-11 09:35:28.561128 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-09-11 09:35:29.089829 | debian-bookworm | ok: Runtime: 0:00:00.005523 2025-09-11 09:35:29.098571 | 2025-09-11 09:35:29.099513 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-09-11 09:35:29.409425 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-11 09:35:29.410811 | debian-bookworm | sudo: a password is required 2025-09-11 09:35:29.660565 | debian-bookworm | ok: Runtime: 0:00:00.017067 2025-09-11 09:35:29.679421 | 2025-09-11 09:35:29.679585 | TASK [tox : Check to see if the constraints file exists] 2025-09-11 09:35:29.715027 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:29.727166 | 2025-09-11 09:35:29.727333 | TASK [tox : Fail if constraints file is missing] 2025-09-11 09:35:29.762523 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:29.775407 | 2025-09-11 09:35:29.775579 | TASK [tox : Record file location] 2025-09-11 09:35:29.810548 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:29.822656 | 2025-09-11 09:35:29.822856 | TASK [tox : Install tox siblings] 2025-09-11 09:35:29.851440 | debian-bookworm | ok 2025-09-11 09:35:29.862199 | debian-bookworm | included: /var/lib/zuul/builds/85e3c9e9b1914d918e1ea8ec3b5f5fc0/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-09-11 09:35:29.867916 | 2025-09-11 09:35:29.868021 | TASK [tox : Run tox without tests] 2025-09-11 09:35:30.857687 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2025-09-11 09:35:42.769642 | debian-bookworm | test: OK (12.41 seconds) 2025-09-11 09:35:42.769704 | debian-bookworm | congratulations :) (12.43 seconds) 2025-09-11 09:35:42.899540 | debian-bookworm | ok: Runtime: 0:00:12.672718 2025-09-11 09:35:42.913255 | 2025-09-11 09:35:42.913417 | TASK [tox : Create a tempfile to save tox showconfig] 2025-09-11 09:35:43.315148 | debian-bookworm | changed 2025-09-11 09:35:43.323315 | 2025-09-11 09:35:43.323455 | TASK [tox : Get tox envlist config] 2025-09-11 09:35:44.354775 | debian-bookworm | ok: Runtime: 0:00:00.303620 2025-09-11 09:35:44.369424 | 2025-09-11 09:35:44.369586 | TASK [tox : Install any sibling python packages] 2025-09-11 09:35:44.804295 | debian-bookworm | ok: No setup.cfg, no action needed 2025-09-11 09:35:44.814311 | 2025-09-11 09:35:44.814466 | TASK [tox : Remove tempfile] 2025-09-11 09:35:45.239722 | debian-bookworm | changed 2025-09-11 09:35:45.246207 | 2025-09-11 09:35:45.246322 | TASK [tox : Emit tox command] 2025-09-11 09:35:45.302047 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2025-09-11 09:35:45.310478 | 2025-09-11 09:35:45.310625 | TASK [tox : Run tox] 2025-09-11 09:35:45.887859 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2025-09-11 09:35:46.488191 | debian-bookworm | ............... 2025-09-11 09:35:46.488237 | debian-bookworm | ---------------------------------------------------------------------- 2025-09-11 09:35:46.488250 | debian-bookworm | Ran 15 tests in 0.178s 2025-09-11 09:35:46.488255 | debian-bookworm | 2025-09-11 09:35:46.488276 | debian-bookworm | OK 2025-09-11 09:35:46.559648 | debian-bookworm | test: OK (0.71=setup[0.04]+cmd[0.67] seconds) 2025-09-11 09:35:46.559772 | debian-bookworm | congratulations :) (0.74 seconds) 2025-09-11 09:35:46.875431 | debian-bookworm | ok: Runtime: 0:00:00.988483 2025-09-11 09:35:46.889533 | 2025-09-11 09:35:46.889707 | TASK [tox : Look for output] 2025-09-11 09:35:47.292938 | debian-bookworm | ok 2025-09-11 09:35:47.310142 | 2025-09-11 09:35:47.310266 | TASK [tox : Return file comments to Zuul] 2025-09-11 09:35:47.347084 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:47.353229 | 2025-09-11 09:35:47.353309 | PLAY RECAP 2025-09-11 09:35:47.353362 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-11 09:35:47.353387 | 2025-09-11 09:35:47.479056 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-11 09:35:47.481691 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-11 09:35:48.194931 | 2025-09-11 09:35:48.195084 | PLAY [all] 2025-09-11 09:35:48.209946 | 2025-09-11 09:35:48.210071 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-09-11 09:35:48.265670 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:48.278919 | 2025-09-11 09:35:48.279124 | TASK [fetch-tox-output : Set tox log path for single node] 2025-09-11 09:35:48.327142 | debian-bookworm | ok 2025-09-11 09:35:48.335123 | 2025-09-11 09:35:48.335262 | TASK [fetch-tox-output : Ensure local tox dir] 2025-09-11 09:35:48.796482 | debian-bookworm -> localhost | changed 2025-09-11 09:35:48.813098 | 2025-09-11 09:35:48.813304 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-09-11 09:35:48.870496 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:48.883566 | 2025-09-11 09:35:48.883733 | TASK [fetch-tox-output : Set envlist fact] 2025-09-11 09:35:48.917599 | debian-bookworm | ok 2025-09-11 09:35:48.925355 | 2025-09-11 09:35:48.925468 | TASK [fetch-tox-output : Get tox version] 2025-09-11 09:35:49.547434 | 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-09-11 09:35:49.564506 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-11 09:35:49.957318 | debian-bookworm | ok: Runtime: 0:00:00.240403 2025-09-11 09:35:49.970901 | 2025-09-11 09:35:49.971086 | TASK [fetch-tox-output : Find all default environments] 2025-09-11 09:35:50.504699 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:50.511026 | 2025-09-11 09:35:50.511164 | TASK [fetch-tox-output : Set envlist fact] 2025-09-11 09:35:50.545773 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:50.553738 | 2025-09-11 09:35:50.553898 | TASK [fetch-tox-output : Find all default environments] 2025-09-11 09:35:51.082960 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:51.094818 | 2025-09-11 09:35:51.095040 | TASK [fetch-tox-output : Set envlist fact] 2025-09-11 09:35:51.131031 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:51.143743 | 2025-09-11 09:35:51.143934 | LOOP [fetch-tox-output : Copy tox logs] 2025-09-11 09:35:51.188565 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:51.214416 | 2025-09-11 09:35:51.214591 | LOOP [fetch-tox-output : Collect tox logs] 2025-09-11 09:35:52.005650 | debian-bookworm | changed: 2025-09-11 09:35:52.005862 | debian-bookworm | created directory /var/lib/zuul/builds/85e3c9e9b1914d918e1ea8ec3b5f5fc0/work/logs/tox/test 2025-09-11 09:35:52.005919 | debian-bookworm | cd+++++++++ ./ 2025-09-11 09:35:52.005948 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-09-11 09:35:52.005978 | 2025-09-11 09:35:52.020119 | 2025-09-11 09:35:52.020237 | PLAY RECAP 2025-09-11 09:35:52.020311 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-09-11 09:35:52.020343 | 2025-09-11 09:35:52.150681 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-11 09:35:52.151777 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-11 09:35:52.885855 | 2025-09-11 09:35:52.886030 | PLAY [all] 2025-09-11 09:35:52.902643 | 2025-09-11 09:35:52.902779 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-11 09:35:53.153433 | debian-bookworm | changed: non-zero return code 2025-09-11 09:35:53.163059 | 2025-09-11 09:35:53.163240 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-11 09:35:53.187985 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:53.196121 | 2025-09-11 09:35:53.196256 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-11 09:35:53.235393 | 2025-09-11 09:35:53.235667 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-11 09:35:53.273029 | 2025-09-11 09:35:53.273304 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-11 09:35:53.298432 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:53.310281 | 2025-09-11 09:35:53.310486 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-11 09:35:53.347812 | 2025-09-11 09:35:53.348136 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-11 09:35:53.373197 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:53.387524 | 2025-09-11 09:35:53.387697 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-11 09:35:53.412417 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:53.424547 | 2025-09-11 09:35:53.424723 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-11 09:35:53.449816 | debian-bookworm | skipping: Conditional result was False 2025-09-11 09:35:53.464051 | 2025-09-11 09:35:53.464178 | PLAY RECAP 2025-09-11 09:35:53.464261 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-11 09:35:53.464301 | 2025-09-11 09:35:53.586213 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-11 09:35:53.587429 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-11 09:35:54.316385 | 2025-09-11 09:35:54.316540 | PLAY [Base post] 2025-09-11 09:35:54.330015 | 2025-09-11 09:35:54.330149 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-11 09:35:54.837351 | debian-bookworm | changed 2025-09-11 09:35:54.843698 | 2025-09-11 09:35:54.843803 | PLAY RECAP 2025-09-11 09:35:54.843862 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-11 09:35:54.843941 | 2025-09-11 09:35:54.959035 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-11 09:35:54.960865 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-11 09:35:55.783455 | 2025-09-11 09:35:55.783620 | PLAY [Base post-logs] 2025-09-11 09:35:55.794352 | 2025-09-11 09:35:55.794478 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-11 09:35:56.258692 | localhost | changed 2025-09-11 09:35:56.272538 | 2025-09-11 09:35:56.272699 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-11 09:35:56.299267 | localhost | ok 2025-09-11 09:35:56.304003 | 2025-09-11 09:35:56.304142 | TASK [Set zuul-log-path fact] 2025-09-11 09:35:56.319918 | localhost | ok 2025-09-11 09:35:56.329996 | 2025-09-11 09:35:56.330124 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-11 09:35:56.366462 | localhost | ok 2025-09-11 09:35:56.373681 | 2025-09-11 09:35:56.373955 | TASK [upload-logs : Create log directories] 2025-09-11 09:35:56.852681 | localhost | changed 2025-09-11 09:35:56.857625 | 2025-09-11 09:35:56.857788 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-11 09:35:57.338277 | localhost -> localhost | ok: Runtime: 0:00:00.004229 2025-09-11 09:35:57.342406 | 2025-09-11 09:35:57.342524 | TASK [upload-logs : Upload logs to log server] 2025-09-11 09:35:57.888771 | localhost | Output suppressed because no_log was given 2025-09-11 09:35:57.893055 | 2025-09-11 09:35:57.893276 | LOOP [upload-logs : Compress console log and json output] 2025-09-11 09:35:57.955316 | localhost | skipping: Conditional result was False 2025-09-11 09:35:57.960002 | localhost | skipping: Conditional result was False 2025-09-11 09:35:57.967507 | 2025-09-11 09:35:57.967755 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-11 09:35:58.027279 | localhost | skipping: Conditional result was False 2025-09-11 09:35:58.027539 | 2025-09-11 09:35:58.036399 | localhost | skipping: Conditional result was False 2025-09-11 09:35:58.041619 | 2025-09-11 09:35:58.041742 | LOOP [upload-logs : Upload console log and json output]