autopep8 formatting , creating a new file/folder becomes hang and even debugger becomes slow on mac m1

[grimmerk]

Environment data

Hardware: MacBook Air (M1, 2020)

• VS Code version: 1.60.1 (Universal), 1.59.1 (Universal)
• Extension version (available under the Extensions sidebar): v2021.9.1218897484, v2021.9.1230869389, v2021.10.1239554536-dev
• OS and version: macOS Big Sur 11.5.2, 11.6
• Python version (& distribution if applicable, e.g. Anaconda): 3.9.2
• Type of virtual environment used (N/A | venv | virtualenv | conda | ...): venv
• Relevant/affected Python packages and their versions: autopep8 1.5.7
• Relevant/affected Python-related VS Code extensions and their versions: None
• Value of the python.languageServer setting: Pylance v2021.9.1

[NOTE: If you suspect that your issue is related to the Microsoft Python Language Server (python.languageServer: 'Microsoft'), please download our new language server Pylance from the VS Code marketplace to see if that fixes your issue]

Expected behaviour

Every action such as "creating a folder" and format the code using autopep8 should be smoothy as before.

Actual behaviour

Either creating a folder, a file or format the code using autopep8, they all hang a very long long time.

• Running 'File Create' participants... hangs
• Saving 'xxx.py': Running 'Python' Formatter (configure). ... hangs

Steps to reproduce:

[NOTE: Self-contained, minimal reproducing code samples are extremely helpful and will expedite addressing your issue]

formatter case:

1. Fresh install a new VSCode.
2. Install Python extension.
3. Install autopep8 package.
4. Enable format on save.
5. Set Python formatting provider as autopep8
6. Create an empty python file, named a.py
7. Save
8. Hang on Saving 'a.py': Running 'Python' Formatter (configure).

Creating a new file/folder case:
Just click new file or new folder on VSCode left panel.

Logs

Output for Python in the Output panel (View→Output, change the drop-down the upper-right of the Output panel to Python)

XXX

No logs

[kimadeline]

Hi @grimmer0125, thank you for reaching out.

Does the issue also happen if. you use any 2021.8 versions of the extension, or only with 2021.9?

No logs

If the extension activated then there should be logs in the Python output panel, could you copy them here please?

Thanks!

[grimmerk]

@kimadeline This issue does not happen in v2021.9.1191016588 and two or three old versions I tried, like August versions and v2021.7.1060902895. Actually, other Python formatters have this issue, too. And I tried macOS 11.6 this morning and got the same result. Extension v2021.10.1239554536-dev happens, too.

[kimadeline]

Please copy your logs. Additionally, do you have telemetry or A/B experiments disabled?

[grimmerk]

@kimadeline
I used the default Setting and they both were enabled before. When I disable A/B experiments and this issue does not happen !!! Thank you.

The only shown log is OUTPUT of log(Window)

[2021-09-17 00:17:02.006] [renderer3] [warning] UNRESPONSIVE extension host, 'ms-python.python' took 82% of 7428.417ms, saved PROFILE here: 'file:///var/folders/my/7d72glhs38v7fhhxgfvjdmx40000gr/T/exthost-6e5503.cpuprofile' [{"id":"gc","total":1305456,"percentage":18},{"id":"ms-python.python","total":6076454,"percentage":82},{"id":"program","total":38090,"percentage":1},{"id":"self","total":8334,"percentage":0}]
[2021-09-17 00:17:38.819] [renderer3] [warning] UNRESPONSIVE extension host, 'ms-python.python' took 80% of 6262.166ms, saved PROFILE here: 'file:///var/folders/my/7d72glhs38v7fhhxgfvjdmx40000gr/T/exthost-019a0f.cpuprofile' [{"id":"gc","total":1228870,"percentage":20},{"id":"ms-python.python","total":4997833,"percentage":80},{"id":"program","total":28925,"percentage":0},{"id":"self","total":6372,"percentage":0}]
[2021-09-17 00:18:17.882] [renderer3] [warning] UNRESPONSIVE extension host, 'ms-python.python' took 78% of 6084.167ms, saved PROFILE here: 'file:///var/folders/my/7d72glhs38v7fhhxgfvjdmx40000gr/T/exthost-7eb270.cpuprofile' [{"id":"gc","total":1291714,"percentage":21},{"id":"ms-python.python","total":4753825,"percentage":78},{"id":"program","total":31461,"percentage":1},{"id":"self","total":6958,"percentage":0}]
[2021-09-17 00:18:59.970] [renderer3] [warning] UNRESPONSIVE extension host, 'ms-python.python' took 75% of 5181.25ms, saved PROFILE here: 'file:///var/folders/my/7d72glhs38v7fhhxgfvjdmx40000gr/T/exthost-0d5644.cpuprofile' [{"id":"gc","total":1232388,"percentage":24},{"id":"ms-python.python","total":3891697,"percentage":75},{"id":"ms-toolsai.jupyter","total":6875,"percentage":0},{"id":"program","total":28372,"percentage":1},{"id":"self","total":21876,"percentage":0}]
[2021-09-17 00:19:55.459] [renderer3] [warning] UNRESPONSIVE extension host, 'ms-python.python' took 76% of 6102.417ms, saved PROFILE here: 'file:///var/folders/my/7d72glhs38v7fhhxgfvjdmx40000gr/T/exthost-823e17.cpuprofile' [{"id":"gc","total":1430868,"percentage":23},{"id":"ms-python.python","total":4620840,"percentage":76},{"id":"ms-toolsai.jupyter","total":125,"percentage":0},{"id":"program","total":41789,"percentage":1},{"id":"self","total":8711,"percentage":0}]
[2021-09-17 00:21:07.164] [renderer3] [warning] UNRESPONSIVE extension host, 'ms-python.python' took 78% of 5864.041ms, saved PROFILE here: 'file:///var/folders/my/7d72glhs38v7fhhxgfvjdmx40000gr/T/exthost-53cc84.cpuprofile' [{"id":"gc","total":1254209,"percentage":21},{"id":"ms-python.python","total":4577583,"percentage":78},{"id":"program","total":27956,"percentage":0},{"id":"self","total":4085,"percentage":0}]
[2021-09-17 00:22:26.140] [renderer3] [warning] UNRESPONSIVE extension host, 'ms-python.python' took 82% of 7464.292ms, saved PROFILE here: 'file:///var/folders/my/7d72glhs38v7fhhxgfvjdmx40000gr/T/exthost-a223c3.cpuprofile' [{"id":"gc","total":1223261,"percentage":16},{"id":"ms-python.python","total":6132069,"percentage":82},{"id":"ms-toolsai.jupyter","total":4791,"percentage":0},{"id":"program","total":28502,"percentage":0},{"id":"self","total":73084,"percentage":1},{"id":"vscode.git","total":2376,"percentage":0}]

The first 4 PROFILE files zip:

exthost-cpuprofile.zip

[kimadeline]

Glad to hear you found a workaround for now!

For future reference, if the Python extension was activated please copy the logs of the "Python" output by selecting it from the dropdown:

[grimmerk]

Got it. The below log is printed when I open a new window

使用者屬於 "pythonaa" 實驗性群組
使用者屬於 "pythonJediLSP" 實驗性群組
使用者屬於 "pythonDiscoveryModuleWithoutWatcher" 實驗性群組
使用者屬於 "pythonTensorboardExperiment" 實驗性群組
使用者屬於 "pythonSurveyNotification" 實驗性群組
使用者屬於 "PythonPyTorchProfiler" 實驗性群組
使用者屬於 "pythonDeprecatePythonPath" 實驗性群組
使用者屬於 "pythonSortEnvs" 實驗性群組
使用者屬於 "pythonRunFailedTestsButtonDisplayedcf" 實驗性群組
使用者屬於 "pythonRefreshTestsButtonDisplayedcf" 實驗性群組
使用者屬於 "pythonRememberDebugConfig" 實驗性群組
Error 2021-09-17 01:03:31: Reading directory to watch failed [Error: ENOENT: no such file or directory, scandir 'grimmer create poetry:/Users/grimmer/git'
	at Object.readdirSync (fs.js:1021:3)
	at Object.e.readdirSync (electron/js2c/asar_bundle.js:5:10478)
	at /Users/grimmer/.vscode/extensions/ms-python.python-2021.9.1230869389/out/client/extension.js:9:269803
	at /Users/grimmer/.vscode/extensions/ms-python.python-2021.9.1230869389/out/client/extension.js:9:269954
	at Array.map (<anonymous>)
	at h.initWatchers (/Users/grimmer/.vscode/extensions/ms-python.python-2021.9.1230869389/out/client/extension.js:9:269749)
	at async h.ensureWatchersReady (/Users/grimmer/.vscode/extensions/ms-python.python-2021.9.1230869389/out/client/extension.js:37:4319)] {
  errno: -2,
  syscall: 'scandir',
  code: 'ENOENT',
  path: 'grimmer create poetry:/Users/grimmer/git'
}
Python interpreter path: /opt/homebrew/bin/python3
Starting Pylance language server.
Error 2021-09-17 01:03:34: Failed to check if file needs to be fixed [EntryNotFound (FileSystemError): Unable to read file '/Users/grimmer/git/test_python_ext/.vscode/settings.json' (Error: Unable to resolve non-existing file '/Users/grimmer/git/test_python_ext/.vscode/settings.json')
	at _handleError (/Applications/Visual Studio Code.app/Contents/Resources/app/out/vs/workbench/services/extensions/node/extensionHostProcess.js:94:160087)
	at runMicrotasks (<anonymous>)
	at processTicksAndRejections (internal/process/task_queues.js:93:5)
	at async y.readText (/Users/grimmer/.vscode/extensions/ms-python.python-2021.9.1230869389/out/client/extension.js:9:314351)
	at async p.doesFileNeedToBeFixed (/Users/grimmer/.vscode/extensions/ms-python.python-2021.9.1230869389/out/client/extension.js:59:823969)
	at async /Users/grimmer/.vscode/extensions/ms-python.python-2021.9.1230869389/out/client/extension.js:59:823096
	at async Promise.all (index 1)
	at async p.getFilesToBeFixed (/Users/grimmer/.vscode/extensions/ms-python.python-2021.9.1230869389/out/client/extension.js:59:823042)
	at async p.updateTestSettings (/Users/grimmer/.vscode/extensions/ms-python.python-2021.9.1230869389/out/client/extension.js:59:822669)] {
  code: 'FileNotFound'
}

When I trigger saving this very simple file (just one line print("")), after 1~2 min hang, the below is printed and after more seconds, the formatting is finished.

> /opt/homebrew/bin/python3 -m autopep8 --diff ~/git/test_python_ext/test.py.d059eebd4e62c300399f17862af86969.tmp
cwd: ~/git/test_python_ext
> /opt/homebrew/bin/python3 -m autopep8 --diff ~/git/test_python_ext/test.py.d059eebd4e62c300399f17862af86969.tmp
cwd: ~/git/test_python_ext
> conda --version

The folder is very simple, one test.py and one pyproject.toml. And I know the keypoint to reproduce this issue. If I do not use poetry init to generate pyproject.toml, there will be no hang behavior.