2026-06-07 17:50:39.415397 | Job console starting 2026-06-07 17:50:39.433369 | Updating git repos 2026-06-07 17:50:39.521443 | Cloning repos into workspace 2026-06-07 17:50:39.611932 | Restoring repo states 2026-06-07 17:50:39.619417 | Merging changes 2026-06-07 17:50:40.093565 | Checking out repos 2026-06-07 17:50:40.193750 | Preparing playbooks 2026-06-07 17:50:41.320950 | Running Ansible setup 2026-06-07 17:50:46.361288 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-06-07 17:50:47.520600 | 2026-06-07 17:50:47.520777 | PLAY [Base pre] 2026-06-07 17:50:47.539773 | 2026-06-07 17:50:47.539929 | TASK [Setup log path fact] 2026-06-07 17:50:47.579771 | debian-bookworm | ok 2026-06-07 17:50:47.596380 | 2026-06-07 17:50:47.596549 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-07 17:50:47.625839 | debian-bookworm | ok 2026-06-07 17:50:47.644738 | 2026-06-07 17:50:47.644895 | TASK [emit-job-header : Print job information] 2026-06-07 17:50:47.729699 | # Job Information 2026-06-07 17:50:47.729903 | Ansible Version: 2.16.14 2026-06-07 17:50:47.729937 | Job: tox 2026-06-07 17:50:47.729970 | Pipeline: check 2026-06-07 17:50:47.729994 | Executor: 521e9411259a 2026-06-07 17:50:47.730015 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1229 2026-06-07 17:50:47.730037 | Event ID: 5f460a20-6299-11f1-8029-bc590e731c36 2026-06-07 17:50:47.736244 | 2026-06-07 17:50:47.736397 | LOOP [emit-job-header : Print node information] 2026-06-07 17:50:47.853710 | debian-bookworm | ok: 2026-06-07 17:50:47.853908 | debian-bookworm | # Node Information 2026-06-07 17:50:47.853943 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-06-07 17:50:47.853968 | debian-bookworm | Hostname: debian 2026-06-07 17:50:47.853990 | debian-bookworm | Username: zuul 2026-06-07 17:50:47.854010 | debian-bookworm | Distro: Debian 12.14 2026-06-07 17:50:47.854035 | debian-bookworm | Provider: regiocloud-a 2026-06-07 17:50:47.854095 | debian-bookworm | Region: 2026-06-07 17:50:47.854117 | debian-bookworm | Label: debian-bookworm 2026-06-07 17:50:47.854137 | debian-bookworm | Product Name: OpenStack Nova 2026-06-07 17:50:47.854156 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec7:125 2026-06-07 17:50:47.868246 | 2026-06-07 17:50:47.868406 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-07 17:50:48.445783 | debian-bookworm -> localhost | changed 2026-06-07 17:50:48.453576 | 2026-06-07 17:50:48.453728 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-07 17:50:49.898190 | debian-bookworm -> localhost | changed 2026-06-07 17:50:49.908711 | 2026-06-07 17:50:49.908823 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-07 17:50:50.199990 | debian-bookworm -> localhost | ok 2026-06-07 17:50:50.205923 | 2026-06-07 17:50:50.206036 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-07 17:50:50.245675 | debian-bookworm | ok 2026-06-07 17:50:50.269995 | debian-bookworm | included: /var/lib/zuul/builds/9bcfd26664414d688273422b6d83e2e5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-07 17:50:50.285751 | 2026-06-07 17:50:50.285860 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-07 17:50:53.058279 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-06-07 17:50:53.058580 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/9bcfd26664414d688273422b6d83e2e5/work/9bcfd26664414d688273422b6d83e2e5_id_rsa 2026-06-07 17:50:53.058644 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/9bcfd26664414d688273422b6d83e2e5/work/9bcfd26664414d688273422b6d83e2e5_id_rsa.pub 2026-06-07 17:50:53.058690 | debian-bookworm -> localhost | The key fingerprint is: 2026-06-07 17:50:53.058731 | debian-bookworm -> localhost | SHA256:vNCjl0iqWIgMw5q/elTfrimFj9tyHMjwyJZPPc64m8Q zuul-build-sshkey 2026-06-07 17:50:53.058771 | debian-bookworm -> localhost | The key's randomart image is: 2026-06-07 17:50:53.058823 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-06-07 17:50:53.058886 | debian-bookworm -> localhost | | | 2026-06-07 17:50:53.058924 | debian-bookworm -> localhost | | | 2026-06-07 17:50:53.058960 | debian-bookworm -> localhost | | | 2026-06-07 17:50:53.058995 | debian-bookworm -> localhost | |. . . o | 2026-06-07 17:50:53.059030 | debian-bookworm -> localhost | |oo B =o.S | 2026-06-07 17:50:53.059097 | debian-bookworm -> localhost | |=+*.*o*+.+ | 2026-06-07 17:50:53.059137 | debian-bookworm -> localhost | |=+.oEOo++ | 2026-06-07 17:50:53.059172 | debian-bookworm -> localhost | | +.o*o*o. | 2026-06-07 17:50:53.059209 | debian-bookworm -> localhost | |oo+.=B+. | 2026-06-07 17:50:53.059245 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-06-07 17:50:53.059330 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.000993 2026-06-07 17:50:53.068753 | 2026-06-07 17:50:53.068882 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-07 17:50:53.099077 | debian-bookworm | ok 2026-06-07 17:50:53.118433 | debian-bookworm | included: /var/lib/zuul/builds/9bcfd26664414d688273422b6d83e2e5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-07 17:50:53.131962 | 2026-06-07 17:50:53.132089 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-07 17:50:53.145686 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:50:53.153526 | 2026-06-07 17:50:53.153635 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-07 17:50:53.780466 | debian-bookworm | changed 2026-06-07 17:50:53.786690 | 2026-06-07 17:50:53.786790 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-07 17:50:54.032275 | debian-bookworm | ok 2026-06-07 17:50:54.038508 | 2026-06-07 17:50:54.038607 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-07 17:50:54.656583 | debian-bookworm | changed 2026-06-07 17:50:54.663405 | 2026-06-07 17:50:54.663751 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-07 17:50:55.386807 | debian-bookworm | changed 2026-06-07 17:50:55.398432 | 2026-06-07 17:50:55.398578 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-07 17:50:55.422548 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:50:55.429609 | 2026-06-07 17:50:55.429745 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-07 17:50:55.950516 | debian-bookworm -> localhost | changed 2026-06-07 17:50:55.961863 | 2026-06-07 17:50:55.962742 | TASK [add-build-sshkey : Add back temp key] 2026-06-07 17:50:56.343337 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/9bcfd26664414d688273422b6d83e2e5/work/9bcfd26664414d688273422b6d83e2e5_id_rsa (zuul-build-sshkey) 2026-06-07 17:50:56.343593 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012258 2026-06-07 17:50:56.352094 | 2026-06-07 17:50:56.352235 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-07 17:50:56.759301 | debian-bookworm | ok 2026-06-07 17:50:56.764912 | 2026-06-07 17:50:56.765085 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-07 17:50:56.789343 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:50:56.801061 | 2026-06-07 17:50:56.801193 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-07 17:50:57.265435 | debian-bookworm | ok 2026-06-07 17:50:57.277199 | 2026-06-07 17:50:57.277339 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-07 17:50:57.327737 | debian-bookworm | ok 2026-06-07 17:50:57.333189 | 2026-06-07 17:50:57.333322 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-07 17:50:58.035289 | debian-bookworm -> localhost | ok 2026-06-07 17:50:58.042491 | 2026-06-07 17:50:58.042617 | TASK [validate-host : Collect information about the host] 2026-06-07 17:50:59.203827 | debian-bookworm | ok 2026-06-07 17:50:59.223796 | 2026-06-07 17:50:59.223937 | TASK [validate-host : Sanitize hostname] 2026-06-07 17:50:59.327662 | debian-bookworm | ok 2026-06-07 17:50:59.391727 | 2026-06-07 17:50:59.391868 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-07 17:50:59.975584 | debian-bookworm -> localhost | changed 2026-06-07 17:50:59.981978 | 2026-06-07 17:50:59.982256 | TASK [validate-host : Collect information about zuul worker] 2026-06-07 17:51:00.395401 | debian-bookworm | ok 2026-06-07 17:51:00.402402 | 2026-06-07 17:51:00.402540 | TASK [validate-host : Write out all zuul information for each host] 2026-06-07 17:51:01.014649 | debian-bookworm -> localhost | changed 2026-06-07 17:51:01.028119 | 2026-06-07 17:51:01.028286 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-06-07 17:51:01.320632 | debian-bookworm | ok 2026-06-07 17:51:01.329492 | 2026-06-07 17:51:01.329649 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-06-07 17:51:02.305530 | debian-bookworm | changed: 2026-06-07 17:51:02.305740 | debian-bookworm | cd+++++++++ src/ 2026-06-07 17:51:02.305776 | debian-bookworm | cd+++++++++ src/github.com/ 2026-06-07 17:51:02.305800 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-06-07 17:51:02.305821 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-06-07 17:51:02.305842 | debian-bookworm | =7.0.3 2026-06-07 17:51:27.321436 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-06-07 17:51:27.363185 | debian-bookworm | Collecting colorama>=0.4.6 2026-06-07 17:51:27.375835 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-06-07 17:51:27.438480 | debian-bookworm | Collecting filelock>=3.25 2026-06-07 17:51:27.450409 | debian-bookworm | Downloading filelock-3.29.1-py3-none-any.whl (40 kB) 2026-06-07 17:51:27.459486 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 40.8/40.8 kB 4.9 MB/s eta 0:00:00 2026-06-07 17:51:27.512420 | debian-bookworm | Collecting packaging>=26 2026-06-07 17:51:27.524349 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-06-07 17:51:27.541052 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 6.7 MB/s eta 0:00:00 2026-06-07 17:51:27.609669 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-06-07 17:51:27.621941 | debian-bookworm | Downloading platformdirs-4.10.0-py3-none-any.whl (22 kB) 2026-06-07 17:51:27.662323 | debian-bookworm | Collecting pluggy>=1.6 2026-06-07 17:51:27.674603 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-06-07 17:51:27.733512 | debian-bookworm | Collecting pyproject-api>=1.10 2026-06-07 17:51:27.747287 | debian-bookworm | Downloading pyproject_api-1.10.1-py3-none-any.whl (12 kB) 2026-06-07 17:51:27.793008 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-06-07 17:51:27.805324 | debian-bookworm | Downloading python_discovery-1.4.0-py3-none-any.whl (33 kB) 2026-06-07 17:51:27.856836 | debian-bookworm | Collecting tomli-w>=1.2 2026-06-07 17:51:27.870982 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-06-07 17:51:28.015823 | debian-bookworm | Collecting virtualenv>=21.1 2026-06-07 17:51:28.031049 | debian-bookworm | Downloading virtualenv-21.4.2-py3-none-any.whl (7.6 MB) 2026-06-07 17:51:28.444886 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 18.6 MB/s eta 0:00:00 2026-06-07 17:51:28.560081 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-06-07 17:51:28.572480 | debian-bookworm | Downloading distlib-0.4.1-py2.py3-none-any.whl (469 kB) 2026-06-07 17:51:28.596829 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.2/469.2 kB 23.0 MB/s eta 0:00:00 2026-06-07 17:51:28.709603 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-06-07 17:51:29.535235 | debian-bookworm | Successfully installed cachetools-7.1.4 colorama-0.4.6 distlib-0.4.1 filelock-3.29.1 packaging-26.2 platformdirs-4.10.0 pluggy-1.6.0 pyproject-api-1.10.1 python-discovery-1.4.0 tomli-w-1.2.0 tox-4.55.1 virtualenv-21.4.2 2026-06-07 17:51:29.705499 | debian-bookworm | ok: Runtime: 0:00:03.227912 2026-06-07 17:51:29.711217 | 2026-06-07 17:51:29.711339 | TASK [ensure-tox : Export installed tox_executable path] 2026-06-07 17:51:29.741946 | debian-bookworm | ok 2026-06-07 17:51:29.748308 | 2026-06-07 17:51:29.748429 | TASK [ensure-tox : Output tox version] 2026-06-07 17:51:30.279467 | 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-06-07 17:51:30.297235 | debian-bookworm | 4.55.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-06-07 17:51:30.784980 | debian-bookworm | ok: Runtime: 0:00:00.328081 2026-06-07 17:51:30.790997 | 2026-06-07 17:51:30.791140 | TASK [ensure-tox : Make global symlink] 2026-06-07 17:51:30.816623 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:30.823339 | 2026-06-07 17:51:30.823424 | PLAY RECAP 2026-06-07 17:51:30.823476 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-06-07 17:51:30.823501 | 2026-06-07 17:51:30.986964 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-06-07 17:51:30.988088 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-06-07 17:51:31.837002 | 2026-06-07 17:51:31.837203 | PLAY [all] 2026-06-07 17:51:31.855862 | 2026-06-07 17:51:31.856058 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-06-07 17:51:32.349201 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-06-07 17:51:32.393236 | debian-bookworm | ok: Runtime: 0:00:00.027871 2026-06-07 17:51:32.398658 | 2026-06-07 17:51:32.398787 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-06-07 17:51:32.927531 | debian-bookworm | ok: Runtime: 0:00:00.008921 2026-06-07 17:51:32.933808 | 2026-06-07 17:51:32.933943 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-06-07 17:51:33.232229 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-06-07 17:51:33.233923 | debian-bookworm | sudo: a password is required 2026-06-07 17:51:33.468392 | debian-bookworm | ok: Runtime: 0:00:00.020918 2026-06-07 17:51:33.481349 | 2026-06-07 17:51:33.481468 | TASK [tox : Check to see if the constraints file exists] 2026-06-07 17:51:33.508007 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:33.515625 | 2026-06-07 17:51:33.515775 | TASK [tox : Fail if constraints file is missing] 2026-06-07 17:51:33.541945 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:33.549329 | 2026-06-07 17:51:33.549509 | TASK [tox : Record file location] 2026-06-07 17:51:33.574107 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:33.581089 | 2026-06-07 17:51:33.581222 | TASK [tox : Install tox siblings] 2026-06-07 17:51:33.604249 | debian-bookworm | ok 2026-06-07 17:51:33.616488 | debian-bookworm | included: /var/lib/zuul/builds/9bcfd26664414d688273422b6d83e2e5/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-06-07 17:51:33.622274 | 2026-06-07 17:51:33.622396 | TASK [tox : Run tox without tests] 2026-06-07 17:51:34.919587 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-06-07 17:51:47.944864 | debian-bookworm | test: OK (13.76 seconds) 2026-06-07 17:51:47.944944 | debian-bookworm | congratulations :) (13.79 seconds) 2026-06-07 17:51:48.160562 | debian-bookworm | ok: Runtime: 0:00:14.084796 2026-06-07 17:51:48.172489 | 2026-06-07 17:51:48.172657 | TASK [tox : Create a tempfile to save tox showconfig] 2026-06-07 17:51:48.574574 | debian-bookworm | changed 2026-06-07 17:51:48.583011 | 2026-06-07 17:51:48.583206 | TASK [tox : Detect tox version] 2026-06-07 17:51:49.132867 | debian-bookworm | 4.55.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-06-07 17:51:49.614935 | debian-bookworm | ok: Runtime: 0:00:00.310878 2026-06-07 17:51:49.627836 | 2026-06-07 17:51:49.628012 | TASK [tox : Get tox envlist config] 2026-06-07 17:51:50.672488 | debian-bookworm | ok: Runtime: 0:00:00.324911 2026-06-07 17:51:50.685331 | 2026-06-07 17:51:50.685498 | TASK [tox : Install any sibling python packages] 2026-06-07 17:51:51.119835 | debian-bookworm | ok: No setup.cfg, no action needed 2026-06-07 17:51:51.129626 | 2026-06-07 17:51:51.129786 | TASK [tox : Remove tempfile] 2026-06-07 17:51:51.579212 | debian-bookworm | changed 2026-06-07 17:51:51.584944 | 2026-06-07 17:51:51.585061 | TASK [tox : Emit tox command] 2026-06-07 17:51:51.626262 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-06-07 17:51:51.632720 | 2026-06-07 17:51:51.632846 | TASK [tox : Run tox] 2026-06-07 17:51:52.190730 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-06-07 17:51:52.924218 | debian-bookworm | ............... 2026-06-07 17:51:52.924850 | debian-bookworm | ---------------------------------------------------------------------- 2026-06-07 17:51:52.924876 | debian-bookworm | Ran 15 tests in 0.176s 2026-06-07 17:51:52.924881 | debian-bookworm | 2026-06-07 17:51:52.924888 | debian-bookworm | OK 2026-06-07 17:51:53.019982 | debian-bookworm | test: OK (0.87=setup[0.04]+cmd[0.83] seconds) 2026-06-07 17:51:53.020053 | debian-bookworm | congratulations :) (0.89 seconds) 2026-06-07 17:51:53.164096 | debian-bookworm | ok: Runtime: 0:00:01.167620 2026-06-07 17:51:53.176937 | 2026-06-07 17:51:53.177093 | TASK [tox : Look for output] 2026-06-07 17:51:53.571255 | debian-bookworm | ok 2026-06-07 17:51:53.589116 | 2026-06-07 17:51:53.589294 | TASK [tox : Return file comments to Zuul] 2026-06-07 17:51:53.623776 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:53.636859 | 2026-06-07 17:51:53.636980 | PLAY RECAP 2026-06-07 17:51:53.637062 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-06-07 17:51:53.637103 | 2026-06-07 17:51:53.770244 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-06-07 17:51:53.772709 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-06-07 17:51:54.525563 | 2026-06-07 17:51:54.525734 | PLAY [all] 2026-06-07 17:51:54.541060 | 2026-06-07 17:51:54.541237 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-06-07 17:51:54.586092 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:54.597922 | 2026-06-07 17:51:54.598154 | TASK [fetch-tox-output : Set tox log path for single node] 2026-06-07 17:51:54.655555 | debian-bookworm | ok 2026-06-07 17:51:54.664156 | 2026-06-07 17:51:54.664365 | TASK [fetch-tox-output : Ensure local tox dir] 2026-06-07 17:51:55.147855 | debian-bookworm -> localhost | changed 2026-06-07 17:51:55.159976 | 2026-06-07 17:51:55.160114 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-06-07 17:51:55.204625 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:55.221691 | 2026-06-07 17:51:55.221944 | TASK [fetch-tox-output : Set envlist fact] 2026-06-07 17:51:55.269258 | debian-bookworm | ok 2026-06-07 17:51:55.278333 | 2026-06-07 17:51:55.278478 | TASK [fetch-tox-output : Get tox version] 2026-06-07 17:51:55.964411 | 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-06-07 17:51:55.981660 | debian-bookworm | 4.55.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-06-07 17:51:56.310872 | debian-bookworm | ok: Runtime: 0:00:00.307750 2026-06-07 17:51:56.316655 | 2026-06-07 17:51:56.316779 | TASK [fetch-tox-output : Find all default environments] 2026-06-07 17:51:56.845347 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:56.854696 | 2026-06-07 17:51:56.854911 | TASK [fetch-tox-output : Set envlist fact] 2026-06-07 17:51:56.890131 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:56.899672 | 2026-06-07 17:51:56.899840 | TASK [fetch-tox-output : Find all default environments] 2026-06-07 17:51:57.439515 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:57.452958 | 2026-06-07 17:51:57.453240 | TASK [fetch-tox-output : Set envlist fact] 2026-06-07 17:51:57.489373 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:57.499173 | 2026-06-07 17:51:57.499329 | LOOP [fetch-tox-output : Copy tox logs] 2026-06-07 17:51:57.549392 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:57.569197 | 2026-06-07 17:51:57.569385 | LOOP [fetch-tox-output : Collect tox logs] 2026-06-07 17:51:58.350780 | debian-bookworm | changed: 2026-06-07 17:51:58.351053 | debian-bookworm | created directory /var/lib/zuul/builds/9bcfd26664414d688273422b6d83e2e5/work/logs/tox/test 2026-06-07 17:51:58.351090 | debian-bookworm | cd+++++++++ ./ 2026-06-07 17:51:58.351114 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-06-07 17:51:58.351146 | 2026-06-07 17:51:58.358557 | 2026-06-07 17:51:58.358646 | PLAY RECAP 2026-06-07 17:51:58.358701 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-06-07 17:51:58.358726 | 2026-06-07 17:51:58.496715 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-06-07 17:51:58.497800 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-07 17:51:59.256068 | 2026-06-07 17:51:59.256254 | PLAY [all] 2026-06-07 17:51:59.272419 | 2026-06-07 17:51:59.272578 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-06-07 17:51:59.506046 | debian-bookworm | changed: non-zero return code 2026-06-07 17:51:59.511732 | 2026-06-07 17:51:59.511866 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-06-07 17:51:59.535743 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:59.541919 | 2026-06-07 17:51:59.542041 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-06-07 17:51:59.572727 | 2026-06-07 17:51:59.572914 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-06-07 17:51:59.617401 | 2026-06-07 17:51:59.617624 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-06-07 17:51:59.641538 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:59.651779 | 2026-06-07 17:51:59.651930 | LOOP [fetch-subunit-output : Generate subunit file] 2026-06-07 17:51:59.696808 | 2026-06-07 17:51:59.697125 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-06-07 17:51:59.722090 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:59.733706 | 2026-06-07 17:51:59.733874 | TASK [fetch-subunit-output : Remove the temporary file] 2026-06-07 17:51:59.758318 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:59.768728 | 2026-06-07 17:51:59.768897 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-06-07 17:51:59.793478 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:51:59.807266 | 2026-06-07 17:51:59.807405 | PLAY RECAP 2026-06-07 17:51:59.807487 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-06-07 17:51:59.807528 | 2026-06-07 17:51:59.942661 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-06-07 17:51:59.947230 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-07 17:52:00.702447 | 2026-06-07 17:52:00.702616 | PLAY [Base post-fetch] 2026-06-07 17:52:00.717401 | 2026-06-07 17:52:00.717532 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-07 17:52:00.793380 | debian-bookworm | skipping: Conditional result was False 2026-06-07 17:52:00.805831 | 2026-06-07 17:52:00.806078 | TASK [fetch-output : Set log path for single node] 2026-06-07 17:52:00.852237 | debian-bookworm | ok 2026-06-07 17:52:00.860211 | 2026-06-07 17:52:00.860389 | LOOP [fetch-output : Ensure local output dirs] 2026-06-07 17:52:01.342629 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/9bcfd26664414d688273422b6d83e2e5/work/logs" 2026-06-07 17:52:01.637138 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/9bcfd26664414d688273422b6d83e2e5/work/artifacts" 2026-06-07 17:52:01.906111 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/9bcfd26664414d688273422b6d83e2e5/work/docs" 2026-06-07 17:52:01.936727 | 2026-06-07 17:52:01.936976 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-07 17:52:02.701002 | debian-bookworm | changed: 2026-06-07 17:52:02.701401 | debian-bookworm | .d..t...... ./ 2026-06-07 17:52:02.701468 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-06-07 17:52:02.701499 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-06-07 17:52:02.701526 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-06-07 17:52:02.701580 | debian-bookworm | changed: All items complete 2026-06-07 17:52:02.701606 | 2026-06-07 17:52:03.279213 | debian-bookworm | changed: .d..t...... ./ 2026-06-07 17:52:03.822686 | debian-bookworm | changed: .d..t...... ./ 2026-06-07 17:52:03.841932 | 2026-06-07 17:52:03.842079 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-07 17:52:04.343645 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.007754 2026-06-07 17:52:04.634580 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.011916 2026-06-07 17:52:04.654501 | 2026-06-07 17:52:04.654645 | PLAY RECAP 2026-06-07 17:52:04.654719 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-07 17:52:04.654755 | 2026-06-07 17:52:04.788703 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-07 17:52:04.791563 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-07 17:52:05.537672 | 2026-06-07 17:52:05.537822 | PLAY [Base post] 2026-06-07 17:52:05.551438 | 2026-06-07 17:52:05.551579 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-07 17:52:06.112688 | debian-bookworm | changed 2026-06-07 17:52:06.121199 | 2026-06-07 17:52:06.121347 | PLAY RECAP 2026-06-07 17:52:06.121425 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-07 17:52:06.121501 | 2026-06-07 17:52:06.247574 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-07 17:52:06.249843 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-07 17:52:07.027250 | 2026-06-07 17:52:07.027439 | PLAY [Base post-logs] 2026-06-07 17:52:07.038089 | 2026-06-07 17:52:07.038230 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-07 17:52:07.534187 | localhost | changed 2026-06-07 17:52:07.551661 | 2026-06-07 17:52:07.551871 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-07 17:52:07.589711 | localhost | ok 2026-06-07 17:52:07.594765 | 2026-06-07 17:52:07.594949 | TASK [Set zuul-log-path fact] 2026-06-07 17:52:07.612468 | localhost | ok 2026-06-07 17:52:07.623681 | 2026-06-07 17:52:07.623823 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-07 17:52:07.661603 | localhost | ok 2026-06-07 17:52:07.668830 | 2026-06-07 17:52:07.669017 | TASK [upload-logs : Create log directories] 2026-06-07 17:52:08.170779 | localhost | changed 2026-06-07 17:52:08.175208 | 2026-06-07 17:52:08.175391 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-07 17:52:08.700334 | localhost -> localhost | ok: Runtime: 0:00:00.007103 2026-06-07 17:52:08.709430 | 2026-06-07 17:52:08.709664 | TASK [upload-logs : Upload logs to log server] 2026-06-07 17:52:09.278453 | localhost | Output suppressed because no_log was given 2026-06-07 17:52:09.282689 | 2026-06-07 17:52:09.282886 | LOOP [upload-logs : Compress console log and json output] 2026-06-07 17:52:09.340522 | localhost | skipping: Conditional result was False 2026-06-07 17:52:09.345519 | localhost | skipping: Conditional result was False 2026-06-07 17:52:09.357156 | 2026-06-07 17:52:09.357387 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-07 17:52:09.404193 | localhost | skipping: Conditional result was False 2026-06-07 17:52:09.406178 | 2026-06-07 17:52:09.411265 | localhost | skipping: Conditional result was False 2026-06-07 17:52:09.424740 | 2026-06-07 17:52:09.424954 | LOOP [upload-logs : Upload console log and json output]