Skip to content
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.

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

Slow cell execution and laggy UI over remote #13518

Closed
jrich100 opened this issue May 17, 2023 · 10 comments
Closed

Slow cell execution and laggy UI over remote #13518

jrich100 opened this issue May 17, 2023 · 10 comments
Assignees
Labels
bug Issue identified by VS Code Team member as probable bug info-needed Issue requires more information from poster notebook-execution Kernels issues (start/restart/switch/execution, install ipykernel) perf Performance issues user-setup Issues caused by incorrectly configured python environments, packages, or the like

Comments

@jrich100
Copy link

jrich100 commented May 17, 2023

Hi,

First off I just want to express some appreciation for this product and all of the updates being made, especially to the notebook functionality, which is far better than jupyterlab in my opinion!

However, I have found that performance and latency degrades very quickly as the size of notebooks grow. Specifically, this seems to occur on notebooks with plots and images in the cell metadata. I have seen these types of issues with notebooks around 800K in size, which in my opinion is not very large. This leads to latency issue like extremely long cell execution, a long time before the cell even starts to execute, and general lag in the UI as it appears that the remote extension host is unusable for long periods of time.

I am also aware that this issue has been raised elsewhere, like in this thread: microsoft/vscode#172345

But there seems to be no transparency around this particular issue, and it has gotten to the point where VS Code is practically unusable. Could you provide some more information related this problem?

I am happy to provide any additional information and reproduce any scenario.

Environment data

  • VS Code version: 1.78.2
  • Jupyter Extension version (available under the Extensions sidebar): v2023.4.1001091014
  • Python Extension version (available under the Extensions sidebar): v2023.8.0
  • OS (Windows | Mac | Linux distro) and version: Ubuntu
  • Python and/or Anaconda version: 3.9.16
  • Type of virtual environment used (N/A | venv | virtualenv | conda | ...): venv
  • Jupyter server running: Local And Remote

Expected behaviour

Notebooks are responsive and usable.

Actual behaviour

image

Steps to reproduce:

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

  1. Create a notebook with a handful of matplotlib plots.

  2. execute a cell

Logs

Output for Jupyter in the Output panel (ViewOutput, change the drop-down the upper-right of the Output panel to Jupyter)

code-server (1.78.2, <remote-host>, server-distro)
Jupyter Extension Version: 2023.4.1001091014.
Python Extension Version: 2023.8.0.
Platform: linux (x64).
Workspace folder ~/sb-package-analysis, Home = /home/jovyan
14:19:38.980 [info] ZMQ loaded without the fallback mechanism.
User belongs to experiment group 'jupyterEnhancedDataViewer'
User belongs to experiment group 'jupyterTest'
14:19:39.665 [debug] Get Custom Env Variables, Class name = wc (started execution), Arg 1: undefined, Arg 2: "RunPythonCode"
14:19:39.667 [info] Start refreshing Kernel Picker (1684333179667)
14:19:39.693 [info] Not using Pylance
14:19:39.787 [debug] Get Custom Env Variables, Class name = wc, completed in 178ms, has a truthy return value, Arg 1: undefined, Arg 2: "RunPythonCode"
14:19:39.787 [debug] Jupyter Paths /kernels: 
14:19:39.799 [debug] Kernel Spec Root Paths, /usr/share/jupyter/kernels, /usr/local/share/jupyter/kernels, ~/.local/share/jupyter/kernels
14:19:39.807 [debug] Getting activated env variables, Class name = Is (started execution), Arg 1: "~/sb-package-analysis", Arg 2: <Uri:/usr/local/share/awb/venv/bin/python>, Arg 3: undefined
14:19:39.807 [debug] Get Custom Env Variables, Class name = wc (started execution), Arg 1: "~/sb-package-analysis", Arg 2: "RunPythonCode"
14:19:39.809 [debug] Get Custom Env Variables, Class name = wc, completed in 2ms, has a truthy return value, Arg 1: "~/sb-package-analysis", Arg 2: "RunPythonCode"
14:19:39.889 [debug] Preferred Remote kernel for ~/sb-package-analysis/test2.ipynb is 1ded2003-4fc7-4d8c-9c10-f0ff8633f142
14:19:39.915 [debug] Not Searching for kernels as path does not exist, ~/.local/share/jupyter/kernels
14:19:39.916 [debug] Not Searching for kernels as path does not exist, /usr/share/jupyter/kernels
14:19:39.931 [info] Setting Jupyter Server URI to remote: http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214?token%3D2235b5d629534c7abe166612855f9700
14:19:40.106 [debug] Loading kernelspec from /usr/local/share/jupyter/kernels/ir/kernel.json for 
14:19:40.106 [debug] Loading kernelspec from /usr/local/share/jupyter/kernels/python3/kernel.json for 
14:19:40.171 [debug] Conda file returned by Python Extension is conda
14:19:40.180 [debug] Disposing session manager
14:19:40.180 [debug] SessionManager - dispose contents manager
14:19:40.180 [debug] ShutdownSessionAndConnection - dispose session manager
14:19:40.181 [debug] Finished disposing jupyter session manager
14:19:40.479 [debug] API called from ms-toolsai.vscode-jupyter-powertoys
14:19:40.774 [debug] Full interpreter list after refreshing (total 1ms, resolve 1ms, refresh 0ms) is length: 6, /bin/python3:undefined::/bin/python3:/bin/python3, /usr/bin/python3:undefined::/usr/bin/python3:/usr/bin/python3, /usr/local/bin/python3:undefined::/usr/local/bin/python3:/usr/local/bin/python3, /usr/local/share/awb/venv/bin/python:venv:Venv:/usr/local/share/awb/venv/bin/python:/usr/local/share/awb/venv/bin/python, /bin/python2:undefined::/bin/python2:/bin/python2, /usr/bin/python2:undefined::/usr/bin/python2:/usr/bin/python2
14:19:40.779 [debug] refreshData after detecting changes to interpreters
14:19:40.780 [debug] Listing Python & non-Python kernels for Interpreter /usr/local/share/awb/venv/bin/python
14:19:40.780 [debug] Listing Python & non-Python kernels for Interpreter /bin/python3
14:19:40.781 [debug] Finding Global Python KernelSpecs
14:19:40.784 [debug] refreshData after detecting changes to interpreters
14:19:40.784 [debug] Listing Python & non-Python kernels for Interpreter /usr/bin/python3
14:19:40.785 [debug] refreshData after detecting changes to interpreters
14:19:40.785 [debug] Listing Python & non-Python kernels for Interpreter /usr/local/bin/python3
14:19:40.786 [debug] refreshData after detecting changes to interpreters
14:19:40.786 [debug] Listing Python & non-Python kernels for Interpreter /bin/python2
14:19:40.787 [debug] refreshData after detecting changes to interpreters
14:19:40.787 [debug] Listing Python & non-Python kernels for Interpreter /usr/bin/python2
14:19:40.788 [debug] refreshData after detecting changes to interpreters
14:19:40.789 [debug] Full interpreter list is length: 6, /usr/local/share/awb/venv/bin/python:venv:Venv:/usr/local/share/awb/venv/bin/python, /bin/python3::Unknown:/bin/python3, /usr/bin/python3::Unknown:/usr/bin/python3, /usr/local/bin/python3::Unknown:/usr/local/bin/python3, /bin/python2::Unknown:/bin/python2, /usr/bin/python2::Unknown:/usr/bin/python2
14:19:40.789 [debug] Refreshed Environments
14:19:40.790 [debug] Not Searching for kernels as path does not exist, /usr/local/share/awb/venv/share/jupyter/kernels
14:19:40.790 [debug] Kernel Specs found in interpreter /usr/local/share/awb/venv/bin/python are []
14:19:40.792 [debug] Kernel for interpreter /usr/local/share/awb/venv/bin/python is .jvsc74a57bd0dad53270699e48e90e28291c73c830530df1c536d0cf11094b78e7dd7726c0ba./usr/local/share/awb/venv/python./usr/local/share/awb/venv/python.-m#ipykernel_launcher
14:19:40.792 [debug] Kernels for interpreter /usr/local/share/awb/venv/bin/python are .jvsc74a57bd0dad53270699e48e90e28291c73c830530df1c536d0cf11094b78e7dd7726c0ba./usr/local/share/awb/venv/python./usr/local/share/awb/venv/python.-m#ipykernel_launcher
14:19:40.795 [debug] Kernel for interpreter /bin/python3 is .jvsc74a57bd0916dbcbb3f70747c44a77c7bcd40155683ae19c65e1c03b4aa3499c5328201f1./bin/python3./bin/python3.-m#ipykernel_launcher
14:19:40.795 [debug] Kernels for interpreter /bin/python3 are .jvsc74a57bd0916dbcbb3f70747c44a77c7bcd40155683ae19c65e1c03b4aa3499c5328201f1./bin/python3./bin/python3.-m#ipykernel_launcher
14:19:40.798 [debug] Kernel for interpreter /usr/bin/python3 is .jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher
14:19:40.798 [debug] Kernels for interpreter /usr/bin/python3 are .jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher
14:19:40.801 [debug] Kernel for interpreter /usr/local/bin/python3 is .jvsc74a57bd0aee8b7b246df8f9039afb4144a1f6fd8d2ca17a180786b69acc140d282b71a49./usr/local/bin/python3./usr/local/bin/python3.-m#ipykernel_launcher
14:19:40.801 [debug] Kernels for interpreter /usr/local/bin/python3 are .jvsc74a57bd0aee8b7b246df8f9039afb4144a1f6fd8d2ca17a180786b69acc140d282b71a49./usr/local/bin/python3./usr/local/bin/python3.-m#ipykernel_launcher
14:19:40.804 [debug] Kernel for interpreter /bin/python2 is .jvsc74a57bd0e55666fbbf217aa3df372b978577f47b6009e2f78e2ec76a584f49cd54a1e62c./bin/python2./bin/python2.-m#ipykernel_launcher
14:19:40.804 [debug] Kernels for interpreter /bin/python2 are .jvsc74a57bd0e55666fbbf217aa3df372b978577f47b6009e2f78e2ec76a584f49cd54a1e62c./bin/python2./bin/python2.-m#ipykernel_launcher
14:19:40.806 [debug] Kernel for interpreter /usr/bin/python2 is .jvsc74a57bd0f92b2924b84ff19c1c3dc485f7644d4486f64738191026bf8e6de303969141b5./usr/bin/python2./usr/bin/python2.-m#ipykernel_launcher
14:19:40.806 [debug] Kernels for interpreter /usr/bin/python2 are .jvsc74a57bd0f92b2924b84ff19c1c3dc485f7644d4486f64738191026bf8e6de303969141b5./usr/bin/python2./usr/bin/python2.-m#ipykernel_launcher
14:19:41.869 [info] Registering dummy command feature
14:19:43.389 [info] End refreshing Kernel Picker (1684333179667)
14:19:54.430 [debug] Found 2 saved URIs, [{"time":1684333028863,"serverId":"83cb170cd886cb29d6b50e5cd7a32fae379b15a425c2aaf8727de960ffe31457","displayName":"127.0.0.1","uri":"https://remote/?id=_builtin.jupyterServerUrlProvider&uriHandle=32f61bdb-6cc5-44d9-a51b-a5d29ad028d4","isValidated":false},{"time":1684331767770,"serverId":"1cd044accfee6b133ed57b3b066638947e8e157cb1a35664bb91cdbadce6e282","displayName":"http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214?token=2235b5d629534c7abe166612855f9700","uri":"http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214?token=2235b5d629534c7abe166612855f9700","isValidated":true}]
14:19:54.432 [debug] UniversalRemoteKernelFinder: get from cache
14:19:54.436 [debug] Checking for server usability.
14:19:54.436 [debug] Starting notebook server.
14:19:54.437 [debug] Connecting to process server
14:19:54.437 [info] Connecting remote server kernel http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214
14:19:54.440 [debug] Connection complete server
14:19:54.487 [debug] Disposing session manager
14:19:54.487 [debug] SessionManager - dispose contents manager
14:19:54.487 [debug] ShutdownSessionAndConnection - dispose session manager
14:19:54.487 [debug] Finished disposing jupyter session manager
14:19:54.487 [debug] UniversalRemoteKernelFinder: Writing 3 remote kernel connection metadata to cache
14:19:54.521 [debug] Got env vars with python /usr/local/share/awb/venv/bin/python, with env var count 135 in 14714ms. 
    PATH value is /usr/local/share/awb/venv/bin:/usr/local/vscode/lib/vscode/bin/remote-cli:~/.local/bin:~/.local/bin:/usr/local/share/awb/venv/bin:/srv/npm/bin:/usr/lib/rstudio-server/bin/:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin and 
    Path value is undefined
14:19:54.521 [debug] Prepend PATH with python bin for /usr/local/share/awb/venv/bin/python, 
    PATH value is /usr/local/share/awb/venv/bin:/usr/local/vscode/lib/vscode/bin/remote-cli:~/.local/bin:~/.local/bin:/usr/local/share/awb/venv/bin:/srv/npm/bin:/usr/lib/rstudio-server/bin/:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin and 
    Path value is undefined
14:19:54.522 [debug] Activated Env Variables for /usr/local/share/awb/venv/bin/python, 
    PATH value is /usr/local/share/awb/venv/bin:/usr/local/vscode/lib/vscode/bin/remote-cli:~/.local/bin:~/.local/bin:/usr/local/share/awb/venv/bin:/srv/npm/bin:/usr/lib/rstudio-server/bin/:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin and 
    Path value is undefined
14:19:54.522 [debug] Got env vars with python /usr/local/share/awb/venv/bin/python in 14715ms with 135 variables
14:19:54.523 [debug] Getting activated env variables, Class name = Is, completed in 14715ms, has a truthy return value, Arg 1: "~/sb-package-analysis", Arg 2: <Uri:/usr/local/share/awb/venv/bin/python>, Arg 3: undefined
14:19:54.536 [info] Process Execution: /usr/local/share/awb/venv/bin/python -m pip list
14:19:55.561 [info] End refreshing Kernel Picker (1684333179667)
14:19:55.806 [debug] Updating cache with Local kernels startUsingLocalKernelSpec:'.ir./usr/lib/R/bin/R././usr/lib/r/bin/r#--slave#-e#irkernel::main()#--args#{connection_file} (interpreter id = )', Added = , Updated = , Removed = 
14:19:55.807 [debug] Updating cache with Python kernels startUsingPythonInterpreter:'.jvsc74a57bd0dad53270699e48e90e28291c73c830530df1c536d0cf11094b78e7dd7726c0ba./usr/local/share/awb/venv/python./usr/local/share/awb/venv/python.-m#ipykernel_launcher (interpreter id = /usr/local/share/awb/venv/bin/python)', startUsingPythonInterpreter:'.jvsc74a57bd0916dbcbb3f70747c44a77c7bcd40155683ae19c65e1c03b4aa3499c5328201f1./bin/python3./bin/python3.-m#ipykernel_launcher (interpreter id = /bin/python3)', startUsingPythonInterpreter:'.jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher (interpreter id = /usr/bin/python3)', startUsingPythonInterpreter:'.jvsc74a57bd0aee8b7b246df8f9039afb4144a1f6fd8d2ca17a180786b69acc140d282b71a49./usr/local/bin/python3./usr/local/bin/python3.-m#ipykernel_launcher (interpreter id = /usr/local/bin/python3)', startUsingPythonInterpreter:'.jvsc74a57bd0e55666fbbf217aa3df372b978577f47b6009e2f78e2ec76a584f49cd54a1e62c./bin/python2./bin/python2.-m#ipykernel_launcher (interpreter id = /bin/python2)', startUsingPythonInterpreter:'.jvsc74a57bd0f92b2924b84ff19c1c3dc485f7644d4486f64738191026bf8e6de303969141b5./usr/bin/python2./usr/bin/python2.-m#ipykernel_launcher (interpreter id = /usr/bin/python2)', Added = startUsingPythonInterpreter:'.jvsc74a57bd0f92b2924b84ff19c1c3dc485f7644d4486f64738191026bf8e6de303969141b5./usr/bin/python2./usr/bin/python2.-m#ipykernel_launcher (interpreter id = /usr/bin/python2)', Updated = , Removed = 
14:19:59.740 [debug] UniversalRemoteKernelFinder: get from cache
14:19:59.741 [debug] Checking for server usability.
14:19:59.741 [debug] Starting notebook server.
14:19:59.742 [debug] Connecting to process server
14:19:59.742 [info] Connecting remote server kernel http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214
14:19:59.746 [debug] Connection complete server
14:19:59.779 [debug] Disposing session manager
14:19:59.779 [debug] SessionManager - dispose contents manager
14:19:59.779 [debug] ShutdownSessionAndConnection - dispose session manager
14:19:59.780 [debug] Finished disposing jupyter session manager
14:19:59.780 [debug] UniversalRemoteKernelFinder: Writing 3 remote kernel connection metadata to cache
14:20:01.444 [info] End refreshing Kernel Picker (1684333179667)
14:20:10.560 [debug] Updating cache with Remote kernels connectToLiveRemoteKernel:'6f6c123e-728e-4605-8afd-76caca2d8490 (interpreter id = )', startUsingRemoteKernelSpec:'1cd044accfee6b133ed57b3b066638947e8e157cb1a35664bb91cdbadce6e282..python3./usr/local/share/awb/venv/python./.-m#ipykernel_launcher (interpreter id = )', startUsingRemoteKernelSpec:'1cd044accfee6b133ed57b3b066638947e8e157cb1a35664bb91cdbadce6e282..ir./usr/lib/R/bin/R././usr/lib/r/bin/r#--slave#-e#irkernel::main()#--args#{connection_file} (interpreter id = )', Added = connectToLiveRemoteKernel:'6f6c123e-728e-4605-8afd-76caca2d8490 (interpreter id = )', startUsingRemoteKernelSpec:'1cd044accfee6b133ed57b3b066638947e8e157cb1a35664bb91cdbadce6e282..python3./usr/local/share/awb/venv/python./.-m#ipykernel_launcher (interpreter id = )', startUsingRemoteKernelSpec:'1cd044accfee6b133ed57b3b066638947e8e157cb1a35664bb91cdbadce6e282..ir./usr/lib/R/bin/R././usr/lib/r/bin/r#--slave#-e#irkernel::main()#--args#{connection_file} (interpreter id = )', Updated = , Removed = 
14:20:16.443 [debug] Updating cache with Remote kernels connectToLiveRemoteKernel:'6f6c123e-728e-4605-8afd-76caca2d8490 (interpreter id = )', startUsingRemoteKernelSpec:'2b02d3d561d084f49531fa3deafa75a6dfacb9473aaa470742d6ee783526ccbc..python3./usr/local/share/awb/venv/python./.-m#ipykernel_launcher (interpreter id = )', startUsingRemoteKernelSpec:'2b02d3d561d084f49531fa3deafa75a6dfacb9473aaa470742d6ee783526ccbc..ir./usr/lib/R/bin/R././usr/lib/r/bin/r#--slave#-e#irkernel::main()#--args#{connection_file} (interpreter id = )', Added = connectToLiveRemoteKernel:'6f6c123e-728e-4605-8afd-76caca2d8490 (interpreter id = )', startUsingRemoteKernelSpec:'2b02d3d561d084f49531fa3deafa75a6dfacb9473aaa470742d6ee783526ccbc..python3./usr/local/share/awb/venv/python./.-m#ipykernel_launcher (interpreter id = )', startUsingRemoteKernelSpec:'2b02d3d561d084f49531fa3deafa75a6dfacb9473aaa470742d6ee783526ccbc..ir./usr/lib/R/bin/R././usr/lib/r/bin/r#--slave#-e#irkernel::main()#--args#{connection_file} (interpreter id = )', Updated = , Removed = 
14:22:40.428 [debug] Live Notebook not available for ~/sb-package-analysis/test2.ipynb
14:22:56.026 [debug] Preferred Remote kernel for ~/sb-package-analysis/test2.ipynb is 1ded2003-4fc7-4d8c-9c10-f0ff8633f142
14:22:56.888 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:22:56.889 [debug] Execute Cell 0 ~/sb-package-analysis/test2.ipynb
14:22:56.891 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:22:56.893 [info] Starting Kernel startUsingRemoteKernelSpec, 2b02d3d561d084f49531fa3deafa75a6dfacb9473aaa470742d6ee783526ccbc..python3./usr/local/share/awb/venv/python./.-m#ipykernel_launcher  for '~/sb-package-analysis/test2.ipynb' (disableUI=false)
14:22:56.895 [debug] Checking for server usability.
14:22:56.895 [debug] Starting notebook server.
14:22:56.896 [debug] Connecting to process server
14:22:56.896 [info] Connecting remote server kernel http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214
14:22:56.900 [debug] Connection complete server
14:22:56.924 [debug] createNewKernelSession 2b02d3d561d084f49531fa3deafa75a6dfacb9473aaa470742d6ee783526ccbc..python3./usr/local/share/awb/venv/python./.-m#ipykernel_launcher
14:22:57.064 [debug] http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214: Kernel started: b00d692c-1361-45ed-b04c-1f7aac606182
14:22:57.066 [debug] Waiting for 60000ms idle on (kernel): b00d692c-1361-45ed-b04c-1f7aac606182 -> unknown
14:22:57.083 [debug] KernelProvider switched kernel to id = 2b02d3d561d084f49531fa3deafa75a6dfacb9473aaa470742d6ee783526ccbc..python3./usr/local/share/awb/venv/python./.-m#ipykernel_launcher
14:22:57.084 [debug] Initialize notebook communications for editor ~/sb-package-analysis/test2.ipynb
14:22:57.084 [debug] Resolving notebook UI Comms (resolve) for ~/sb-package-analysis/test2.ipynb
14:22:57.085 [debug] initialize CommonMessageCoordinator
14:22:57.086 [debug] Registering commtarget jupyter.widget
14:22:57.086 [debug] IPyWidgetMessageDispatcher.initialize
14:22:57.087 [debug] Attempting to determine version of IPyWidgets
14:22:57.087 [debug] Controller selection change completed
14:22:57.088 [debug] Waiting for IPyWidgets version
14:22:57.088 [debug] Waiting for IPyWidgets version promise
14:23:01.773 [debug] Got status busy in waitForIdleOnSession
14:23:01.813 [debug] Got status idle in waitForIdleOnSession
14:23:01.813 [debug] Finished waiting for idle on (kernel): b00d692c-1361-45ed-b04c-1f7aac606182 -> idle
14:23:01.814 [info] Started new session b00d692c-1361-45ed-b04c-1f7aac606182
14:23:01.814 [info] Started session for kernel startUsingRemoteKernelSpec:2b02d3d561d084f49531fa3deafa75a6dfacb9473aaa470742d6ee783526ccbc..python3./usr/local/share/awb/venv/python./.-m#ipykernel_launcher
14:23:01.814 [debug] http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214: Creating new notebook 
14:23:01.815 [debug] Started running kernel initialization for ~/sb-package-analysis/test2.ipynb
14:23:01.817 [debug] Executing silently Code (busy) = try:\nimport ipywidgets as _VSCODE_ipywidgets\nprint("e976ee50-99ed-4aba-9b6b-9dcd5634d07d:IPy
14:23:02.089 [debug] Executing silently Code (completed) = try:\nimport ipywidgets as _VSCODE_ipywidgets\nprint("e976ee50-99ed-4aba-9b6b-9dcd5634d07d:IPy with 1 output(s)
14:23:02.089 [debug] Determined IPyKernel Version as 8 and event fired
14:23:02.089 [debug] Version of IPyWidgets 8 determined after 5.002s
14:23:02.093 [debug] Executing silently Code (idle) = import os as _VSCODE_os\nimport sys as _VSCODE_sys\n%cd "/~/sb-package-analysis"\nif _VSCODE
14:23:02.358 [debug] Executing silently Code (completed) = import os as _VSCODE_os\nimport sys as _VSCODE_sys\n%cd "/~/sb-package-analysis"\nif _VSCODE with 1 output(s)
14:23:02.359 [debug] Requesting Kernel info
14:23:02.359 [debug] Got Kernel info
14:23:02.359 [debug] End running kernel initialization, now waiting for idle
14:23:02.360 [debug] Waiting for 60000ms idle on (kernel): b00d692c-1361-45ed-b04c-1f7aac606182 -> idle
14:23:02.360 [debug] Finished waiting for idle on (kernel): b00d692c-1361-45ed-b04c-1f7aac606182 -> idle
14:23:02.360 [debug] End running kernel initialization, session is idle
14:23:02.361 [debug] Updating preferred kernel for remote notebook b00d692c-1361-45ed-b04c-1f7aac606182
14:23:02.365 [debug] IPyWidgetScriptSource.initialize
14:23:02.365 [debug] IPyWidgetMessageDispatcher.initialize
14:23:02.366 [debug] Checking for server usability.
14:23:02.366 [debug] Starting notebook server.
14:23:02.366 [debug] Storing Preferred remote kernel for ~/sb-package-analysis/test2.ipynb is b00d692c-1361-45ed-b04c-1f7aac606182
14:23:02.370 [debug] Connecting to process server
14:23:02.370 [info] Connecting remote server kernel http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214
14:23:02.373 [debug] Connection complete server
14:23:02.375 [debug] Start cell 0 execution @ 1684333382375
14:23:02.391 [debug] Executing silently Code (busy) = # Copyright (c) Microsoft Corporation. All rights reserved.\n# Licensed under the MIT License.\n# Sou
14:23:02.427 [debug] Disposing session manager
14:23:02.427 [debug] SessionManager - dispose contents manager
14:23:02.427 [debug] ShutdownSessionAndConnection - dispose session manager
14:23:02.427 [debug] Finished disposing jupyter session manager
14:23:02.427 [debug] UniversalRemoteKernelFinder: Writing 4 remote kernel connection metadata to cache
14:23:02.434 [debug] Disposing session manager
14:23:02.434 [debug] SessionManager - dispose contents manager
14:23:02.434 [debug] ShutdownSessionAndConnection - dispose session manager
14:23:02.435 [debug] Finished disposing jupyter session manager
14:23:02.435 [debug] UniversalRemoteKernelFinder: Writing 4 remote kernel connection metadata to cache
14:23:02.709 [debug] Executing silently Code (completed) = # Copyright (c) Microsoft Corporation. All rights reserved.\n# Licensed under the MIT License.\n# Sou with 1 output(s)
14:23:02.854 [debug] IPyWidgetMessageDispatcher.initialize
14:23:02.854 [debug] Web view is ready to receive widget messages
14:23:03.980 [debug] End cell 0 execution @ 1684333383979, started @ 1684333382375, elapsed time = 1.604s
14:23:03.981 [debug] Cell 0 executed with state Success
14:23:03.981 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 7094ms, has a falsy return value
14:23:04.689 [info] End refreshing Kernel Picker (1684333179667)
14:23:06.774 [debug] Widget Message: Received IPyWidgets_kernelOptions in ScriptManager
14:23:06.775 [debug] Widget Message: Received IPyWidgetMessages.IPyWidgets_kernelOptions
14:23:06.775 [debug] IPyWidgetMessageDispatcher.initialize
14:23:06.775 [debug] IPyWidgetMessageDispatcher.initialize
14:23:06.776 [debug] Widget Message: data-base-url set to http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214
14:23:06.777 [debug] Widget Message: Received IPyWidgets_kernelOptions in ScriptManager
14:23:06.777 [debug] Widget Message: Received IPyWidgetMessages.IPyWidgets_kernelOptions
14:23:06.777 [debug] Widget Message: data-base-url set to http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214
14:23:06.779 [debug] Widget Message: Received IPyWidget scripts [{"moduleName":"@bokeh/jupyter_bokeh","scriptUri":"http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214/nbextensions/jupyter_bokeh/index","source":"remote"}]
14:23:06.780 [debug] Widget Message: Source for IPyWidget @bokeh/jupyter_bokeh found in remote @ http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214/nbextensions/jupyter_bokeh/index.
14:23:06.780 [debug] Widget Message: Registering IPyWidget @bokeh/jupyter_bokeh found in http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214/nbextensions/jupyter_bokeh/index.
14:23:06.782 [debug] Widget Message: Received IPyWidget scripts [{"moduleName":"jupyterlab-plotly","scriptUri":"http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214/nbextensions/jupyterlab-plotly/index","source":"remote"}]
14:23:06.782 [debug] Widget Message: Source for IPyWidget jupyterlab-plotly found in remote @ http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214/nbextensions/jupyterlab-plotly/index.
14:23:06.782 [debug] Widget Message: Registering IPyWidget jupyterlab-plotly found in http://127.0.0.1:8888/user/joshrichardson/a6bc8d68560f346080c342ba33e67214/nbextensions/jupyterlab-plotly/index.
14:23:17.903 [debug] Jupyter completion time: 11
14:23:19.184 [debug] Updating cache with Remote kernels connectToLiveRemoteKernel:'6f6c123e-728e-4605-8afd-76caca2d8490 (interpreter id = )', connectToLiveRemoteKernel:'b00d692c-1361-45ed-b04c-1f7aac606182 (interpreter id = )', startUsingRemoteKernelSpec:'2b02d3d561d084f49531fa3deafa75a6dfacb9473aaa470742d6ee783526ccbc..python3./usr/local/share/awb/venv/python./.-m#ipykernel_launcher (interpreter id = )', startUsingRemoteKernelSpec:'2b02d3d561d084f49531fa3deafa75a6dfacb9473aaa470742d6ee783526ccbc..ir./usr/lib/R/bin/R././usr/lib/r/bin/r#--slave#-e#irkernel::main()#--args#{connection_file} (interpreter id = )', Added = connectToLiveRemoteKernel:'b00d692c-1361-45ed-b04c-1f7aac606182 (interpreter id = )', Updated = , Removed = 
14:23:19.396 [debug] Jupyter completion time: 9
14:23:19.688 [debug] Updating cache with Remote kernels connectToLiveRemoteKernel:'6f6c123e-728e-4605-8afd-76caca2d8490 (interpreter id = )', connectToLiveRemoteKernel:'b00d692c-1361-45ed-b04c-1f7aac606182 (interpreter id = )', startUsingRemoteKernelSpec:'1cd044accfee6b133ed57b3b066638947e8e157cb1a35664bb91cdbadce6e282..python3./usr/local/share/awb/venv/python./.-m#ipykernel_launcher (interpreter id = )', startUsingRemoteKernelSpec:'1cd044accfee6b133ed57b3b066638947e8e157cb1a35664bb91cdbadce6e282..ir./usr/lib/R/bin/R././usr/lib/r/bin/r#--slave#-e#irkernel::main()#--args#{connection_file} (interpreter id = )', Added = connectToLiveRemoteKernel:'b00d692c-1361-45ed-b04c-1f7aac606182 (interpreter id = )', Updated = , Removed = 
14:23:20.104 [debug] Jupyter completion time: 9
14:23:21.434 [debug] Jupyter completion time: 9
14:23:22.106 [debug] Jupyter completion time: 11
14:23:34.145 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:23:34.149 [debug] Execute Cell 1 ~/sb-package-analysis/test2.ipynb
14:23:34.150 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:23:34.154 [debug] Start cell 1 execution @ 1684333414153
14:23:34.678 [debug] End cell 1 execution @ 1684333414678, started @ 1684333414153, elapsed time = 0.525s
14:23:34.679 [debug] Cell 1 executed with state Success
14:23:34.679 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 534ms, has a falsy return value
14:23:36.739 [debug] Jupyter completion time: 9
14:23:40.082 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:23:40.085 [debug] Execute Cell 2 ~/sb-package-analysis/test2.ipynb
14:23:40.085 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:23:40.087 [debug] Start cell 2 execution @ 1684333420087
14:23:41.128 [debug] End cell 2 execution @ 1684333421128, started @ 1684333420087, elapsed time = 1.041s
14:23:41.128 [debug] Cell 2 executed with state Success
14:23:41.129 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 1047ms, has a falsy return value
14:25:17.988 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:25:17.990 [debug] Execute Cell 3 ~/sb-package-analysis/test2.ipynb
14:25:17.991 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:25:17.996 [debug] Start cell 3 execution @ 1684333517996
14:25:18.843 [debug] End cell 3 execution @ 1684333518843, started @ 1684333517996, elapsed time = 0.847s
14:25:18.845 [debug] Cell 3 executed with state Success
14:25:18.845 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 857ms, has a falsy return value
14:25:20.148 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:25:20.151 [debug] Execute Cell 4 ~/sb-package-analysis/test2.ipynb
14:25:20.151 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:25:20.153 [debug] Start cell 4 execution @ 1684333520153
14:25:21.345 [debug] End cell 4 execution @ 1684333521345, started @ 1684333520153, elapsed time = 1.192s
14:25:21.346 [debug] Cell 4 executed with state Success
14:25:21.346 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 1198ms, has a falsy return value
14:25:24.688 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:25:24.691 [debug] Execute Cell 5 ~/sb-package-analysis/test2.ipynb
14:25:24.691 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:25:24.693 [debug] Start cell 5 execution @ 1684333524693
14:25:24.710 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:25:24.712 [debug] Execute Cell 6 ~/sb-package-analysis/test2.ipynb
14:25:24.712 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:25:25.726 [debug] End cell 5 execution @ 1684333525726, started @ 1684333524693, elapsed time = 1.033s
14:25:25.727 [debug] Start cell 6 execution @ 1684333525727
14:25:25.727 [debug] Cell 5 executed with state Success
14:25:25.727 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 1039ms, has a falsy return value
14:25:29.212 [debug] End cell 6 execution @ 1684333529212, started @ 1684333525727, elapsed time = 3.485s
14:25:29.213 [debug] Cell 6 executed with state Success
14:25:29.213 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 4503ms, has a falsy return value
14:25:37.392 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:25:37.394 [debug] Execute Cell 7 ~/sb-package-analysis/test2.ipynb
14:25:37.395 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:25:37.398 [debug] Start cell 7 execution @ 1684333537397
14:25:39.727 [debug] End cell 7 execution @ 1684333539726, started @ 1684333537397, elapsed time = 2.329s
14:25:39.727 [debug] Cell 7 executed with state Success
14:25:39.727 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 2335ms, has a falsy return value
14:43:57.757 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:43:57.759 [debug] Execute Cell 8 ~/sb-package-analysis/test2.ipynb
14:43:57.760 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:43:57.763 [debug] Start cell 8 execution @ 1684334637763
14:43:58.284 [debug] End cell 8 execution @ 1684334638283, started @ 1684334637763, elapsed time = 0.52s
14:43:58.284 [debug] Cell 8 executed with state Success
14:43:58.285 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 528ms, has a falsy return value
14:44:05.972 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:44:05.974 [debug] Execute Cell 9 ~/sb-package-analysis/test2.ipynb
14:44:05.974 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:44:05.976 [debug] Start cell 9 execution @ 1684334645976
14:44:11.002 [debug] End cell 9 execution @ 1684334651002, started @ 1684334645976, elapsed time = 5.026s
14:44:11.003 [debug] Cell 9 executed with state Success
14:44:11.003 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 5031ms, has a falsy return value
14:44:22.328 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:44:22.330 [debug] Execute Cell 10 ~/sb-package-analysis/test2.ipynb
14:44:22.330 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:44:22.332 [debug] Start cell 10 execution @ 1684334662332
14:44:24.206 [debug] End cell 10 execution @ 1684334664206, started @ 1684334662332, elapsed time = 1.874s
14:44:24.206 [debug] Cell 10 executed with state Success
14:44:24.206 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 1878ms, has a falsy return value
14:44:34.132 [debug] Jupyter completion time: 48
14:44:36.518 [debug] Jupyter completion time: 19
14:44:36.653 [debug] VSCodeNotebookController::handleExecution, Class name = Ty (started execution)
14:44:36.656 [debug] Execute Cell 11 ~/sb-package-analysis/test2.ipynb
14:44:36.656 [debug] start the kernel, options.disableUI=false for ~/sb-package-analysis/test2.ipynb
14:44:36.658 [debug] Start cell 11 execution @ 1684334676658
14:45:19.288 [debug] End cell 11 execution @ 1684334719288, started @ 1684334676658, elapsed time = 42.63s
14:45:19.289 [debug] Cell 11 executed with state Success
14:45:19.289 [debug] VSCodeNotebookController::handleExecution, Class name = Ty, completed in 42636ms, has a falsy return value
15:00:47.814 [debug] notebook communications already initialized for editor ~/sb-package-analysis/test2.ipynb

@jrich100 jrich100 added the bug Issue identified by VS Code Team member as probable bug label May 17, 2023
@DonJayamanne
Copy link
Contributor

Thanks for filing this issue and sorry you are running into this.
Please can you try this with the latest pre-release version of the Jupyter extension
This might be fixed in there, let me know how that goes.

  • Please install latest VS Code Insiders
  • Open the extensions page, search for Jupyter and click the button Switch to Pre-Release Version
  • Similarly please install the latest Pre-Release version of the Python extension

Screenshot 2023-02-04 at 09 24 37

@jrich100
Copy link
Author

Now thinking this may be more related to networking issues on my end - I'll close for now

@DonJayamanne DonJayamanne added the user-setup Issues caused by incorrectly configured python environments, packages, or the like label May 18, 2023
@jrich100
Copy link
Author

jrich100 commented May 22, 2023

@DonJayamanne I was able to reproduce this using

VS Code: 1.79.0-insider (Universal) Commit: f6be5461f8bc69013a605f5baea834651c6589fb
Python: v2023.9.11371007 (Pre-Release)
Jupyter: v2023.5.1001411100 (Pre-Release)

Here is a recording of the behavior. I had to trim the video, but it took almost 3 minutes from running the print cell before it started executing.

Start.screencap.mov
End.screencap.mov

The resulting notebook was 4.5M.

Logs

Output for Jupyter in the Output panel (ViewOutput, change the drop-down the upper-right of the Output panel to Jupyter)

Visual Studio Code - Insiders (1.79.0-insider, ssh-remote, desktop)
Jupyter Extension Version: 2023.5.1001411100.
Python Extension Version: 2023.9.11371007.
Platform: linux (x64).
Workspace folder ~/workbench, Home = /home/joshrichardson
20:10:50.120 [debug] Get Custom Env Variables, Class name = Lc (started execution), Arg 1: undefined, Arg 2: "RunPythonCode"
20:10:50.121 [info] Start refreshing Kernel Picker (1684786250121)
20:10:50.146 [info] Using Pylance
20:10:50.202 [debug] Preferred Remote kernel for ~/workbench/notebook.ipynb is undefined
20:10:50.339 [debug] Found 1 saved URIs, [{"time":1678397924747,"serverId":"a9dfbf4a962360de15b670b933682803e9cc36acfead4083af8dd24d3a10f48a","displayName":"localhost","uri":"https://remote/?id=_builtin.jupyterServerUrlProvider&uriHandle=909a19f0-f572-463a-bff3-c63a964d083d","isValidated":true,"provider":{"handle":"909a19f0-f572-463a-bff3-c63a964d083d","id":"_builtin.jupyterServerUrlProvider"}}]
20:10:50.340 [debug] UniversalRemoteKernelFinder: get from cache
20:10:50.359 [debug] UniversalRemoteKernelFinder: Writing 0 remote kernel connection metadata to cache
20:10:50.769 [debug] Get Custom Env Variables, Class name = Lc, completed in 650ms, has a truthy return value, Arg 1: undefined, Arg 2: "RunPythonCode"
20:10:50.769 [debug] Jupyter Paths /kernels: 
20:10:50.774 [debug] Kernel Spec Root Paths, /usr/share/jupyter/kernels, /usr/local/share/jupyter/kernels, ~/.local/share/jupyter/kernels
20:10:50.791 [debug] Getting activated env variables, Class name = Bs (started execution), Arg 1: "~/workbench", Arg 2: <Uri:/usr/bin/python3>, Arg 3: undefined
20:10:50.791 [debug] Get Custom Env Variables, Class name = Lc (started execution), Arg 1: "~/workbench", Arg 2: "RunPythonCode"
20:10:50.792 [debug] Get Custom Env Variables, Class name = Lc, completed in 1ms, has a truthy return value, Arg 1: "~/workbench", Arg 2: "RunPythonCode"
20:10:50.812 [debug] Not Searching for kernels as path does not exist, /usr/share/jupyter/kernels
20:10:50.812 [debug] Not Searching for kernels as path does not exist, /usr/local/share/jupyter/kernels
20:10:50.834 [debug] Loading kernelspec from ~/.local/share/jupyter/kernels/python3/kernel.json for 
20:10:51.140 [debug] Full interpreter list after refreshing (total 384ms, resolve 14ms, refresh 370ms) is length: 4, /usr/bin/python3:undefined::/usr/bin/python3:/usr/bin/python3, /bin/python2.7:undefined::/bin/python2.7:/bin/python2.7, /bin/python3:undefined::/bin/python3:/bin/python3, /usr/bin/python2.7:undefined::/usr/bin/python2.7:/usr/bin/python2.7
20:10:51.145 [debug] Full interpreter list after refreshing (total 2ms, resolve 1ms, refresh 1ms) is length: 4, /usr/bin/python3:undefined::/usr/bin/python3:/usr/bin/python3, /bin/python2.7:undefined::/bin/python2.7:/bin/python2.7, /bin/python3:undefined::/bin/python3:/bin/python3, /usr/bin/python2.7:undefined::/usr/bin/python2.7:/usr/bin/python2.7
20:10:51.154 [debug] refreshData after detecting changes to interpreters
20:10:51.155 [debug] Listing Python & non-Python kernels for Interpreter /usr/bin/python3
20:10:51.155 [debug] Listing Python & non-Python kernels for Interpreter /bin/python2.7
20:10:51.156 [debug] Finding Global Python KernelSpecs
20:10:51.159 [debug] refreshData after detecting changes to interpreters
20:10:51.159 [debug] Listing Python & non-Python kernels for Interpreter /bin/python3
20:10:51.160 [debug] refreshData after detecting changes to interpreters
20:10:51.160 [debug] Listing Python & non-Python kernels for Interpreter /usr/bin/python2.7
20:10:51.161 [debug] refreshData after detecting changes to interpreters
20:10:51.163 [debug] refreshData after detecting changes to interpreters
20:10:51.164 [debug] Full interpreter list is length: 4, /usr/bin/python3::Unknown:/usr/bin/python3, /bin/python2.7::Unknown:/bin/python2.7, /bin/python3::Unknown:/bin/python3, /usr/bin/python2.7::Unknown:/usr/bin/python2.7
20:10:51.164 [debug] Refreshed Environments
20:10:51.167 [debug] Kernel for interpreter /bin/python2.7 is .jvsc74a57bd0db2f90f1f00b3e893a9357df64636d40bf6fd81f38bfd24fd967392d4ed4d76a./bin/python2.7./bin/python2.7.-m#ipykernel_launcher
20:10:51.167 [debug] Kernels for interpreter /bin/python2.7 are .jvsc74a57bd0db2f90f1f00b3e893a9357df64636d40bf6fd81f38bfd24fd967392d4ed4d76a./bin/python2.7./bin/python2.7.-m#ipykernel_launcher
20:10:51.173 [debug] Kernel for interpreter /usr/bin/python3 is .jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher
20:10:51.173 [debug] Kernels for interpreter /usr/bin/python3 are .jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher
20:10:51.176 [debug] Kernel for interpreter /bin/python3 is .jvsc74a57bd0916dbcbb3f70747c44a77c7bcd40155683ae19c65e1c03b4aa3499c5328201f1./bin/python3./bin/python3.-m#ipykernel_launcher
20:10:51.176 [debug] Kernels for interpreter /bin/python3 are .jvsc74a57bd0916dbcbb3f70747c44a77c7bcd40155683ae19c65e1c03b4aa3499c5328201f1./bin/python3./bin/python3.-m#ipykernel_launcher
20:10:51.178 [debug] Kernel for interpreter /usr/bin/python2.7 is .jvsc74a57bd0beb4f305451b4835b36a63cb1c75e625a7251cf8d76b65be2f5856eee68cb551./usr/bin/python2.7./usr/bin/python2.7.-m#ipykernel_launcher
20:10:51.178 [debug] Kernels for interpreter /usr/bin/python2.7 are .jvsc74a57bd0beb4f305451b4835b36a63cb1c75e625a7251cf8d76b65be2f5856eee68cb551./usr/bin/python2.7./usr/bin/python2.7.-m#ipykernel_launcher
20:10:51.222 [debug] Conda file returned by Python Extension is conda
20:10:51.319 [debug] KernelProvider switched kernel to id = .jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher
20:10:51.320 [debug] start the kernel, options.disableUI=true for ~/workbench/notebook.ipynb
20:10:51.325 [debug] Initialize notebook communications for editor ~/workbench/notebook.ipynb
20:10:51.326 [debug] Resolving notebook UI Comms (resolve) for ~/workbench/notebook.ipynb
20:10:51.326 [debug] initialize CommonMessageCoordinator
20:10:51.354 [debug] Registering commtarget jupyter.widget
20:10:51.354 [debug] IPyWidgetMessageDispatcher.initialize
20:10:51.355 [debug] Attempting to determine version of IPyWidgets
20:10:51.358 [debug] Controller selection change completed
20:10:51.359 [debug] Waiting for IPyWidgets version
20:10:51.359 [debug] Waiting for IPyWidgets version promise
20:10:51.364 [info] Starting Kernel startUsingPythonInterpreter, .jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher  (Python Path: /usr/bin/python3, Unknown, 3.8.10) for '~/workbench/notebook.ipynb' (disableUI=true)
20:10:51.366 [debug] Creating raw notebook for resource '~/workbench/notebook.ipynb'
20:10:51.398 [debug] Kernel Launcher. launch, Class name = $g (started execution), Arg 1: ".jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher", Arg 2: 60000, Arg 3: "~/workbench/notebook.ipynb", Arg 4: "~/workbench", Arg 5: ""
20:10:51.399 [debug] Getting activated env variables, Class name = Bs (started execution), Arg 1: "~/workbench/notebook.ipynb", Arg 2: <Uri:/usr/bin/python3>, Arg 3: undefined
20:10:51.399 [debug] Get Custom Env Variables, Class name = Lc (started execution), Arg 1: "~/workbench/notebook.ipynb", Arg 2: "RunPythonCode"
20:10:51.412 [debug] Get Custom Env Variables, Class name = Lc, completed in 13ms, has a truthy return value, Arg 1: "~/workbench/notebook.ipynb", Arg 2: "RunPythonCode"
20:10:51.443 [debug] Launching kernel in kernelProcess.ts, Class name = Vg (started execution), Arg 1: "~/workbench", Arg 2: ""
20:10:51.546 [warn] Failed to get activated env vars for /usr/bin/python3 in 755ms
20:10:51.547 [debug] Create ProcessService, Class name = wc (started execution), Arg 1: undefined
20:10:51.547 [debug] Get Custom Env Variables, Class name = Lc (started execution), Arg 1: undefined, Arg 2: "RunNonPythonCode", Arg 3: undefined
20:10:51.549 [debug] Get Custom Env Variables, Class name = Lc, completed in 2ms, has a truthy return value, Arg 1: undefined, Arg 2: "RunNonPythonCode", Arg 3: undefined
20:10:51.550 [debug] Create ProcessService, Class name = wc, completed in 3ms, has a truthy return value, Arg 1: undefined
20:10:51.564 [info] Process Execution: /usr/bin/python3 -c "import site;print("USER_BASE_VALUE");print(site.USER_BASE);print("USER_BASE_VALUE");"
20:10:51.593 [debug] USER_SITE for /usr/bin/python3 is ~/.local/bin
20:10:51.594 [debug] Prepend PATH with user site path for /usr/bin/python3, user site ~/.local/bin
20:10:51.594 [debug] Prepend PATH with python bin for /usr/bin/python3, 
    PATH value is ~/.local/bin:~/.vscode-server-insiders/bin/f6be5461f8bc69013a605f5baea834651c6589fb/bin/remote-cli:/usr/local/bin:/usr/bin:/bin:/usr/games:/snap/bin:/opt/puppetlabs/bin:~/.vscode-server-insiders/bin/f6be5461f8bc69013a605f5baea834651c6589fb/bin/remote-cli:/usr/local/bin:/usr/bin:/bin:/usr/games:/snap/bin:/opt/puppetlabs/bin and 
    Path value is undefined
20:10:51.594 [debug] Activated Env Variables for /usr/bin/python3, 
    PATH value is /usr/bin:~/.local/bin:~/.vscode-server-insiders/bin/f6be5461f8bc69013a605f5baea834651c6589fb/bin/remote-cli:/usr/local/bin:/usr/bin:/bin:/usr/games:/snap/bin:/opt/puppetlabs/bin:~/.vscode-server-insiders/bin/f6be5461f8bc69013a605f5baea834651c6589fb/bin/remote-cli:/usr/local/bin:/usr/bin:/bin:/usr/games:/snap/bin:/opt/puppetlabs/bin and 
    Path value is undefined
20:10:51.594 [debug] Got env vars with python /usr/bin/python3 in 803ms with 33 variables
20:10:51.594 [debug] Getting activated env variables, Class name = Bs, completed in 803ms, has a truthy return value, Arg 1: "~/workbench", Arg 2: <Uri:/usr/bin/python3>, Arg 3: undefined
20:10:51.609 [info] Process Execution: /usr/bin/python3 -m pip list
20:10:51.616 [warn] Failed to get activated env vars for /usr/bin/python3 in 217ms
20:10:51.617 [debug] Prepend PATH with user site path for /usr/bin/python3, user site ~/.local/bin
20:10:51.617 [debug] Prepend PATH with python bin for /usr/bin/python3, 
    PATH value is ~/.local/bin:~/.vscode-server-insiders/bin/f6be5461f8bc69013a605f5baea834651c6589fb/bin/remote-cli:/usr/local/bin:/usr/bin:/bin:/usr/games:/snap/bin:/opt/puppetlabs/bin:~/.vscode-server-insiders/bin/f6be5461f8bc69013a605f5baea834651c6589fb/bin/remote-cli:/usr/local/bin:/usr/bin:/bin:/usr/games:/snap/bin:/opt/puppetlabs/bin and 
    Path value is undefined
20:10:51.617 [debug] Activated Env Variables for /usr/bin/python3, 
    PATH value is /usr/bin:~/.local/bin:~/.vscode-server-insiders/bin/f6be5461f8bc69013a605f5baea834651c6589fb/bin/remote-cli:/usr/local/bin:/usr/bin:/bin:/usr/games:/snap/bin:/opt/puppetlabs/bin:~/.vscode-server-insiders/bin/f6be5461f8bc69013a605f5baea834651c6589fb/bin/remote-cli:/usr/local/bin:/usr/bin:/bin:/usr/games:/snap/bin:/opt/puppetlabs/bin and 
    Path value is undefined
20:10:51.618 [debug] Got env vars with python /usr/bin/python3 in 219ms with 33 variables
20:10:51.618 [debug] Getting activated env variables, Class name = Bs, completed in 219ms, has a truthy return value, Arg 1: "~/workbench/notebook.ipynb", Arg 2: <Uri:/usr/bin/python3>, Arg 3: undefined
20:10:51.638 [info] Process Execution: /usr/bin/python3 -c "import ipykernel; print(ipykernel.__version__); print("5dc3a68c-e34e-4080-9c3e-2a532b2ccb4d"); print(ipykernel.__file__)"
20:10:51.639 [debug] Kernel Env Variables for ~/.vscode-server-insiders/extensions/ms-toolsai.jupyter-2023.5.1001411100-linux-x64/temp/jupyter/kernels/pythonjvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6/kernel.json, PATH value is /usr/bin:~/.local/bin:~/.vscode-server-insiders/bin/f6be5461f8bc69013a605f5baea834651c6589fb/bin/remote-cli:/usr/local/bin:/usr/bin:/bin:/usr/games:/snap/bin:/opt/puppetlabs/bin:~/.vscode-server-insiders/bin/f6be5461f8bc69013a605f5baea834651c6589fb/bin/remote-cli:/usr/local/bin:/usr/bin:/bin:/usr/games:/snap/bin:/opt/puppetlabs/bin
20:10:51.661 [info] Process Execution: /usr/bin/python3 -m ipykernel_launcher --ip=127.0.0.1 --stdin=9003 --control=9001 --hb=9000 --Session.signature_scheme="hmac-sha256" --Session.key=b"da3bf6c1-467f-45b7-a287-7b99e58c4416" --shell=9002 --transport="tcp" --iopub=9004 --f=~/.local/share/jupyter/runtime/kernel-v2-74280PLGm55OVsGn9.json
    > cwd: ~/workbench
20:10:51.661 [debug] Launching kernel in kernelProcess.ts, Class name = Vg, completed in 218ms, has a truthy return value, Arg 1: "~/workbench", Arg 2: ""
20:10:52.002 [info] ipykernel version & path 6.23.1, ~/.local/lib/python3.8/site-packages/ipykernel/__init__.py for /usr/bin/python3
20:10:52.072 [debug] _jupyterPythonPathFunction: Giving Pylance "/usr/bin/python3" as python path for "file://~/workbench/notebook.ipynb"
20:10:52.077 [debug] _jupyterPythonPathFunction: "file://~/workbench" is not a notebook
20:10:52.084 [debug] _jupyterPythonPathFunction: "file://~/workbench" is not a notebook
20:10:52.165 [info] End refreshing Kernel Picker (1684786250121)
20:10:52.822 [debug] KernelProcess error: ~/.local/lib/python3.8/site-packages/traitlets/traitlets.py:2548: FutureWarning: Supporting extra quotes around strings is deprecated in traitlets 5.0. You can use 'hmac-sha256' instead of '"hmac-sha256"' if you require traitlets >=5.
  warn(

20:10:52.823 [warn] StdErr from Kernel Process ~/.local/lib/python3.8/site-packages/traitlets/traitlets.py:2548: FutureWarning: Supporting extra quotes around strings is deprecated in traitlets 5.0. You can use 'hmac-sha256' instead of '"hmac-sha256"' if you require traitlets >=5.
  warn(
20:10:52.826 [debug] KernelProcess error: ~/.local/lib/python3.8/site-packages/traitlets/traitlets.py:2499: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use 'da3bf6c1-467f-45b7-a287-7b99e58c4416' instead of 'b"da3bf6c1-467f-45b7-a287-7b99e58c4416"'.
  warn(

20:10:52.826 [warn] StdErr from Kernel Process ~/.local/lib/python3.8/site-packages/traitlets/traitlets.py:2499: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use 'da3bf6c1-467f-45b7-a287-7b99e58c4416' instead of 'b"da3bf6c1-467f-45b7-a287-7b99e58c4416"'.
  warn(
20:10:52.857 [debug] KernelProcess output: NOTE: When using the `ipython kernel` entry point, Ctrl-C will not work.

To exit, you will have to explicitly quit this process, by either sending
"quit" from a client, or using Ctrl-\ in UNIX-like environments.

To read more about this, see https://github.com/ipython/ipython/issues/2049


To connect another client to this kernel, use:
    --existing kernel-v2-74280PLGm55OVsGn9.json

20:10:52.857 [debug] Kernel Output: To connect another client to this kernel, use: --existing kernel-v2-74280PLGm55OVsGn9.json

20:10:52.906 [debug] Kernel Launcher. launch, Class name = $g, completed in 1508ms, has a truthy return value, Arg 1: ".jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher", Arg 2: 60000, Arg 3: "~/workbench/notebook.ipynb", Arg 4: "~/workbench", Arg 5: ""
20:10:52.952 [debug] Waiting for Raw Session to be ready in postStartRawSession
20:10:52.952 [debug] Waiting for Raw session to be ready, currently connected
20:10:52.952 [debug] Raw session connected
20:10:52.952 [debug] Waiting for Raw session to be ready for 30s
20:10:52.953 [debug] Waited for Raw session to be ready & got connected
20:10:52.953 [debug] Successfully waited for Raw Session to be ready in postStartRawSession
20:10:52.953 [debug] Kernel status before requesting kernel info and after ready is unknown
20:10:52.953 [debug] Sending request for kernelinfo
20:10:53.021 [debug] Got response for requestKernelInfo
20:10:53.021 [debug] Successfully compelted postStartRawSession
20:10:53.022 [info] Started Kernel Python 3.8.10 (pid: 74379)
20:10:53.022 [info] Started new session 56f2d93b-ffd1-4cae-a1f7-c8dc34fe12f3
20:10:53.024 [debug] Started running kernel initialization for ~/workbench/notebook.ipynb
20:10:53.025 [debug] Executing silently Code (idle) = try:\nimport ipywidgets as _VSCODE_ipywidgets\nprint("e976ee50-99ed-4aba-9b6b-9dcd5634d07d:IPy
20:10:53.190 [debug] Executing silently Code (completed) = try:\nimport ipywidgets as _VSCODE_ipywidgets\nprint("e976ee50-99ed-4aba-9b6b-9dcd5634d07d:IPy with 1 output(s)
20:10:53.191 [debug] Determined IPyWidgets Version as 8 and event fired
20:10:53.191 [debug] Version of IPyWidgets 8 determined after 1.836s
20:10:53.193 [debug] Executing silently Code (idle) = import os as _VSCODE_os\nimport sys as _VSCODE_sys\n%cd "/~/workbench"\nif _VSCODE_o
20:10:53.224 [debug] Executing silently Code (completed) = import os as _VSCODE_os\nimport sys as _VSCODE_sys\n%cd "/~/workbench"\nif _VSCODE_o with 1 output(s)
20:10:53.225 [debug] Requesting Kernel info
20:10:53.225 [debug] Got Kernel info
20:10:53.226 [debug] End running kernel initialization, now waiting for idle
20:10:53.226 [debug] Waiting for 60000ms idle on (kernel): 56f2d93b-ffd1-4cae-a1f7-c8dc34fe12f3 -> idle
20:10:53.226 [debug] Finished waiting for idle on (kernel): 56f2d93b-ffd1-4cae-a1f7-c8dc34fe12f3 -> idle
20:10:53.227 [debug] End running kernel initialization, session is idle
20:10:53.230 [debug] IPyWidgetScriptSource.initialize
20:10:53.231 [debug] IPyWidgetMessageDispatcher.initialize
20:10:53.236 [debug] getDataDirsImpl, Class name = hh (started execution), Arg 1: "~/workbench/notebook.ipynb", Arg 2: "/usr/bin/python3"
20:10:53.236 [debug] Get Custom Env Variables, Class name = Lc (started execution), Arg 1: undefined, Arg 2: "RunPythonCode"
20:10:53.244 [debug] Get Custom Env Variables, Class name = Lc, completed in 8ms, has a truthy return value, Arg 1: undefined, Arg 2: "RunPythonCode"
20:10:53.244 [debug] Jupyter Paths : 
20:10:53.285 [info] Process Execution: /usr/bin/python3 ~/.vscode-server-insiders/extensions/ms-toolsai.jupyter-2023.5.1001411100-linux-x64/pythonFiles/printJupyterDataDir.py
20:10:53.353 [debug] getDataDirsImpl, Class name = hh, completed in 117ms, has a truthy return value, Arg 1: "~/workbench/notebook.ipynb", Arg 2: "/usr/bin/python3"
20:10:53.866 [debug] IPyWidgetMessageDispatcher.initialize
20:10:53.866 [debug] Web view is ready to receive widget messages
20:10:53.945 [debug] Widget Message: Received IPyWidgets_kernelOptions in ScriptManager
20:10:53.947 [debug] Widget Message: Received IPyWidgetMessages.IPyWidgets_kernelOptions
20:10:53.947 [debug] IPyWidgetMessageDispatcher.initialize
20:10:53.948 [debug] IPyWidgetMessageDispatcher.initialize
20:10:53.954 [debug] Widget Message: data-base-url set to https://vscode-remote%2Bssh-002dremote-002bgue1-002djoshrtest-002d<username>-002dgkn4-002egue1-002espotify-002enet.vscode-resource.vscode-cdn.net~/.vscode-server-insiders/extensions/ms-toolsai.jupyter-2023.5.1001411100-linux-x64/temp/scripts/31287cf9e2e18afa40154cd18cb1e57335b009e6bfea9730c6beb09c8a1ec8b0/jupyter
20:10:53.954 [debug] Widget Message: Received IPyWidgets_kernelOptions in ScriptManager
20:10:53.955 [debug] Widget Message: Received IPyWidgetMessages.IPyWidgets_kernelOptions
20:10:53.955 [debug] Widget Message: data-base-url set to https://vscode-remote%2Bssh-002dremote-002bgue1-002djoshrtest-002d<username>-002dgkn4-002egue1-002espotify-002enet.vscode-resource.vscode-cdn.net~/.vscode-server-insiders/extensions/ms-toolsai.jupyter-2023.5.1001411100-linux-x64/temp/scripts/31287cf9e2e18afa40154cd18cb1e57335b009e6bfea9730c6beb09c8a1ec8b0/jupyter
20:10:53.975 [debug] Widget Message: Received IPyWidget scripts [{"moduleName":"@bokeh/jupyter_bokeh","scriptUri":"https://vscode-remote%2Bssh-002dremote-002bgue1-002djoshrtest-002d<username>-002dgkn4-002egue1-002espotify-002enet.vscode-resource.vscode-cdn.net~/.vscode-server-insiders/extensions/ms-toolsai.jupyter-2023.5.1001411100-linux-x64/temp/scripts/31287cf9e2e18afa40154cd18cb1e57335b009e6bfea9730c6beb09c8a1ec8b0/jupyter/nbextensions/jupyter_bokeh/index","source":"local"}]
20:10:53.975 [debug] Widget Message: Source for IPyWidget @bokeh/jupyter_bokeh found in local @ https://vscode-remote%2Bssh-002dremote-002bgue1-002djoshrtest-002d<username>-002dgkn4-002egue1-002espotify-002enet.vscode-resource.vscode-cdn.net~/.vscode-server-insiders/extensions/ms-toolsai.jupyter-2023.5.1001411100-linux-x64/temp/scripts/31287cf9e2e18afa40154cd18cb1e57335b009e6bfea9730c6beb09c8a1ec8b0/jupyter/nbextensions/jupyter_bokeh/index.
20:10:53.976 [debug] Widget Message: Registering IPyWidget @bokeh/jupyter_bokeh found in https://vscode-remote%2Bssh-002dremote-002bgue1-002djoshrtest-002d<username>-002dgkn4-002egue1-002espotify-002enet.vscode-resource.vscode-cdn.net~/.vscode-server-insiders/extensions/ms-toolsai.jupyter-2023.5.1001411100-linux-x64/temp/scripts/31287cf9e2e18afa40154cd18cb1e57335b009e6bfea9730c6beb09c8a1ec8b0/jupyter/nbextensions/jupyter_bokeh/index.
20:11:06.180 [debug] Updating cache with Python kernels startUsingPythonInterpreter:'.jvsc74a57bd0db2f90f1f00b3e893a9357df64636d40bf6fd81f38bfd24fd967392d4ed4d76a./bin/python2.7./bin/python2.7.-m#ipykernel_launcher (interpreter id = /bin/python2.7)', startUsingPythonInterpreter:'.jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher (interpreter id = /usr/bin/python3)', startUsingPythonInterpreter:'.jvsc74a57bd0916dbcbb3f70747c44a77c7bcd40155683ae19c65e1c03b4aa3499c5328201f1./bin/python3./bin/python3.-m#ipykernel_launcher (interpreter id = /bin/python3)', startUsingPythonInterpreter:'.jvsc74a57bd0beb4f305451b4835b36a63cb1c75e625a7251cf8d76b65be2f5856eee68cb551./usr/bin/python2.7./usr/bin/python2.7.-m#ipykernel_launcher (interpreter id = /usr/bin/python2.7)', Added = startUsingPythonInterpreter:'.jvsc74a57bd0beb4f305451b4835b36a63cb1c75e625a7251cf8d76b65be2f5856eee68cb551./usr/bin/python2.7./usr/bin/python2.7.-m#ipykernel_launcher (interpreter id = /usr/bin/python2.7)', Updated = , Removed = 
20:11:17.088 [debug] VSCodeNotebookController::handleExecution, Class name = Nv (started execution)
20:11:17.089 [debug] Execute Cell 0 ~/workbench/notebook.ipynb
20:11:17.090 [debug] start the kernel, options.disableUI=false for ~/workbench/notebook.ipynb
20:11:17.101 [debug] Start cell 0 execution @ 1684786277097 (clear output)
20:11:17.631 [debug] End cell 0 execution @ 1684786277626, started @ 1684786277097, elapsed time = 0.529s
20:11:17.631 [debug] Cell 0 executed with state Success
20:11:17.632 [debug] VSCodeNotebookController::handleExecution, Class name = Nv, completed in 544ms, has a falsy return value
20:11:18.228 [debug] VSCodeNotebookController::handleExecution, Class name = Nv (started execution)
20:11:18.229 [debug] Execute Cell 1 ~/workbench/notebook.ipynb
20:11:18.230 [debug] start the kernel, options.disableUI=false for ~/workbench/notebook.ipynb
20:11:18.245 [debug] Start cell 1 execution @ 1684786278234 (clear output)
20:11:18.247 [debug] End cell 1 execution @ 1684786278241, started @ 1684786278234, elapsed time = 0.007s
20:11:18.248 [debug] Cell 1 executed with state Success
20:11:18.248 [debug] VSCodeNotebookController::handleExecution, Class name = Nv, completed in 20ms, has a falsy return value
20:11:19.460 [debug] VSCodeNotebookController::handleExecution, Class name = Nv (started execution)
20:11:19.462 [debug] Execute Cell 2 ~/workbench/notebook.ipynb
20:11:19.463 [debug] start the kernel, options.disableUI=false for ~/workbench/notebook.ipynb
20:11:19.486 [debug] Start cell 2 execution @ 1684786279467 (clear output)
20:11:38.639 [debug] End cell 2 execution @ 1684786298635, started @ 1684786279467, elapsed time = 19.168s
20:11:38.640 [debug] Cell 2 executed with state Success
20:11:38.640 [debug] VSCodeNotebookController::handleExecution, Class name = Nv, completed in 19180ms, has a falsy return value
20:13:05.371 [debug] VSCodeNotebookController::handleExecution, Class name = Nv (started execution)
20:13:05.372 [debug] Execute Cell 3 ~/workbench/notebook.ipynb
20:13:05.373 [debug] start the kernel, options.disableUI=false for ~/workbench/notebook.ipynb
20:13:05.382 [debug] Start cell 3 execution @ 1684786385379 (clear output)
20:13:05.388 [debug] End cell 3 execution @ 1684786385385, started @ 1684786385379, elapsed time = 0.006s
20:13:05.389 [debug] Cell 3 executed with state Success
20:13:05.389 [debug] VSCodeNotebookController::handleExecution, Class name = Nv, completed in 18ms, has a falsy return value
20:13:13.782 [debug] VSCodeNotebookController::handleExecution, Class name = Nv (started execution)
20:13:13.786 [debug] Execute Cell 4 ~/workbench/notebook.ipynb
20:13:13.786 [debug] start the kernel, options.disableUI=false for ~/workbench/notebook.ipynb
20:13:13.796 [debug] Start cell 4 execution @ 1684786393791 (clear output)
20:13:13.799 [debug] End cell 4 execution @ 1684786393796, started @ 1684786393791, elapsed time = 0.005s
20:13:13.799 [debug] Cell 4 executed with state Success
20:13:13.800 [debug] VSCodeNotebookController::handleExecution, Class name = Nv, completed in 18ms, has a falsy return value
20:17:46.062 [info] Loading webview. View is notset
20:17:46.064 [info] Loading web view...
20:17:46.065 [info] Webview panel created.

@jrich100 jrich100 reopened this May 22, 2023
@DonJayamanne
Copy link
Contributor

@jrich100 Are you still experiencing these issues?

@DonJayamanne DonJayamanne added the info-needed Issue requires more information from poster label Dec 1, 2023
@DonJayamanne DonJayamanne added notebook-execution Kernels issues (start/restart/switch/execution, install ipykernel) and removed notebook-execution labels Dec 14, 2023
@amunger amunger removed the ssh label Dec 14, 2023
@jrich100
Copy link
Author

@DonJayamanne

VS Code: 1.86.0-insider (Universal) Commit: 46a233646602c378c2168fa2a104dff8e8551558
Python: v2023.23.13481009 (Pre-Release)
Jupyter: v2024.1.2023121501 (Pre-Release)

There is definitely a huge improvement!

It took maybe 10s to execute the print() cell, which is longer than I would expect.

Also the cell execution timer says it took 0.0s which is obviously not correct.

Thanks!

@DonJayamanne
Copy link
Contributor

Please could you enable logging as follows:

  • Open settings & go into Jupyter->Logging
  • Change the value to verbose
  • Reload VS Code,
  • Attempt to repro the issue & then copy the output from the Jupyter output panel.

@tmchartrand
Copy link

I'm having the same issue, although inconsistently. I can run the same cell (in this case just an assert False) several times, and get a laggy response about half the time. I've included the logs from two consecutive executions below, with one completed in 138ms, the other 1533ms. Unfortunately nothing else seems to be different in the logs!

Jupyter extension log

debug 01:01:23.745: VSCodeNotebookController::handleExecution, Class name = O (started execution)
debug 01:01:23.746: Execute Cell 35 /root/capsule/code/abc thalamus spatial subset.ipynb
debug 01:01:23.746: start the kernel, options.disableUI=false for /root/capsule/code/abc thalamus spatial subset.ipynb
debug 01:01:23.746: Start Jupyter Session in kernel.ts with disableUI = false
info 01:01:23.883: Cancel all remaining cells true || Error || undefined
debug 01:01:23.883: Cancel pending cells
debug 01:01:23.883: Cell 35 executed with state Error
debug 01:01:23.883: VSCodeNotebookController::handleExecution, Class name = O, completed in 138ms, has a falsy return value
debug 01:01:37.944: VSCodeNotebookController::handleExecution, Class name = O (started execution)
debug 01:01:37.944: Execute Cell 35 /root/capsule/code/abc thalamus spatial subset.ipynb
debug 01:01:37.945: start the kernel, options.disableUI=false for /root/capsule/code/abc thalamus spatial subset.ipynb
debug 01:01:37.945: Start Jupyter Session in kernel.ts with disableUI = false
info 01:01:39.476: Cancel all remaining cells true || Error || undefined
debug 01:01:39.476: Cancel pending cells
debug 01:01:39.477: Cell 35 executed with state Error
debug 01:01:39.477: VSCodeNotebookController::handleExecution, Class name = O, completed in 1533ms, has a falsy return value

@vscodenpa
Copy link

Hey @DonJayamanne, this issue might need further attention.

@jrich100, you can help us out by closing this issue if the problem no longer exists, or adding more information.

@DonJayamanne
Copy link
Contributor

DonJayamanne commented Mar 12, 2024

@tmchartrand I'm sorry I missed your message.
Please can you update to the latest version and share the logs again.
Also, please try to click the run button once, I think you may be accidentally hitting it twice, perhaps because it looks like nothing is happening.

Also, please can you ensure you have disabled all extensions except Jupyter, Python and Notebook Renderer extensions.
I'd like to rule out all other extensions

@vscodenpa
Copy link

This issue has been closed automatically because it needs more information and has not had recent activity. See also our issue reporting guidelines.

Happy Coding!

@vscodenpa vscodenpa closed this as not planned Won't fix, can't repro, duplicate, stale Mar 20, 2024
@github-actions github-actions bot locked as resolved and limited conversation to collaborators May 5, 2024
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
bug Issue identified by VS Code Team member as probable bug info-needed Issue requires more information from poster notebook-execution Kernels issues (start/restart/switch/execution, install ipykernel) perf Performance issues user-setup Issues caused by incorrectly configured python environments, packages, or the like
Projects
None yet
Development

No branches or pull requests

6 participants
@DonJayamanne @amunger @jrich100 @tmchartrand @vscodenpa and others