2025-05-16 03:33:01.263335 | Job console starting 2025-05-16 03:33:01.286520 | Updating git repos 2025-05-16 03:33:01.312907 | Cloning repos into workspace 2025-05-16 03:33:01.339346 | Restoring repo states 2025-05-16 03:33:01.360189 | Merging changes 2025-05-16 03:33:01.360211 | Checking out repos 2025-05-16 03:33:01.387034 | Preparing playbooks 2025-05-16 03:33:01.983975 | Running Ansible setup 2025-05-16 03:33:06.201536 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-05-16 03:33:06.985321 | 2025-05-16 03:33:06.985495 | PLAY [Base pre] 2025-05-16 03:33:07.011835 | 2025-05-16 03:33:07.011997 | TASK [Setup log path fact] 2025-05-16 03:33:07.032982 | debian-bookworm | ok 2025-05-16 03:33:07.049850 | 2025-05-16 03:33:07.050040 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-05-16 03:33:07.081128 | debian-bookworm | ok 2025-05-16 03:33:07.091574 | 2025-05-16 03:33:07.091700 | TASK [emit-job-header : Print job information] 2025-05-16 03:33:07.152128 | # Job Information 2025-05-16 03:33:07.152446 | Ansible Version: 2.16.14 2025-05-16 03:33:07.152493 | Job: cfg-generics-tox-check 2025-05-16 03:33:07.152529 | Pipeline: periodic-daily 2025-05-16 03:33:07.152554 | Executor: 521e9411259a 2025-05-16 03:33:07.152575 | Triggered by: https://github.com/osism/cfg-generics 2025-05-16 03:33:07.152598 | Event ID: 5a37b44c8d784b81adb77c73650c4484 2025-05-16 03:33:07.160272 | 2025-05-16 03:33:07.160395 | LOOP [emit-job-header : Print node information] 2025-05-16 03:33:07.304248 | debian-bookworm | ok: 2025-05-16 03:33:07.304457 | debian-bookworm | # Node Information 2025-05-16 03:33:07.304491 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-05-16 03:33:07.304515 | debian-bookworm | Hostname: debian 2025-05-16 03:33:07.304537 | debian-bookworm | Username: zuul 2025-05-16 03:33:07.304558 | debian-bookworm | Distro: Debian 12.10 2025-05-16 03:33:07.304582 | debian-bookworm | Provider: regiocloud-a 2025-05-16 03:33:07.304603 | debian-bookworm | Region: 2025-05-16 03:33:07.304625 | debian-bookworm | Label: debian-bookworm 2025-05-16 03:33:07.304645 | debian-bookworm | Product Name: OpenStack Nova 2025-05-16 03:33:07.304665 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe9c:ec59 2025-05-16 03:33:07.335422 | 2025-05-16 03:33:07.335584 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-05-16 03:33:07.845982 | debian-bookworm -> localhost | changed 2025-05-16 03:33:07.859492 | 2025-05-16 03:33:07.859638 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-05-16 03:33:08.989982 | debian-bookworm -> localhost | changed 2025-05-16 03:33:09.001220 | 2025-05-16 03:33:09.001364 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-05-16 03:33:09.305352 | debian-bookworm -> localhost | ok 2025-05-16 03:33:09.319417 | 2025-05-16 03:33:09.319603 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-05-16 03:33:09.354053 | debian-bookworm | ok 2025-05-16 03:33:09.376006 | debian-bookworm | included: /var/lib/zuul/builds/ff67a428548c4d48b8e574f3339516e2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-05-16 03:33:09.382053 | 2025-05-16 03:33:09.382178 | TASK [add-build-sshkey : Create Temp SSH key] 2025-05-16 03:33:10.705695 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-05-16 03:33:10.705943 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ff67a428548c4d48b8e574f3339516e2/work/ff67a428548c4d48b8e574f3339516e2_id_rsa 2025-05-16 03:33:10.705988 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ff67a428548c4d48b8e574f3339516e2/work/ff67a428548c4d48b8e574f3339516e2_id_rsa.pub 2025-05-16 03:33:10.706017 | debian-bookworm -> localhost | The key fingerprint is: 2025-05-16 03:33:10.706042 | debian-bookworm -> localhost | SHA256:hvbapzNwMwKtrwHXPPB0ceuGJRkL95hFvXSL4Uetxt0 zuul-build-sshkey 2025-05-16 03:33:10.706065 | debian-bookworm -> localhost | The key's randomart image is: 2025-05-16 03:33:10.706119 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-05-16 03:33:10.706146 | debian-bookworm -> localhost | | . +.+. . | 2025-05-16 03:33:10.706168 | debian-bookworm -> localhost | | o @ .+ o .| 2025-05-16 03:33:10.706194 | debian-bookworm -> localhost | | ... B +o B +.| 2025-05-16 03:33:10.706214 | debian-bookworm -> localhost | | .*.o = + * E| 2025-05-16 03:33:10.706234 | debian-bookworm -> localhost | | . .oB S o o | 2025-05-16 03:33:10.706258 | debian-bookworm -> localhost | | o..o++. | 2025-05-16 03:33:10.706278 | debian-bookworm -> localhost | | .. +.o | 2025-05-16 03:33:10.706301 | debian-bookworm -> localhost | | ..oo . | 2025-05-16 03:33:10.706322 | debian-bookworm -> localhost | | ... o= | 2025-05-16 03:33:10.706342 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-05-16 03:33:10.706405 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.691897 2025-05-16 03:33:10.713690 | 2025-05-16 03:33:10.713831 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-05-16 03:33:10.772830 | debian-bookworm | ok 2025-05-16 03:33:10.782612 | debian-bookworm | included: /var/lib/zuul/builds/ff67a428548c4d48b8e574f3339516e2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-05-16 03:33:10.809548 | 2025-05-16 03:33:10.809685 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-05-16 03:33:10.843875 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:33:10.850271 | 2025-05-16 03:33:10.850391 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-05-16 03:33:11.640173 | debian-bookworm | changed 2025-05-16 03:33:11.645676 | 2025-05-16 03:33:11.645786 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-05-16 03:33:11.890633 | debian-bookworm | ok 2025-05-16 03:33:11.897445 | 2025-05-16 03:33:11.897578 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-05-16 03:33:12.559754 | debian-bookworm | changed 2025-05-16 03:33:12.570434 | 2025-05-16 03:33:12.570585 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-05-16 03:33:13.195883 | debian-bookworm | changed 2025-05-16 03:33:13.206408 | 2025-05-16 03:33:13.206580 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-05-16 03:33:13.242268 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:33:13.251366 | 2025-05-16 03:33:13.251517 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-05-16 03:33:13.872539 | debian-bookworm -> localhost | changed 2025-05-16 03:33:13.884119 | 2025-05-16 03:33:13.884246 | TASK [add-build-sshkey : Add back temp key] 2025-05-16 03:33:14.194779 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ff67a428548c4d48b8e574f3339516e2/work/ff67a428548c4d48b8e574f3339516e2_id_rsa (zuul-build-sshkey) 2025-05-16 03:33:14.195063 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010632 2025-05-16 03:33:14.203518 | 2025-05-16 03:33:14.203632 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-05-16 03:33:14.620295 | debian-bookworm | ok 2025-05-16 03:33:14.628694 | 2025-05-16 03:33:14.628912 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-05-16 03:33:14.655919 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:33:14.668041 | 2025-05-16 03:33:14.668206 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-05-16 03:33:15.071756 | debian-bookworm | ok 2025-05-16 03:33:15.089047 | 2025-05-16 03:33:15.089220 | TASK [validate-host : Define zuul_info_dir fact] 2025-05-16 03:33:15.119352 | debian-bookworm | ok 2025-05-16 03:33:15.124634 | 2025-05-16 03:33:15.124743 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-05-16 03:33:15.463263 | debian-bookworm -> localhost | ok 2025-05-16 03:33:15.469929 | 2025-05-16 03:33:15.470042 | TASK [validate-host : Collect information about the host] 2025-05-16 03:33:16.598596 | debian-bookworm | ok 2025-05-16 03:33:16.614386 | 2025-05-16 03:33:16.614522 | TASK [validate-host : Sanitize hostname] 2025-05-16 03:33:16.759017 | debian-bookworm | ok 2025-05-16 03:33:16.820915 | 2025-05-16 03:33:16.821051 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-05-16 03:33:17.479662 | debian-bookworm -> localhost | changed 2025-05-16 03:33:17.488331 | 2025-05-16 03:33:17.488469 | TASK [validate-host : Collect information about zuul worker] 2025-05-16 03:33:18.016882 | debian-bookworm | ok 2025-05-16 03:33:18.023674 | 2025-05-16 03:33:18.023808 | TASK [validate-host : Write out all zuul information for each host] 2025-05-16 03:33:18.774578 | debian-bookworm -> localhost | changed 2025-05-16 03:33:18.801228 | 2025-05-16 03:33:18.801836 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-05-16 03:33:19.082959 | debian-bookworm | ok 2025-05-16 03:33:19.105593 | 2025-05-16 03:33:19.105832 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-05-16 03:33:19.978936 | debian-bookworm | changed: 2025-05-16 03:33:19.979149 | debian-bookworm | cd+++++++++ src/ 2025-05-16 03:33:19.979185 | debian-bookworm | cd+++++++++ src/github.com/ 2025-05-16 03:33:19.979209 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-05-16 03:33:19.979230 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-generics/ 2025-05-16 03:33:19.979250 | debian-bookworm | =5.5.1 2025-05-16 03:33:42.895200 | debian-bookworm | Downloading cachetools-5.5.2-py3-none-any.whl (10 kB) 2025-05-16 03:33:42.934485 | debian-bookworm | Collecting chardet>=5.2 2025-05-16 03:33:42.942686 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-05-16 03:33:42.987633 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 4.6 MB/s eta 0:00:00 2025-05-16 03:33:43.027300 | debian-bookworm | Collecting colorama>=0.4.6 2025-05-16 03:33:43.035086 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-05-16 03:33:43.082544 | debian-bookworm | Collecting filelock>=3.16.1 2025-05-16 03:33:43.090147 | debian-bookworm | Downloading filelock-3.18.0-py3-none-any.whl (16 kB) 2025-05-16 03:33:43.132858 | debian-bookworm | Collecting packaging>=24.2 2025-05-16 03:33:43.140370 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-05-16 03:33:43.148882 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.0 MB/s eta 0:00:00 2025-05-16 03:33:43.202843 | debian-bookworm | Collecting platformdirs>=4.3.6 2025-05-16 03:33:43.210368 | debian-bookworm | Downloading platformdirs-4.3.8-py3-none-any.whl (18 kB) 2025-05-16 03:33:43.242988 | debian-bookworm | Collecting pluggy>=1.5 2025-05-16 03:33:43.249957 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-05-16 03:33:43.281279 | debian-bookworm | Collecting pyproject-api>=1.8 2025-05-16 03:33:43.289311 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-05-16 03:33:43.407336 | debian-bookworm | Collecting virtualenv>=20.31 2025-05-16 03:33:43.414614 | debian-bookworm | Downloading virtualenv-20.31.2-py3-none-any.whl (6.1 MB) 2025-05-16 03:33:43.686631 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.1/6.1 MB 22.5 MB/s eta 0:00:00 2025-05-16 03:33:43.791120 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-05-16 03:33:43.810046 | debian-bookworm | Downloading distlib-0.3.9-py2.py3-none-any.whl (468 kB) 2025-05-16 03:33:43.830264 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 25.9 MB/s eta 0:00:00 2025-05-16 03:33:43.973702 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-05-16 03:33:44.687834 | debian-bookworm | Successfully installed cachetools-5.5.2 chardet-5.2.0 colorama-0.4.6 distlib-0.3.9 filelock-3.18.0 packaging-25.0 platformdirs-4.3.8 pluggy-1.6.0 pyproject-api-1.9.1 tox-4.26.0 virtualenv-20.31.2 2025-05-16 03:33:44.829344 | debian-bookworm | ok: Runtime: 0:00:02.616879 2025-05-16 03:33:44.842274 | 2025-05-16 03:33:44.842436 | TASK [ensure-tox : Export installed tox_executable path] 2025-05-16 03:33:44.889134 | debian-bookworm | ok 2025-05-16 03:33:44.895629 | 2025-05-16 03:33:44.895762 | TASK [ensure-tox : Output tox version] 2025-05-16 03:33:45.370271 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-05-16 03:33:45.387051 | debian-bookworm | 4.26.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-05-16 03:33:45.455338 | debian-bookworm | ok: Runtime: 0:00:00.251716 2025-05-16 03:33:45.465332 | 2025-05-16 03:33:45.465477 | TASK [ensure-tox : Make global symlink] 2025-05-16 03:33:45.499693 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:33:45.507718 | 2025-05-16 03:33:45.507818 | PLAY RECAP 2025-05-16 03:33:45.507878 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-05-16 03:33:45.507904 | 2025-05-16 03:33:45.666795 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-05-16 03:33:45.667981 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-05-16 03:33:46.494317 | 2025-05-16 03:33:46.494486 | PLAY [all] 2025-05-16 03:33:46.514562 | 2025-05-16 03:33:46.514722 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-05-16 03:33:47.030603 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-05-16 03:33:47.073551 | debian-bookworm | ok: Runtime: 0:00:00.024022 2025-05-16 03:33:47.084302 | 2025-05-16 03:33:47.084479 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-05-16 03:33:47.630205 | debian-bookworm | ok: Runtime: 0:00:00.005279 2025-05-16 03:33:47.636455 | 2025-05-16 03:33:47.636605 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-05-16 03:33:47.932990 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-05-16 03:33:47.934331 | debian-bookworm | sudo: a password is required 2025-05-16 03:33:48.195257 | debian-bookworm | ok: Runtime: 0:00:00.016066 2025-05-16 03:33:48.206076 | 2025-05-16 03:33:48.206297 | TASK [tox : Check to see if the constraints file exists] 2025-05-16 03:33:48.238420 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:33:48.245591 | 2025-05-16 03:33:48.245726 | TASK [tox : Fail if constraints file is missing] 2025-05-16 03:33:48.269604 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:33:48.276219 | 2025-05-16 03:33:48.276345 | TASK [tox : Record file location] 2025-05-16 03:33:48.311704 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:33:48.318357 | 2025-05-16 03:33:48.318489 | TASK [tox : Install tox siblings] 2025-05-16 03:33:48.348890 | debian-bookworm | ok 2025-05-16 03:33:48.357504 | debian-bookworm | included: /var/lib/zuul/builds/ff67a428548c4d48b8e574f3339516e2/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-05-16 03:33:48.363179 | 2025-05-16 03:33:48.363295 | TASK [tox : Run tox without tests] 2025-05-16 03:33:48.809280 | debian-bookworm | ROOT: 137 D setup logging to DEBUG on pid 1424 [tox/report.py:222] 2025-05-16 03:33:48.878616 | debian-bookworm | check: 206 D created app data folder /home/zuul/.local/share/virtualenv [virtualenv/app_data/__init__.py:42] 2025-05-16 03:33:48.881324 | debian-bookworm | check: 209 I find interpreter for spec PythonSpec(major=3, free_threaded=False) [virtualenv/discovery/builtin.py:76] 2025-05-16 03:33:48.882142 | debian-bookworm | check: 210 D get interpreter info via cmd: /usr/bin/python3.11 /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/discovery/py_info.py ZGLbbaRXwVxX2vKaSyJRap2z8de7amdP QLqAd3jtTUgl0GThFYy7SIpEG9q551pd [virtualenv/discovery/cached_py_info.py:117] 2025-05-16 03:33:48.951282 | debian-bookworm | check: 279 D wrote python info of /usr/bin/python3.11 at /home/zuul/.local/share/virtualenv/py_info/2/ca3ed784184f1b3bb7c3539bfb45e71710cd27667424f92c2d5bb4df9c107c23.json [virtualenv/app_data/via_disk_folder.py:152] 2025-05-16 03:33:48.952205 | debian-bookworm | check: 280 D filesystem is case-sensitive [virtualenv/info.py:27] 2025-05-16 03:33:48.952618 | debian-bookworm | check: 280 I proposed PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Nov 30 2024, 21:22:50) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:83] 2025-05-16 03:33:48.952941 | debian-bookworm | check: 280 D accepted PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Nov 30 2024, 21:22:50) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:85] 2025-05-16 03:33:48.990177 | debian-bookworm | check: 318 I create virtual environment via CPython3Posix(dest=/home/zuul/src/github.com/osism/cfg-generics/.tox/check, clear=False, no_vcs_ignore=False, global=False) [virtualenv/run/session.py:52] 2025-05-16 03:33:48.990421 | debian-bookworm | check: 318 D create folder /home/zuul/src/github.com/osism/cfg-generics/.tox/check/bin [virtualenv/util/path/_sync.py:14] 2025-05-16 03:33:48.990559 | debian-bookworm | check: 318 D create folder /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages [virtualenv/util/path/_sync.py:14] 2025-05-16 03:33:48.990781 | debian-bookworm | check: 318 D write /home/zuul/src/github.com/osism/cfg-generics/.tox/check/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:35] 2025-05-16 03:33:48.990867 | debian-bookworm | check: 319 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:48.990928 | debian-bookworm | check: 319 D implementation = CPython [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:48.990977 | debian-bookworm | check: 319 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:48.991024 | debian-bookworm | check: 319 D virtualenv = 20.31.2 [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:48.991069 | debian-bookworm | check: 319 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:48.991125 | debian-bookworm | check: 319 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:48.991181 | debian-bookworm | check: 319 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:48.991248 | debian-bookworm | check: 319 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:48.991551 | debian-bookworm | check: 319 D symlink /usr/bin/python3.11 to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/bin/python [virtualenv/util/path/_sync.py:34] 2025-05-16 03:33:48.991871 | debian-bookworm | check: 320 D create virtualenv import hook file /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/_virtualenv.pth [virtualenv/create/via_global_ref/api.py:93] 2025-05-16 03:33:48.992051 | debian-bookworm | check: 320 D create /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/_virtualenv.py [virtualenv/create/via_global_ref/api.py:96] 2025-05-16 03:33:48.992430 | debian-bookworm | check: 320 D ============================== target debug ============================== [virtualenv/run/session.py:54] 2025-05-16 03:33:48.992673 | debian-bookworm | check: 320 D debug via /home/zuul/src/github.com/osism/cfg-generics/.tox/check/bin/python /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/create/debug.py [virtualenv/create/creator.py:215] 2025-05-16 03:33:49.032037 | debian-bookworm | check: 320 D { 2025-05-16 03:33:49.032072 | debian-bookworm | "sys": { 2025-05-16 03:33:49.032077 | debian-bookworm | "executable": "/home/zuul/src/github.com/osism/cfg-generics/.tox/check/bin/python", 2025-05-16 03:33:49.032082 | debian-bookworm | "_base_executable": "/usr/bin/python3.11", 2025-05-16 03:33:49.032087 | debian-bookworm | "prefix": "/home/zuul/src/github.com/osism/cfg-generics/.tox/check", 2025-05-16 03:33:49.032092 | debian-bookworm | "base_prefix": "/usr", 2025-05-16 03:33:49.032097 | debian-bookworm | "real_prefix": null, 2025-05-16 03:33:49.032101 | debian-bookworm | "exec_prefix": "/home/zuul/src/github.com/osism/cfg-generics/.tox/check", 2025-05-16 03:33:49.032107 | debian-bookworm | "base_exec_prefix": "/usr", 2025-05-16 03:33:49.032111 | debian-bookworm | "path": [ 2025-05-16 03:33:49.032115 | debian-bookworm | "/usr/lib/python311.zip", 2025-05-16 03:33:49.032119 | debian-bookworm | "/usr/lib/python3.11", 2025-05-16 03:33:49.032123 | debian-bookworm | "/usr/lib/python3.11/lib-dynload", 2025-05-16 03:33:49.032127 | debian-bookworm | "/home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages" 2025-05-16 03:33:49.032131 | debian-bookworm | ], 2025-05-16 03:33:49.032135 | debian-bookworm | "meta_path": [ 2025-05-16 03:33:49.032139 | debian-bookworm | "", 2025-05-16 03:33:49.032143 | debian-bookworm | "", 2025-05-16 03:33:49.032147 | debian-bookworm | "", 2025-05-16 03:33:49.032150 | debian-bookworm | "" 2025-05-16 03:33:49.032154 | debian-bookworm | ], 2025-05-16 03:33:49.032158 | debian-bookworm | "fs_encoding": "utf-8", 2025-05-16 03:33:49.032161 | debian-bookworm | "io_encoding": "utf-8" 2025-05-16 03:33:49.032165 | debian-bookworm | }, 2025-05-16 03:33:49.032169 | debian-bookworm | "version": "3.11.2 (main, Nov 30 2024, 21:22:50) [GCC 12.2.0]", 2025-05-16 03:33:49.032173 | debian-bookworm | "makefile_filename": "/usr/lib/python3.11/config-3.11-x86_64-linux-gnu/Makefile", 2025-05-16 03:33:49.032177 | debian-bookworm | "os": "", 2025-05-16 03:33:49.032181 | debian-bookworm | "site": "", 2025-05-16 03:33:49.032185 | debian-bookworm | "datetime": "", 2025-05-16 03:33:49.032189 | debian-bookworm | "math": "", 2025-05-16 03:33:49.032192 | debian-bookworm | "json": "" 2025-05-16 03:33:49.032196 | debian-bookworm | } [virtualenv/run/session.py:55] 2025-05-16 03:33:49.032414 | debian-bookworm | check: 360 I add seed packages via FromAppData(download=False, pip=bundle, setuptools=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) [virtualenv/run/session.py:59] 2025-05-16 03:33:49.035301 | debian-bookworm | check: 363 D install pip from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/pip-25.1.1-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:51] 2025-05-16 03:33:49.039452 | debian-bookworm | check: 364 D build install image for pip-25.1.1-py3-none-any.whl to /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:49] 2025-05-16 03:33:49.039967 | debian-bookworm | check: 368 D install setuptools from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/setuptools-80.3.1-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:51] 2025-05-16 03:33:49.041635 | debian-bookworm | check: 369 D build install image for setuptools-80.3.1-py3-none-any.whl to /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:49] 2025-05-16 03:33:49.208938 | debian-bookworm | check: 537 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any/pip-25.1.1.dist-info to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/pip-25.1.1.dist-info [virtualenv/util/path/_sync.py:42] 2025-05-16 03:33:49.212097 | debian-bookworm | check: 540 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any/pip to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/pip [virtualenv/util/path/_sync.py:42] 2025-05-16 03:33:49.245186 | debian-bookworm | check: 573 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/_distutils_hack to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/_distutils_hack [virtualenv/util/path/_sync.py:42] 2025-05-16 03:33:49.246464 | debian-bookworm | check: 574 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/setuptools to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/setuptools [virtualenv/util/path/_sync.py:42] 2025-05-16 03:33:49.374789 | debian-bookworm | check: 702 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.1.1-py3-none-any/pip-25.1.1.virtualenv to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/pip-25.1.1.virtualenv [virtualenv/util/path/_sync.py:42] 2025-05-16 03:33:49.376107 | debian-bookworm | check: 704 D generated console scripts pip-3.11 pip3 pip3.11 pip [virtualenv/seed/embed/via_app_data/pip_install/base.py:45] 2025-05-16 03:33:49.392496 | debian-bookworm | check: 720 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/pkg_resources to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/pkg_resources [virtualenv/util/path/_sync.py:42] 2025-05-16 03:33:49.394641 | debian-bookworm | check: 722 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/setuptools-80.3.1.virtualenv to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/setuptools-80.3.1.virtualenv [virtualenv/util/path/_sync.py:42] 2025-05-16 03:33:49.394833 | debian-bookworm | check: 723 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/setuptools-80.3.1.dist-info to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/setuptools-80.3.1.dist-info [virtualenv/util/path/_sync.py:42] 2025-05-16 03:33:49.395583 | debian-bookworm | check: 723 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.3.1-py3-none-any/distutils-precedence.pth to /home/zuul/src/github.com/osism/cfg-generics/.tox/check/lib/python3.11/site-packages/distutils-precedence.pth [virtualenv/util/path/_sync.py:42] 2025-05-16 03:33:49.395766 | debian-bookworm | check: 723 D generated console scripts [virtualenv/seed/embed/via_app_data/pip_install/base.py:45] 2025-05-16 03:33:49.396464 | debian-bookworm | check: 724 I add activators for Bash, CShell, Fish, Nushell, PowerShell, Python [virtualenv/run/session.py:65] 2025-05-16 03:33:49.402100 | debian-bookworm | check: 730 D write /home/zuul/src/github.com/osism/cfg-generics/.tox/check/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:35] 2025-05-16 03:33:49.402251 | debian-bookworm | check: 730 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:49.402349 | debian-bookworm | check: 730 D implementation = CPython [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:49.402435 | debian-bookworm | check: 730 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:49.402516 | debian-bookworm | check: 730 D virtualenv = 20.31.2 [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:49.402595 | debian-bookworm | check: 730 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:49.402698 | debian-bookworm | check: 730 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:49.402790 | debian-bookworm | check: 731 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:49.402919 | debian-bookworm | check: 731 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:40] 2025-05-16 03:33:49.408986 | debian-bookworm | check: 737 W install_deps> python -I -m pip install -r requirements.txt [tox/tox_env/api.py:435] 2025-05-16 03:33:50.443155 | debian-bookworm | Collecting Jinja2==3.1.6 (from -r requirements.txt (line 1)) 2025-05-16 03:33:50.498918 | debian-bookworm | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-05-16 03:33:50.587668 | debian-bookworm | Collecting PyYAML==6.0.2 (from -r requirements.txt (line 2)) 2025-05-16 03:33:50.595284 | debian-bookworm | Downloading PyYAML-6.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (2.1 kB) 2025-05-16 03:33:50.655525 | debian-bookworm | Collecting ansible==11.5.0 (from -r requirements.txt (line 3)) 2025-05-16 03:33:50.663755 | debian-bookworm | Downloading ansible-11.5.0-py3-none-any.whl.metadata (8.1 kB) 2025-05-16 03:33:50.696970 | debian-bookworm | Collecting packaging==25.0 (from -r requirements.txt (line 4)) 2025-05-16 03:33:50.704854 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl.metadata (3.3 kB) 2025-05-16 03:33:50.776438 | debian-bookworm | Collecting pwgen==0.8.2.post0 (from -r requirements.txt (line 5)) 2025-05-16 03:33:50.786875 | debian-bookworm | Downloading pwgen-0.8.2.post0.tar.gz (2.9 kB) 2025-05-16 03:33:50.803600 | debian-bookworm | Installing build dependencies: started 2025-05-16 03:33:51.836189 | debian-bookworm | Installing build dependencies: finished with status 'done' 2025-05-16 03:33:51.837021 | debian-bookworm | Getting requirements to build wheel: started 2025-05-16 03:33:52.235495 | debian-bookworm | Getting requirements to build wheel: finished with status 'done' 2025-05-16 03:33:52.236531 | debian-bookworm | Preparing metadata (pyproject.toml): started 2025-05-16 03:33:52.461293 | debian-bookworm | Preparing metadata (pyproject.toml): finished with status 'done' 2025-05-16 03:33:52.490322 | debian-bookworm | Collecting python-gilt==1.2.3 (from -r requirements.txt (line 6)) 2025-05-16 03:33:52.499316 | debian-bookworm | Downloading python_gilt-1.2.3-py2.py3-none-any.whl.metadata (1.4 kB) 2025-05-16 03:33:52.555054 | debian-bookworm | Collecting requests==2.32.3 (from -r requirements.txt (line 7)) 2025-05-16 03:33:52.562519 | debian-bookworm | Downloading requests-2.32.3-py3-none-any.whl.metadata (4.6 kB) 2025-05-16 03:33:52.590411 | debian-bookworm | Collecting tabulate==0.9.0 (from -r requirements.txt (line 8)) 2025-05-16 03:33:52.598033 | debian-bookworm | Downloading tabulate-0.9.0-py3-none-any.whl.metadata (34 kB) 2025-05-16 03:33:52.677135 | debian-bookworm | Collecting MarkupSafe>=2.0 (from Jinja2==3.1.6->-r requirements.txt (line 1)) 2025-05-16 03:33:52.684951 | debian-bookworm | Downloading MarkupSafe-3.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (4.0 kB) 2025-05-16 03:33:52.741682 | debian-bookworm | Collecting ansible-core~=2.18.5 (from ansible==11.5.0->-r requirements.txt (line 3)) 2025-05-16 03:33:52.750104 | debian-bookworm | Downloading ansible_core-2.18.5-py3-none-any.whl.metadata (7.7 kB) 2025-05-16 03:33:52.784902 | debian-bookworm | Collecting click (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-05-16 03:33:52.792133 | debian-bookworm | Downloading click-8.2.0-py3-none-any.whl.metadata (2.5 kB) 2025-05-16 03:33:52.821250 | debian-bookworm | Collecting colorama (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-05-16 03:33:52.828652 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2025-05-16 03:33:52.877768 | debian-bookworm | Collecting fasteners (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-05-16 03:33:52.886911 | debian-bookworm | Downloading fasteners-0.19-py3-none-any.whl.metadata (4.9 kB) 2025-05-16 03:33:52.928193 | debian-bookworm | Collecting sh (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-05-16 03:33:52.936355 | debian-bookworm | Downloading sh-2.2.2-py3-none-any.whl.metadata (3.7 kB) 2025-05-16 03:33:53.050358 | debian-bookworm | Collecting charset-normalizer<4,>=2 (from requests==2.32.3->-r requirements.txt (line 7)) 2025-05-16 03:33:53.058409 | debian-bookworm | Downloading charset_normalizer-3.4.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (35 kB) 2025-05-16 03:33:53.093169 | debian-bookworm | Collecting idna<4,>=2.5 (from requests==2.32.3->-r requirements.txt (line 7)) 2025-05-16 03:33:53.100411 | debian-bookworm | Downloading idna-3.10-py3-none-any.whl.metadata (10 kB) 2025-05-16 03:33:53.148763 | debian-bookworm | Collecting urllib3<3,>=1.21.1 (from requests==2.32.3->-r requirements.txt (line 7)) 2025-05-16 03:33:53.156016 | debian-bookworm | Downloading urllib3-2.4.0-py3-none-any.whl.metadata (6.5 kB) 2025-05-16 03:33:53.192774 | debian-bookworm | Collecting certifi>=2017.4.17 (from requests==2.32.3->-r requirements.txt (line 7)) 2025-05-16 03:33:53.200033 | debian-bookworm | Downloading certifi-2025.4.26-py3-none-any.whl.metadata (2.5 kB) 2025-05-16 03:33:53.420219 | debian-bookworm | Collecting cryptography (from ansible-core~=2.18.5->ansible==11.5.0->-r requirements.txt (line 3)) 2025-05-16 03:33:53.427659 | debian-bookworm | Downloading cryptography-44.0.3-cp39-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-05-16 03:33:53.465707 | debian-bookworm | Collecting resolvelib<1.1.0,>=0.5.3 (from ansible-core~=2.18.5->ansible==11.5.0->-r requirements.txt (line 3)) 2025-05-16 03:33:53.473057 | debian-bookworm | Downloading resolvelib-1.0.1-py2.py3-none-any.whl.metadata (4.0 kB) 2025-05-16 03:33:53.684615 | debian-bookworm | Collecting cffi>=1.12 (from cryptography->ansible-core~=2.18.5->ansible==11.5.0->-r requirements.txt (line 3)) 2025-05-16 03:33:53.692657 | debian-bookworm | Downloading cffi-1.17.1-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB) 2025-05-16 03:33:53.784281 | debian-bookworm | Collecting pycparser (from cffi>=1.12->cryptography->ansible-core~=2.18.5->ansible==11.5.0->-r requirements.txt (line 3)) 2025-05-16 03:33:53.794035 | debian-bookworm | Downloading pycparser-2.22-py3-none-any.whl.metadata (943 bytes) 2025-05-16 03:33:53.821401 | debian-bookworm | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-05-16 03:33:53.841831 | debian-bookworm | Downloading PyYAML-6.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (762 kB) 2025-05-16 03:33:53.882137 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 763.0/763.0 kB 20.7 MB/s eta 0:00:00 2025-05-16 03:33:53.894288 | debian-bookworm | Downloading ansible-11.5.0-py3-none-any.whl (55.5 MB) 2025-05-16 03:33:55.832347 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 55.5/55.5 MB 28.6 MB/s eta 0:00:00 2025-05-16 03:33:55.840899 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-05-16 03:33:55.861219 | debian-bookworm | Downloading python_gilt-1.2.3-py2.py3-none-any.whl (22 kB) 2025-05-16 03:33:55.876040 | debian-bookworm | Downloading requests-2.32.3-py3-none-any.whl (64 kB) 2025-05-16 03:33:55.891327 | debian-bookworm | Downloading tabulate-0.9.0-py3-none-any.whl (35 kB) 2025-05-16 03:33:55.910385 | debian-bookworm | Downloading ansible_core-2.18.5-py3-none-any.whl (2.2 MB) 2025-05-16 03:33:56.040460 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 2.2/2.2 MB 16.9 MB/s eta 0:00:00 2025-05-16 03:33:56.050597 | debian-bookworm | Downloading charset_normalizer-3.4.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (147 kB) 2025-05-16 03:33:56.071588 | debian-bookworm | Downloading idna-3.10-py3-none-any.whl (70 kB) 2025-05-16 03:33:56.092182 | debian-bookworm | Downloading resolvelib-1.0.1-py2.py3-none-any.whl (17 kB) 2025-05-16 03:33:56.112322 | debian-bookworm | Downloading urllib3-2.4.0-py3-none-any.whl (128 kB) 2025-05-16 03:33:56.132711 | debian-bookworm | Downloading certifi-2025.4.26-py3-none-any.whl (159 kB) 2025-05-16 03:33:56.154107 | debian-bookworm | Downloading MarkupSafe-3.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (23 kB) 2025-05-16 03:33:56.172682 | debian-bookworm | Downloading click-8.2.0-py3-none-any.whl (102 kB) 2025-05-16 03:33:56.194235 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-05-16 03:33:56.215003 | debian-bookworm | Downloading cryptography-44.0.3-cp39-abi3-manylinux_2_34_x86_64.whl (4.2 MB) 2025-05-16 03:33:56.375365 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.2/4.2 MB 27.0 MB/s eta 0:00:00 2025-05-16 03:33:56.386125 | debian-bookworm | Downloading cffi-1.17.1-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (467 kB) 2025-05-16 03:33:56.419691 | debian-bookworm | Downloading fasteners-0.19-py3-none-any.whl (18 kB) 2025-05-16 03:33:56.439216 | debian-bookworm | Downloading pycparser-2.22-py3-none-any.whl (117 kB) 2025-05-16 03:33:56.459048 | debian-bookworm | Downloading sh-2.2.2-py3-none-any.whl (38 kB) 2025-05-16 03:33:56.739421 | debian-bookworm | Building wheels for collected packages: pwgen 2025-05-16 03:33:56.740649 | debian-bookworm | Building wheel for pwgen (pyproject.toml): started 2025-05-16 03:33:57.010502 | debian-bookworm | Building wheel for pwgen (pyproject.toml): finished with status 'done' 2025-05-16 03:33:57.012526 | debian-bookworm | Created wheel for pwgen: filename=pwgen-0.8.2.post0-py3-none-any.whl size=3798 sha256=cb6e1eb3a04558f4ce4b5e136e42e64b67723308fedaadcc852c07e40510ccc2 2025-05-16 03:33:57.013761 | debian-bookworm | Stored in directory: /home/zuul/.cache/pip/wheels/c3/81/ed/8e13725daf8a767fbde13d7583ce457f3fdf974023341fac95 2025-05-16 03:33:57.018938 | debian-bookworm | Successfully built pwgen 2025-05-16 03:33:57.248262 | debian-bookworm | Installing collected packages: resolvelib, pwgen, urllib3, tabulate, sh, PyYAML, pycparser, packaging, MarkupSafe, idna, fasteners, colorama, click, charset-normalizer, certifi, requests, python-gilt, Jinja2, cffi, cryptography, ansible-core, ansible 2025-05-16 03:34:20.146598 | debian-bookworm | 2025-05-16 03:34:20.148667 | debian-bookworm | Successfully installed Jinja2-3.1.6 MarkupSafe-3.0.2 PyYAML-6.0.2 ansible-11.5.0 ansible-core-2.18.5 certifi-2025.4.26 cffi-1.17.1 charset-normalizer-3.4.2 click-8.2.0 colorama-0.4.6 cryptography-44.0.3 fasteners-0.19 idna-3.10 packaging-25.0 pwgen-0.8.2.post0 pycparser-2.22 python-gilt-1.2.3 requests-2.32.3 resolvelib-1.0.1 sh-2.2.2 tabulate-0.9.0 urllib3-2.4.0 2025-05-16 03:34:20.273956 | debian-bookworm | check: 31601 I exit 0 (30.86 seconds) /home/zuul/src/github.com/osism/cfg-generics> python -I -m pip install -r requirements.txt pid=1437 [tox/execute/api.py:294] 2025-05-16 03:34:20.276377 | debian-bookworm | check: OK (31.40 seconds) 2025-05-16 03:34:20.276444 | debian-bookworm | congratulations :) (31.47 seconds) 2025-05-16 03:34:20.409774 | debian-bookworm | ok: Runtime: 0:00:31.679913 2025-05-16 03:34:20.417749 | 2025-05-16 03:34:20.417880 | TASK [tox : Create a tempfile to save tox showconfig] 2025-05-16 03:34:20.863659 | debian-bookworm | changed 2025-05-16 03:34:20.869268 | 2025-05-16 03:34:20.869395 | TASK [tox : Get tox envlist config] 2025-05-16 03:34:21.938791 | debian-bookworm | ok: Runtime: 0:00:00.278065 2025-05-16 03:34:21.946916 | 2025-05-16 03:34:21.947072 | TASK [tox : Install any sibling python packages] 2025-05-16 03:34:22.457591 | debian-bookworm | ok: No setup.cfg, no action needed 2025-05-16 03:34:22.466043 | 2025-05-16 03:34:22.466215 | TASK [tox : Remove tempfile] 2025-05-16 03:34:23.228669 | debian-bookworm | changed 2025-05-16 03:34:23.239829 | 2025-05-16 03:34:23.239965 | TASK [tox : Emit tox command] 2025-05-16 03:34:23.323312 | /home/zuul/.local/tox/bin/tox -echeck -vv --skip-missing-interpreters=false 2025-05-16 03:34:23.331732 | 2025-05-16 03:34:23.332020 | TASK [tox : Run tox] 2025-05-16 03:34:23.944403 | debian-bookworm | ROOT: 123 D setup logging to DEBUG on pid 1592 [tox/report.py:222] 2025-05-16 03:34:24.011344 | debian-bookworm | check: 190 I find interpreter for spec PythonSpec(major=3, free_threaded=False) [virtualenv/discovery/builtin.py:76] 2025-05-16 03:34:24.012032 | debian-bookworm | check: 190 D got python info of /usr/bin/python3.11 from /home/zuul/.local/share/virtualenv/py_info/2/ca3ed784184f1b3bb7c3539bfb45e71710cd27667424f92c2d5bb4df9c107c23.json [virtualenv/app_data/via_disk_folder.py:132] 2025-05-16 03:34:24.012835 | debian-bookworm | check: 191 D filesystem is case-sensitive [virtualenv/info.py:27] 2025-05-16 03:34:24.012980 | debian-bookworm | check: 191 I proposed PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Nov 30 2024, 21:22:50) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:83] 2025-05-16 03:34:24.013033 | debian-bookworm | check: 192 D accepted PythonInfo(spec=CPython3.11.2.final.0-64, system=/usr/bin/python3.11, exe=/home/zuul/.local/tox/bin/python3, platform=linux, version='3.11.2 (main, Nov 30 2024, 21:22:50) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:85] 2025-05-16 03:34:24.043734 | debian-bookworm | check: 222 W commands[0]> python3 src/check-inventory-sorting.py [tox/tox_env/api.py:435] 2025-05-16 03:34:24.108739 | debian-bookworm | check: 287 I exit 0 (0.06 seconds) /home/zuul/src/github.com/osism/cfg-generics> python3 src/check-inventory-sorting.py pid=1599 [tox/execute/api.py:294] 2025-05-16 03:34:24.109812 | debian-bookworm | check: OK (0.10=setup[0.04]+cmd[0.06] seconds) 2025-05-16 03:34:24.109833 | debian-bookworm | congratulations :) (0.17 seconds) 2025-05-16 03:34:24.460965 | debian-bookworm | ok: Runtime: 0:00:00.382868 2025-05-16 03:34:24.483236 | 2025-05-16 03:34:24.483365 | TASK [tox : Look for output] 2025-05-16 03:34:25.166064 | debian-bookworm | ok 2025-05-16 03:34:25.286608 | 2025-05-16 03:34:25.286732 | TASK [tox : Return file comments to Zuul] 2025-05-16 03:34:25.343892 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:25.355652 | 2025-05-16 03:34:25.355725 | PLAY RECAP 2025-05-16 03:34:25.355768 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-05-16 03:34:25.355788 | 2025-05-16 03:34:25.472717 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-05-16 03:34:25.473507 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-05-16 03:34:26.827421 | 2025-05-16 03:34:26.827552 | PLAY [all] 2025-05-16 03:34:26.853064 | 2025-05-16 03:34:26.853205 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-05-16 03:34:26.907260 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:26.912847 | 2025-05-16 03:34:26.912987 | TASK [fetch-tox-output : Set tox log path for single node] 2025-05-16 03:34:26.950914 | debian-bookworm | ok 2025-05-16 03:34:26.959054 | 2025-05-16 03:34:26.959177 | TASK [fetch-tox-output : Ensure local tox dir] 2025-05-16 03:34:27.570298 | debian-bookworm -> localhost | changed 2025-05-16 03:34:27.580376 | 2025-05-16 03:34:27.580563 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-05-16 03:34:27.638997 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:27.646199 | 2025-05-16 03:34:27.646306 | TASK [fetch-tox-output : Set envlist fact] 2025-05-16 03:34:27.736333 | debian-bookworm | ok 2025-05-16 03:34:27.743753 | 2025-05-16 03:34:27.743854 | TASK [fetch-tox-output : Get tox version] 2025-05-16 03:34:28.488279 | debian-bookworm | ROOT: No tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-05-16 03:34:28.507088 | debian-bookworm | 4.26.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-05-16 03:34:28.786789 | debian-bookworm | ok: Runtime: 0:00:00.287738 2025-05-16 03:34:28.792487 | 2025-05-16 03:34:28.792580 | TASK [fetch-tox-output : Find all default environments] 2025-05-16 03:34:29.323943 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:29.329502 | 2025-05-16 03:34:29.329604 | TASK [fetch-tox-output : Set envlist fact] 2025-05-16 03:34:29.352785 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:29.357966 | 2025-05-16 03:34:29.358056 | TASK [fetch-tox-output : Find all default environments] 2025-05-16 03:34:29.884200 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:29.898550 | 2025-05-16 03:34:29.898725 | TASK [fetch-tox-output : Set envlist fact] 2025-05-16 03:34:29.934012 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:29.945790 | 2025-05-16 03:34:29.945944 | LOOP [fetch-tox-output : Copy tox logs] 2025-05-16 03:34:30.000861 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:30.017604 | 2025-05-16 03:34:30.017765 | LOOP [fetch-tox-output : Collect tox logs] 2025-05-16 03:34:30.782105 | debian-bookworm | changed: 2025-05-16 03:34:30.782386 | debian-bookworm | created directory /var/lib/zuul/builds/ff67a428548c4d48b8e574f3339516e2/work/logs/tox/check 2025-05-16 03:34:30.782438 | debian-bookworm | cd+++++++++ ./ 2025-05-16 03:34:30.782475 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-05-16 03:34:30.782517 | 2025-05-16 03:34:30.799431 | 2025-05-16 03:34:30.799539 | PLAY RECAP 2025-05-16 03:34:30.799599 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-05-16 03:34:30.799631 | 2025-05-16 03:34:30.910299 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-05-16 03:34:30.912680 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-05-16 03:34:31.735785 | 2025-05-16 03:34:31.735961 | PLAY [all] 2025-05-16 03:34:31.752496 | 2025-05-16 03:34:31.752658 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-05-16 03:34:31.966126 | debian-bookworm | changed: non-zero return code 2025-05-16 03:34:31.972249 | 2025-05-16 03:34:31.972387 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-05-16 03:34:32.006753 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:32.014260 | 2025-05-16 03:34:32.014402 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-05-16 03:34:32.055755 | 2025-05-16 03:34:32.056114 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-05-16 03:34:32.112440 | 2025-05-16 03:34:32.112663 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-05-16 03:34:32.137601 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:32.148876 | 2025-05-16 03:34:32.149052 | LOOP [fetch-subunit-output : Generate subunit file] 2025-05-16 03:34:32.187832 | 2025-05-16 03:34:32.188206 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-05-16 03:34:32.214314 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:32.229774 | 2025-05-16 03:34:32.229967 | TASK [fetch-subunit-output : Remove the temporary file] 2025-05-16 03:34:32.255754 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:32.269227 | 2025-05-16 03:34:32.269414 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-05-16 03:34:32.295582 | debian-bookworm | skipping: Conditional result was False 2025-05-16 03:34:32.302408 | 2025-05-16 03:34:32.302509 | PLAY RECAP 2025-05-16 03:34:32.302562 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-05-16 03:34:32.302588 | 2025-05-16 03:34:32.435552 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-05-16 03:34:32.436646 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-05-16 03:34:33.237800 | 2025-05-16 03:34:33.237970 | PLAY [Base post] 2025-05-16 03:34:33.251774 | 2025-05-16 03:34:33.251915 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-05-16 03:34:33.822501 | debian-bookworm | changed 2025-05-16 03:34:33.829355 | 2025-05-16 03:34:33.829482 | PLAY RECAP 2025-05-16 03:34:33.829546 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-05-16 03:34:33.829608 | 2025-05-16 03:34:34.035242 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-05-16 03:34:34.036864 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-05-16 03:34:34.918347 | 2025-05-16 03:34:34.918521 | PLAY [Base post-logs] 2025-05-16 03:34:34.929475 | 2025-05-16 03:34:34.929611 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-05-16 03:34:35.436455 | localhost | changed 2025-05-16 03:34:35.449300 | 2025-05-16 03:34:35.449479 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-05-16 03:34:35.479620 | localhost | ok 2025-05-16 03:34:35.482879 | 2025-05-16 03:34:35.482987 | TASK [Set zuul-log-path fact] 2025-05-16 03:34:35.498335 | localhost | ok 2025-05-16 03:34:35.508556 | 2025-05-16 03:34:35.508684 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-05-16 03:34:35.533930 | localhost | ok 2025-05-16 03:34:35.537251 | 2025-05-16 03:34:35.537364 | TASK [upload-logs : Create log directories] 2025-05-16 03:34:36.086472 | localhost | changed 2025-05-16 03:34:36.089374 | 2025-05-16 03:34:36.089485 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-05-16 03:34:36.588876 | localhost -> localhost | ok: Runtime: 0:00:00.008035 2025-05-16 03:34:36.593044 | 2025-05-16 03:34:36.593207 | TASK [upload-logs : Upload logs to log server] 2025-05-16 03:34:37.218795 | localhost | Output suppressed because no_log was given 2025-05-16 03:34:37.221336 | 2025-05-16 03:34:37.221479 | LOOP [upload-logs : Compress console log and json output] 2025-05-16 03:34:37.280594 | localhost | skipping: Conditional result was False 2025-05-16 03:34:37.286385 | localhost | skipping: Conditional result was False 2025-05-16 03:34:37.289902 | 2025-05-16 03:34:37.290012 | LOOP [upload-logs : Upload compressed console log and json output] 2025-05-16 03:34:37.348539 | localhost | skipping: Conditional result was False 2025-05-16 03:34:37.348844 | 2025-05-16 03:34:37.356110 | localhost | skipping: Conditional result was False 2025-05-16 03:34:37.370326 | 2025-05-16 03:34:37.370551 | LOOP [upload-logs : Upload console log and json output]