2026-05-26 22:10:29.998430 | Job console starting 2026-05-26 22:10:30.021779 | Updating git repos 2026-05-26 22:10:30.077657 | Cloning repos into workspace 2026-05-26 22:10:30.205289 | Restoring repo states 2026-05-26 22:10:30.237299 | Merging changes 2026-05-26 22:10:30.767252 | Checking out repos 2026-05-26 22:10:30.873751 | Preparing playbooks 2026-05-26 22:10:32.411510 | Running Ansible setup 2026-05-26 22:10:38.219310 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-26 22:10:39.245939 | 2026-05-26 22:10:39.246159 | PLAY [Base pre] 2026-05-26 22:10:39.282454 | 2026-05-26 22:10:39.282679 | TASK [Setup log path fact] 2026-05-26 22:10:39.307707 | debian-bookworm | ok 2026-05-26 22:10:39.348293 | 2026-05-26 22:10:39.348510 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 22:10:39.405173 | debian-bookworm | ok 2026-05-26 22:10:39.426745 | 2026-05-26 22:10:39.426955 | TASK [emit-job-header : Print job information] 2026-05-26 22:10:39.510078 | # Job Information 2026-05-26 22:10:39.510323 | Ansible Version: 2.16.14 2026-05-26 22:10:39.510359 | Job: tox 2026-05-26 22:10:39.510393 | Pipeline: check 2026-05-26 22:10:39.510416 | Executor: 521e9411259a 2026-05-26 22:10:39.510438 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1220 2026-05-26 22:10:39.510460 | Event ID: ae8a1750-594f-11f1-8b01-13c0c983a16f 2026-05-26 22:10:39.520249 | 2026-05-26 22:10:39.520420 | LOOP [emit-job-header : Print node information] 2026-05-26 22:10:39.761266 | debian-bookworm | ok: 2026-05-26 22:10:39.761488 | debian-bookworm | # Node Information 2026-05-26 22:10:39.761523 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-26 22:10:39.761548 | debian-bookworm | Hostname: debian 2026-05-26 22:10:39.761571 | debian-bookworm | Username: zuul 2026-05-26 22:10:39.761592 | debian-bookworm | Distro: Debian 12.14 2026-05-26 22:10:39.761628 | debian-bookworm | Provider: regiocloud-a 2026-05-26 22:10:39.761650 | debian-bookworm | Region: 2026-05-26 22:10:39.761672 | debian-bookworm | Label: debian-bookworm 2026-05-26 22:10:39.761692 | debian-bookworm | Product Name: OpenStack Nova 2026-05-26 22:10:39.761711 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe05:df69 2026-05-26 22:10:39.786740 | 2026-05-26 22:10:39.787836 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-26 22:10:40.445203 | debian-bookworm -> localhost | changed 2026-05-26 22:10:40.454398 | 2026-05-26 22:10:40.454585 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-26 22:10:42.112498 | debian-bookworm -> localhost | changed 2026-05-26 22:10:42.124910 | 2026-05-26 22:10:42.125090 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-26 22:10:42.554329 | debian-bookworm -> localhost | ok 2026-05-26 22:10:42.560708 | 2026-05-26 22:10:42.560820 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-26 22:10:42.593265 | debian-bookworm | ok 2026-05-26 22:10:42.632483 | debian-bookworm | included: /var/lib/zuul/builds/f5c99ed977384a938496da32e639e076/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-26 22:10:42.647570 | 2026-05-26 22:10:42.647730 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-26 22:10:44.131231 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-26 22:10:44.131495 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/f5c99ed977384a938496da32e639e076/work/f5c99ed977384a938496da32e639e076_id_rsa 2026-05-26 22:10:44.131534 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/f5c99ed977384a938496da32e639e076/work/f5c99ed977384a938496da32e639e076_id_rsa.pub 2026-05-26 22:10:44.131561 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-26 22:10:44.131586 | debian-bookworm -> localhost | SHA256:YcxlE17vlAtfj4z71+fTfqrpoS46jhwZO584nN5Yrrg zuul-build-sshkey 2026-05-26 22:10:44.131607 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-26 22:10:44.131655 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-26 22:10:44.131677 | debian-bookworm -> localhost | | =.. | 2026-05-26 22:10:44.131697 | debian-bookworm -> localhost | | o + o . . | 2026-05-26 22:10:44.131717 | debian-bookworm -> localhost | | = . . + .| 2026-05-26 22:10:44.131736 | debian-bookworm -> localhost | | . . B +.| 2026-05-26 22:10:44.131756 | debian-bookworm -> localhost | | . S . * .| 2026-05-26 22:10:44.131782 | debian-bookworm -> localhost | | + . | 2026-05-26 22:10:44.131803 | debian-bookworm -> localhost | | .=.. o o| 2026-05-26 22:10:44.131823 | debian-bookworm -> localhost | | o+@... . + .*| 2026-05-26 22:10:44.131845 | debian-bookworm -> localhost | | EoB+Bo oo.+.o=*| 2026-05-26 22:10:44.131865 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-26 22:10:44.131915 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.803877 2026-05-26 22:10:44.138372 | 2026-05-26 22:10:44.138467 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-26 22:10:44.179008 | debian-bookworm | ok 2026-05-26 22:10:44.205528 | debian-bookworm | included: /var/lib/zuul/builds/f5c99ed977384a938496da32e639e076/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-26 22:10:44.243706 | 2026-05-26 22:10:44.243828 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-26 22:10:44.266694 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:10:44.272968 | 2026-05-26 22:10:44.273065 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-26 22:10:45.039329 | debian-bookworm | changed 2026-05-26 22:10:45.044455 | 2026-05-26 22:10:45.044537 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-26 22:10:45.338252 | debian-bookworm | ok 2026-05-26 22:10:45.345900 | 2026-05-26 22:10:45.346006 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-26 22:10:46.035659 | debian-bookworm | changed 2026-05-26 22:10:46.050101 | 2026-05-26 22:10:46.050422 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-26 22:10:46.703166 | debian-bookworm | changed 2026-05-26 22:10:46.709892 | 2026-05-26 22:10:46.709998 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-26 22:10:46.734366 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:10:46.742396 | 2026-05-26 22:10:46.742531 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-26 22:10:47.153961 | debian-bookworm -> localhost | changed 2026-05-26 22:10:47.164281 | 2026-05-26 22:10:47.164361 | TASK [add-build-sshkey : Add back temp key] 2026-05-26 22:10:47.485883 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/f5c99ed977384a938496da32e639e076/work/f5c99ed977384a938496da32e639e076_id_rsa (zuul-build-sshkey) 2026-05-26 22:10:47.486088 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010631 2026-05-26 22:10:47.493436 | 2026-05-26 22:10:47.493526 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-26 22:10:47.916108 | debian-bookworm | ok 2026-05-26 22:10:47.921018 | 2026-05-26 22:10:47.921102 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-26 22:10:47.944316 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:10:47.955520 | 2026-05-26 22:10:47.955639 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-26 22:10:48.387134 | debian-bookworm | ok 2026-05-26 22:10:48.397621 | 2026-05-26 22:10:48.397733 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-26 22:10:48.426126 | debian-bookworm | ok 2026-05-26 22:10:48.431391 | 2026-05-26 22:10:48.431474 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-26 22:10:48.827539 | debian-bookworm -> localhost | ok 2026-05-26 22:10:48.833850 | 2026-05-26 22:10:48.833932 | TASK [validate-host : Collect information about the host] 2026-05-26 22:10:50.053959 | debian-bookworm | ok 2026-05-26 22:10:50.064197 | 2026-05-26 22:10:50.064286 | TASK [validate-host : Sanitize hostname] 2026-05-26 22:10:50.143133 | debian-bookworm | ok 2026-05-26 22:10:50.172643 | 2026-05-26 22:10:50.172804 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-26 22:10:50.732732 | debian-bookworm -> localhost | changed 2026-05-26 22:10:50.738198 | 2026-05-26 22:10:50.738284 | TASK [validate-host : Collect information about zuul worker] 2026-05-26 22:10:51.111802 | debian-bookworm | ok 2026-05-26 22:10:51.117495 | 2026-05-26 22:10:51.117691 | TASK [validate-host : Write out all zuul information for each host] 2026-05-26 22:10:51.953118 | debian-bookworm -> localhost | changed 2026-05-26 22:10:51.973686 | 2026-05-26 22:10:51.973812 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-26 22:10:52.245326 | debian-bookworm | ok 2026-05-26 22:10:52.253423 | 2026-05-26 22:10:52.253541 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-26 22:10:53.578230 | debian-bookworm | changed: 2026-05-26 22:10:53.578446 | debian-bookworm | cd+++++++++ src/ 2026-05-26 22:10:53.578483 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-26 22:10:53.578507 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-26 22:10:53.578528 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-26 22:10:53.578549 | debian-bookworm | =7.0.3 2026-05-26 22:11:17.163162 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-05-26 22:11:17.200833 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-26 22:11:17.211641 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-26 22:11:17.276854 | debian-bookworm | Collecting filelock>=3.25 2026-05-26 22:11:17.287573 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-26 22:11:17.331384 | debian-bookworm | Collecting packaging>=26 2026-05-26 22:11:17.341689 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-26 22:11:17.357186 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 6.8 MB/s eta 0:00:00 2026-05-26 22:11:17.400485 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-26 22:11:17.411348 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-26 22:11:17.460022 | debian-bookworm | Collecting pluggy>=1.6 2026-05-26 22:11:17.470706 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-26 22:11:17.506779 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-26 22:11:17.519406 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-26 22:11:17.549767 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-26 22:11:17.560225 | debian-bookworm | Downloading python_discovery-1.3.1-py3-none-any.whl (33 kB) 2026-05-26 22:11:17.595711 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-26 22:11:17.606483 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-26 22:11:17.751143 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-26 22:11:17.762083 | debian-bookworm | Downloading virtualenv-21.3.3-py3-none-any.whl (7.6 MB) 2026-05-26 22:11:18.145714 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 20.0 MB/s eta 0:00:00 2026-05-26 22:11:18.247481 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-26 22:11:18.258347 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-26 22:11:18.278992 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 25.3 MB/s eta 0:00:00 2026-05-26 22:11:18.386496 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-26 22:11:19.017870 | debian-bookworm | Successfully installed cachetools-7.1.4 colorama-0.4.6 distlib-0.4.0 filelock-3.29.0 packaging-26.2 platformdirs-4.9.6 pluggy-1.6.0 pyproject-api-1.10.0 python-discovery-1.3.1 tomli-w-1.2.0 tox-4.54.0 virtualenv-21.3.3 2026-05-26 22:11:19.232898 | debian-bookworm | ok: Runtime: 0:00:02.617885 2026-05-26 22:11:19.239175 | 2026-05-26 22:11:19.239289 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-26 22:11:19.282778 | debian-bookworm | ok 2026-05-26 22:11:19.288913 | 2026-05-26 22:11:19.289057 | TASK [ensure-tox : Output tox version] 2026-05-26 22:11:19.751049 | 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-05-26 22:11:19.764280 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-26 22:11:19.838548 | debian-bookworm | ok: Runtime: 0:00:00.229831 2026-05-26 22:11:19.851751 | 2026-05-26 22:11:19.851978 | TASK [ensure-tox : Make global symlink] 2026-05-26 22:11:19.879253 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:19.896520 | 2026-05-26 22:11:19.896710 | PLAY RECAP 2026-05-26 22:11:19.896806 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-26 22:11:19.896849 | 2026-05-26 22:11:20.070607 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-26 22:11:20.071796 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-26 22:11:20.848790 | 2026-05-26 22:11:20.848974 | PLAY [all] 2026-05-26 22:11:20.865511 | 2026-05-26 22:11:20.865731 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-26 22:11:21.321620 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-26 22:11:21.394774 | debian-bookworm | ok: Runtime: 0:00:00.023975 2026-05-26 22:11:21.401250 | 2026-05-26 22:11:21.401394 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-26 22:11:21.943125 | debian-bookworm | ok: Runtime: 0:00:00.005963 2026-05-26 22:11:21.958395 | 2026-05-26 22:11:21.958622 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-26 22:11:22.255613 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-26 22:11:22.256980 | debian-bookworm | sudo: a password is required 2026-05-26 22:11:22.494335 | debian-bookworm | ok: Runtime: 0:00:00.015419 2026-05-26 22:11:22.513807 | 2026-05-26 22:11:22.513965 | TASK [tox : Check to see if the constraints file exists] 2026-05-26 22:11:22.553227 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:22.565598 | 2026-05-26 22:11:22.565804 | TASK [tox : Fail if constraints file is missing] 2026-05-26 22:11:22.611489 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:22.618473 | 2026-05-26 22:11:22.618662 | TASK [tox : Record file location] 2026-05-26 22:11:22.643785 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:22.650619 | 2026-05-26 22:11:22.650771 | TASK [tox : Install tox siblings] 2026-05-26 22:11:22.683190 | debian-bookworm | ok 2026-05-26 22:11:22.692242 | debian-bookworm | included: /var/lib/zuul/builds/f5c99ed977384a938496da32e639e076/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-26 22:11:22.699031 | 2026-05-26 22:11:22.699253 | TASK [tox : Run tox without tests] 2026-05-26 22:11:23.888388 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-26 22:11:35.877211 | debian-bookworm | test: OK (12.64 seconds) 2026-05-26 22:11:35.877281 | debian-bookworm | congratulations :) (12.67 seconds) 2026-05-26 22:11:36.241515 | debian-bookworm | ok: Runtime: 0:00:12.939634 2026-05-26 22:11:36.253598 | 2026-05-26 22:11:36.253759 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-26 22:11:36.651987 | debian-bookworm | changed 2026-05-26 22:11:36.661682 | 2026-05-26 22:11:36.661848 | TASK [tox : Detect tox version] 2026-05-26 22:11:37.163079 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-26 22:11:37.243744 | debian-bookworm | ok: Runtime: 0:00:00.261982 2026-05-26 22:11:37.252188 | 2026-05-26 22:11:37.252324 | TASK [tox : Get tox envlist config] 2026-05-26 22:11:38.287103 | debian-bookworm | ok: Runtime: 0:00:00.304822 2026-05-26 22:11:38.299059 | 2026-05-26 22:11:38.299214 | TASK [tox : Install any sibling python packages] 2026-05-26 22:11:38.746090 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-26 22:11:38.755302 | 2026-05-26 22:11:38.755451 | TASK [tox : Remove tempfile] 2026-05-26 22:11:39.242241 | debian-bookworm | changed 2026-05-26 22:11:39.258554 | 2026-05-26 22:11:39.258895 | TASK [tox : Emit tox command] 2026-05-26 22:11:39.318383 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-26 22:11:39.326623 | 2026-05-26 22:11:39.326778 | TASK [tox : Run tox] 2026-05-26 22:11:39.880692 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-26 22:11:40.514600 | debian-bookworm | ............... 2026-05-26 22:11:40.514632 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-26 22:11:40.514642 | debian-bookworm | Ran 15 tests in 0.166s 2026-05-26 22:11:40.514647 | debian-bookworm | 2026-05-26 22:11:40.514653 | debian-bookworm | OK 2026-05-26 22:11:40.570856 | debian-bookworm | test: OK (0.73=setup[0.04]+cmd[0.69] seconds) 2026-05-26 22:11:40.570900 | debian-bookworm | congratulations :) (0.75 seconds) 2026-05-26 22:11:40.862414 | debian-bookworm | ok: Runtime: 0:00:01.004997 2026-05-26 22:11:40.874109 | 2026-05-26 22:11:40.874276 | TASK [tox : Look for output] 2026-05-26 22:11:41.266937 | debian-bookworm | ok 2026-05-26 22:11:41.292377 | 2026-05-26 22:11:41.292535 | TASK [tox : Return file comments to Zuul] 2026-05-26 22:11:41.337654 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:41.350477 | 2026-05-26 22:11:41.350637 | PLAY RECAP 2026-05-26 22:11:41.350720 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-26 22:11:41.350759 | 2026-05-26 22:11:41.489308 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-26 22:11:41.490484 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-26 22:11:42.233902 | 2026-05-26 22:11:42.234112 | PLAY [all] 2026-05-26 22:11:42.249546 | 2026-05-26 22:11:42.249700 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-26 22:11:42.304849 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:42.313840 | 2026-05-26 22:11:42.314027 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-26 22:11:42.371688 | debian-bookworm | ok 2026-05-26 22:11:42.380642 | 2026-05-26 22:11:42.380797 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-26 22:11:42.861497 | debian-bookworm -> localhost | changed 2026-05-26 22:11:42.869611 | 2026-05-26 22:11:42.869733 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-26 22:11:42.924824 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:42.936899 | 2026-05-26 22:11:42.937081 | TASK [fetch-tox-output : Set envlist fact] 2026-05-26 22:11:42.973405 | debian-bookworm | ok 2026-05-26 22:11:42.982414 | 2026-05-26 22:11:42.982541 | TASK [fetch-tox-output : Get tox version] 2026-05-26 22:11:43.682418 | 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-05-26 22:11:43.699748 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-26 22:11:44.016013 | debian-bookworm | ok: Runtime: 0:00:00.309975 2026-05-26 22:11:44.027993 | 2026-05-26 22:11:44.028166 | TASK [fetch-tox-output : Find all default environments] 2026-05-26 22:11:44.561345 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:44.576424 | 2026-05-26 22:11:44.576647 | TASK [fetch-tox-output : Set envlist fact] 2026-05-26 22:11:44.612718 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:44.623059 | 2026-05-26 22:11:44.623268 | TASK [fetch-tox-output : Find all default environments] 2026-05-26 22:11:45.156193 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:45.169945 | 2026-05-26 22:11:45.170134 | TASK [fetch-tox-output : Set envlist fact] 2026-05-26 22:11:45.207101 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:45.213797 | 2026-05-26 22:11:45.213914 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-26 22:11:45.256717 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:45.265856 | 2026-05-26 22:11:45.265990 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-26 22:11:46.094150 | debian-bookworm | changed: 2026-05-26 22:11:46.094391 | debian-bookworm | created directory /var/lib/zuul/builds/f5c99ed977384a938496da32e639e076/work/logs/tox/test 2026-05-26 22:11:46.094434 | debian-bookworm | cd+++++++++ ./ 2026-05-26 22:11:46.094466 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-26 22:11:46.094500 | 2026-05-26 22:11:46.109760 | 2026-05-26 22:11:46.109877 | PLAY RECAP 2026-05-26 22:11:46.109942 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-26 22:11:46.109976 | 2026-05-26 22:11:46.243821 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-26 22:11:46.245813 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-26 22:11:47.192077 | 2026-05-26 22:11:47.192304 | PLAY [all] 2026-05-26 22:11:47.211614 | 2026-05-26 22:11:47.211770 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-26 22:11:47.470579 | debian-bookworm | changed: non-zero return code 2026-05-26 22:11:47.479241 | 2026-05-26 22:11:47.479410 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-26 22:11:47.504268 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:47.514531 | 2026-05-26 22:11:47.514728 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-26 22:11:47.556172 | 2026-05-26 22:11:47.556518 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-26 22:11:47.611697 | 2026-05-26 22:11:47.612452 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-26 22:11:47.637923 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:47.644232 | 2026-05-26 22:11:47.644354 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-26 22:11:47.674740 | 2026-05-26 22:11:47.674978 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-26 22:11:47.699024 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:47.712173 | 2026-05-26 22:11:47.712343 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-26 22:11:47.736951 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:47.750188 | 2026-05-26 22:11:47.750338 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-26 22:11:47.774684 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:47.783163 | 2026-05-26 22:11:47.783268 | PLAY RECAP 2026-05-26 22:11:47.783329 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-26 22:11:47.783359 | 2026-05-26 22:11:47.908375 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-26 22:11:47.909931 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-26 22:11:48.669131 | 2026-05-26 22:11:48.669311 | PLAY [Base post-fetch] 2026-05-26 22:11:48.684574 | 2026-05-26 22:11:48.684734 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-26 22:11:48.740108 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:11:48.751586 | 2026-05-26 22:11:48.751816 | TASK [fetch-output : Set log path for single node] 2026-05-26 22:11:48.798436 | debian-bookworm | ok 2026-05-26 22:11:48.807077 | 2026-05-26 22:11:48.807246 | LOOP [fetch-output : Ensure local output dirs] 2026-05-26 22:11:49.309689 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/f5c99ed977384a938496da32e639e076/work/logs" 2026-05-26 22:11:49.598729 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f5c99ed977384a938496da32e639e076/work/artifacts" 2026-05-26 22:11:49.864361 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f5c99ed977384a938496da32e639e076/work/docs" 2026-05-26 22:11:49.893489 | 2026-05-26 22:11:49.893750 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-26 22:11:50.711166 | debian-bookworm | changed: 2026-05-26 22:11:50.711482 | debian-bookworm | .d..t...... ./ 2026-05-26 22:11:50.711536 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-26 22:11:50.711606 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-26 22:11:50.711650 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-26 22:11:50.711712 | debian-bookworm | changed: All items complete 2026-05-26 22:11:50.711752 | 2026-05-26 22:11:51.325459 | debian-bookworm | changed: .d..t...... ./ 2026-05-26 22:11:51.930715 | debian-bookworm | changed: .d..t...... ./ 2026-05-26 22:11:51.955698 | 2026-05-26 22:11:51.955930 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-26 22:11:52.479117 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010262 2026-05-26 22:11:52.752764 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008114 2026-05-26 22:11:52.773980 | 2026-05-26 22:11:52.774154 | PLAY RECAP 2026-05-26 22:11:52.774259 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-26 22:11:52.774320 | 2026-05-26 22:11:52.924054 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-26 22:11:52.926639 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 22:11:53.675250 | 2026-05-26 22:11:53.675418 | PLAY [Base post] 2026-05-26 22:11:53.689334 | 2026-05-26 22:11:53.689498 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-26 22:11:54.252416 | debian-bookworm | changed 2026-05-26 22:11:54.262197 | 2026-05-26 22:11:54.262331 | PLAY RECAP 2026-05-26 22:11:54.262412 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-26 22:11:54.262487 | 2026-05-26 22:11:54.391328 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 22:11:54.393954 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-26 22:11:55.219840 | 2026-05-26 22:11:55.220083 | PLAY [Base post-logs] 2026-05-26 22:11:55.231239 | 2026-05-26 22:11:55.231377 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-26 22:11:55.695422 | localhost | changed 2026-05-26 22:11:55.708416 | 2026-05-26 22:11:55.708596 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-26 22:11:55.735709 | localhost | ok 2026-05-26 22:11:55.740523 | 2026-05-26 22:11:55.740668 | TASK [Set zuul-log-path fact] 2026-05-26 22:11:55.757357 | localhost | ok 2026-05-26 22:11:55.769940 | 2026-05-26 22:11:55.770077 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 22:11:55.796215 | localhost | ok 2026-05-26 22:11:55.800693 | 2026-05-26 22:11:55.800825 | TASK [upload-logs : Create log directories] 2026-05-26 22:11:56.296314 | localhost | changed 2026-05-26 22:11:56.299223 | 2026-05-26 22:11:56.299334 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-26 22:11:56.820473 | localhost -> localhost | ok: Runtime: 0:00:00.005625 2026-05-26 22:11:56.824707 | 2026-05-26 22:11:56.824830 | TASK [upload-logs : Upload logs to log server] 2026-05-26 22:11:57.410716 | localhost | Output suppressed because no_log was given 2026-05-26 22:11:57.413731 | 2026-05-26 22:11:57.413882 | LOOP [upload-logs : Compress console log and json output] 2026-05-26 22:11:57.472161 | localhost | skipping: Conditional result was False 2026-05-26 22:11:57.480160 | localhost | skipping: Conditional result was False 2026-05-26 22:11:57.484217 | 2026-05-26 22:11:57.484346 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-26 22:11:57.545571 | localhost | skipping: Conditional result was False 2026-05-26 22:11:57.546195 | 2026-05-26 22:11:57.549174 | localhost | skipping: Conditional result was False 2026-05-26 22:11:57.554616 | 2026-05-26 22:11:57.554767 | LOOP [upload-logs : Upload console log and json output]