Skip to content

tox4: endless spinning #1937

New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom
Closed
fschulze opened this issue Mar 1, 2021 · 11 comments
Closed

tox4: endless spinning #1937

fschulze opened this issue Mar 1, 2021 · 11 comments
Assignees
@gaborbernat
Labels
needs:reproducer ideally a failing test marked as xfail. If that is not possible exact instructions to reproduce type:question ❔ a question about how things work or if something is a bug or a feature

Comments

@fschulze
Copy link

fschulze commented Mar 1, 2021

It seems like tox is endlessly trying to discover my python versions.

I run current devpi main branch in the server directory.

I have an active virtualenv with Python 3.8 and the other Python versions from 3.6 to 3.9 are in the path via pyenv.

% tox4 -r -vvvvvv -n -o

This is the output until ctrl-c:

ROOT: 195 D setup logging to NOTSET on pid 27488 [tox/report.py:211]
py36: 255 W remove tox env folder /Users/fschulze/Development/devpi/devel/server/.tox/4/py36 [tox/tox_env/api.py:202]
py36: 284 I find interpreter for spec PythonSpec(implementation=CPython, major=3, minor=6) [virtualenv/discovery/builtin.py:62]
py36: 284 D discover exe for PythonInfo(spec=CPython3.8.6.final.0-64, exe=/Users/fschulze/Development/devpi/devel/bin/python3.8, platform=darwin, version='3.8.6 (default, Feb 19 2021, 11:43:49) \n[Clang 12.0.0 (clang-1200.0.32.29)]', encoding_fs_io=utf-8-utf-8) in /Users/fschulze/.pyenv/versions/3.8.6 [virtualenv/discovery/py_info.py:372]
py36: 285 D filesystem is not case-sensitive [virtualenv/info.py:28]
py36: 286 D Attempting to acquire lock 4559859616 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a2aaea49ce029cf5dce8f285e23fbc02dd70f7cebf214a80b97ba262cecb011c.lock [filelock.py:270]
py36: 286 D Lock 4559859616 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a2aaea49ce029cf5dce8f285e23fbc02dd70f7cebf214a80b97ba262cecb011c.lock [filelock.py:274]
py36: 286 D got python info of /Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8 from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a2aaea49ce029cf5dce8f285e23fbc02dd70f7cebf214a80b97ba262cecb011c.json [virtualenv/app_data/via_disk_folder.py:135]
py36: 287 D Attempting to release lock 4559859616 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a2aaea49ce029cf5dce8f285e23fbc02dd70f7cebf214a80b97ba262cecb011c.lock [filelock.py:315]
py36: 287 D Lock 4559859616 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a2aaea49ce029cf5dce8f285e23fbc02dd70f7cebf214a80b97ba262cecb011c.lock [filelock.py:318]
py36: 287 I proposed PythonInfo(spec=CPython3.8.6.final.0-64, system=/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8, exe=/Users/fschulze/Development/devpi/devel/bin/python3.8, platform=darwin, version='3.8.6 (default, Feb 19 2021, 11:43:49) \n[Clang 12.0.0 (clang-1200.0.32.29)]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:69]
py36: 287 D discover PATH[0]=/Users/fschulze/Development/devpi/devel/bin [virtualenv/discovery/builtin.py:114]
py36: 288 D Attempting to acquire lock 4559858560 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/457b6a7d6339db1a957b59cf4fccc581ae942bf4ab5fd7e55f54f41f3b768f61.lock [filelock.py:270]
py36: 288 D Lock 4559858560 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/457b6a7d6339db1a957b59cf4fccc581ae942bf4ab5fd7e55f54f41f3b768f61.lock [filelock.py:274]
py36: 288 D got python info of /Users/fschulze/Development/devpi/devel/bin/python3 from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/457b6a7d6339db1a957b59cf4fccc581ae942bf4ab5fd7e55f54f41f3b768f61.json [virtualenv/app_data/via_disk_folder.py:135]
py36: 289 D Attempting to release lock 4559858560 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/457b6a7d6339db1a957b59cf4fccc581ae942bf4ab5fd7e55f54f41f3b768f61.lock [filelock.py:315]
py36: 289 D Lock 4559858560 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/457b6a7d6339db1a957b59cf4fccc581ae942bf4ab5fd7e55f54f41f3b768f61.lock [filelock.py:318]
py36: 289 D discover exe from cache /Users/fschulze/.pyenv/versions/3.8.6 - exact False: PythonInfo({'architecture': 64, 'base_exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'base_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'distutils_install': {'data': '', 'headers': 'include/python3.8/UNKNOWN', 'platlib': 'lib/python3.8/site-packages', 'purelib': 'lib/python3.8/site-packages', 'scripts': 'bin'}, 'exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'executable': '/Users/fschulze/Development/devpi/devel/bin/python3.8', 'file_system_encoding': 'utf-8', 'has_venv': True, 'implementation': 'CPython', 'max_size': 9223372036854775807, 'original_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'os': 'posix', 'path': ['/Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/discovery', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python38.zip', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/lib-dynload', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/site-packages'], 'platform': 'darwin', 'prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'real_prefix': None, 'stdout_encoding': 'utf-8', 'sysconfig': {'makefile_filename': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/config-3.8-darwin/Makefile'}, 'sysconfig_paths': {'data': '{base}', 'include': '{installed_base}/include/python{py_version_short}{abiflags}', 'platlib': '{platbase}/lib/python{py_version_short}/site-packages', 'platstdlib': '{platbase}/lib/python{py_version_short}', 'purelib': '{base}/lib/python{py_version_short}/site-packages', 'scripts': '{base}/bin', 'stdlib': '{installed_base}/lib/python{py_version_short}'}, 'sysconfig_vars': {'PYTHONFRAMEWORK': '', 'abiflags': '', 'base': '/Users/fschulze/.pyenv/versions/3.8.6', 'installed_base': '/Users/fschulze/.pyenv/versions/3.8.6', 'platbase': '/Users/fschulze/.pyenv/versions/3.8.6', 'py_version_short': '3.8'}, 'system_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'system_stdlib': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'system_stdlib_platform': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'version': '3.8.6 (default, Feb 19 2021, 11:43:49) \n[Clang 12.0.0 (clang-1200.0.32.29)]', 'version_info': VersionInfo(major=3, minor=8, micro=6, releaselevel='final', serial=0)}) [virtualenv/discovery/py_info.py:370]
py36: 290 D Attempting to acquire lock 4559917744 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9e337246b659eaf7a10894a6c8f01ec04125ae3a4befefc6b2a834ba394cc1a9.lock [filelock.py:270]
py36: 290 D Lock 4559917744 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9e337246b659eaf7a10894a6c8f01ec04125ae3a4befefc6b2a834ba394cc1a9.lock [filelock.py:274]
py36: 290 D got python info of /Users/fschulze/Development/devpi/devel/bin/python from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9e337246b659eaf7a10894a6c8f01ec04125ae3a4befefc6b2a834ba394cc1a9.json [virtualenv/app_data/via_disk_folder.py:135]
py36: 291 D Attempting to release lock 4559917744 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9e337246b659eaf7a10894a6c8f01ec04125ae3a4befefc6b2a834ba394cc1a9.lock [filelock.py:315]
py36: 291 D Lock 4559917744 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9e337246b659eaf7a10894a6c8f01ec04125ae3a4befefc6b2a834ba394cc1a9.lock [filelock.py:318]
py36: 291 D discover exe from cache /Users/fschulze/.pyenv/versions/3.8.6 - exact False: PythonInfo({'architecture': 64, 'base_exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'base_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'distutils_install': {'data': '', 'headers': 'include/python3.8/UNKNOWN', 'platlib': 'lib/python3.8/site-packages', 'purelib': 'lib/python3.8/site-packages', 'scripts': 'bin'}, 'exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'executable': '/Users/fschulze/Development/devpi/devel/bin/python3', 'file_system_encoding': 'utf-8', 'has_venv': True, 'implementation': 'CPython', 'max_size': 9223372036854775807, 'original_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'os': 'posix', 'path': ['/Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/discovery', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python38.zip', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/lib-dynload', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/site-packages'], 'platform': 'darwin', 'prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'real_prefix': None, 'stdout_encoding': 'utf-8', 'sysconfig': {'makefile_filename': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/config-3.8-darwin/Makefile'}, 'sysconfig_paths': {'data': '{base}', 'include': '{installed_base}/include/python{py_version_short}{abiflags}', 'platlib': '{platbase}/lib/python{py_version_short}/site-packages', 'platstdlib': '{platbase}/lib/python{py_version_short}', 'purelib': '{base}/lib/python{py_version_short}/site-packages', 'scripts': '{base}/bin', 'stdlib': '{installed_base}/lib/python{py_version_short}'}, 'sysconfig_vars': {'PYTHONFRAMEWORK': '', 'abiflags': '', 'base': '/Users/fschulze/.pyenv/versions/3.8.6', 'installed_base': '/Users/fschulze/.pyenv/versions/3.8.6', 'platbase': '/Users/fschulze/.pyenv/versions/3.8.6', 'py_version_short': '3.8'}, 'system_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'system_stdlib': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'system_stdlib_platform': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'version': '3.8.6 (default, Feb 19 2021, 11:43:49) \n[Clang 12.0.0 (clang-1200.0.32.29)]', 'version_info': VersionInfo(major=3, minor=8, micro=6, releaselevel='final', serial=0)}) [virtualenv/discovery/py_info.py:370]
py36: 291 D discover PATH[1]=/Users/fschulze/.cargo/bin [virtualenv/discovery/builtin.py:114]
py36: 291 D discover PATH[2]=/Users/fschulze/bin [virtualenv/discovery/builtin.py:114]
py36: 292 D Attempting to acquire lock 4559918656 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/fbc1892380c568224b2642e2dc075d155af408ee5e257b140040b2927bf7fc8e.lock [filelock.py:270]
py36: 292 D Lock 4559918656 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/fbc1892380c568224b2642e2dc075d155af408ee5e257b140040b2927bf7fc8e.lock [filelock.py:274]
py36: 292 D get interpreter info via cmd: /Users/fschulze/bin/python3.6 /Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/discovery/py_info.py [virtualenv/discovery/cached_py_info.py:91]

I see no progress and my terminal title switches between bash and env a lot.

This is the rest of the output after ctrl-c:

ROOT: 151867 E [27488] KeyboardInterrupt - teardown started [tox/session/cmd/run/common.py:188]
ROOT: 151867 W interrupt tox environment: py36 [tox/tox_env/api.py:54]
ROOT: 151867 W interrupt tox environment: .pkg [tox/tox_env/api.py:54]
py36: 151868 D Attempting to release lock 4559918656 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/fbc1892380c568224b2642e2dc075d155af408ee5e257b140040b2927bf7fc8e.lock [filelock.py:315]
py36: 151868 D Lock 4559918656 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/fbc1892380c568224b2642e2dc075d155af408ee5e257b140040b2927bf7fc8e.lock [filelock.py:318]
py36: 151868 I failed to query /Users/fschulze/bin/python3.6 with code -2 [virtualenv/discovery/cached_py_info.py:33]
py36: 151869 D Attempting to acquire lock 4559918704 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a5b569f4bae94e69a478e4d1dae8a28e477b0290b2e577758f0de4075e2e69b1.lock [filelock.py:270]
py36: 151869 D Lock 4559918704 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a5b569f4bae94e69a478e4d1dae8a28e477b0290b2e577758f0de4075e2e69b1.lock [filelock.py:274]
py36: 151869 D got python info of /Users/fschulze/bin/python3 from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a5b569f4bae94e69a478e4d1dae8a28e477b0290b2e577758f0de4075e2e69b1.json [virtualenv/app_data/via_disk_folder.py:135]
py36: 151870 D Attempting to release lock 4559918704 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a5b569f4bae94e69a478e4d1dae8a28e477b0290b2e577758f0de4075e2e69b1.lock [filelock.py:315]
py36: 151870 D Lock 4559918704 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a5b569f4bae94e69a478e4d1dae8a28e477b0290b2e577758f0de4075e2e69b1.lock [filelock.py:318]
py36: 151870 D discover exe from cache /Users/fschulze/.pyenv/versions/3.8.6 - exact False: PythonInfo({'architecture': 64, 'base_exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'base_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'distutils_install': {'data': '', 'headers': 'include/python3.8/UNKNOWN', 'platlib': 'lib/python3.8/site-packages', 'purelib': 'lib/python3.8/site-packages', 'scripts': 'bin'}, 'exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'executable': '/Users/fschulze/Development/devpi/devel/bin/python', 'file_system_encoding': 'utf-8', 'has_venv': True, 'implementation': 'CPython', 'max_size': 9223372036854775807, 'original_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'os': 'posix', 'path': ['/Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/discovery', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python38.zip', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/lib-dynload', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/site-packages'], 'platform': 'darwin', 'prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'real_prefix': None, 'stdout_encoding': 'utf-8', 'sysconfig': {'makefile_filename': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/config-3.8-darwin/Makefile'}, 'sysconfig_paths': {'data': '{base}', 'include': '{installed_base}/include/python{py_version_short}{abiflags}', 'platlib': '{platbase}/lib/python{py_version_short}/site-packages', 'platstdlib': '{platbase}/lib/python{py_version_short}', 'purelib': '{base}/lib/python{py_version_short}/site-packages', 'scripts': '{base}/bin', 'stdlib': '{installed_base}/lib/python{py_version_short}'}, 'sysconfig_vars': {'PYTHONFRAMEWORK': '', 'abiflags': '', 'base': '/Users/fschulze/.pyenv/versions/3.8.6', 'installed_base': '/Users/fschulze/.pyenv/versions/3.8.6', 'platbase': '/Users/fschulze/.pyenv/versions/3.8.6', 'py_version_short': '3.8'}, 'system_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'system_stdlib': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'system_stdlib_platform': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'version': '3.8.6 (default, Feb 19 2021, 11:43:49) \n[Clang 12.0.0 (clang-1200.0.32.29)]', 'version_info': VersionInfo(major=3, minor=8, micro=6, releaselevel='final', serial=0)}) [virtualenv/discovery/py_info.py:370]
py36: 151871 D Attempting to acquire lock 4559920288 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9165cd0eb334d0dba0bb0d634e5ad830bc7149a6db517f598f2ad676c816c2ac.lock [filelock.py:270]
py36: 151871 D Lock 4559920288 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9165cd0eb334d0dba0bb0d634e5ad830bc7149a6db517f598f2ad676c816c2ac.lock [filelock.py:274]
py36: 151872 D got python info of /Users/fschulze/bin/python from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9165cd0eb334d0dba0bb0d634e5ad830bc7149a6db517f598f2ad676c816c2ac.json [virtualenv/app_data/via_disk_folder.py:135]
py36: 151872 D Attempting to release lock 4559920288 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9165cd0eb334d0dba0bb0d634e5ad830bc7149a6db517f598f2ad676c816c2ac.lock [filelock.py:315]
py36: 151872 D Lock 4559920288 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9165cd0eb334d0dba0bb0d634e5ad830bc7149a6db517f598f2ad676c816c2ac.lock [filelock.py:318]
py36: 151872 D discover exe from cache /Users/fschulze/.pyenv/versions/3.8.6 - exact False: PythonInfo({'architecture': 64, 'base_exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'base_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'distutils_install': {'data': '', 'headers': 'include/python3.8/UNKNOWN', 'platlib': 'lib/python3.8/site-packages', 'purelib': 'lib/python3.8/site-packages', 'scripts': 'bin'}, 'exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'executable': '/Users/fschulze/bin/python3', 'file_system_encoding': 'utf-8', 'has_venv': True, 'implementation': 'CPython', 'max_size': 9223372036854775807, 'original_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'os': 'posix', 'path': ['/Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/discovery', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python38.zip', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/lib-dynload', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/site-packages'], 'platform': 'darwin', 'prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'real_prefix': None, 'stdout_encoding': 'utf-8', 'sysconfig': {'makefile_filename': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/config-3.8-darwin/Makefile'}, 'sysconfig_paths': {'data': '{base}', 'include': '{installed_base}/include/python{py_version_short}{abiflags}', 'platlib': '{platbase}/lib/python{py_version_short}/site-packages', 'platstdlib': '{platbase}/lib/python{py_version_short}', 'purelib': '{base}/lib/python{py_version_short}/site-packages', 'scripts': '{base}/bin', 'stdlib': '{installed_base}/lib/python{py_version_short}'}, 'sysconfig_vars': {'PYTHONFRAMEWORK': '', 'abiflags': '', 'base': '/Users/fschulze/.pyenv/versions/3.8.6', 'installed_base': '/Users/fschulze/.pyenv/versions/3.8.6', 'platbase': '/Users/fschulze/.pyenv/versions/3.8.6', 'py_version_short': '3.8'}, 'system_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'system_stdlib': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'system_stdlib_platform': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'version': '3.8.6 (default, Feb 19 2021, 11:43:49) \n[Clang 12.0.0 (clang-1200.0.32.29)]', 'version_info': VersionInfo(major=3, minor=8, micro=6, releaselevel='final', serial=0)}) [virtualenv/discovery/py_info.py:370]
py36: 151873 D discover PATH[3]=/usr/local/sbin [virtualenv/discovery/builtin.py:114]
py36: 151873 D discover PATH[4]=/usr/local/bin [virtualenv/discovery/builtin.py:114]
py36: 151874 D Attempting to acquire lock 4559920912 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/4cd7ab41f5fca4b9b44701077e38c5ffd31fe66a6cab21e0214b68d958d0e462.lock [filelock.py:270]
py36: 151874 D Lock 4559920912 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/4cd7ab41f5fca4b9b44701077e38c5ffd31fe66a6cab21e0214b68d958d0e462.lock [filelock.py:274]
py36: 151874 D got python info of /usr/local/bin/python from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/4cd7ab41f5fca4b9b44701077e38c5ffd31fe66a6cab21e0214b68d958d0e462.json [virtualenv/app_data/via_disk_folder.py:135]
py36: 151875 D Attempting to release lock 4559920912 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/4cd7ab41f5fca4b9b44701077e38c5ffd31fe66a6cab21e0214b68d958d0e462.lock [filelock.py:315]
py36: 151875 D Lock 4559920912 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/4cd7ab41f5fca4b9b44701077e38c5ffd31fe66a6cab21e0214b68d958d0e462.lock [filelock.py:318]
py36: 151875 D Attempting to acquire lock 4560009536 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9f8db13761904f9a05eb54cadd7876dec043b4411add2dfc35f9ba54388f47e8.lock [filelock.py:270]
py36: 151875 D Lock 4560009536 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9f8db13761904f9a05eb54cadd7876dec043b4411add2dfc35f9ba54388f47e8.lock [filelock.py:274]
py36: 151876 D got python info of /usr/local/opt/python@2/bin/python2.7 from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9f8db13761904f9a05eb54cadd7876dec043b4411add2dfc35f9ba54388f47e8.json [virtualenv/app_data/via_disk_folder.py:135]
py36: 151876 D Attempting to release lock 4560009536 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9f8db13761904f9a05eb54cadd7876dec043b4411add2dfc35f9ba54388f47e8.lock [filelock.py:315]
py36: 151876 D Lock 4560009536 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9f8db13761904f9a05eb54cadd7876dec043b4411add2dfc35f9ba54388f47e8.lock [filelock.py:318]
py36: 151877 I proposed PathPythonInfo(spec=CPython2.7.16.final.0-64, system=/usr/local/opt/python@2/bin/python2.7, exe=/usr/local/bin/python, platform=darwin, version='2.7.16 (default, Mar  4 2019, 09:01:38) \n[GCC 4.2.1 Compatible Apple LLVM 10.0.0 (clang-1000.11.45.5)]', encoding_fs_io=utf-8-None) [virtualenv/discovery/builtin.py:69]
py36: 151877 D discover PATH[5]=/usr/local/opt/postgresql@10/bin [virtualenv/discovery/builtin.py:114]
py36: 151877 D discover PATH[6]=/usr/bin [virtualenv/discovery/builtin.py:114]
py36: 151878 D Attempting to acquire lock 4559920864 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/31f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6.lock [filelock.py:270]
py36: 151878 D Lock 4559920864 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/31f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6.lock [filelock.py:274]
py36: 151879 D got python info of /usr/bin/python3 from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/31f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6.json [virtualenv/app_data/via_disk_folder.py:135]
py36: 151880 D Attempting to release lock 4559920864 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/31f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6.lock [filelock.py:315]
py36: 151880 D Lock 4559920864 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/31f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6.lock [filelock.py:318]
py36: 151880 D Attempting to acquire lock 4559920336 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/20bf69066c0dd38d51965b69d5e1b6e387082e3198ba56e97997ac55f4e50ad0.lock [filelock.py:270]
py36: 151881 D Lock 4559920336 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/20bf69066c0dd38d51965b69d5e1b6e387082e3198ba56e97997ac55f4e50ad0.lock [filelock.py:274]
py36: 151881 D got python info of /Applications/Xcode.app/Contents/Developer/usr/bin/python3 from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/20bf69066c0dd38d51965b69d5e1b6e387082e3198ba56e97997ac55f4e50ad0.json [virtualenv/app_data/via_disk_folder.py:135]
py36: 151882 D Attempting to release lock 4559920336 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/20bf69066c0dd38d51965b69d5e1b6e387082e3198ba56e97997ac55f4e50ad0.lock [filelock.py:315]
py36: 151882 D Lock 4559920336 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/20bf69066c0dd38d51965b69d5e1b6e387082e3198ba56e97997ac55f4e50ad0.lock [filelock.py:318]
py36: 151882 I proposed PathPythonInfo(spec=CPython3.8.2.final.0-64, system=/Applications/Xcode.app/Contents/Developer/usr/bin/python3, exe=/usr/bin/python3, platform=darwin, version='3.8.2 (default, Dec 21 2020, 15:06:04) \n[Clang 12.0.0 (clang-1200.0.32.29)]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:69]
py36: 151882 D Attempting to acquire lock 4559918848 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/767d51c1340bd893661ea55ea3124f6de3c7a262a8b4abca0554b478b1e2ff90.lock [filelock.py:270]
py36: 151882 D Lock 4559918848 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/767d51c1340bd893661ea55ea3124f6de3c7a262a8b4abca0554b478b1e2ff90.lock [filelock.py:274]
py36: 151883 D got python info of /usr/bin/python from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/767d51c1340bd893661ea55ea3124f6de3c7a262a8b4abca0554b478b1e2ff90.json [virtualenv/app_data/via_disk_folder.py:135]
py36: 151883 D Attempting to release lock 4559918848 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/767d51c1340bd893661ea55ea3124f6de3c7a262a8b4abca0554b478b1e2ff90.lock [filelock.py:315]
py36: 151883 D Lock 4559918848 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/767d51c1340bd893661ea55ea3124f6de3c7a262a8b4abca0554b478b1e2ff90.lock [filelock.py:318]
py36: 151884 D Attempting to acquire lock 4559917648 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/545e036c4b32438aced1f6b3c8d38ca151d9c36189e05839cb0aa568fda70ddd.lock [filelock.py:270]
py36: 151884 D Lock 4559917648 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/545e036c4b32438aced1f6b3c8d38ca151d9c36189e05839cb0aa568fda70ddd.lock [filelock.py:274]
py36: 151884 D got python info of /System/Library/Frameworks/Python.framework/Versions/2.7/Resources/Python.app/Contents/MacOS/Python from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/545e036c4b32438aced1f6b3c8d38ca151d9c36189e05839cb0aa568fda70ddd.json [virtualenv/app_data/via_disk_folder.py:135]
py36: 151885 D Attempting to release lock 4559917648 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/545e036c4b32438aced1f6b3c8d38ca151d9c36189e05839cb0aa568fda70ddd.lock [filelock.py:315]
py36: 151885 D Lock 4559917648 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/545e036c4b32438aced1f6b3c8d38ca151d9c36189e05839cb0aa568fda70ddd.lock [filelock.py:318]
py36: 151885 I proposed PathPythonInfo(spec=CPython2.7.16.final.0-64, system=/System/Library/Frameworks/Python.framework/Versions/2.7/Resources/Python.app/Contents/MacOS/Python, exe=/usr/bin/python, platform=darwin, version='2.7.16 (default, Jun  5 2020, 22:59:21) \n[GCC 4.2.1 Compatible Apple LLVM 11.0.3 (clang-1103.0.29.20) (-macos10.15-objc-', encoding_fs_io=utf-8-None) [virtualenv/discovery/builtin.py:69]
py36: 151885 D discover PATH[7]=/bin [virtualenv/discovery/builtin.py:114]
py36: 151885 D discover PATH[8]=/usr/sbin [virtualenv/discovery/builtin.py:114]
py36: 151886 D discover PATH[9]=/sbin [virtualenv/discovery/builtin.py:114]
py36: 151886 D discover PATH[10]=/Library/TeX/texbin [virtualenv/discovery/builtin.py:114]
py36: 151886 D discover PATH[11]=/usr/local/MacGPG2/bin [virtualenv/discovery/builtin.py:114]
py36: 151886 D discover PATH[12]=/opt/X11/bin [virtualenv/discovery/builtin.py:114]
py36: 151887 D discover PATH[13]=/Applications/Wireshark.app/Contents/MacOS [virtualenv/discovery/builtin.py:114]
py36: 151887 W skipped environment because could not find python interpreter with spec(s): py36 [tox/session/cmd/run/single.py:44]
  py36: SKIP (151.63 seconds)
  py36-keyfs_sqlite: FAIL code -3 (0.01 seconds)
  py38: FAIL code -3 (0.01 seconds)
  pypy3: FAIL code -3 (0.01 seconds)
  evaluation failed :( (151.69 seconds)

From the output I suspect that either the Python discovery or the virtualenv creation is failing, but even though I provided lots of -v and use -o I don't see what is happening at the time it is looping.

I haven't tried with pdb yet to see what is going in, because the code base is new and I currently have no clue where to start.
@fschulze fschulze added the bug:normal affects many people or has quite an impact label Mar 1, 2021
@gaborbernat
Copy link
Member

tox uses virtualenv for python discovery, so this is likely a bug there 🤔

@gaborbernat
Copy link
Member

gaborbernat commented Mar 1, 2021 •
edited
Loading

What does virtualenv -p py36 venv --clear -vv does?

PS. If you want a more interactive support experience you can join our user chat at https://discord.gg/tox

@gaborbernat gaborbernat added this to the 4.0 milestone Mar 1, 2021
@gaborbernat gaborbernat added type:question ❔ a question about how things work or if something is a bug or a feature and removed bug:normal affects many people or has quite an impact labels Mar 1, 2021
@fschulze
Copy link
Author

fschulze commented Mar 1, 2021

Seems to be doing fine and was done quickly:

159 setup logging to DEBUG [DEBUG report:43]
249 find interpreter for spec PythonSpec(implementation=CPython, major=3, minor=6) [INFO builtin:62]
249 discover exe for PythonInfo(spec=CPython3.8.6.final.0-64, exe=/Users/fschulze/Development/devpi/devel/bin/python3.8, platform=darwin, version='3.8.6 (default, Feb 19 2021, 11:43:49) \n[Clang 12.0.0 (clang-1200.0.32.29)]', encoding_fs_io=utf-8-utf-8) in /Users/fschulze/.pyenv/versions/3.8.6 [DEBUG py_info:372]
250 filesystem is not case-sensitive [DEBUG info:28]
251 Attempting to acquire lock 4448791760 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a2aaea49ce029cf5dce8f285e23fbc02dd70f7cebf214a80b97ba262cecb011c.lock [DEBUG filelock:270]
252 Lock 4448791760 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a2aaea49ce029cf5dce8f285e23fbc02dd70f7cebf214a80b97ba262cecb011c.lock [INFO filelock:274]
253 got python info of /Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8 from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a2aaea49ce029cf5dce8f285e23fbc02dd70f7cebf214a80b97ba262cecb011c.json [DEBUG via_disk_folder:135]
254 Attempting to release lock 4448791760 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a2aaea49ce029cf5dce8f285e23fbc02dd70f7cebf214a80b97ba262cecb011c.lock [DEBUG filelock:315]
254 Lock 4448791760 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/a2aaea49ce029cf5dce8f285e23fbc02dd70f7cebf214a80b97ba262cecb011c.lock [INFO filelock:318]
254 proposed PythonInfo(spec=CPython3.8.6.final.0-64, system=/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8, exe=/Users/fschulze/Development/devpi/devel/bin/python3.8, platform=darwin, version='3.8.6 (default, Feb 19 2021, 11:43:49) \n[Clang 12.0.0 (clang-1200.0.32.29)]', encoding_fs_io=utf-8-utf-8) [INFO builtin:69]
255 discover PATH[0]=/Users/fschulze/Development/devpi/devel/bin [DEBUG builtin:114]
255 Attempting to acquire lock 4448789360 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/457b6a7d6339db1a957b59cf4fccc581ae942bf4ab5fd7e55f54f41f3b768f61.lock [DEBUG filelock:270]
256 Lock 4448789360 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/457b6a7d6339db1a957b59cf4fccc581ae942bf4ab5fd7e55f54f41f3b768f61.lock [INFO filelock:274]
256 got python info of /Users/fschulze/Development/devpi/devel/bin/python3 from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/457b6a7d6339db1a957b59cf4fccc581ae942bf4ab5fd7e55f54f41f3b768f61.json [DEBUG via_disk_folder:135]
257 Attempting to release lock 4448789360 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/457b6a7d6339db1a957b59cf4fccc581ae942bf4ab5fd7e55f54f41f3b768f61.lock [DEBUG filelock:315]
257 Lock 4448789360 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/457b6a7d6339db1a957b59cf4fccc581ae942bf4ab5fd7e55f54f41f3b768f61.lock [INFO filelock:318]
257 discover exe from cache /Users/fschulze/.pyenv/versions/3.8.6 - exact False: PythonInfo({'architecture': 64, 'base_exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'base_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'distutils_install': {'data': '', 'headers': 'include/python3.8/UNKNOWN', 'platlib': 'lib/python3.8/site-packages', 'purelib': 'lib/python3.8/site-packages', 'scripts': 'bin'}, 'exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'executable': '/Users/fschulze/Development/devpi/devel/bin/python3.8', 'file_system_encoding': 'utf-8', 'has_venv': True, 'implementation': 'CPython', 'max_size': 9223372036854775807, 'original_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'os': 'posix', 'path': ['/Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/discovery', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python38.zip', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/lib-dynload', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/site-packages'], 'platform': 'darwin', 'prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'real_prefix': None, 'stdout_encoding': 'utf-8', 'sysconfig': {'makefile_filename': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/config-3.8-darwin/Makefile'}, 'sysconfig_paths': {'data': '{base}', 'include': '{installed_base}/include/python{py_version_short}{abiflags}', 'platlib': '{platbase}/lib/python{py_version_short}/site-packages', 'platstdlib': '{platbase}/lib/python{py_version_short}', 'purelib': '{base}/lib/python{py_version_short}/site-packages', 'scripts': '{base}/bin', 'stdlib': '{installed_base}/lib/python{py_version_short}'}, 'sysconfig_vars': {'PYTHONFRAMEWORK': '', 'abiflags': '', 'base': '/Users/fschulze/.pyenv/versions/3.8.6', 'installed_base': '/Users/fschulze/.pyenv/versions/3.8.6', 'platbase': '/Users/fschulze/.pyenv/versions/3.8.6', 'py_version_short': '3.8'}, 'system_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'system_stdlib': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'system_stdlib_platform': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'version': '3.8.6 (default, Feb 19 2021, 11:43:49) \n[Clang 12.0.0 (clang-1200.0.32.29)]', 'version_info': VersionInfo(major=3, minor=8, micro=6, releaselevel='final', serial=0)}) [DEBUG py_info:370]
258 Attempting to acquire lock 4448701072 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9e337246b659eaf7a10894a6c8f01ec04125ae3a4befefc6b2a834ba394cc1a9.lock [DEBUG filelock:270]
258 Lock 4448701072 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9e337246b659eaf7a10894a6c8f01ec04125ae3a4befefc6b2a834ba394cc1a9.lock [INFO filelock:274]
259 got python info of /Users/fschulze/Development/devpi/devel/bin/python from /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9e337246b659eaf7a10894a6c8f01ec04125ae3a4befefc6b2a834ba394cc1a9.json [DEBUG via_disk_folder:135]
260 Attempting to release lock 4448701072 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9e337246b659eaf7a10894a6c8f01ec04125ae3a4befefc6b2a834ba394cc1a9.lock [DEBUG filelock:315]
260 Lock 4448701072 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/9e337246b659eaf7a10894a6c8f01ec04125ae3a4befefc6b2a834ba394cc1a9.lock [INFO filelock:318]
260 discover exe from cache /Users/fschulze/.pyenv/versions/3.8.6 - exact False: PythonInfo({'architecture': 64, 'base_exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'base_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'distutils_install': {'data': '', 'headers': 'include/python3.8/UNKNOWN', 'platlib': 'lib/python3.8/site-packages', 'purelib': 'lib/python3.8/site-packages', 'scripts': 'bin'}, 'exec_prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'executable': '/Users/fschulze/Development/devpi/devel/bin/python3', 'file_system_encoding': 'utf-8', 'has_venv': True, 'implementation': 'CPython', 'max_size': 9223372036854775807, 'original_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'os': 'posix', 'path': ['/Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/discovery', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python38.zip', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/lib-dynload', '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/site-packages'], 'platform': 'darwin', 'prefix': '/Users/fschulze/.pyenv/versions/3.8.6', 'real_prefix': None, 'stdout_encoding': 'utf-8', 'sysconfig': {'makefile_filename': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8/config-3.8-darwin/Makefile'}, 'sysconfig_paths': {'data': '{base}', 'include': '{installed_base}/include/python{py_version_short}{abiflags}', 'platlib': '{platbase}/lib/python{py_version_short}/site-packages', 'platstdlib': '{platbase}/lib/python{py_version_short}', 'purelib': '{base}/lib/python{py_version_short}/site-packages', 'scripts': '{base}/bin', 'stdlib': '{installed_base}/lib/python{py_version_short}'}, 'sysconfig_vars': {'PYTHONFRAMEWORK': '', 'abiflags': '', 'base': '/Users/fschulze/.pyenv/versions/3.8.6', 'installed_base': '/Users/fschulze/.pyenv/versions/3.8.6', 'platbase': '/Users/fschulze/.pyenv/versions/3.8.6', 'py_version_short': '3.8'}, 'system_executable': '/Users/fschulze/.pyenv/versions/3.8.6/bin/python3.8', 'system_stdlib': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'system_stdlib_platform': '/Users/fschulze/.pyenv/versions/3.8.6/lib/python3.8', 'version': '3.8.6 (default, Feb 19 2021, 11:43:49) \n[Clang 12.0.0 (clang-1200.0.32.29)]', 'version_info': VersionInfo(major=3, minor=8, micro=6, releaselevel='final', serial=0)}) [DEBUG py_info:370]
260 discover PATH[1]=/Users/fschulze/.cargo/bin [DEBUG builtin:114]
261 discover PATH[2]=/Users/fschulze/bin [DEBUG builtin:114]
262 Attempting to acquire lock 4448895520 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/fbc1892380c568224b2642e2dc075d155af408ee5e257b140040b2927bf7fc8e.lock [DEBUG filelock:270]
262 Lock 4448895520 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/fbc1892380c568224b2642e2dc075d155af408ee5e257b140040b2927bf7fc8e.lock [INFO filelock:274]
262 get interpreter info via cmd: /Users/fschulze/bin/python3.6 /Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/discovery/py_info.py [DEBUG cached_py_info:91]
483 wrote python info of /Users/fschulze/bin/python3.6 at /Users/fschulze/Library/Application Support/virtualenv/py_info/1/fbc1892380c568224b2642e2dc075d155af408ee5e257b140040b2927bf7fc8e.json [DEBUG via_disk_folder:161]
483 Attempting to release lock 4448895520 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/fbc1892380c568224b2642e2dc075d155af408ee5e257b140040b2927bf7fc8e.lock [DEBUG filelock:315]
484 Lock 4448895520 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/fbc1892380c568224b2642e2dc075d155af408ee5e257b140040b2927bf7fc8e.lock [INFO filelock:318]
484 Attempting to acquire lock 4448898208 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/1f5049fc27945c91e1ca11925a9796874ae630ecfe41f2bd3783d8d676d44a31.lock [DEBUG filelock:270]
484 Lock 4448898208 acquired on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/1f5049fc27945c91e1ca11925a9796874ae630ecfe41f2bd3783d8d676d44a31.lock [INFO filelock:274]
485 get interpreter info via cmd: /Users/fschulze/.pyenv/versions/3.6.12/bin/python3.6 /Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/discovery/py_info.py [DEBUG cached_py_info:91]
585 wrote python info of /Users/fschulze/.pyenv/versions/3.6.12/bin/python3.6 at /Users/fschulze/Library/Application Support/virtualenv/py_info/1/1f5049fc27945c91e1ca11925a9796874ae630ecfe41f2bd3783d8d676d44a31.json [DEBUG via_disk_folder:161]
585 Attempting to release lock 4448898208 on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/1f5049fc27945c91e1ca11925a9796874ae630ecfe41f2bd3783d8d676d44a31.lock [DEBUG filelock:315]
585 Lock 4448898208 released on /Users/fschulze/Library/Application Support/virtualenv/py_info/1/1f5049fc27945c91e1ca11925a9796874ae630ecfe41f2bd3783d8d676d44a31.lock [INFO filelock:318]
585 proposed PathPythonInfo(spec=CPython3.6.12.final.0-64, system=/Users/fschulze/.pyenv/versions/3.6.12/bin/python3.6, exe=/Users/fschulze/bin/python3.6, platform=darwin, version='3.6.12 (default, Feb 19 2021, 11:51:55) \n[GCC Apple LLVM 12.0.0 (clang-1200.0.32.29)]', encoding_fs_io=utf-8-UTF-8) [INFO builtin:69]
586 accepted PathPythonInfo(spec=CPython3.6.12.final.0-64, system=/Users/fschulze/.pyenv/versions/3.6.12/bin/python3.6, exe=/Users/fschulze/bin/python3.6, platform=darwin, version='3.6.12 (default, Feb 19 2021, 11:51:55) \n[GCC Apple LLVM 12.0.0 (clang-1200.0.32.29)]', encoding_fs_io=utf-8-UTF-8) [DEBUG builtin:71]
658 create virtual environment via CPython3Posix(dest=/Users/fschulze/Development/devpi/devel/venv, clear=True, no_vcs_ignore=False, global=False) [INFO session:52]
658 create folder /Users/fschulze/Development/devpi/devel/venv/bin [DEBUG _sync:25]
658 create folder /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages [DEBUG _sync:25]
659 write /Users/fschulze/Development/devpi/devel/venv/pyvenv.cfg [DEBUG pyenv_cfg:34]
659 	home = /Users/fschulze/.pyenv/versions/3.6.12 [DEBUG pyenv_cfg:38]
659 	implementation = CPython [DEBUG pyenv_cfg:38]
659 	version_info = 3.6.12.final.0 [DEBUG pyenv_cfg:38]
659 	virtualenv = 20.4.2 [DEBUG pyenv_cfg:38]
659 	include-system-site-packages = false [DEBUG pyenv_cfg:38]
659 	base-prefix = /Users/fschulze/.pyenv/versions/3.6.12 [DEBUG pyenv_cfg:38]
659 	base-exec-prefix = /Users/fschulze/.pyenv/versions/3.6.12 [DEBUG pyenv_cfg:38]
659 	base-executable = /Users/fschulze/.pyenv/versions/3.6.12/bin/python3.6 [DEBUG pyenv_cfg:38]
660 symlink /Users/fschulze/.pyenv/versions/3.6.12/bin/python3.6 to /Users/fschulze/Development/devpi/devel/venv/bin/python [DEBUG _sync:44]
661 create virtualenv import hook file /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/_virtualenv.pth [DEBUG api:95]
661 create /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/_virtualenv.py [DEBUG api:98]
662 ============================== target debug ============================== [DEBUG session:54]
663 debug via /Users/fschulze/Development/devpi/devel/venv/bin/python /Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/create/debug.py [DEBUG creator:224]
662 {
  "sys": {
    "executable": "/Users/fschulze/Development/devpi/devel/venv/bin/python",
    "_base_executable": null,
    "prefix": "/Users/fschulze/Development/devpi/devel/venv",
    "base_prefix": "/Users/fschulze/.pyenv/versions/3.6.12",
    "real_prefix": null,
    "exec_prefix": "/Users/fschulze/Development/devpi/devel/venv",
    "base_exec_prefix": "/Users/fschulze/.pyenv/versions/3.6.12",
    "path": [
      "/Users/fschulze/.pyenv/versions/3.6.12/lib/python36.zip",
      "/Users/fschulze/.pyenv/versions/3.6.12/lib/python3.6",
      "/Users/fschulze/.pyenv/versions/3.6.12/lib/python3.6/lib-dynload",
      "/Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages"
    ],
    "meta_path": [
      "<class '_virtualenv._Finder'>",
      "<class '_frozen_importlib.BuiltinImporter'>",
      "<class '_frozen_importlib.FrozenImporter'>",
      "<class '_frozen_importlib_external.PathFinder'>"
    ],
    "fs_encoding": "utf-8",
    "io_encoding": "UTF-8"
  },
  "version": "3.6.12 (default, Feb 19 2021, 11:51:55) \n[GCC Apple LLVM 12.0.0 (clang-1200.0.32.29)]",
  "makefile_filename": "/Users/fschulze/.pyenv/versions/3.6.12/lib/python3.6/config-3.6m-darwin/Makefile",
  "os": "<module 'os' from '/Users/fschulze/.pyenv/versions/3.6.12/lib/python3.6/os.py'>",
  "site": "<module 'site' from '/Users/fschulze/.pyenv/versions/3.6.12/lib/python3.6/site.py'>",
  "datetime": "<module 'datetime' from '/Users/fschulze/.pyenv/versions/3.6.12/lib/python3.6/datetime.py'>",
  "math": "<module 'math' from '/Users/fschulze/.pyenv/versions/3.6.12/lib/python3.6/lib-dynload/math.cpython-36m-darwin.so'>",
  "json": "<module 'json' from '/Users/fschulze/.pyenv/versions/3.6.12/lib/python3.6/json/__init__.py'>"
} [DEBUG session:55]
724 add seed packages via FromAppData(download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/Users/fschulze/Library/Application Support/virtualenv) [INFO session:59]
728 got embed update of distribution pip from /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/embed/1/pip.json [DEBUG via_disk_folder:135]
732 got embed update of distribution wheel from /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/embed/1/wheel.json [DEBUG via_disk_folder:135]
733 got embed update of distribution setuptools from /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/embed/1/setuptools.json [DEBUG via_disk_folder:135]
735 got embed update of distribution wheel from /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/embed/1/wheel.json [DEBUG via_disk_folder:135]
736 got embed update of distribution pip from /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/embed/1/pip.json [DEBUG via_disk_folder:135]
736 got embed update of distribution setuptools from /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/embed/1/setuptools.json [DEBUG via_disk_folder:135]
737 using periodically updated wheel /Users/fschulze/Library/Application Support/virtualenv/wheel/house/setuptools-53.0.0-py3-none-any.whl [DEBUG periodic_update:53]
737 install wheel from wheel /Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/seed/wheels/embed/wheel-0.36.2-py2.py3-none-any.whl via CopyPipInstall [DEBUG via_app_data:49]
737 install pip from wheel /Users/fschulze/Development/devpi/devel/lib/python3.8/site-packages/virtualenv/seed/wheels/embed/pip-21.0.1-py3-none-any.whl via CopyPipInstall [DEBUG via_app_data:49]
737 install setuptools from wheel /Users/fschulze/Library/Application Support/virtualenv/wheel/house/setuptools-53.0.0-py3-none-any.whl via CopyPipInstall [DEBUG via_app_data:49]
738 Attempting to acquire lock 4452372592 on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/pip-21.0.1-py3-none-any.lock [DEBUG filelock:270]
738 Attempting to acquire lock 4452372928 on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/wheel-0.36.2-py2.py3-none-any.lock [DEBUG filelock:270]
738 Attempting to acquire lock 4452373168 on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/setuptools-53.0.0-py3-none-any.lock [DEBUG filelock:270]
739 Lock 4452372592 acquired on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/pip-21.0.1-py3-none-any.lock [INFO filelock:274]
739 Lock 4452373168 acquired on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/setuptools-53.0.0-py3-none-any.lock [INFO filelock:274]
739 build install image for setuptools-53.0.0-py3-none-any.whl to /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/setuptools-53.0.0-py3-none-any [DEBUG base:52]
739 Lock 4452372928 acquired on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/wheel-0.36.2-py2.py3-none-any.lock [INFO filelock:274]
739 Attempting to release lock 4452372592 on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/pip-21.0.1-py3-none-any.lock [DEBUG filelock:315]
739 Lock 4452372592 released on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/pip-21.0.1-py3-none-any.lock [INFO filelock:318]
740 Attempting to release lock 4452372928 on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/wheel-0.36.2-py2.py3-none-any.lock [DEBUG filelock:315]
740 Lock 4452372928 released on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/wheel-0.36.2-py2.py3-none-any.lock [INFO filelock:318]
740 copy directory /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/pip-21.0.1-py3-none-any/pip-21.0.1.dist-info to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/pip-21.0.1.dist-info [DEBUG _sync:52]
743 copy directory /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/wheel-0.36.2-py2.py3-none-any/wheel-0.36.2.dist-info to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/wheel-0.36.2.dist-info [DEBUG _sync:52]
755 copy directory /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/pip-21.0.1-py3-none-any/pip to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/pip [DEBUG _sync:52]
755 copy directory /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/wheel-0.36.2-py2.py3-none-any/wheel to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/wheel [DEBUG _sync:52]
783 copy /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/wheel-0.36.2-py2.py3-none-any/wheel-0.36.2.virtualenv to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/wheel-0.36.2.virtualenv [DEBUG _sync:52]
790 generated console scripts wheel3 wheel-3.6 wheel3.6 wheel [DEBUG base:48]
864 Attempting to release lock 4452373168 on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/setuptools-53.0.0-py3-none-any.lock [DEBUG filelock:315]
864 Lock 4452373168 released on /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/setuptools-53.0.0-py3-none-any.lock [INFO filelock:318]
865 copy /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/setuptools-53.0.0-py3-none-any/setuptools-53.0.0.virtualenv to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/setuptools-53.0.0.virtualenv [DEBUG _sync:52]
865 copy /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/setuptools-53.0.0-py3-none-any/distutils-precedence.pth to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/distutils-precedence.pth [DEBUG _sync:52]
867 copy directory /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/setuptools-53.0.0-py3-none-any/setuptools to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/setuptools [DEBUG _sync:52]
950 copy directory /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/setuptools-53.0.0-py3-none-any/pkg_resources to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/pkg_resources [DEBUG _sync:52]
963 copy directory /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/setuptools-53.0.0-py3-none-any/_distutils_hack to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/_distutils_hack [DEBUG _sync:52]
964 copy directory /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/setuptools-53.0.0-py3-none-any/setuptools-53.0.0.dist-info to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/setuptools-53.0.0.dist-info [DEBUG _sync:52]
970 generated console scripts  [DEBUG base:48]
1160 copy /Users/fschulze/Library/Application Support/virtualenv/wheel/3.6/image/1/CopyPipInstall/pip-21.0.1-py3-none-any/pip-21.0.1.virtualenv to /Users/fschulze/Development/devpi/devel/venv/lib/python3.6/site-packages/pip-21.0.1.virtualenv [DEBUG _sync:52]
1163 generated console scripts pip pip3.6 pip3 pip-3.6 [DEBUG base:48]
1163 add activators for Bash, CShell, Fish, PowerShell, Python, Xonsh [INFO session:64]
1165 write /Users/fschulze/Development/devpi/devel/venv/pyvenv.cfg [DEBUG pyenv_cfg:34]
1165 	home = /Users/fschulze/.pyenv/versions/3.6.12 [DEBUG pyenv_cfg:38]
1166 	implementation = CPython [DEBUG pyenv_cfg:38]
1166 	version_info = 3.6.12.final.0 [DEBUG pyenv_cfg:38]
1166 	virtualenv = 20.4.2 [DEBUG pyenv_cfg:38]
1166 	include-system-site-packages = false [DEBUG pyenv_cfg:38]
1166 	base-prefix = /Users/fschulze/.pyenv/versions/3.6.12 [DEBUG pyenv_cfg:38]
1166 	base-exec-prefix = /Users/fschulze/.pyenv/versions/3.6.12 [DEBUG pyenv_cfg:38]
1166 	base-executable = /Users/fschulze/.pyenv/versions/3.6.12/bin/python3.6 [DEBUG pyenv_cfg:38]
1166 created virtual environment CPython3.6.12.final.0-64 in 1011ms
  creator CPython3Posix(dest=/Users/fschulze/Development/devpi/devel/venv, clear=True, no_vcs_ignore=False, global=False)
  seeder FromAppData(download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/Users/fschulze/Library/Application Support/virtualenv)
    added seed packages: pip==21.0.1, setuptools==53.0.0, wheel==0.36.2
  activators BashActivator,CShellActivator,FishActivator,PowerShellActivator,PythonActivator,XonshActivator [WARNING __main__:19]

@fschulze
Copy link
Author

fschulze commented Mar 1, 2021

Maybe you can tell from the original output where I could best start with a pdb? Or maybe I can somehow enable more verbose output? It seems like the output of the command that is running is captured.

@gaborbernat
Copy link
Member

If you don't find it by tomorrow, just reach out to me, so we can set up something 👍🏻

@gaborbernat
Copy link
Member

@fschulze ping on this, can you still replicate with the latest version?

@gaborbernat gaborbernat added the needs:reproducer ideally a failing test marked as xfail. If that is not possible exact instructions to reproduce label Aug 2, 2021
@gaborbernat gaborbernat removed this from the 4.0 milestone Aug 2, 2021
@fschulze
Copy link
Author

fschulze commented Aug 2, 2021

I did pip --pre -U tox and got tox 4.0.0a7 which still has the same behaviour.

@gaborbernat gaborbernat self-assigned this Aug 2, 2021
@gaborbernat
Copy link
Member

@fschulze seems I can't reproduce this. Can we setup something when you can share your screen and we can debug? Thanks!

@fschulze
Copy link
Author

fschulze commented Aug 6, 2021

We found the cause. I use pyenv and the PYENV_VERSION environment variable needs to be passed by tox. There are other issues with virtualenv vs pyenv, but my use case isn't affected by those. The endless loop is happening in pyenv. Unsetting PYENV_VERSION and just calling python3.6 (or any other) doesn't exit. I will look into that and open an issue there.

@fschulze fschulze closed this as completed Aug 6, 2021
@jugmac00
Copy link
Member

jugmac00 commented Aug 6, 2021

Thanks for reporting back!

@fschulze
Copy link
Author

A quick follow up. I didn't file a report at pyenv, because if you use it as intended this can never happen.

I created symlinks to the pyenv shim into my PATH which made them always accessible, even if PYENV_VERSION isn't set. With regular pyenv use the PATH is managed by pyenv and only the versions currently set are accessible. If PYENV_VERSION is unset, then no python* would be found and thus there can't be an endless loop.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@gaborbernat gaborbernat

Labels
needs:reproducer ideally a failing test marked as xfail. If that is not possible exact instructions to reproduce type:question ❔ a question about how things work or if something is a bug or a feature
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@fschulze @gaborbernat @jugmac00