2025-09-25 03:01:38.612744 | Job console starting 2025-09-25 03:01:38.619152 | Updating git repos 2025-09-25 03:01:38.659926 | Cloning repos into workspace 2025-09-25 03:01:38.715472 | Restoring repo states 2025-09-25 03:01:38.747750 | Merging changes 2025-09-25 03:01:38.747763 | Checking out repos 2025-09-25 03:01:38.837578 | Preparing playbooks 2025-09-25 03:01:40.085668 | Running Ansible setup 2025-09-25 03:01:45.638633 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-25 03:01:46.676905 | 2025-09-25 03:01:46.677015 | PLAY [Base pre] 2025-09-25 03:01:46.691042 | 2025-09-25 03:01:46.691139 | TASK [Setup log path fact] 2025-09-25 03:01:46.728026 | debian-bookworm | ok 2025-09-25 03:01:46.739933 | 2025-09-25 03:01:46.740043 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-25 03:01:46.813525 | debian-bookworm | ok 2025-09-25 03:01:46.821132 | 2025-09-25 03:01:46.821239 | TASK [emit-job-header : Print job information] 2025-09-25 03:01:46.888433 | # Job Information 2025-09-25 03:01:46.888573 | Ansible Version: 2.16.14 2025-09-25 03:01:46.888601 | Job: cfg-generics-tox-check 2025-09-25 03:01:46.888627 | Pipeline: periodic-daily 2025-09-25 03:01:46.888645 | Executor: 521e9411259a 2025-09-25 03:01:46.888662 | Triggered by: https://github.com/osism/generics 2025-09-25 03:01:46.888680 | Event ID: 9d00bf6902a9402f92b8fbb14588191c 2025-09-25 03:01:46.892580 | 2025-09-25 03:01:46.892660 | LOOP [emit-job-header : Print node information] 2025-09-25 03:01:47.011062 | debian-bookworm | ok: 2025-09-25 03:01:47.011198 | debian-bookworm | # Node Information 2025-09-25 03:01:47.011380 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-25 03:01:47.011406 | debian-bookworm | Hostname: debian 2025-09-25 03:01:47.011426 | debian-bookworm | Username: zuul 2025-09-25 03:01:47.011444 | debian-bookworm | Distro: Debian 12.12 2025-09-25 03:01:47.011464 | debian-bookworm | Provider: regiocloud-a 2025-09-25 03:01:47.011482 | debian-bookworm | Region: 2025-09-25 03:01:47.011499 | debian-bookworm | Label: debian-bookworm 2025-09-25 03:01:47.011515 | debian-bookworm | Product Name: OpenStack Nova 2025-09-25 03:01:47.011531 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe3e:8254 2025-09-25 03:01:47.029686 | 2025-09-25 03:01:47.029778 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-25 03:01:47.525961 | debian-bookworm -> localhost | changed 2025-09-25 03:01:47.532589 | 2025-09-25 03:01:47.532682 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-25 03:01:48.808777 | debian-bookworm -> localhost | changed 2025-09-25 03:01:48.817367 | 2025-09-25 03:01:48.817458 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-25 03:01:49.300900 | debian-bookworm -> localhost | ok 2025-09-25 03:01:49.305966 | 2025-09-25 03:01:49.306058 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-25 03:01:49.332864 | debian-bookworm | ok 2025-09-25 03:01:49.365960 | debian-bookworm | included: /var/lib/zuul/builds/126a37b250494eb2992a25d6d3ec5b60/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-25 03:01:49.370969 | 2025-09-25 03:01:49.371047 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-25 03:01:50.457575 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-25 03:01:50.457768 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/126a37b250494eb2992a25d6d3ec5b60/work/126a37b250494eb2992a25d6d3ec5b60_id_rsa 2025-09-25 03:01:50.457806 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/126a37b250494eb2992a25d6d3ec5b60/work/126a37b250494eb2992a25d6d3ec5b60_id_rsa.pub 2025-09-25 03:01:50.457834 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-25 03:01:50.457861 | debian-bookworm -> localhost | SHA256:AwNi6DQsNAr0TzDQe0UzPJCD5JQlN9It8Qujh96P+u0 zuul-build-sshkey 2025-09-25 03:01:50.457884 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-25 03:01:50.457916 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-25 03:01:50.457938 | debian-bookworm -> localhost | |=**B**B+ | 2025-09-25 03:01:50.457970 | debian-bookworm -> localhost | |====**o=o | 2025-09-25 03:01:50.458000 | debian-bookworm -> localhost | |= .o.+*.. | 2025-09-25 03:01:50.458033 | debian-bookworm -> localhost | | . .=.oo. | 2025-09-25 03:01:50.458065 | debian-bookworm -> localhost | | o.o .S | 2025-09-25 03:01:50.458102 | debian-bookworm -> localhost | | . o . | 2025-09-25 03:01:50.458137 | debian-bookworm -> localhost | | . . | 2025-09-25 03:01:50.458161 | debian-bookworm -> localhost | | + | 2025-09-25 03:01:50.458182 | debian-bookworm -> localhost | | .oo.E | 2025-09-25 03:01:50.458213 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-25 03:01:50.458267 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.455491 2025-09-25 03:01:50.464970 | 2025-09-25 03:01:50.465059 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-25 03:01:50.509332 | debian-bookworm | ok 2025-09-25 03:01:50.526654 | debian-bookworm | included: /var/lib/zuul/builds/126a37b250494eb2992a25d6d3ec5b60/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-25 03:01:50.548537 | 2025-09-25 03:01:50.548639 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-25 03:01:50.576417 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:01:50.583047 | 2025-09-25 03:01:50.583151 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-25 03:01:51.301739 | debian-bookworm | changed 2025-09-25 03:01:51.306131 | 2025-09-25 03:01:51.306232 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-25 03:01:51.615534 | debian-bookworm | ok 2025-09-25 03:01:51.620005 | 2025-09-25 03:01:51.620089 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-25 03:01:52.390357 | debian-bookworm | changed 2025-09-25 03:01:52.395715 | 2025-09-25 03:01:52.395796 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-25 03:01:53.210384 | debian-bookworm | changed 2025-09-25 03:01:53.216866 | 2025-09-25 03:01:53.218892 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-25 03:01:53.283488 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:01:53.288478 | 2025-09-25 03:01:53.288559 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-25 03:01:54.042013 | debian-bookworm -> localhost | changed 2025-09-25 03:01:54.051591 | 2025-09-25 03:01:54.051680 | TASK [add-build-sshkey : Add back temp key] 2025-09-25 03:01:54.831950 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/126a37b250494eb2992a25d6d3ec5b60/work/126a37b250494eb2992a25d6d3ec5b60_id_rsa (zuul-build-sshkey) 2025-09-25 03:01:54.832126 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.022469 2025-09-25 03:01:54.838502 | 2025-09-25 03:01:54.838583 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-25 03:01:55.263345 | debian-bookworm | ok 2025-09-25 03:01:55.267777 | 2025-09-25 03:01:55.267852 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-25 03:01:55.311009 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:01:55.320551 | 2025-09-25 03:01:55.320638 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-25 03:01:55.807868 | debian-bookworm | ok 2025-09-25 03:01:55.816330 | 2025-09-25 03:01:55.816420 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-25 03:01:55.859312 | debian-bookworm | ok 2025-09-25 03:01:55.863588 | 2025-09-25 03:01:55.863655 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-25 03:01:56.078826 | debian-bookworm -> localhost | ok 2025-09-25 03:01:56.092052 | 2025-09-25 03:01:56.092179 | TASK [validate-host : Collect information about the host] 2025-09-25 03:01:57.208048 | debian-bookworm | ok 2025-09-25 03:01:57.223270 | 2025-09-25 03:01:57.223381 | TASK [validate-host : Sanitize hostname] 2025-09-25 03:01:57.295275 | debian-bookworm | ok 2025-09-25 03:01:57.338602 | 2025-09-25 03:01:57.338698 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-25 03:01:57.856919 | debian-bookworm -> localhost | changed 2025-09-25 03:01:57.861627 | 2025-09-25 03:01:57.861705 | TASK [validate-host : Collect information about zuul worker] 2025-09-25 03:01:58.268803 | debian-bookworm | ok 2025-09-25 03:01:58.276622 | 2025-09-25 03:01:58.276773 | TASK [validate-host : Write out all zuul information for each host] 2025-09-25 03:01:59.094115 | debian-bookworm -> localhost | changed 2025-09-25 03:01:59.105313 | 2025-09-25 03:01:59.105447 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-25 03:01:59.405619 | debian-bookworm | ok 2025-09-25 03:01:59.413879 | 2025-09-25 03:01:59.413964 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-25 03:02:00.314473 | debian-bookworm | changed: 2025-09-25 03:02:00.314635 | debian-bookworm | cd+++++++++ src/ 2025-09-25 03:02:00.314668 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-25 03:02:00.314692 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-25 03:02:00.314713 | debian-bookworm | cd+++++++++ src/github.com/osism/generics/ 2025-09-25 03:02:00.314733 | debian-bookworm | =6.1 2025-09-25 03:02:25.825775 | debian-bookworm | Downloading cachetools-6.2.0-py3-none-any.whl (11 kB) 2025-09-25 03:02:25.857237 | debian-bookworm | Collecting chardet>=5.2 2025-09-25 03:02:25.865035 | debian-bookworm | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2025-09-25 03:02:25.883822 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 199.4/199.4 kB 12.4 MB/s eta 0:00:00 2025-09-25 03:02:25.919162 | debian-bookworm | Collecting colorama>=0.4.6 2025-09-25 03:02:25.927646 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-25 03:02:25.977835 | debian-bookworm | Collecting filelock>=3.18 2025-09-25 03:02:25.985795 | debian-bookworm | Downloading filelock-3.19.1-py3-none-any.whl (15 kB) 2025-09-25 03:02:26.031627 | debian-bookworm | Collecting packaging>=25 2025-09-25 03:02:26.039614 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-25 03:02:26.058283 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 4.6 MB/s eta 0:00:00 2025-09-25 03:02:26.123743 | debian-bookworm | Collecting platformdirs>=4.3.8 2025-09-25 03:02:26.133718 | debian-bookworm | Downloading platformdirs-4.4.0-py3-none-any.whl (18 kB) 2025-09-25 03:02:26.188983 | debian-bookworm | Collecting pluggy>=1.6 2025-09-25 03:02:26.197129 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-09-25 03:02:26.252251 | debian-bookworm | Collecting pyproject-api>=1.9.1 2025-09-25 03:02:26.260560 | debian-bookworm | Downloading pyproject_api-1.9.1-py3-none-any.whl (13 kB) 2025-09-25 03:02:26.391371 | debian-bookworm | Collecting virtualenv>=20.31.2 2025-09-25 03:02:26.401606 | debian-bookworm | Downloading virtualenv-20.34.0-py3-none-any.whl (6.0 MB) 2025-09-25 03:02:26.677955 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 6.0/6.0 MB 22.1 MB/s eta 0:00:00 2025-09-25 03:02:26.793760 | debian-bookworm | Collecting distlib<1,>=0.3.7 2025-09-25 03:02:26.801929 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2025-09-25 03:02:26.889495 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 5.6 MB/s eta 0:00:00 2025-09-25 03:02:27.055493 | debian-bookworm | Installing collected packages: distlib, pluggy, platformdirs, packaging, filelock, colorama, chardet, cachetools, virtualenv, pyproject-api, tox 2025-09-25 03:02:28.097919 | debian-bookworm | Successfully installed cachetools-6.2.0 chardet-5.2.0 colorama-0.4.6 distlib-0.4.0 filelock-3.19.1 packaging-25.0 platformdirs-4.4.0 pluggy-1.6.0 pyproject-api-1.9.1 tox-4.30.2 virtualenv-20.34.0 2025-09-25 03:02:28.702453 | debian-bookworm | ok: Runtime: 0:00:03.236158 2025-09-25 03:02:28.708512 | 2025-09-25 03:02:28.708591 | TASK [ensure-tox : Export installed tox_executable path] 2025-09-25 03:02:28.778558 | debian-bookworm | ok 2025-09-25 03:02:28.784716 | 2025-09-25 03:02:28.785128 | TASK [ensure-tox : Output tox version] 2025-09-25 03:02:29.314095 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-09-25 03:02:29.333447 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-25 03:02:29.820162 | debian-bookworm | ok: Runtime: 0:00:00.320376 2025-09-25 03:02:29.834006 | 2025-09-25 03:02:29.834124 | TASK [ensure-tox : Make global symlink] 2025-09-25 03:02:29.858143 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:02:29.879269 | 2025-09-25 03:02:29.879813 | PLAY RECAP 2025-09-25 03:02:29.879916 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2025-09-25 03:02:29.879967 | 2025-09-25 03:02:30.029159 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2025-09-25 03:02:30.030327 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-25 03:02:30.894510 | 2025-09-25 03:02:30.894639 | PLAY [all] 2025-09-25 03:02:30.918889 | 2025-09-25 03:02:30.919188 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-09-25 03:02:31.445995 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-25 03:02:31.493006 | debian-bookworm | ok: Runtime: 0:00:00.030075 2025-09-25 03:02:31.506526 | 2025-09-25 03:02:31.506653 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-09-25 03:02:32.062673 | debian-bookworm | ok: Runtime: 0:00:00.006200 2025-09-25 03:02:32.069220 | 2025-09-25 03:02:32.069326 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-09-25 03:02:32.481180 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2025-09-25 03:02:32.484729 | debian-bookworm | sudo: a password is required 2025-09-25 03:02:32.637655 | debian-bookworm | ok: Runtime: 0:00:00.028155 2025-09-25 03:02:32.647230 | 2025-09-25 03:02:32.647317 | TASK [tox : Check to see if the constraints file exists] 2025-09-25 03:02:32.691008 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:02:32.696954 | 2025-09-25 03:02:32.697051 | TASK [tox : Fail if constraints file is missing] 2025-09-25 03:02:32.740166 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:02:32.746127 | 2025-09-25 03:02:32.746263 | TASK [tox : Record file location] 2025-09-25 03:02:32.789489 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:02:32.794992 | 2025-09-25 03:02:32.795091 | TASK [tox : Install tox siblings] 2025-09-25 03:02:32.824794 | debian-bookworm | ok 2025-09-25 03:02:32.832855 | debian-bookworm | included: /var/lib/zuul/builds/126a37b250494eb2992a25d6d3ec5b60/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2025-09-25 03:02:32.875476 | 2025-09-25 03:02:32.875585 | TASK [tox : Run tox without tests] 2025-09-25 03:02:33.507527 | debian-bookworm | ROOT: 216 D setup logging to DEBUG on pid 1422 [tox/report.py:222] 2025-09-25 03:02:33.539248 | debian-bookworm | check: 248 D created app data folder /home/zuul/.local/share/virtualenv [virtualenv/app_data/__init__.py:42] 2025-09-25 03:02:33.542452 | debian-bookworm | check: 251 I find interpreter for spec PythonSpec(major=3, free_threaded=False) [virtualenv/discovery/builtin.py:77] 2025-09-25 03:02:33.543484 | debian-bookworm | check: 253 D get interpreter info via cmd: /usr/bin/python3.11 /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/discovery/py_info.py BN87433QQLQ8u5ZoWRFjgu4VUAzzlRnM 98QzBSTh9BpYtsFL1tOT7FpexRLSOQwT [virtualenv/discovery/cached_py_info.py:145] 2025-09-25 03:02:33.617689 | debian-bookworm | check: 327 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-09-25 03:02:33.618767 | debian-bookworm | check: 328 D filesystem is case-sensitive [virtualenv/discovery/info.py:20] 2025-09-25 03:02:33.619533 | debian-bookworm | check: 328 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, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:84] 2025-09-25 03:02:33.619860 | debian-bookworm | check: 329 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, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:86] 2025-09-25 03:02:33.622936 | debian-bookworm | check: 332 D symlink on filesystem does work [virtualenv/info.py:50] 2025-09-25 03:02:33.623238 | debian-bookworm | check: 332 D filesystem is case-sensitive [virtualenv/info.py:27] 2025-09-25 03:02:33.661786 | debian-bookworm | check: 371 I create virtual environment via CPython3Posix(dest=/home/zuul/src/github.com/osism/generics/.tox/check, clear=False, no_vcs_ignore=False, global=False) [virtualenv/run/session.py:52] 2025-09-25 03:02:33.662069 | debian-bookworm | check: 371 D create folder /home/zuul/src/github.com/osism/generics/.tox/check/bin [virtualenv/util/path/_sync.py:14] 2025-09-25 03:02:33.662252 | debian-bookworm | check: 371 D create folder /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages [virtualenv/util/path/_sync.py:14] 2025-09-25 03:02:33.662481 | debian-bookworm | check: 372 D write /home/zuul/src/github.com/osism/generics/.tox/check/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:35] 2025-09-25 03:02:33.662569 | debian-bookworm | check: 372 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:33.662623 | debian-bookworm | check: 372 D implementation = CPython [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:33.662670 | debian-bookworm | check: 372 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:33.662717 | debian-bookworm | check: 372 D virtualenv = 20.34.0 [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:33.662769 | debian-bookworm | check: 372 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:33.662823 | debian-bookworm | check: 372 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:33.662881 | debian-bookworm | check: 372 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:33.662946 | debian-bookworm | check: 372 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:33.663250 | debian-bookworm | check: 372 D symlink /usr/bin/python3.11 to /home/zuul/src/github.com/osism/generics/.tox/check/bin/python [virtualenv/util/path/_sync.py:34] 2025-09-25 03:02:33.663637 | debian-bookworm | check: 373 D create virtualenv import hook file /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages/_virtualenv.pth [virtualenv/create/via_global_ref/api.py:98] 2025-09-25 03:02:33.663786 | debian-bookworm | check: 373 D create /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages/_virtualenv.py [virtualenv/create/via_global_ref/api.py:101] 2025-09-25 03:02:33.664185 | debian-bookworm | check: 373 D ============================== target debug ============================== [virtualenv/run/session.py:54] 2025-09-25 03:02:33.664453 | debian-bookworm | check: 374 D debug via /home/zuul/src/github.com/osism/generics/.tox/check/bin/python /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/create/debug.py [virtualenv/create/creator.py:215] 2025-09-25 03:02:33.717898 | debian-bookworm | check: 373 D { 2025-09-25 03:02:33.717959 | debian-bookworm | "sys": { 2025-09-25 03:02:33.717965 | debian-bookworm | "executable": "/home/zuul/src/github.com/osism/generics/.tox/check/bin/python", 2025-09-25 03:02:33.717971 | debian-bookworm | "_base_executable": "/usr/bin/python3.11", 2025-09-25 03:02:33.717978 | debian-bookworm | "prefix": "/home/zuul/src/github.com/osism/generics/.tox/check", 2025-09-25 03:02:33.717984 | debian-bookworm | "base_prefix": "/usr", 2025-09-25 03:02:33.717993 | debian-bookworm | "real_prefix": null, 2025-09-25 03:02:33.717999 | debian-bookworm | "exec_prefix": "/home/zuul/src/github.com/osism/generics/.tox/check", 2025-09-25 03:02:33.718004 | debian-bookworm | "base_exec_prefix": "/usr", 2025-09-25 03:02:33.718009 | debian-bookworm | "path": [ 2025-09-25 03:02:33.718013 | debian-bookworm | "/usr/lib/python311.zip", 2025-09-25 03:02:33.718021 | debian-bookworm | "/usr/lib/python3.11", 2025-09-25 03:02:33.718025 | debian-bookworm | "/usr/lib/python3.11/lib-dynload", 2025-09-25 03:02:33.718030 | debian-bookworm | "/home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages" 2025-09-25 03:02:33.718035 | debian-bookworm | ], 2025-09-25 03:02:33.718040 | debian-bookworm | "meta_path": [ 2025-09-25 03:02:33.718045 | debian-bookworm | "", 2025-09-25 03:02:33.718050 | debian-bookworm | "", 2025-09-25 03:02:33.718055 | debian-bookworm | "", 2025-09-25 03:02:33.718060 | debian-bookworm | "" 2025-09-25 03:02:33.718064 | debian-bookworm | ], 2025-09-25 03:02:33.718069 | debian-bookworm | "fs_encoding": "utf-8", 2025-09-25 03:02:33.718074 | debian-bookworm | "io_encoding": "utf-8" 2025-09-25 03:02:33.718078 | debian-bookworm | }, 2025-09-25 03:02:33.718083 | debian-bookworm | "version": "3.11.2 (main, Apr 28 2025, 14:11:48) [GCC 12.2.0]", 2025-09-25 03:02:33.718088 | debian-bookworm | "makefile_filename": "/usr/lib/python3.11/config-3.11-x86_64-linux-gnu/Makefile", 2025-09-25 03:02:33.718093 | debian-bookworm | "os": "", 2025-09-25 03:02:33.718098 | debian-bookworm | "site": "", 2025-09-25 03:02:33.718102 | debian-bookworm | "datetime": "", 2025-09-25 03:02:33.718107 | debian-bookworm | "math": "", 2025-09-25 03:02:33.718112 | debian-bookworm | "json": "" 2025-09-25 03:02:33.718116 | debian-bookworm | } [virtualenv/run/session.py:55] 2025-09-25 03:02:33.718609 | debian-bookworm | check: 427 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-09-25 03:02:33.721390 | debian-bookworm | check: 431 D install pip from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/pip-25.2-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:51] 2025-09-25 03:02:33.725230 | debian-bookworm | check: 432 D build install image for pip-25.2-py3-none-any.whl to /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.2-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:49] 2025-09-25 03:02:33.728232 | debian-bookworm | check: 437 D install setuptools from wheel /home/zuul/.local/tox/lib/python3.11/site-packages/virtualenv/seed/wheels/embed/setuptools-80.9.0-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:51] 2025-09-25 03:02:33.729774 | debian-bookworm | check: 438 D build install image for setuptools-80.9.0-py3-none-any.whl to /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:49] 2025-09-25 03:02:33.933729 | debian-bookworm | check: 643 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.2-py3-none-any/pip to /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages/pip [virtualenv/util/path/_sync.py:42] 2025-09-25 03:02:33.973512 | debian-bookworm | check: 683 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/setuptools to /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages/setuptools [virtualenv/util/path/_sync.py:42] 2025-09-25 03:02:34.111032 | debian-bookworm | check: 820 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.2-py3-none-any/pip-25.2.dist-info to /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages/pip-25.2.dist-info [virtualenv/util/path/_sync.py:42] 2025-09-25 03:02:34.129398 | debian-bookworm | check: 838 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/pip-25.2-py3-none-any/pip-25.2.virtualenv to /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages/pip-25.2.virtualenv [virtualenv/util/path/_sync.py:42] 2025-09-25 03:02:34.131450 | debian-bookworm | check: 841 D generated console scripts pip-3.11 pip3 pip3.11 pip [virtualenv/seed/embed/via_app_data/pip_install/base.py:45] 2025-09-25 03:02:34.139277 | debian-bookworm | check: 848 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/distutils-precedence.pth to /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages/distutils-precedence.pth [virtualenv/util/path/_sync.py:42] 2025-09-25 03:02:34.139776 | debian-bookworm | check: 849 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/_distutils_hack to /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages/_distutils_hack [virtualenv/util/path/_sync.py:42] 2025-09-25 03:02:34.140642 | debian-bookworm | check: 850 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/pkg_resources to /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages/pkg_resources [virtualenv/util/path/_sync.py:42] 2025-09-25 03:02:34.146462 | debian-bookworm | check: 856 D copy directory /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/setuptools-80.9.0.dist-info to /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages/setuptools-80.9.0.dist-info [virtualenv/util/path/_sync.py:42] 2025-09-25 03:02:34.148395 | debian-bookworm | check: 858 D copy /home/zuul/.local/share/virtualenv/wheel/3.11/image/1/CopyPipInstall/setuptools-80.9.0-py3-none-any/setuptools-80.9.0.virtualenv to /home/zuul/src/github.com/osism/generics/.tox/check/lib/python3.11/site-packages/setuptools-80.9.0.virtualenv [virtualenv/util/path/_sync.py:42] 2025-09-25 03:02:34.148745 | debian-bookworm | check: 858 D generated console scripts [virtualenv/seed/embed/via_app_data/pip_install/base.py:45] 2025-09-25 03:02:34.149090 | debian-bookworm | check: 858 I add activators for Bash, CShell, Fish, Nushell, PowerShell, Python [virtualenv/run/session.py:65] 2025-09-25 03:02:34.157431 | debian-bookworm | check: 867 D write /home/zuul/src/github.com/osism/generics/.tox/check/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:35] 2025-09-25 03:02:34.157476 | debian-bookworm | check: 867 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:34.157612 | debian-bookworm | check: 867 D implementation = CPython [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:34.157700 | debian-bookworm | check: 867 D version_info = 3.11.2.final.0 [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:34.157764 | debian-bookworm | check: 867 D virtualenv = 20.34.0 [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:34.157853 | debian-bookworm | check: 867 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:34.157933 | debian-bookworm | check: 867 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:34.158021 | debian-bookworm | check: 867 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:34.158125 | debian-bookworm | check: 867 D base-executable = /usr/bin/python3.11 [virtualenv/create/pyenv_cfg.py:40] 2025-09-25 03:02:34.164715 | debian-bookworm | check: 874 W install_deps> python -I -m pip install -r requirements.txt [tox/tox_env/api.py:463] 2025-09-25 03:02:35.327684 | debian-bookworm | Collecting Jinja2==3.1.6 (from -r requirements.txt (line 1)) 2025-09-25 03:02:35.392139 | debian-bookworm | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-09-25 03:02:35.511887 | debian-bookworm | Collecting PyYAML==6.0.2 (from -r requirements.txt (line 2)) 2025-09-25 03:02:35.520298 | debian-bookworm | Downloading PyYAML-6.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (2.1 kB) 2025-09-25 03:02:35.592448 | debian-bookworm | Collecting ansible==11.10.0 (from -r requirements.txt (line 3)) 2025-09-25 03:02:35.601752 | debian-bookworm | Downloading ansible-11.10.0-py3-none-any.whl.metadata (8.1 kB) 2025-09-25 03:02:35.648119 | debian-bookworm | Collecting packaging==25.0 (from -r requirements.txt (line 4)) 2025-09-25 03:02:35.656974 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl.metadata (3.3 kB) 2025-09-25 03:02:35.775674 | debian-bookworm | Collecting pwgen==0.8.2.post0 (from -r requirements.txt (line 5)) 2025-09-25 03:02:35.786033 | debian-bookworm | Downloading pwgen-0.8.2.post0.tar.gz (2.9 kB) 2025-09-25 03:02:35.806994 | debian-bookworm | Installing build dependencies: started 2025-09-25 03:02:36.842455 | debian-bookworm | Installing build dependencies: finished with status 'done' 2025-09-25 03:02:36.842971 | debian-bookworm | Getting requirements to build wheel: started 2025-09-25 03:02:37.205742 | debian-bookworm | Getting requirements to build wheel: finished with status 'done' 2025-09-25 03:02:37.206585 | debian-bookworm | Preparing metadata (pyproject.toml): started 2025-09-25 03:02:37.407779 | debian-bookworm | Preparing metadata (pyproject.toml): finished with status 'done' 2025-09-25 03:02:37.527679 | debian-bookworm | Collecting python-gilt==1.2.3 (from -r requirements.txt (line 6)) 2025-09-25 03:02:37.537266 | debian-bookworm | Downloading python_gilt-1.2.3-py2.py3-none-any.whl.metadata (1.4 kB) 2025-09-25 03:02:37.603319 | debian-bookworm | Collecting requests==2.32.5 (from -r requirements.txt (line 7)) 2025-09-25 03:02:37.611141 | debian-bookworm | Downloading requests-2.32.5-py3-none-any.whl.metadata (4.9 kB) 2025-09-25 03:02:37.639969 | debian-bookworm | Collecting tabulate==0.9.0 (from -r requirements.txt (line 8)) 2025-09-25 03:02:37.647374 | debian-bookworm | Downloading tabulate-0.9.0-py3-none-any.whl.metadata (34 kB) 2025-09-25 03:02:37.751974 | debian-bookworm | Collecting MarkupSafe>=2.0 (from Jinja2==3.1.6->-r requirements.txt (line 1)) 2025-09-25 03:02:37.759554 | debian-bookworm | Downloading MarkupSafe-3.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (4.0 kB) 2025-09-25 03:02:37.844022 | debian-bookworm | Collecting ansible-core~=2.18.9 (from ansible==11.10.0->-r requirements.txt (line 3)) 2025-09-25 03:02:37.851731 | debian-bookworm | Downloading ansible_core-2.18.9-py3-none-any.whl.metadata (7.7 kB) 2025-09-25 03:02:37.887176 | debian-bookworm | Collecting click (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-09-25 03:02:37.894649 | debian-bookworm | Downloading click-8.3.0-py3-none-any.whl.metadata (2.6 kB) 2025-09-25 03:02:37.924258 | debian-bookworm | Collecting colorama (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-09-25 03:02:37.931720 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl.metadata (17 kB) 2025-09-25 03:02:37.963115 | debian-bookworm | Collecting fasteners (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-09-25 03:02:37.971371 | debian-bookworm | Downloading fasteners-0.20-py3-none-any.whl.metadata (4.8 kB) 2025-09-25 03:02:38.003028 | debian-bookworm | Collecting sh (from python-gilt==1.2.3->-r requirements.txt (line 6)) 2025-09-25 03:02:38.010750 | debian-bookworm | Downloading sh-2.2.2-py3-none-any.whl.metadata (3.7 kB) 2025-09-25 03:02:38.137723 | debian-bookworm | Collecting charset_normalizer<4,>=2 (from requests==2.32.5->-r requirements.txt (line 7)) 2025-09-25 03:02:38.145770 | debian-bookworm | Downloading charset_normalizer-3.4.3-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (36 kB) 2025-09-25 03:02:38.177652 | debian-bookworm | Collecting idna<4,>=2.5 (from requests==2.32.5->-r requirements.txt (line 7)) 2025-09-25 03:02:38.185305 | debian-bookworm | Downloading idna-3.10-py3-none-any.whl.metadata (10 kB) 2025-09-25 03:02:38.252237 | debian-bookworm | Collecting urllib3<3,>=1.21.1 (from requests==2.32.5->-r requirements.txt (line 7)) 2025-09-25 03:02:38.260386 | debian-bookworm | Downloading urllib3-2.5.0-py3-none-any.whl.metadata (6.5 kB) 2025-09-25 03:02:38.300051 | debian-bookworm | Collecting certifi>=2017.4.17 (from requests==2.32.5->-r requirements.txt (line 7)) 2025-09-25 03:02:38.307837 | debian-bookworm | Downloading certifi-2025.8.3-py3-none-any.whl.metadata (2.4 kB) 2025-09-25 03:02:38.580219 | debian-bookworm | Collecting cryptography (from ansible-core~=2.18.9->ansible==11.10.0->-r requirements.txt (line 3)) 2025-09-25 03:02:38.587879 | debian-bookworm | Downloading cryptography-46.0.1-cp311-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-09-25 03:02:38.621006 | debian-bookworm | Collecting resolvelib<1.1.0,>=0.5.3 (from ansible-core~=2.18.9->ansible==11.10.0->-r requirements.txt (line 3)) 2025-09-25 03:02:38.628085 | debian-bookworm | Downloading resolvelib-1.0.1-py2.py3-none-any.whl.metadata (4.0 kB) 2025-09-25 03:02:38.826289 | debian-bookworm | Collecting cffi>=2.0.0 (from cryptography->ansible-core~=2.18.9->ansible==11.10.0->-r requirements.txt (line 3)) 2025-09-25 03:02:38.833642 | debian-bookworm | Downloading cffi-2.0.0-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-09-25 03:02:38.953001 | debian-bookworm | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core~=2.18.9->ansible==11.10.0->-r requirements.txt (line 3)) 2025-09-25 03:02:38.962299 | debian-bookworm | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-09-25 03:02:38.988104 | debian-bookworm | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-09-25 03:02:39.013047 | debian-bookworm | Downloading PyYAML-6.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (762 kB) 2025-09-25 03:02:39.051538 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 763.0/763.0 kB 22.9 MB/s 0:00:00 2025-09-25 03:02:39.060181 | debian-bookworm | Downloading ansible-11.10.0-py3-none-any.whl (56.6 MB) 2025-09-25 03:02:40.977217 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 56.6/56.6 MB 29.6 MB/s 0:00:01 2025-09-25 03:02:40.985821 | debian-bookworm | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-09-25 03:02:41.006379 | debian-bookworm | Downloading python_gilt-1.2.3-py2.py3-none-any.whl (22 kB) 2025-09-25 03:02:41.025755 | debian-bookworm | Downloading requests-2.32.5-py3-none-any.whl (64 kB) 2025-09-25 03:02:41.044996 | debian-bookworm | Downloading tabulate-0.9.0-py3-none-any.whl (35 kB) 2025-09-25 03:02:41.063793 | debian-bookworm | Downloading ansible_core-2.18.9-py3-none-any.whl (2.2 MB) 2025-09-25 03:02:41.175348 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 2.2/2.2 MB 20.2 MB/s 0:00:00 2025-09-25 03:02:41.184939 | debian-bookworm | Downloading charset_normalizer-3.4.3-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (150 kB) 2025-09-25 03:02:41.211042 | debian-bookworm | Downloading idna-3.10-py3-none-any.whl (70 kB) 2025-09-25 03:02:41.231596 | debian-bookworm | Downloading resolvelib-1.0.1-py2.py3-none-any.whl (17 kB) 2025-09-25 03:02:41.250500 | debian-bookworm | Downloading urllib3-2.5.0-py3-none-any.whl (129 kB) 2025-09-25 03:02:41.272389 | debian-bookworm | Downloading certifi-2025.8.3-py3-none-any.whl (161 kB) 2025-09-25 03:02:41.295167 | debian-bookworm | Downloading MarkupSafe-3.0.2-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (23 kB) 2025-09-25 03:02:41.313837 | debian-bookworm | Downloading click-8.3.0-py3-none-any.whl (107 kB) 2025-09-25 03:02:41.332970 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2025-09-25 03:02:41.349283 | debian-bookworm | Downloading cryptography-46.0.1-cp311-abi3-manylinux_2_34_x86_64.whl (4.6 MB) 2025-09-25 03:02:41.568072 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.6/4.6 MB 21.1 MB/s 0:00:00 2025-09-25 03:02:41.578976 | debian-bookworm | Downloading cffi-2.0.0-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (215 kB) 2025-09-25 03:02:41.610686 | debian-bookworm | Downloading fasteners-0.20-py3-none-any.whl (18 kB) 2025-09-25 03:02:41.628417 | debian-bookworm | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-09-25 03:02:41.649052 | debian-bookworm | Downloading sh-2.2.2-py3-none-any.whl (38 kB) 2025-09-25 03:02:41.937011 | debian-bookworm | Building wheels for collected packages: pwgen 2025-09-25 03:02:41.938343 | debian-bookworm | Building wheel for pwgen (pyproject.toml): started 2025-09-25 03:02:42.229242 | debian-bookworm | Building wheel for pwgen (pyproject.toml): finished with status 'done' 2025-09-25 03:02:42.231022 | debian-bookworm | Created wheel for pwgen: filename=pwgen-0.8.2.post0-py3-none-any.whl size=3797 sha256=e7706eea5acdf25357fbc997004dad0f12b1e63980ff808d80a1183cf7fe7e11 2025-09-25 03:02:42.231656 | debian-bookworm | Stored in directory: /home/zuul/.cache/pip/wheels/c3/81/ed/8e13725daf8a767fbde13d7583ce457f3fdf974023341fac95 2025-09-25 03:02:42.236859 | debian-bookworm | Successfully built pwgen 2025-09-25 03:02:42.480151 | 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-09-25 03:03:09.089551 | debian-bookworm | 2025-09-25 03:03:09.092649 | debian-bookworm | Successfully installed Jinja2-3.1.6 MarkupSafe-3.0.2 PyYAML-6.0.2 ansible-11.10.0 ansible-core-2.18.9 certifi-2025.8.3 cffi-2.0.0 charset_normalizer-3.4.3 click-8.3.0 colorama-0.4.6 cryptography-46.0.1 fasteners-0.20 idna-3.10 packaging-25.0 pwgen-0.8.2.post0 pycparser-2.23 python-gilt-1.2.3 requests-2.32.5 resolvelib-1.0.1 sh-2.2.2 tabulate-0.9.0 urllib3-2.5.0 2025-09-25 03:03:09.246948 | debian-bookworm | check: 35956 I exit 0 (35.08 seconds) /home/zuul/src/github.com/osism/generics> python -I -m pip install -r requirements.txt pid=1435 [tox/execute/api.py:294] 2025-09-25 03:03:09.249203 | debian-bookworm | check: OK (35.71 seconds) 2025-09-25 03:03:09.249295 | debian-bookworm | congratulations :) (35.74 seconds) 2025-09-25 03:03:09.458985 | debian-bookworm | ok: Runtime: 0:00:36.035650 2025-09-25 03:03:09.478238 | 2025-09-25 03:03:09.478407 | TASK [tox : Create a tempfile to save tox showconfig] 2025-09-25 03:03:10.046479 | debian-bookworm | changed 2025-09-25 03:03:10.054357 | 2025-09-25 03:03:10.054493 | TASK [tox : Get tox envlist config] 2025-09-25 03:03:11.085565 | debian-bookworm | ok: Runtime: 0:00:00.289879 2025-09-25 03:03:11.099534 | 2025-09-25 03:03:11.099658 | TASK [tox : Install any sibling python packages] 2025-09-25 03:03:11.924591 | debian-bookworm | ok: No setup.cfg, no action needed 2025-09-25 03:03:11.950297 | 2025-09-25 03:03:11.955512 | TASK [tox : Remove tempfile] 2025-09-25 03:03:12.835088 | debian-bookworm | changed 2025-09-25 03:03:12.845683 | 2025-09-25 03:03:12.845808 | TASK [tox : Emit tox command] 2025-09-25 03:03:12.936015 | /home/zuul/.local/tox/bin/tox -echeck -vv --skip-missing-interpreters=false 2025-09-25 03:03:12.941404 | 2025-09-25 03:03:12.941530 | TASK [tox : Run tox] 2025-09-25 03:03:13.823133 | debian-bookworm | ROOT: 214 D setup logging to DEBUG on pid 1590 [tox/report.py:222] 2025-09-25 03:03:13.857213 | debian-bookworm | check: 248 I find interpreter for spec PythonSpec(major=3, free_threaded=False) [virtualenv/discovery/builtin.py:77] 2025-09-25 03:03:13.858130 | debian-bookworm | check: 249 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-09-25 03:03:13.860395 | debian-bookworm | check: 251 D filesystem is case-sensitive [virtualenv/discovery/info.py:20] 2025-09-25 03:03:13.860500 | debian-bookworm | check: 252 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, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:84] 2025-09-25 03:03:13.860530 | debian-bookworm | check: 252 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, Apr 28 2025, 14:11:48) [GCC 12.2.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:86] 2025-09-25 03:03:13.861875 | debian-bookworm | check: 253 D symlink on filesystem does work [virtualenv/info.py:50] 2025-09-25 03:03:13.862067 | debian-bookworm | check: 253 D filesystem is case-sensitive [virtualenv/info.py:27] 2025-09-25 03:03:13.894708 | debian-bookworm | check: 286 W commands[0]> python3 src/check-inventory-sorting.py [tox/tox_env/api.py:463] 2025-09-25 03:03:13.961334 | debian-bookworm | check: 352 I exit 0 (0.06 seconds) /home/zuul/src/github.com/osism/generics> python3 src/check-inventory-sorting.py pid=1597 [tox/execute/api.py:294] 2025-09-25 03:03:13.962817 | debian-bookworm | check: OK (0.11=setup[0.05]+cmd[0.06] seconds) 2025-09-25 03:03:13.962842 | debian-bookworm | congratulations :) (0.14 seconds) 2025-09-25 03:03:14.219443 | debian-bookworm | ok: Runtime: 0:00:00.459257 2025-09-25 03:03:14.223923 | 2025-09-25 03:03:14.223997 | TASK [tox : Look for output] 2025-09-25 03:03:14.674520 | debian-bookworm | ok 2025-09-25 03:03:14.684736 | 2025-09-25 03:03:14.684820 | TASK [tox : Return file comments to Zuul] 2025-09-25 03:03:14.767004 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:14.772971 | 2025-09-25 03:03:14.773038 | PLAY RECAP 2025-09-25 03:03:14.773088 | debian-bookworm | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2025-09-25 03:03:14.773114 | 2025-09-25 03:03:14.996285 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2025-09-25 03:03:14.997184 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-25 03:03:15.780609 | 2025-09-25 03:03:15.780724 | PLAY [all] 2025-09-25 03:03:15.821553 | 2025-09-25 03:03:15.821661 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2025-09-25 03:03:15.925788 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:15.930564 | 2025-09-25 03:03:15.930667 | TASK [fetch-tox-output : Set tox log path for single node] 2025-09-25 03:03:15.977628 | debian-bookworm | ok 2025-09-25 03:03:15.982484 | 2025-09-25 03:03:15.982563 | TASK [fetch-tox-output : Ensure local tox dir] 2025-09-25 03:03:16.944696 | debian-bookworm -> localhost | changed 2025-09-25 03:03:16.950936 | 2025-09-25 03:03:16.951024 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2025-09-25 03:03:17.003993 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:17.008467 | 2025-09-25 03:03:17.008630 | TASK [fetch-tox-output : Set envlist fact] 2025-09-25 03:03:17.067694 | debian-bookworm | ok 2025-09-25 03:03:17.072234 | 2025-09-25 03:03:17.072307 | TASK [fetch-tox-output : Get tox version] 2025-09-25 03:03:17.818000 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2025-09-25 03:03:17.836248 | debian-bookworm | 4.30.2 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2025-09-25 03:03:18.131151 | debian-bookworm | ok: Runtime: 0:00:00.296542 2025-09-25 03:03:18.138130 | 2025-09-25 03:03:18.138233 | TASK [fetch-tox-output : Find all default environments] 2025-09-25 03:03:18.698698 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:18.713264 | 2025-09-25 03:03:18.713376 | TASK [fetch-tox-output : Set envlist fact] 2025-09-25 03:03:18.756432 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:18.761818 | 2025-09-25 03:03:18.761916 | TASK [fetch-tox-output : Find all default environments] 2025-09-25 03:03:19.308189 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:19.313655 | 2025-09-25 03:03:19.313751 | TASK [fetch-tox-output : Set envlist fact] 2025-09-25 03:03:19.346656 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:19.352580 | 2025-09-25 03:03:19.352677 | LOOP [fetch-tox-output : Copy tox logs] 2025-09-25 03:03:19.419878 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:19.431885 | 2025-09-25 03:03:19.431984 | LOOP [fetch-tox-output : Collect tox logs] 2025-09-25 03:03:20.449734 | debian-bookworm | changed: 2025-09-25 03:03:20.449875 | debian-bookworm | created directory /var/lib/zuul/builds/126a37b250494eb2992a25d6d3ec5b60/work/logs/tox/check 2025-09-25 03:03:20.449905 | debian-bookworm | cd+++++++++ ./ 2025-09-25 03:03:20.449926 | debian-bookworm | >f+++++++++ 1-commands[0].log 2025-09-25 03:03:20.449950 | 2025-09-25 03:03:20.462937 | 2025-09-25 03:03:20.463007 | PLAY RECAP 2025-09-25 03:03:20.463048 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2025-09-25 03:03:20.463069 | 2025-09-25 03:03:20.646725 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2025-09-25 03:03:20.649074 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-25 03:03:21.363785 | 2025-09-25 03:03:21.363901 | PLAY [all] 2025-09-25 03:03:21.377432 | 2025-09-25 03:03:21.377561 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-09-25 03:03:21.579661 | debian-bookworm | changed: non-zero return code 2025-09-25 03:03:21.584120 | 2025-09-25 03:03:21.584239 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-09-25 03:03:21.596873 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:21.601368 | 2025-09-25 03:03:21.601454 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-09-25 03:03:21.618607 | 2025-09-25 03:03:21.618744 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-09-25 03:03:21.646317 | 2025-09-25 03:03:21.646455 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-09-25 03:03:21.658697 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:21.663214 | 2025-09-25 03:03:21.663290 | LOOP [fetch-subunit-output : Generate subunit file] 2025-09-25 03:03:21.690361 | 2025-09-25 03:03:21.690497 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-09-25 03:03:21.722942 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:21.728770 | 2025-09-25 03:03:21.728875 | TASK [fetch-subunit-output : Remove the temporary file] 2025-09-25 03:03:21.741900 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:21.746536 | 2025-09-25 03:03:21.746616 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-09-25 03:03:21.778941 | debian-bookworm | skipping: Conditional result was False 2025-09-25 03:03:21.785033 | 2025-09-25 03:03:21.785106 | PLAY RECAP 2025-09-25 03:03:21.785151 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-09-25 03:03:21.785176 | 2025-09-25 03:03:21.878447 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2025-09-25 03:03:21.879258 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-25 03:03:22.514439 | 2025-09-25 03:03:22.514547 | PLAY [Base post] 2025-09-25 03:03:22.539826 | 2025-09-25 03:03:22.539925 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-25 03:03:23.205671 | debian-bookworm | changed 2025-09-25 03:03:23.211054 | 2025-09-25 03:03:23.211134 | PLAY RECAP 2025-09-25 03:03:23.211187 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-25 03:03:23.211264 | 2025-09-25 03:03:23.282815 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-25 03:03:23.283614 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-25 03:03:23.948531 | 2025-09-25 03:03:23.948641 | PLAY [Base post-logs] 2025-09-25 03:03:23.968856 | 2025-09-25 03:03:23.968969 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-25 03:03:24.356772 | localhost | changed 2025-09-25 03:03:24.364279 | 2025-09-25 03:03:24.364386 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-25 03:03:24.388076 | localhost | ok 2025-09-25 03:03:24.390394 | 2025-09-25 03:03:24.390468 | TASK [Set zuul-log-path fact] 2025-09-25 03:03:24.403900 | localhost | ok 2025-09-25 03:03:24.409877 | 2025-09-25 03:03:24.409954 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-25 03:03:24.433401 | localhost | ok 2025-09-25 03:03:24.435734 | 2025-09-25 03:03:24.435806 | TASK [upload-logs : Create log directories] 2025-09-25 03:03:24.785672 | localhost | changed 2025-09-25 03:03:24.788921 | 2025-09-25 03:03:24.789024 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-25 03:03:25.270642 | localhost -> localhost | ok: Runtime: 0:00:00.010066 2025-09-25 03:03:25.275607 | 2025-09-25 03:03:25.275725 | TASK [upload-logs : Upload logs to log server] 2025-09-25 03:03:25.766365 | localhost | Output suppressed because no_log was given 2025-09-25 03:03:25.767966 | 2025-09-25 03:03:25.768048 | LOOP [upload-logs : Compress console log and json output] 2025-09-25 03:03:25.806978 | localhost | skipping: Conditional result was False 2025-09-25 03:03:25.812575 | localhost | skipping: Conditional result was False 2025-09-25 03:03:25.823315 | 2025-09-25 03:03:25.823416 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-25 03:03:25.858127 | localhost | skipping: Conditional result was False 2025-09-25 03:03:25.858339 | 2025-09-25 03:03:25.862152 | localhost | skipping: Conditional result was False 2025-09-25 03:03:25.868438 | 2025-09-25 03:03:25.868534 | LOOP [upload-logs : Upload console log and json output]