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

Jupyter over remote ssh sometimes becomes slow and/or unresponsive #138784

Closed
murphycj2 opened this issue Nov 22, 2021 · 43 comments · Fixed by #171126
Closed

Jupyter over remote ssh sometimes becomes slow and/or unresponsive #138784

murphycj2 opened this issue Nov 22, 2021 · 43 comments · Fixed by #171126
Assignees
Labels
bug Issue identified by VS Code Team member as probable bug notebook-perf verified Verification succeeded
Milestone

Comments

@murphycj2
Copy link

Environment data

  • VS Code version: 1.62.3 (Universal)
  • Jupyter Extension version (available under the Extensions sidebar): v2021.10.1101450599
  • Python Extension version (available under the Extensions sidebar): v2021.11.1422169775
  • OS (Windows | Mac | Linux distro) and version: Mac
  • Python and/or Anaconda version: 3.7.12
  • Type of virtual environment used (N/A | venv | virtualenv | conda | ...): conda
  • Jupyter server running: Remote

Expected behaviour

I expect code cells to begin executing immediately and (for simple code snippets) to finish executing immediately (e.g. print('hello'))

Actual behaviour

Once the jupyter notebook starts to contain a non-trivial amount of stuff, I start to observe the following behaviors: (1) code cells that contain simple tasks (e.g. print('hello')) start taking several seconds or more to complete, (2) it may take several seconds or even minutes for vscode to even visually show that it will start executing a code cell, and (3) in severe cases, the whole vscode editor will become unresponsive and I will need to force shut it down.

Steps to reproduce:

Below is code for a jupyter notebook that contains a minimal example. The problems start to appear once you've cycled through the code cells 2 or 3 times. I think one of the main contributing factors to this issue are just notebooks that have a lot of "stuff" in them. To demonstrate that, the last code block prints 50 scatter plots as pngs, which the native jupyter lab server over the browser handles just fine... but vscode seems to have issues. Moreover, in this simple example I have observed instances where it starts to take a while to save the notebook and the editor may even become unresponsive.

Other things to note:

  • I am running this jupyter notebook on an AWS EC2 instance with ~90GB memory 48 CPUs (using a AWS linux ami: ami-083ac7c7ecf9bb9b0).
  • I have not yet observed these same issues when I have the kernel running on my local mac machine.
Jupyter example

# %%
# %load_ext autoreload
# %autoreload 2

import pandas as pd
import plotly.express as px
import numpy as np
import plotly.io as pio
pio.renderers.default='jupyterlab+notebook'
pd.set_option("display.max_columns", 20)

# %%
data = pd.DataFrame(np.random.random(size=(1000,1000)))

# %%
data

# %%
print('hello')

# %%
for i in range(50):
    fig = px.scatter(x=data[i], y=data[i+1])
    fig.show(renderer='png')
    

Logs

Here is a screenshot where it takes almost 3 seconds for a simple print statement. I've seen worse in some of my code related to real projects, though. This is just what I was able to reproduce with a minimal example.

Screen Shot 2021-11-22 at 4 48 27 PM

@murphycj2 murphycj2 changed the title Jupyter over remote ssh is sometimes becomes slow and/or unresponsive Jupyter over remote ssh sometimes becomes slow and/or unresponsive Nov 22, 2021
@DonJayamanne
Copy link
Contributor

Thanks for filing this issue & I'm sorry you're running into this issue, please could you try the following:

  • Check the performance withouth any data frames in the outputs
  • You can run most of the same cells, but not display the outputs (e.g. don't run the cells with data)
    I'd like to check whether the perf is a result of large outputs.

If that doesn't make any difference please could you:

  • Install VS Code Insiders
  • Go into your settings and search for Jupyter logging and change the logging level for Jupyter extension to Verbose
  • Reload VS Code
  • Try the same thing & provide the logs from the Jupyter output panel (use the command Jupyter: View Output to get to the output panel)

@murphycj2
Copy link
Author

Thanks @DonJayamanne . Not displaying large amounts of output does help. I experimented with the code a bit and here are my observations:

(1) If I disable the cell with just the data statement (but still outputting the figures), while it helps a little, I still see a significant slowdown. In particular, once I execute the code block that prints the figures (see below) and it is done, I try to execute the first code block again (containing the import statements), but there is a good 30-60 second delay until it will start to executing.

(2) If I still create the figures but no longer display them (see below), this helps immensely with performance... there are basically no issues.

for i in range(50):
    fig = px.histogram(x=data[i])
    # fig.show(renderer='png')

(3) While still hiding the figures as in (2), but I go back to displaying data and increasing the number of columns and rows of data that are display to 200 (see below), then there is small decrease in performance.

pd.set_option("display.max_columns", 200)
pd.set_option("display.max_rows", 200)

(4) If I do the same as (3), but take it even further by displaying 500 rows and columns of data, then performance significantly degrades (see the screenshot). Once it was done displaying data, it took ~19 seconds for a print statement, ~35 seconds to make the figures (normally takes a ~3-5 seconds), and it took over a minute to execute that last empty cell (the execution time was hidden once it was done).

Screen Shot 2021-11-23 at 10 14 06 AM

@DonJayamanne
Copy link
Contributor

DonJayamanne commented Nov 29, 2021

Thanks for the feedback.

Please could you:

  • Install VS Code Insiders
  • Go into your settings and search for Jupyter logging and change the logging level for Jupyter extension to Verbose
  • Reload VS Code
  • Run the command Measure Extension Host Latency
    • An editor will be opened with some text such as Roundtrip latency...., please copy all of that & paste that into this issue
  • Try simple print statements (without any data frames, and figures, lets keep it very simple)
    • Please ensure the notebook is empty & does not contain any outputs only 1-2 cells with simple print statements.
  • Provide the logs from the Jupyter output panel (use the command Jupyter: View Output to get to the output panel)
  • Reload VS Code
  • Repeat what you did earlier steps you
  • Provide the logs from the Jupyter output panel (use the command Jupyter: View Output to get to the output panel)

Please note, I'll need two logs, one without any data frames, no plots, just print statements.
The other is the one with dataframes and the like.

@murphycj2
Copy link
Author

murphycj2 commented Dec 3, 2021

Here is the text for Measure Extension Host Latency:

Roundtrip latency: 4.120ms
Up: 1091.4 Mbps
Down: 534.4 Mbps


Authority: ssh-remote+jupyter
Roundtrip latency: 87.140ms
Up: 23.9 Mbps
Down: 330.4 Mbps

Even if vscode totally froze up while trying to run something in jupyter, I was still always able to ssh into the server no problem.

Here is the Jupyter output from just the print statements:

Log
Jupyter Extension Version: 2021.11.1001532801.
Python Extension Verison: 2021.11.1422169775.
Info 18:03:26: Experimentation service retrieved: [object Object]
User belongs to experiment group 'jupyterTestcf'
User belongs to experiment group 'jupyterEnhancedDataViewer'
Info 18:03:26: ZMQ install verified.
Verbose 18:03:26: Get Kernelspec root path, Class name = m (started execution), Arg 1: {"_isCancelled":false,"_emitter":null}
Verbose 18:03:26: Get Jupyter Paths, Class name = m (started execution), Arg 1: {"_isCancelled":false,"_emitter":null}
Verbose 18:03:26: Get Custom Env Variables, Class name = m (started execution), 
Verbose 18:03:26: Get Interpreters, Class name = P (started execution), Arg 1: undefined
Verbose 18:03:26: Get Interpreters, Class name = P (started execution), Arg 1: undefined
Verbose 18:03:26: Find kernel spec, Class name = x (started execution), Arg 1: "/data/test.ipynb", Arg 2: {"interpreter":{"hash":"1b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a"},"kernelspec":{"display_name":"Python 3.7.10 64-bit ('base': conda)","language":"python","name":"python3"},"language_info":{"codemirror_mode":{"name":"ipython","version":3},"file_extension":".py","mimetype":"text/x-python","name":"python","nbconvert_exporter":"python","pygments_lexer":"ipython3","version":"3.7.10"},"orig_nbformat":4}, Arg 3: ""
Verbose 18:03:26: Get Interpreters, Class name = P (started execution), Arg 1: "/data/test.ipynb"
Verbose 18:03:26: Get Custom Env Variables, Class name = m (started execution), Arg 1: undefined
Verbose 18:03:26: Get Custom Env Variables, Class name = m (started execution), Arg 1: "/data"
Verbose 18:03:26: Get Active Interpreter, Class name = P (started execution), Arg 1: "/data"
Verbose 18:03:26: Get Active Interpreter, Class name = P (started execution), Arg 1: "/data/test.ipynb"
Verbose 18:03:26: Get Active Interpreter, Class name = P (started execution), Arg 1: "/data"
Verbose 18:03:26: Get Custom Env Variables, Class name = m, completed in 25ms, has a truthy return value, 
Verbose 18:03:26: Get Custom Env Variables, Class name = m, completed in 12ms, has a truthy return value, Arg 1: undefined
Verbose 18:03:26: Get Jupyter Paths, Class name = m, completed in 25ms, has a truthy return value, Arg 1: {"_isCancelled":false,"_emitter":null}, Return Value: []
Verbose 18:03:26: Get Kernelspec root path, Class name = m, completed in 26ms, has a truthy return value, Arg 1: {"_isCancelled":false,"_emitter":null}, Return Value: ["/usr/share/jupyter/kernels","/usr/local/share/jupyter/kernels","/home/ec2-user/.local/share/jupyter/kernels"]
Verbose 18:03:26: Get Active Interpreter, Class name = P (started execution), Arg 1: undefined
Verbose 18:03:26: Get Interpreters, Class name = P (started execution), 
Info 18:03:26: Freferred kernel connection found in cache .jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a./data/tools/miniconda3/python./data/tools/miniconda3/python.-m#ipykernel_launcher
Verbose 18:03:26: Find kernel spec, Class name = x, completed in 244ms, has a truthy return value, Arg 1: "/data/test.ipynb", Arg 2: {"interpreter":{"hash":"1b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a"},"kernelspec":{"display_name":"Python 3.7.10 64-bit ('base': conda)","language":"python","name":"python3"},"language_info":{"codemirror_mode":{"name":"ipython","version":3},"file_extension":".py","mimetype":"text/x-python","name":"python","nbconvert_exporter":"python","pygments_lexer":"ipython3","version":"3.7.10"},"orig_nbformat":4}, Arg 3: ""
Info 18:03:26: PreferredConnection: .jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a./data/tools/miniconda3/python./data/tools/miniconda3/python.-m#ipykernel_launcher found for NotebookDocument: /data/test.ipynb
Info 18:03:26: Early registration of controller for Kernel connection .jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a./data/tools/miniconda3/python./data/tools/miniconda3/python.-m#ipykernel_launcher
Info 18:03:26: TargetController found ID: .jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a./data/tools/miniconda3/python./data/tools/miniconda3/python.-m#ipykernel_launcher for document /data/test.ipynb
Info 18:03:26: Setting controller affinity for /data/test.ipynb .jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a./data/tools/miniconda3/python./data/tools/miniconda3/python.-m#ipykernel_launcher
Verbose 18:03:26: Get Custom Env Variables, Class name = m, completed in 236ms, has a truthy return value, Arg 1: "/data"
Info 18:03:26: Experiment status for python is {"enabled":true,"optInto":[],"optOutFrom":[]}
Verbose 18:03:26: Loading kernelspec from ~/.local/share/jupyter/kernels/rundigest/kernel.json for 
Verbose 18:03:26: Loading kernelspec from ~/.local/share/jupyter/kernels/mosaic/kernel.json for 
Info 18:03:26: Setting setActiveController for /data/test.ipynb
Verbose 18:03:26: Intiailize notebook communications for editor /data/test.ipynb
Verbose 18:03:26: Resolving notebook UI Comms (resolve) for /data/test.ipynb
Verbose 18:03:26: initialize CommonMessageCoordinator
Verbose 18:03:26: Create activated Env, Class name = k (started execution), Arg 1: ""
Verbose 18:03:26: Getting activated env variables, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:03:26: Getting activated env variables ourselves, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Create ProcessService, Class name = p (started execution), Arg 1: undefined
Verbose 18:03:26: Get Custom Env Variables, Class name = m (started execution), Arg 1: undefined
Verbose 18:03:26: Cached data exists getEnvironmentVariables, <No Resource>
Verbose 18:03:26: Getting env activation commands, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Getting activated env variables from Python, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Info 18:03:26: KernelProvider switched kernel to id = .jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a./data/tools/miniconda3/python./data/tools/miniconda3/python.-m#ipykernel_launcher
Info 18:03:26: Starting Notebook in kernel.ts id = .jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a./data/tools/miniconda3/python./data/tools/miniconda3/python.-m#ipykernel_launcher
Info 18:03:26: Creating raw notebook for /data/test.ipynb
Info 18:03:26: Getting preferred kernel for /data/test.ipynb
Info 18:03:26: Computing working directory /data/test.ipynb
Verbose 18:03:26: Registering commtarget jupyter.widget
Verbose 18:03:26: IPyWidgetMessageDispatcher.initialize
Verbose 18:03:26: Get Custom Env Variables, Class name = m, completed in 3ms, has a truthy return value, Arg 1: undefined
Verbose 18:03:26: Create ProcessService, Class name = p, completed in 4ms, has a truthy return value, Arg 1: undefined
Verbose 18:03:26: Created and initailized CommonMessageCoordinator
Verbose 18:03:26: Attach Coordinator for /data/test.ipynb
Verbose 18:03:26: Getting env activation commands, Class name = I, completed in 5ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Getting activated env variables ourselves, Class name = I, completed in 6ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Got env vars ourselves faster, but empty /data/tools/miniconda3/bin/python
Verbose 18:03:26: Got env vars ourselves /data/tools/miniconda3/bin/python in 6ms
Verbose 18:03:26: Connecting to raw session for /data/test.ipynb with connection .jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a./data/tools/miniconda3/python./data/tools/miniconda3/python.-m#ipykernel_launcher
Info 18:03:26: Starting raw kernel base (Python 3.7.10)
Info 18:03:26: installMissingDependencies /data/tools/miniconda3/bin/python
Info 18:03:26: IPykernel found previously in this enviornment /data/tools/miniconda3/bin/python
Verbose 18:03:26: Install Missing Dependencies, Class name = k, completed in 0ms, has a falsy return value, Return Value: undefined
Info 18:03:26: Kernel launching with ports 9010,9011,9012,9013,9014. Start port is 9000
Verbose 18:03:26: Launching kernel in kernelProcess.ts, Class name = E (started execution), Arg 1: "/data", Arg 2: ""
Verbose 18:03:26: Launching kernel daemon, Class name = m (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data", Arg 3: "python3710jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a", Arg 4: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Get kernel daemon, Class name = y (started execution), Arg 1: "/data/test.ipynb", Arg 2: undefined, Arg 3: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Create daemon, Class name = k (started execution), Arg 1: {"daemonModule":"vscode_datascience_helpers.kernel_launcher_daemon","interpreter":{"sysPrefix":"/data/tools/miniconda3","envType":"Conda","envName":"base","envPath":"/data/tools/miniconda3","path":"/data/tools/miniconda3/bin/python","architecture":3,"sysVersion":"3.7.10 (default, Jun  4 2021, 14:48:32) \n[GCC 7.5.0]","version":{"raw":"3.7.10","major":3,"minor":7,"patch":10,"build":[],"prerelease":["final","0"]},"companyDisplayName":"Anaconda, Inc.","displayName":"Python 3.7.10 64-bit ('base': conda)"},"dedicated":true,"resource":{"$mid":1,"fsPath":"/data/test.ipynb","external":"file:///data/test.ipynb","path":"/data/test.ipynb","scheme":"file"}}
Verbose 18:03:26: Create activated Env, Class name = k (started execution), Arg 1: ""
Verbose 18:03:26: Getting activated env variables, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:03:26: Getting activated env variables ourselves, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Create ProcessService, Class name = p (started execution), Arg 1: "/data/test.ipynb"
Verbose 18:03:26: Get Custom Env Variables, Class name = m (started execution), Arg 1: "/data/test.ipynb"
Verbose 18:03:26: Cached data exists getEnvironmentVariables, /data/test.ipynb
Verbose 18:03:26: Getting env activation commands, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Getting activated env variables from Python, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Getting activated env variables, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:03:26: Getting activated env variables ourselves, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Getting activated env variables from Python, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Info 18:03:26: Launching kernel daemon for Python 3.7.10 64-bit ('base': conda) # /data/tools/miniconda3/bin/python
Verbose 18:03:26: Getting activated env variables, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: false
Verbose 18:03:26: Getting activated env variables ourselves, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Getting activated env variables from Python, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Get Custom Env Variables, Class name = m, completed in 2ms, has a truthy return value, Arg 1: "/data/test.ipynb"
Verbose 18:03:26: Create ProcessService, Class name = p, completed in 3ms, has a truthy return value, Arg 1: "/data/test.ipynb"
Verbose 18:03:26: Getting env activation commands, Class name = I, completed in 3ms, has a falsy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Getting activated env variables ourselves, Class name = I, completed in 3ms, has a falsy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Getting activated env variables ourselves, Class name = I, completed in 2ms, has a falsy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Getting activated env variables ourselves, Class name = I, completed in 2ms, has a falsy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:26: Got env vars ourselves faster, but empty /data/tools/miniconda3/bin/python
Verbose 18:03:26: Got env vars ourselves faster, but empty /data/tools/miniconda3/bin/python
Verbose 18:03:26: Got env vars ourselves faster, but empty /data/tools/miniconda3/bin/python
Verbose 18:03:26: Got env vars ourselves /data/tools/miniconda3/bin/python in 4ms
Verbose 18:03:26: Got env vars ourselves /data/tools/miniconda3/bin/python in 3ms
Verbose 18:03:26: Got env vars ourselves /data/tools/miniconda3/bin/python in 3ms
Verbose 18:03:26: Get Interpreters, Class name = P, completed in 639ms, has a truthy return value, Arg 1: undefined
Verbose 18:03:26: Getting Jupyter KernelSpec Root Path, Class name = m (started execution), 
Verbose 18:03:26: Get Active Interpreter, Class name = P (started execution), Arg 1: undefined
Verbose 18:03:26: Get Kernelspec root path, Class name = m (started execution), 
Verbose 18:03:26: Get Jupyter Paths, Class name = m (started execution), Arg 1: undefined
Verbose 18:03:26: Get Kernelspec root path, Class name = m (started execution), Arg 1: {"_isCancelled":false,"_emitter":null}
Verbose 18:03:26: Get Jupyter Paths, Class name = m (started execution), Arg 1: {"_isCancelled":false,"_emitter":null}
Verbose 18:03:26: Get Interpreters, Class name = P, completed in 639ms, has a truthy return value, Arg 1: undefined
Verbose 18:03:26: Getting Jupyter KernelSpec Root Path, Class name = m (started execution), 
Verbose 18:03:26: Get Active Interpreter, Class name = P (started execution), Arg 1: undefined
Verbose 18:03:26: Get Kernelspec root path, Class name = m (started execution), 
Verbose 18:03:26: Get Jupyter Paths, Class name = m (started execution), Arg 1: undefined
Verbose 18:03:26: Get Interpreters, Class name = P, completed in 637ms, has a truthy return value, Arg 1: "/data/test.ipynb"
Verbose 18:03:26: Getting Jupyter KernelSpec Root Path, Class name = m (started execution), 
Verbose 18:03:26: Get Active Interpreter, Class name = P (started execution), Arg 1: "/data/test.ipynb"
Verbose 18:03:26: Get Kernelspec root path, Class name = m (started execution), 
Verbose 18:03:26: Get Jupyter Paths, Class name = m (started execution), Arg 1: undefined
Verbose 18:03:26: Get Interpreters, Class name = P, completed in 549ms, has a truthy return value, 
Verbose 18:03:26: Getting Jupyter KernelSpec Root Path, Class name = m, completed in 2ms, has a truthy return value, , Return Value: "/home/ec2-user/.local/share/jupyter/kernels"
Verbose 18:03:26: Getting Jupyter KernelSpec Root Path, Class name = m, completed in 2ms, has a truthy return value, , Return Value: "/home/ec2-user/.local/share/jupyter/kernels"
Verbose 18:03:26: Getting Jupyter KernelSpec Root Path, Class name = m, completed in 1ms, has a truthy return value, , Return Value: "/home/ec2-user/.local/share/jupyter/kernels"
Verbose 18:03:26: Get Jupyter Paths, Class name = m, completed in 3ms, has a truthy return value, Arg 1: undefined, Return Value: []
Verbose 18:03:26: Get Jupyter Paths, Class name = m, completed in 3ms, has a truthy return value, Arg 1: {"_isCancelled":false,"_emitter":null}, Return Value: []
Verbose 18:03:26: Get Jupyter Paths, Class name = m, completed in 3ms, has a truthy return value, Arg 1: undefined, Return Value: []
Verbose 18:03:26: Get Jupyter Paths, Class name = m, completed in 2ms, has a truthy return value, Arg 1: undefined, Return Value: []
Verbose 18:03:26: Get Kernelspec root path, Class name = m, completed in 3ms, has a truthy return value, , Return Value: ["/usr/share/jupyter/kernels","/usr/local/share/jupyter/kernels","/home/ec2-user/.local/share/jupyter/kernels"]
Verbose 18:03:26: Get Kernelspec root path, Class name = m, completed in 3ms, has a truthy return value, Arg 1: {"_isCancelled":false,"_emitter":null}, Return Value: ["/usr/share/jupyter/kernels","/usr/local/share/jupyter/kernels","/home/ec2-user/.local/share/jupyter/kernels"]
Verbose 18:03:26: Get Kernelspec root path, Class name = m, completed in 4ms, has a truthy return value, , Return Value: ["/usr/share/jupyter/kernels","/usr/local/share/jupyter/kernels","/home/ec2-user/.local/share/jupyter/kernels"]
Verbose 18:03:26: Get Kernelspec root path, Class name = m, completed in 3ms, has a truthy return value, , Return Value: ["/usr/share/jupyter/kernels","/usr/local/share/jupyter/kernels","/home/ec2-user/.local/share/jupyter/kernels"]
Info 18:03:26: Creating controller for jupyter-notebook with interpreter /data/tools/miniconda3/envs/rundigest/bin/python
Verbose 18:03:26: Get Active Interpreter, Class name = P, completed in 645ms, has a truthy return value, Arg 1: "/data"
Verbose 18:03:26: Get Active Interpreter, Class name = P, completed in 645ms, has a truthy return value, Arg 1: "/data/test.ipynb"
Verbose 18:03:26: Get Active Interpreter, Class name = P, completed in 645ms, has a truthy return value, Arg 1: "/data"
Verbose 18:03:26: Get Active Interpreter, Class name = P, completed in 19ms, has a truthy return value, Arg 1: "/data/test.ipynb"
Verbose 18:03:26: Get Active Interpreter, Class name = P, completed in 572ms, has a truthy return value, Arg 1: undefined
Verbose 18:03:26: Create activated Env, Class name = k (started execution), Arg 1: ""
Verbose 18:03:26: Getting activated env variables, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python", Arg 3: true
Verbose 18:03:26: Getting activated env variables ourselves, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:03:26: Create ProcessService, Class name = p (started execution), Arg 1: undefined
Verbose 18:03:26: Get Custom Env Variables, Class name = m (started execution), Arg 1: undefined
Verbose 18:03:26: Cached data exists getEnvironmentVariables, <No Resource>
Verbose 18:03:26: Getting env activation commands, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:03:26: Getting activated env variables from Python, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:03:26: Get Active Interpreter, Class name = P, completed in 26ms, has a truthy return value, Arg 1: undefined
Verbose 18:03:26: Get Active Interpreter, Class name = P, completed in 25ms, has a truthy return value, Arg 1: undefined
Verbose 18:03:26: Get Custom Env Variables, Class name = m, completed in 1ms, has a truthy return value, Arg 1: undefined
Verbose 18:03:26: Create ProcessService, Class name = p, completed in 2ms, has a truthy return value, Arg 1: undefined
Verbose 18:03:26: Getting env activation commands, Class name = I, completed in 1ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:03:26: Getting activated env variables ourselves, Class name = I, completed in 2ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:03:26: Got env vars ourselves faster, but empty /data/tools/miniconda3/envs/rundigest/bin/python
Verbose 18:03:26: Got env vars ourselves /data/tools/miniconda3/envs/rundigest/bin/python in 2ms
Info 18:03:27: Registering dummy command feature
Verbose 18:03:27: Loading kernelspec from /data/tools/miniconda3/share/jupyter/kernels/python3/kernel.json for /data/tools/miniconda3/bin/python
Verbose 18:03:27: Loading kernelspec from /data/tools/miniconda3/envs/rundigest/share/jupyter/kernels/python3/kernel.json for /data/tools/miniconda3/envs/rundigest/bin/python
Verbose 18:03:27: Loading kernelspec from /data/tools/miniconda3/envs/mosaic/share/jupyter/kernels/python3/kernel.json for /data/tools/miniconda3/envs/mosaic/bin/python
Verbose 18:03:27: Getting env activation commands, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Getting activated env variables from Python, Class name = I, completed in 537ms, has a truthy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Got env vars with python /data/tools/miniconda3/bin/python in 538ms
Verbose 18:03:27: Getting env activation commands, Class name = I, completed in 0ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Getting activated env variables, Class name = I, completed in 539ms, has a truthy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:03:27: Create activated Env, Class name = k, completed in 540ms, has a truthy return value, Arg 1: ""
Info 18:03:27: Process Execution: > /data/tools/miniconda3/bin/python -m pip list
> /data/tools/miniconda3/bin/python -m pip list
Verbose 18:03:27: Getting env activation commands, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Getting activated env variables from Python, Class name = I, completed in 530ms, has a truthy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Got env vars with python /data/tools/miniconda3/bin/python in 531ms
Verbose 18:03:27: Getting env activation commands, Class name = I, completed in 1ms, has a falsy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Getting activated env variables, Class name = I, completed in 532ms, has a truthy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:03:27: Getting env activation commands, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Getting activated env variables from Python, Class name = I, completed in 532ms, has a truthy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Got env vars with python /data/tools/miniconda3/bin/python in 532ms
Verbose 18:03:27: Getting env activation commands, Class name = I, completed in 1ms, has a falsy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Getting activated env variables, Class name = I, completed in 533ms, has a truthy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: false
Verbose 18:03:27: Getting env activation commands, Class name = I (started execution), Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Getting activated env variables from Python, Class name = I, completed in 536ms, has a truthy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Got env vars with python /data/tools/miniconda3/bin/python in 536ms
Verbose 18:03:27: Getting env activation commands, Class name = I, completed in 0ms, has a falsy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:03:27: Getting activated env variables, Class name = I, completed in 536ms, has a truthy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:03:27: Create activated Env, Class name = k, completed in 537ms, has a truthy return value, Arg 1: ""
Info 18:03:27: Creating daemon process for /data/tools/miniconda3/bin/python with env variables count 48
Info 18:03:27: Process Execution: > /data/tools/miniconda3/bin/python -m vscode_datascience_helpers.daemon --daemon-module=vscode_datascience_helpers.kernel_launcher_daemon -v
> /data/tools/miniconda3/bin/python -m vscode_datascience_helpers.daemon --daemon-module=vscode_datascience_helpers.kernel_launcher_daemon -v
Verbose 18:03:27: Daemon is alive
Verbose 18:03:27: Create daemon, Class name = k, completed in 594ms, has a truthy return value, Arg 1: {"daemonModule":"vscode_datascience_helpers.kernel_launcher_daemon","interpreter":{"sysPrefix":"/data/tools/miniconda3","envType":"Conda","envName":"base","envPath":"/data/tools/miniconda3","path":"/data/tools/miniconda3/bin/python","architecture":3,"sysVersion":"3.7.10 (default, Jun  4 2021, 14:48:32) \n[GCC 7.5.0]","version":{"raw":"3.7.10","major":3,"minor":7,"patch":10,"build":[],"prerelease":["final","0"]},"companyDisplayName":"Anaconda, Inc.","displayName":"Python 3.7.10 64-bit ('base': conda)"},"dedicated":true,"resource":{"$mid":1,"fsPath":"/data/test.ipynb","external":"file:///data/test.ipynb","path":"/data/test.ipynb","scheme":"file"}}
Verbose 18:03:27: Get kernel daemon, Class name = y, completed in 595ms, has a truthy return value, Arg 1: "/data/test.ipynb", Arg 2: undefined, Arg 3: "/data/tools/miniconda3/bin/python"
Info 18:03:27: Starting kernel from scratch
Verbose 18:03:27: Python Daemon (pid: 12107): Execute rpc method exec_module in DS Daemon
Verbose 18:03:27: Python Daemon (pid: 12107): Execute rpc method exec_module from /data/tools/miniconda3/bin/python
Verbose 18:03:27: Python Daemon (pid: 12107): Exec module in DS Kernel Launcher Daemon ipykernel_launcher with args ['--ip=127.0.0.1', '--stdin=9013', '--control=9011', '--hb=9010', '--Session.signature_scheme="hmac-sha256"', '--Session.key=b"b2acf7c1-d3d6-4e9a-8cbc-1eef064c7eed"', '--shell=9012', '--transport="tcp"', '--iopub=9014', '--f=/tmp/tmp-11945gRFtwuoT9Cex.json']
Verbose 18:03:27: Python Daemon (pid: 12107): Exec in DS Kernel Launcher Daemon (observable) ipykernel_launcher with args ['--ip=127.0.0.1', '--stdin=9013', '--control=9011', '--hb=9010', '--Session.signature_scheme="hmac-sha256"', '--Session.key=b"b2acf7c1-d3d6-4e9a-8cbc-1eef064c7eed"', '--shell=9012', '--transport="tcp"', '--iopub=9014', '--f=/tmp/tmp-11945gRFtwuoT9Cex.json']
Verbose 18:03:27: Python Daemon (pid: 12107): Exec in DS Kernel Launcher Daemon (observable) ['/data/tools/miniconda3/bin/python', '-m', 'ipykernel_launcher', '--ip=127.0.0.1', '--stdin=9013', '--control=9011', '--hb=9010', '--Session.signature_scheme="hmac-sha256"', '--Session.key=b"b2acf7c1-d3d6-4e9a-8cbc-1eef064c7eed"', '--shell=9012', '--transport="tcp"', '--iopub=9014', '--f=/tmp/tmp-11945gRFtwuoT9Cex.json']
Verbose 18:03:27: Python Daemon (pid: 12107): Exec in DS Kernel Launcher Daemon (observable)
Verbose 18:03:27: Python Daemon (pid: 12107): Kernel launched, with PID 12116
Verbose 18:03:27: Python Daemon (pid: 12107): Waiting for Kernel to die 12116
Verbose 18:03:27: Launching kernel daemon, Class name = m, completed in 599ms, has a truthy return value, Arg 1: "/data/test.ipynb", Arg 2: "/data", Arg 3: "python3710jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a", Arg 4: "/data/tools/miniconda3/bin/python", Return Value: <Return value cannot be serialized for logging>
Verbose 18:03:27: Launching kernel in kernelProcess.ts, Class name = E, completed in 599ms, has a truthy return value, Arg 1: "/data", Arg 2: ""
Verbose 18:03:27: Getting env activation commands, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:03:27: Getting activated env variables from Python, Class name = I, completed in 679ms, has a truthy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:03:27: Got env vars with python /data/tools/miniconda3/envs/rundigest/bin/python in 680ms
Verbose 18:03:27: Getting env activation commands, Class name = I, completed in 0ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:03:27: Getting activated env variables, Class name = I, completed in 680ms, has a truthy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python", Arg 3: true
Verbose 18:03:27: Create activated Env, Class name = k, completed in 681ms, has a truthy return value, Arg 1: ""
Info 18:03:27: Process Execution: > /data/tools/miniconda3/envs/rundigest/bin/python -m pip list
> /data/tools/miniconda3/envs/rundigest/bin/python -m pip list
Verbose 18:03:27: IPyWidgetMessageDispatcher.initialize
Verbose 18:03:27: Hiding default kernel spec Python 3 (ipykernel), /data/tools/miniconda3/bin/python
Verbose 18:03:27: Hiding default kernel spec Python 3 (ipykernel), python
Verbose 18:03:27: Kernel python3710jvsc74a57bd005327433ee0e98a55ee8a734c87123dc45b88cbb9095fa6e0ab3b7b62420e31a matches Python 3.7.10 64-bit ('mosaic': conda) based on path in argv.
Verbose 18:03:27: Hiding default kernel spec Python 3 (ipykernel), /data/tools/miniconda3/bin/python
Verbose 18:03:27: Hiding default kernel spec Python 3 (ipykernel), python
Verbose 18:03:27: Kernel python3710jvsc74a57bd005327433ee0e98a55ee8a734c87123dc45b88cbb9095fa6e0ab3b7b62420e31a matches Python 3.7.10 64-bit ('mosaic': conda) based on path in argv.
Verbose 18:03:27: Hiding default kernel spec Python 3 (ipykernel), /data/tools/miniconda3/bin/python
Verbose 18:03:27: Hiding default kernel spec Python 3 (ipykernel), python
Verbose 18:03:27: Kernel python3710jvsc74a57bd005327433ee0e98a55ee8a734c87123dc45b88cbb9095fa6e0ab3b7b62420e31a matches Python 3.7.10 64-bit ('mosaic': conda) based on path in argv.
Verbose 18:03:27: KernelProcess output: Content-Length: 358
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "log", "params": {"level": "INFO", "msg": "write to stderr: /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2205: 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.\n", "pid": 12107}}Content-Length: 333
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "output", "params": {"source": "stderr", "out": "/data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2205: 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.\n"}}
Verbose 18:03:27: Python Daemon (pid: 12107): write to stderr: /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2205: 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.

Verbose 18:03:27: KernelProcess output: Content-Length: 124
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "log", "params": {"level": "INFO", "msg": "write to stderr:   FutureWarning)\n", "pid": 12107}}Content-Length: 99
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "output", "params": {"source": "stderr", "out": "  FutureWarning)\n"}}Content-Length: 370
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "log", "params": {"level": "INFO", "msg": "write to stderr: /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2160: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use 'b2acf7c1-d3d6-4e9a-8cbc-1eef064c7eed' instead of 'b\"b2acf7c1-d3d6-4e9a-8cbc-1eef064c7eed\"'.\n", "pid": 12107}}Content-Length: 345
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "output", "params": {"source": "stderr", "out": "/data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2160: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use 'b2acf7c1-d3d6-4e9a-8cbc-1eef064c7eed' instead of 'b\"b2acf7c1-d3d6-4e9a-8cbc-1eef064c7eed\"'.\n"}}Content-Length: 124
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "log", "params": {"level": "INFO", "msg": "write to stderr:   FutureWarning)\n", "pid": 12107}}Content-Length: 99
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "output", "params": {"source": "stderr", "out": "  FutureWarning)\n"}}
Warn 18:03:27: StdErr from Kernel Process /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2205: 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.

Verbose 18:03:27: Python Daemon (pid: 12107): write to stderr:   FutureWarning)

Warn 18:03:27: StdErr from Kernel Process   FutureWarning)

Verbose 18:03:27: Python Daemon (pid: 12107): write to stderr: /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2160: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use 'b2acf7c1-d3d6-4e9a-8cbc-1eef064c7eed' instead of 'b"b2acf7c1-d3d6-4e9a-8cbc-1eef064c7eed"'.

Warn 18:03:27: StdErr from Kernel Process /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2160: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use 'b2acf7c1-d3d6-4e9a-8cbc-1eef064c7eed' instead of 'b"b2acf7c1-d3d6-4e9a-8cbc-1eef064c7eed"'.

Verbose 18:03:27: Python Daemon (pid: 12107): write to stderr:   FutureWarning)

Warn 18:03:27: StdErr from Kernel Process   FutureWarning)

Info 18:03:27: Raw session started and connected
Started kernel base (Python 3.7.10)
Info 18:03:27: Finished connecting 145935dd-10ce-4c20-93ac-956f60a056b3
Info 18:03:27: UpdateWorkingDirectoryAndPath in Kernel
Info 18:03:28: changeDirectoryIfPossible
Info 18:03:28: Executing (status idle) silently Code = import os\nimport sys\n%cd "/data"\nif os.getcwd() not in sys.path:\nsys.path.insert(0, os.getcwd())
Info 18:03:28: Executing silently Code (completed) = import os\nimport sys\n%cd "/data"\nif os.getcwd() not in sys.path:\nsys.path.insert(0, os.getcwd())
Info 18:03:28: Executing (status idle) silently Code = %config Completer.use_jedi = False
Info 18:03:28: Executing silently Code (completed) = %config Completer.use_jedi = False
Info 18:03:28: Executing (status idle) silently Code = %config InlineBackend.figure_formats = ['png']
Info 18:03:28: Executing silently Code (completed) = %config InlineBackend.figure_formats = ['png']
Info 18:03:28: Executing (status idle) silently Code = import sys\nprint(sys.executable)
Info 18:03:28: Executing silently Code (completed) = import sys\nprint(sys.executable)
Info 18:03:28: Waiting for idle on (kernel): 4efd271a-e33b-4baf-acf4-29a2679282a6 -> idle
Info 18:03:28: Finished waiting for idle on (kernel): 4efd271a-e33b-4baf-acf4-29a2679282a6 -> idle
Verbose 18:03:28: IPyWidgetMessageDispatcher.initialize
Info 18:03:28: IPyWidgetScriptSource.initialize
Verbose 18:03:28: IPyWidgetMessageDispatcher.initialize
Verbose 18:03:28: IPyWidgetMessageDispatcher.initialize
Info 18:03:28: Registering dummy command feature
Info 18:03:32: Execute Cells request 0
Info 18:03:32: Execute Cell 0 /data/test.ipynb
Info 18:03:32: Cell 0 executed with state Success
Info 18:03:35: Execute Cells request 0
Info 18:03:35: Execute Cell 0 /data/test.ipynb
Info 18:03:35: Cell 0 executed with state Success

Here is the Jupyter output where I print large dataframes and plots:

Log
Jupyter Extension Version: 2021.11.1001532801.
Python Extension Verison: 2021.11.1422169775.
Info 18:08:29: Experimentation service retrieved: [object Object]
User belongs to experiment group 'jupyterTestcf'
User belongs to experiment group 'jupyterEnhancedDataViewer'
Info 18:08:29: ZMQ install verified.
Verbose 18:08:30: Get Custom Env Variables, Class name = m (started execution), Arg 1: undefined
Verbose 18:08:30: Get Custom Env Variables, Class name = m (started execution), Arg 1: "/data"
Verbose 18:08:30: Get Active Interpreter, Class name = P (started execution), Arg 1: "/data"
Verbose 18:08:30: Get Custom Env Variables, Class name = m, completed in 169ms, has a truthy return value, Arg 1: undefined
Verbose 18:08:30: Get Custom Env Variables, Class name = m, completed in 387ms, has a truthy return value, Arg 1: "/data"
Verbose 18:08:30: Get Active Interpreter, Class name = P (started execution), Arg 1: undefined
Verbose 18:08:30: Get Interpreters, Class name = P (started execution), 
Info 18:08:30: Experiment status for python is {"enabled":true,"optInto":[],"optOutFrom":[]}
Verbose 18:08:31: Get Kernelspec root path, Class name = m (started execution), Arg 1: {"_isCancelled":false,"_emitter":null}
Verbose 18:08:31: Get Jupyter Paths, Class name = m (started execution), Arg 1: {"_isCancelled":false,"_emitter":null}
Verbose 18:08:31: Get Custom Env Variables, Class name = m (started execution), 
Verbose 18:08:31: Cached data exists getEnvironmentVariables, <No Resource>
Verbose 18:08:31: Get Interpreters, Class name = P (started execution), Arg 1: undefined
Verbose 18:08:31: Get Interpreters, Class name = P (started execution), Arg 1: undefined
Verbose 18:08:31: Find kernel spec, Class name = x (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: {"orig_nbformat":4,"language_info":{"name":"python"}}, Arg 3: ""
Verbose 18:08:31: Get Interpreters, Class name = P (started execution), Arg 1: "Untitled-1.ipynb"
Verbose 18:08:31: Get Active Interpreter, Class name = P (started execution), Arg 1: "/data"
Verbose 18:08:31: Get Active Interpreter, Class name = P (started execution), Arg 1: "Untitled-1.ipynb"
Verbose 18:08:31: Get Custom Env Variables, Class name = m, completed in 4ms, has a truthy return value, 
Verbose 18:08:31: Get Jupyter Paths, Class name = m, completed in 7ms, has a truthy return value, Arg 1: {"_isCancelled":false,"_emitter":null}, Return Value: []
Verbose 18:08:31: Get Kernelspec root path, Class name = m, completed in 7ms, has a truthy return value, Arg 1: {"_isCancelled":false,"_emitter":null}, Return Value: ["/usr/share/jupyter/kernels","/usr/local/share/jupyter/kernels","/home/ec2-user/.local/share/jupyter/kernels"]
Verbose 18:08:31: Get Interpreters, Class name = P, completed in 463ms, has a truthy return value, 
Verbose 18:08:31: Get Interpreters, Class name = P, completed in 54ms, has a truthy return value, Arg 1: undefined
Verbose 18:08:31: Getting Jupyter KernelSpec Root Path, Class name = m (started execution), 
Verbose 18:08:31: Get Active Interpreter, Class name = P (started execution), Arg 1: undefined
Verbose 18:08:31: Get Kernelspec root path, Class name = m (started execution), 
Verbose 18:08:31: Get Jupyter Paths, Class name = m (started execution), Arg 1: undefined
Verbose 18:08:31: Get Kernelspec root path, Class name = m (started execution), Arg 1: {"_isCancelled":false,"_emitter":null}
Verbose 18:08:31: Get Jupyter Paths, Class name = m (started execution), Arg 1: {"_isCancelled":false,"_emitter":null}
Verbose 18:08:31: Get Interpreters, Class name = P, completed in 54ms, has a truthy return value, Arg 1: undefined
Verbose 18:08:31: Getting Jupyter KernelSpec Root Path, Class name = m (started execution), 
Verbose 18:08:31: Get Active Interpreter, Class name = P (started execution), Arg 1: undefined
Verbose 18:08:31: Get Kernelspec root path, Class name = m (started execution), 
Verbose 18:08:31: Get Jupyter Paths, Class name = m (started execution), Arg 1: undefined
Verbose 18:08:31: Get Interpreters, Class name = P, completed in 54ms, has a truthy return value, Arg 1: "Untitled-1.ipynb"
Verbose 18:08:31: Getting Jupyter KernelSpec Root Path, Class name = m (started execution), 
Verbose 18:08:31: Get Active Interpreter, Class name = P (started execution), Arg 1: "Untitled-1.ipynb"
Verbose 18:08:31: Get Kernelspec root path, Class name = m (started execution), 
Verbose 18:08:31: Get Jupyter Paths, Class name = m (started execution), Arg 1: undefined
Verbose 18:08:31: Getting Jupyter KernelSpec Root Path, Class name = m, completed in 2ms, has a truthy return value, , Return Value: "/home/ec2-user/.local/share/jupyter/kernels"
Verbose 18:08:31: Getting Jupyter KernelSpec Root Path, Class name = m, completed in 1ms, has a truthy return value, , Return Value: "/home/ec2-user/.local/share/jupyter/kernels"
Verbose 18:08:31: Getting Jupyter KernelSpec Root Path, Class name = m, completed in 1ms, has a truthy return value, , Return Value: "/home/ec2-user/.local/share/jupyter/kernels"
Verbose 18:08:31: Get Jupyter Paths, Class name = m, completed in 3ms, has a truthy return value, Arg 1: undefined, Return Value: []
Verbose 18:08:31: Get Jupyter Paths, Class name = m, completed in 3ms, has a truthy return value, Arg 1: {"_isCancelled":false,"_emitter":null}, Return Value: []
Verbose 18:08:31: Get Jupyter Paths, Class name = m, completed in 2ms, has a truthy return value, Arg 1: undefined, Return Value: []
Verbose 18:08:31: Get Jupyter Paths, Class name = m, completed in 2ms, has a truthy return value, Arg 1: undefined, Return Value: []
Verbose 18:08:31: Get Kernelspec root path, Class name = m, completed in 4ms, has a truthy return value, , Return Value: ["/usr/share/jupyter/kernels","/usr/local/share/jupyter/kernels","/home/ec2-user/.local/share/jupyter/kernels"]
Verbose 18:08:31: Get Kernelspec root path, Class name = m, completed in 4ms, has a truthy return value, Arg 1: {"_isCancelled":false,"_emitter":null}, Return Value: ["/usr/share/jupyter/kernels","/usr/local/share/jupyter/kernels","/home/ec2-user/.local/share/jupyter/kernels"]
Verbose 18:08:31: Get Kernelspec root path, Class name = m, completed in 3ms, has a truthy return value, , Return Value: ["/usr/share/jupyter/kernels","/usr/local/share/jupyter/kernels","/home/ec2-user/.local/share/jupyter/kernels"]
Verbose 18:08:31: Get Kernelspec root path, Class name = m, completed in 3ms, has a truthy return value, , Return Value: ["/usr/share/jupyter/kernels","/usr/local/share/jupyter/kernels","/home/ec2-user/.local/share/jupyter/kernels"]
Info 18:08:31: Creating controller for jupyter-notebook with interpreter /data/tools/miniconda3/envs/rundigest/bin/python
Verbose 18:08:31: Get Active Interpreter, Class name = P, completed in 478ms, has a truthy return value, Arg 1: undefined
Verbose 18:08:31: Create activated Env, Class name = k (started execution), Arg 1: ""
Verbose 18:08:31: Getting activated env variables, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python", Arg 3: true
Verbose 18:08:31: Getting activated env variables ourselves, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:08:31: Create ProcessService, Class name = p (started execution), Arg 1: undefined
Verbose 18:08:31: Get Custom Env Variables, Class name = m (started execution), Arg 1: undefined
Verbose 18:08:31: Cached data exists getEnvironmentVariables, <No Resource>
Verbose 18:08:31: Getting env activation commands, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:08:31: Getting activated env variables from Python, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:08:31: Get Active Interpreter, Class name = P, completed in 65ms, has a truthy return value, Arg 1: "Untitled-1.ipynb"
Verbose 18:08:31: Get Active Interpreter, Class name = P, completed in 14ms, has a truthy return value, Arg 1: undefined
Verbose 18:08:31: Get Active Interpreter, Class name = P, completed in 13ms, has a truthy return value, Arg 1: undefined
Verbose 18:08:31: Get Active Interpreter, Class name = P, completed in 14ms, has a truthy return value, Arg 1: "Untitled-1.ipynb"
Verbose 18:08:31: Get Custom Env Variables, Class name = m, completed in 2ms, has a truthy return value, Arg 1: undefined
Verbose 18:08:31: Create ProcessService, Class name = p, completed in 3ms, has a truthy return value, Arg 1: undefined
Verbose 18:08:31: Getting env activation commands, Class name = I, completed in 3ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:08:31: Getting activated env variables ourselves, Class name = I, completed in 5ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:08:31: Got env vars ourselves faster, but empty /data/tools/miniconda3/envs/rundigest/bin/python
Verbose 18:08:31: Got env vars ourselves /data/tools/miniconda3/envs/rundigest/bin/python in 5ms
Verbose 18:08:31: Get Active Interpreter, Class name = P, completed in 1006ms, has a truthy return value, Arg 1: "/data"
Verbose 18:08:31: Get Active Interpreter, Class name = P, completed in 70ms, has a truthy return value, Arg 1: "/data"
Verbose 18:08:31: No controller, hence notebook communications cannot be initialized for editor untitled:Untitled-1.ipynb?jupyter-notebook
Verbose 18:08:31: Get Active Interpreter, Class name = P (started execution), Arg 1: "Untitled-1.ipynb"
Verbose 18:08:31: Get Active Interpreter, Class name = P, completed in 3ms, has a truthy return value, Arg 1: "Untitled-1.ipynb"
Info 18:08:31: Find preferred kernel for untitled:Untitled-1.ipynb?jupyter-notebook with metadata {"orig_nbformat":4,"language_info":{"name":"python"}} & preferred interpreter /data/tools/miniconda3/envs/rundigest/bin/python
Info 18:08:31: findKernel found rundigest (Python 3.7.12)
Verbose 18:08:31: Find kernel spec, Class name = x, completed in 273ms, has a truthy return value, Arg 1: "Untitled-1.ipynb", Arg 2: {"orig_nbformat":4,"language_info":{"name":"python"}}, Arg 3: ""
Info 18:08:31: PreferredConnection: .jvsc74a57bd06c41c2e0997ac8b1d459a3046b4189d58f4c60cf73cc0cd82b6c10d61fd4cc25./data/tools/miniconda3/envs/rundigest/python./data/tools/miniconda3/envs/rundigest/python.-m#ipykernel_launcher found for NotebookDocument: untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:08:31: TargetController found ID: .jvsc74a57bd06c41c2e0997ac8b1d459a3046b4189d58f4c60cf73cc0cd82b6c10d61fd4cc25./data/tools/miniconda3/envs/rundigest/python./data/tools/miniconda3/envs/rundigest/python.-m#ipykernel_launcher for document untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:08:31: Setting controller affinity for untitled:Untitled-1.ipynb?jupyter-notebook .jvsc74a57bd06c41c2e0997ac8b1d459a3046b4189d58f4c60cf73cc0cd82b6c10d61fd4cc25./data/tools/miniconda3/envs/rundigest/python./data/tools/miniconda3/envs/rundigest/python.-m#ipykernel_launcher
Info 18:08:31: Setting setActiveController for untitled:Untitled-1.ipynb?jupyter-notebook
Verbose 18:08:31: Intiailize notebook communications for editor untitled:Untitled-1.ipynb?jupyter-notebook
Verbose 18:08:31: Resolving notebook UI Comms (resolve) for untitled:Untitled-1.ipynb?jupyter-notebook
Verbose 18:08:31: initialize CommonMessageCoordinator
Verbose 18:08:31: IPyWidgetMessageDispatcher.initialize
Verbose 18:08:31: Created and initailized CommonMessageCoordinator
Verbose 18:08:31: Attach Coordinator for untitled:Untitled-1.ipynb?jupyter-notebook
Verbose 18:08:31: Loading kernelspec from ~/.local/share/jupyter/kernels/rundigest/kernel.json for 
Verbose 18:08:31: Loading kernelspec from ~/.local/share/jupyter/kernels/mosaic/kernel.json for 
Info 18:08:31: Registering dummy command feature
Verbose 18:08:31: Loading kernelspec from /data/tools/miniconda3/share/jupyter/kernels/python3/kernel.json for /data/tools/miniconda3/bin/python
Verbose 18:08:31: Loading kernelspec from /data/tools/miniconda3/envs/rundigest/share/jupyter/kernels/python3/kernel.json for /data/tools/miniconda3/envs/rundigest/bin/python
Verbose 18:08:31: Loading kernelspec from /data/tools/miniconda3/envs/mosaic/share/jupyter/kernels/python3/kernel.json for /data/tools/miniconda3/envs/mosaic/bin/python
Verbose 18:08:31: Create activated Env, Class name = k (started execution), Arg 1: ""
Verbose 18:08:31: Getting activated env variables, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:08:31: Getting activated env variables ourselves, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Create ProcessService, Class name = p (started execution), Arg 1: undefined
Verbose 18:08:31: Get Custom Env Variables, Class name = m (started execution), Arg 1: undefined
Verbose 18:08:31: Cached data exists getEnvironmentVariables, <No Resource>
Verbose 18:08:31: Getting env activation commands, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Getting activated env variables from Python, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Info 18:08:31: KernelProvider switched kernel to id = .jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a./data/tools/miniconda3/python./data/tools/miniconda3/python.-m#ipykernel_launcher
Info 18:08:31: Starting Notebook in kernel.ts id = .jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a./data/tools/miniconda3/python./data/tools/miniconda3/python.-m#ipykernel_launcher
Info 18:08:31: Creating raw notebook for untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:08:31: Getting preferred kernel for untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:08:31: Computing working directory untitled:Untitled-1.ipynb?jupyter-notebook
Verbose 18:08:31: Get Custom Env Variables, Class name = m, completed in 1ms, has a truthy return value, Arg 1: undefined
Verbose 18:08:31: Connecting to raw session for untitled:Untitled-1.ipynb?jupyter-notebook with connection .jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a./data/tools/miniconda3/python./data/tools/miniconda3/python.-m#ipykernel_launcher
Info 18:08:31: Starting raw kernel base (Python 3.7.10)
Info 18:08:31: installMissingDependencies /data/tools/miniconda3/bin/python
Info 18:08:31: IPykernel found previously in this enviornment /data/tools/miniconda3/bin/python
Verbose 18:08:31: Create ProcessService, Class name = p, completed in 3ms, has a truthy return value, Arg 1: undefined
Verbose 18:08:31: Install Missing Dependencies, Class name = k, completed in 0ms, has a falsy return value, Return Value: undefined
Verbose 18:08:31: Getting env activation commands, Class name = I, completed in 3ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Getting activated env variables ourselves, Class name = I, completed in 6ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Got env vars ourselves faster, but empty /data/tools/miniconda3/bin/python
Verbose 18:08:31: Got env vars ourselves /data/tools/miniconda3/bin/python in 6ms
Info 18:08:31: Kernel launching with ports 9000,9001,9002,9003,9004. Start port is 9000
Verbose 18:08:31: Launching kernel in kernelProcess.ts, Class name = E (started execution), Arg 1: "/data", Arg 2: ""
Verbose 18:08:31: Launching kernel daemon, Class name = m (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data", Arg 3: "python3710jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a", Arg 4: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Get kernel daemon, Class name = y (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: undefined, Arg 3: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Create daemon, Class name = k (started execution), Arg 1: {"daemonModule":"vscode_datascience_helpers.kernel_launcher_daemon","interpreter":{"sysPrefix":"/data/tools/miniconda3","envType":"Conda","envName":"base","envPath":"/data/tools/miniconda3","path":"/data/tools/miniconda3/bin/python","architecture":3,"sysVersion":"3.7.10 (default, Jun  4 2021, 14:48:32) \n[GCC 7.5.0]","version":{"raw":"3.7.10","major":3,"minor":7,"patch":10,"build":[],"prerelease":["final","0"]},"companyDisplayName":"Anaconda, Inc.","displayName":"Python 3.7.10 64-bit ('base': conda)"},"dedicated":true,"resource":{"$mid":1,"fsPath":"Untitled-1.ipynb","external":"untitled:Untitled-1.ipynb?jupyter-notebook","path":"Untitled-1.ipynb","scheme":"untitled","query":"jupyter-notebook"}}
Verbose 18:08:31: Create activated Env, Class name = k (started execution), Arg 1: ""
Verbose 18:08:31: Getting activated env variables, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:08:31: Getting activated env variables ourselves, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Getting activated env variables from Python, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Getting activated env variables, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:08:31: Getting activated env variables ourselves, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Getting activated env variables from Python, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Info 18:08:31: Launching kernel daemon for Python 3.7.10 64-bit ('base': conda) # /data/tools/miniconda3/bin/python
Verbose 18:08:31: Getting activated env variables, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: false
Verbose 18:08:31: Getting activated env variables ourselves, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Getting activated env variables from Python, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Getting activated env variables ourselves, Class name = I, completed in 2ms, has a falsy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Getting activated env variables ourselves, Class name = I, completed in 2ms, has a falsy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Getting activated env variables ourselves, Class name = I, completed in 1ms, has a falsy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:31: Got env vars ourselves faster, but empty /data/tools/miniconda3/bin/python
Verbose 18:08:31: Got env vars ourselves faster, but empty /data/tools/miniconda3/bin/python
Verbose 18:08:31: Got env vars ourselves faster, but empty /data/tools/miniconda3/bin/python
Verbose 18:08:31: Got env vars ourselves /data/tools/miniconda3/bin/python in 2ms
Verbose 18:08:31: Got env vars ourselves /data/tools/miniconda3/bin/python in 3ms
Verbose 18:08:31: Got env vars ourselves /data/tools/miniconda3/bin/python in 2ms
Verbose 18:08:32: Getting env activation commands, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:08:32: Getting activated env variables from Python, Class name = I, completed in 1213ms, has a truthy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:08:32: Got env vars with python /data/tools/miniconda3/envs/rundigest/bin/python in 1214ms
Verbose 18:08:32: Getting env activation commands, Class name = I, completed in 0ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python"
Verbose 18:08:32: Getting activated env variables, Class name = I, completed in 1214ms, has a truthy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/envs/rundigest/bin/python", Arg 3: true
Verbose 18:08:32: Create activated Env, Class name = k, completed in 1215ms, has a truthy return value, Arg 1: ""
Info 18:08:32: Process Execution: > /data/tools/miniconda3/envs/rundigest/bin/python -m pip list
> /data/tools/miniconda3/envs/rundigest/bin/python -m pip list
Verbose 18:08:32: Getting env activation commands, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Getting activated env variables from Python, Class name = I, completed in 740ms, has a truthy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Got env vars with python /data/tools/miniconda3/bin/python in 741ms
Verbose 18:08:32: Getting env activation commands, Class name = I, completed in 1ms, has a falsy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Getting activated env variables, Class name = I, completed in 742ms, has a truthy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:08:32: Getting env activation commands, Class name = I (started execution), Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Getting activated env variables from Python, Class name = I, completed in 752ms, has a truthy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Got env vars with python /data/tools/miniconda3/bin/python in 753ms
Verbose 18:08:32: Getting env activation commands, Class name = I, completed in 0ms, has a falsy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Getting activated env variables, Class name = I, completed in 754ms, has a truthy return value, Arg 1: undefined, Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:08:32: Create activated Env, Class name = k, completed in 754ms, has a truthy return value, Arg 1: ""
Info 18:08:32: Process Execution: > /data/tools/miniconda3/bin/python -m pip list
> /data/tools/miniconda3/bin/python -m pip list
Verbose 18:08:32: Getting env activation commands, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Getting activated env variables from Python, Class name = I, completed in 751ms, has a truthy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Got env vars with python /data/tools/miniconda3/bin/python in 751ms
Verbose 18:08:32: Getting env activation commands, Class name = I, completed in 0ms, has a falsy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Getting activated env variables, Class name = I, completed in 752ms, has a truthy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: false
Verbose 18:08:32: Hiding default kernel spec Python 3 (ipykernel), /data/tools/miniconda3/bin/python
Verbose 18:08:32: Hiding default kernel spec Python 3 (ipykernel), python
Verbose 18:08:32: Kernel python3710jvsc74a57bd005327433ee0e98a55ee8a734c87123dc45b88cbb9095fa6e0ab3b7b62420e31a matches Python 3.7.10 64-bit ('mosaic': conda) based on path in argv.
Verbose 18:08:32: Hiding default kernel spec Python 3 (ipykernel), /data/tools/miniconda3/bin/python
Verbose 18:08:32: Hiding default kernel spec Python 3 (ipykernel), python
Verbose 18:08:32: Kernel python3710jvsc74a57bd005327433ee0e98a55ee8a734c87123dc45b88cbb9095fa6e0ab3b7b62420e31a matches Python 3.7.10 64-bit ('mosaic': conda) based on path in argv.
Verbose 18:08:32: Hiding default kernel spec Python 3 (ipykernel), /data/tools/miniconda3/bin/python
Verbose 18:08:32: Hiding default kernel spec Python 3 (ipykernel), python
Verbose 18:08:32: Kernel python3710jvsc74a57bd005327433ee0e98a55ee8a734c87123dc45b88cbb9095fa6e0ab3b7b62420e31a matches Python 3.7.10 64-bit ('mosaic': conda) based on path in argv.
Verbose 18:08:32: Getting env activation commands, Class name = I (started execution), Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Getting activated env variables from Python, Class name = I, completed in 770ms, has a truthy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Got env vars with python /data/tools/miniconda3/bin/python in 770ms
Verbose 18:08:32: Getting env activation commands, Class name = I, completed in 0ms, has a falsy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python"
Verbose 18:08:32: Getting activated env variables, Class name = I, completed in 771ms, has a truthy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data/tools/miniconda3/bin/python", Arg 3: true
Verbose 18:08:32: Create activated Env, Class name = k, completed in 771ms, has a truthy return value, Arg 1: ""
Info 18:08:32: Creating daemon process for /data/tools/miniconda3/bin/python with env variables count 48
Info 18:08:32: Process Execution: > /data/tools/miniconda3/bin/python -m vscode_datascience_helpers.daemon --daemon-module=vscode_datascience_helpers.kernel_launcher_daemon -v
> /data/tools/miniconda3/bin/python -m vscode_datascience_helpers.daemon --daemon-module=vscode_datascience_helpers.kernel_launcher_daemon -v
Verbose 18:08:32: Daemon is alive
Verbose 18:08:32: Create daemon, Class name = k, completed in 818ms, has a truthy return value, Arg 1: {"daemonModule":"vscode_datascience_helpers.kernel_launcher_daemon","interpreter":{"sysPrefix":"/data/tools/miniconda3","envType":"Conda","envName":"base","envPath":"/data/tools/miniconda3","path":"/data/tools/miniconda3/bin/python","architecture":3,"sysVersion":"3.7.10 (default, Jun  4 2021, 14:48:32) \n[GCC 7.5.0]","version":{"raw":"3.7.10","major":3,"minor":7,"patch":10,"build":[],"prerelease":["final","0"]},"companyDisplayName":"Anaconda, Inc.","displayName":"Python 3.7.10 64-bit ('base': conda)"},"dedicated":true,"resource":{"$mid":1,"fsPath":"Untitled-1.ipynb","external":"untitled:Untitled-1.ipynb?jupyter-notebook","path":"Untitled-1.ipynb","scheme":"untitled","query":"jupyter-notebook"}}
Verbose 18:08:32: Get kernel daemon, Class name = y, completed in 819ms, has a truthy return value, Arg 1: "Untitled-1.ipynb", Arg 2: undefined, Arg 3: "/data/tools/miniconda3/bin/python"
Info 18:08:32: Starting kernel from scratch
Verbose 18:08:32: Python Daemon (pid: 12874): Execute rpc method exec_module in DS Daemon
Verbose 18:08:32: Python Daemon (pid: 12874): Execute rpc method exec_module from /data/tools/miniconda3/bin/python
Verbose 18:08:32: Python Daemon (pid: 12874): Exec module in DS Kernel Launcher Daemon ipykernel_launcher with args ['--ip=127.0.0.1', '--stdin=9003', '--control=9001', '--hb=9000', '--Session.signature_scheme="hmac-sha256"', '--Session.key=b"4fddf81f-0ab9-4511-964d-0aa76cc906cf"', '--shell=9002', '--transport="tcp"', '--iopub=9004', '--f=/tmp/tmp-12686cI09VtGu93ID.json']
Verbose 18:08:32: Python Daemon (pid: 12874): Exec in DS Kernel Launcher Daemon (observable) ipykernel_launcher with args ['--ip=127.0.0.1', '--stdin=9003', '--control=9001', '--hb=9000', '--Session.signature_scheme="hmac-sha256"', '--Session.key=b"4fddf81f-0ab9-4511-964d-0aa76cc906cf"', '--shell=9002', '--transport="tcp"', '--iopub=9004', '--f=/tmp/tmp-12686cI09VtGu93ID.json']
Verbose 18:08:32: Python Daemon (pid: 12874): Exec in DS Kernel Launcher Daemon (observable) ['/data/tools/miniconda3/bin/python', '-m', 'ipykernel_launcher', '--ip=127.0.0.1', '--stdin=9003', '--control=9001', '--hb=9000', '--Session.signature_scheme="hmac-sha256"', '--Session.key=b"4fddf81f-0ab9-4511-964d-0aa76cc906cf"', '--shell=9002', '--transport="tcp"', '--iopub=9004', '--f=/tmp/tmp-12686cI09VtGu93ID.json']
Verbose 18:08:32: Python Daemon (pid: 12874): Exec in DS Kernel Launcher Daemon (observable)
Verbose 18:08:32: Python Daemon (pid: 12874): Kernel launched, with PID 12882
Verbose 18:08:32: Python Daemon (pid: 12874): Waiting for Kernel to die 12882
Verbose 18:08:32: Launching kernel daemon, Class name = m, completed in 824ms, has a truthy return value, Arg 1: "Untitled-1.ipynb", Arg 2: "/data", Arg 3: "python3710jvsc74a57bd01b8759b233ea9dd7ffd3f7cbc432b1ac0ff68fc2c8ffc507adf0ceb8cbd68d0a", Arg 4: "/data/tools/miniconda3/bin/python", Return Value: <Return value cannot be serialized for logging>
Verbose 18:08:32: Launching kernel in kernelProcess.ts, Class name = E, completed in 824ms, has a truthy return value, Arg 1: "/data", Arg 2: ""
Verbose 18:08:32: Registering commtarget jupyter.widget
Verbose 18:08:32: IPyWidgetMessageDispatcher.initialize
Verbose 18:08:32: IPyWidgetMessageDispatcher.initialize
Verbose 18:08:32: KernelProcess output: Content-Length: 358
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "log", "params": {"level": "INFO", "msg": "write to stderr: /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2205: 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.\n", "pid": 12874}}
Verbose 18:08:32: Python Daemon (pid: 12874): write to stderr: /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2205: 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.

Verbose 18:08:32: KernelProcess output: Content-Length: 333
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "output", "params": {"source": "stderr", "out": "/data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2205: 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.\n"}}Content-Length: 124
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "log", "params": {"level": "INFO", "msg": "write to stderr:   FutureWarning)\n", "pid": 12874}}Content-Length: 99
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "output", "params": {"source": "stderr", "out": "  FutureWarning)\n"}}Content-Length: 370
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "log", "params": {"level": "INFO", "msg": "write to stderr: /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2160: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use '4fddf81f-0ab9-4511-964d-0aa76cc906cf' instead of 'b\"4fddf81f-0ab9-4511-964d-0aa76cc906cf\"'.\n", "pid": 12874}}Content-Length: 345
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "output", "params": {"source": "stderr", "out": "/data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2160: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use '4fddf81f-0ab9-4511-964d-0aa76cc906cf' instead of 'b\"4fddf81f-0ab9-4511-964d-0aa76cc906cf\"'.\n"}}Content-Length: 124
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "log", "params": {"level": "INFO", "msg": "write to stderr:   FutureWarning)\n", "pid": 12874}}Content-Length: 99
Content-Type: application/vscode-jsonrpc; charset=utf8

{"jsonrpc": "2.0", "method": "output", "params": {"source": "stderr", "out": "  FutureWarning)\n"}}
Warn 18:08:32: StdErr from Kernel Process /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2205: 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.

Verbose 18:08:32: Python Daemon (pid: 12874): write to stderr:   FutureWarning)

Warn 18:08:32: StdErr from Kernel Process   FutureWarning)

Verbose 18:08:32: Python Daemon (pid: 12874): write to stderr: /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2160: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use '4fddf81f-0ab9-4511-964d-0aa76cc906cf' instead of 'b"4fddf81f-0ab9-4511-964d-0aa76cc906cf"'.

Warn 18:08:32: StdErr from Kernel Process /data/tools/miniconda3/lib/python3.7/site-packages/traitlets/traitlets.py:2160: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use '4fddf81f-0ab9-4511-964d-0aa76cc906cf' instead of 'b"4fddf81f-0ab9-4511-964d-0aa76cc906cf"'.

Verbose 18:08:32: Python Daemon (pid: 12874): write to stderr:   FutureWarning)

Warn 18:08:32: StdErr from Kernel Process   FutureWarning)

Info 18:08:32: Raw session started and connected
Started kernel base (Python 3.7.10)
Info 18:08:32: Finished connecting 6b427c35-a20f-4b01-941a-bb5934fec0ce
Info 18:08:32: UpdateWorkingDirectoryAndPath in Kernel
Info 18:08:33: Executing (status idle) silently Code = %config Completer.use_jedi = False
Info 18:08:33: Executing silently Code (completed) = %config Completer.use_jedi = False
Info 18:08:33: Executing (status idle) silently Code = %config InlineBackend.figure_formats = ['png']
Info 18:08:33: Executing silently Code (completed) = %config InlineBackend.figure_formats = ['png']
Info 18:08:33: Executing (status idle) silently Code = import sys\nprint(sys.executable)
Info 18:08:33: Executing silently Code (completed) = import sys\nprint(sys.executable)
Info 18:08:33: Waiting for idle on (kernel): 66995c8c-dda3-4e64-8bde-04db02eb64ed -> idle
Info 18:08:33: Finished waiting for idle on (kernel): 66995c8c-dda3-4e64-8bde-04db02eb64ed -> idle
Verbose 18:08:33: IPyWidgetMessageDispatcher.initialize
Info 18:08:33: IPyWidgetScriptSource.initialize
Verbose 18:08:33: IPyWidgetMessageDispatcher.initialize
Verbose 18:08:33: IPyWidgetMessageDispatcher.initialize
Verbose 18:08:33: IPyWidgetMessageDispatcher.initialize
Verbose 18:08:33: IPyWidgetMessageDispatcher.initialize
Info 18:08:33: Registering dummy command feature
Info 18:09:07: Execute Cells request 0
Info 18:09:07: Execute Cell 0 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:09:07: Cell 0 executed with state Success
Info 18:09:17: Execute Cells request 1
Info 18:09:17: Execute Cell 1 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:09:17: Cell 1 executed with state Success
Verbose 18:09:21: Jupyter completions for dat at pos 1:1 with trigger: undefined
   
Verbose 18:09:21: Jupyter results for dat at pos 1:1 with trigger: undefined
   
Verbose 18:09:21: Jupyter completions for da at pos 1:2 with trigger: undefined
   data,data/
Verbose 18:09:21: Jupyter results for da at pos 1:2 with trigger: undefined
   
Verbose 18:09:23: Jupyter completions for d at pos 0:1 with trigger: undefined
   data,data/,%debug,%%debug,def,del,delattr,%dhist,dict,dir,%dirs,display,divmod,%doctest_mode
Verbose 18:09:23: Jupyter results for d at pos 0:1 with trigger: undefined
   %debug,%%debug,%dhist,%dirs,display,%doctest_mode
Verbose 18:09:24: Jupyter completions for data. at pos 0:5 with trigger: .
   data.T,data.abs,data.add,data.add_prefix,data.add_suffix,data.agg,data.aggregate,data.align,data.all,data.any,data.append,data.apply,data.applymap,data.asfreq,data.asof,data.assign,data.astype,data.at,data.at_time,data.attrs,data.axes,data.backfill,data.between_time,data.bfill,data.bool,data.boxplot,data.clip,data.columns,data.combine,data.combine_first,data.compare,data.convert_dtypes,data.copy,data.corr,data.corrwith,data.count,data.cov,data.cummax,data.cummin,data.cumprod,data.cumsum,data.describe,data.diff,data.div,data.divide,data.dot,data.drop,data.drop_duplicates,data.droplevel,data.dropna,data.dtypes,data.duplicated,data.empty,data.eq,data.equals,data.eval,data.ewm,data.expanding,data.explode,data.ffill,data.fillna,data.filter,data.first,data.first_valid_index,data.flags,data.floordiv,data.from_dict,data.from_records,data.ge,data.get,data.groupby,data.gt,data.head,data.hist,data.iat,data.idxmax,data.idxmin,data.iloc,data.index,data.infer_objects,data.info,data.insert,data.interpolate,data.isin,data.isna,data.isnull,data.items,data.iteritems,data.iterrows,data.itertuples,data.join,data.keys,data.kurt,data.kurtosis,data.last,data.last_valid_index,data.le,data.loc,data.lookup,data.lt,data.mad,data.mask,data.max,data.mean,data.median,data.melt,data.memory_usage,data.merge,data.min,data.mod,data.mode,data.mul,data.multiply,data.ndim,data.ne,data.nlargest,data.notna,data.notnull,data.nsmallest,data.nunique,data.pad,data.pct_change,data.pipe,data.pivot,data.pivot_table,data.plot,data.pop,data.pow,data.prod,data.product,data.quantile,data.query,data.radd,data.rank,data.rdiv,data.reindex,data.reindex_like,data.rename,data.rename_axis,data.reorder_levels,data.replace,data.resample,data.reset_index,data.rfloordiv,data.rmod,data.rmul,data.rolling,data.round,data.rpow,data.rsub,data.rtruediv,data.sample,data.select_dtypes,data.sem,data.set_axis,data.set_flags,data.set_index,data.shape,data.shift,data.size,data.skew,data.slice_shift,data.sort_index,data.sort_values,data.sparse,data.squeeze,data.stack,data.std,data.style,data.sub,data.subtract,data.sum,data.swapaxes,data.swaplevel,data.tail,data.take,data.to_clipboard,data.to_csv,data.to_dict,data.to_excel,data.to_feather,data.to_gbq,data.to_hdf,data.to_html,data.to_json,data.to_latex,data.to_markdown,data.to_numpy,data.to_parquet,data.to_period,data.to_pickle,data.to_records,data.to_sql,data.to_stata,data.to_string,data.to_timestamp,data.to_xarray,data.to_xml,data.transform,data.transpose,data.truediv,data.truncate,data.tshift,data.tz_convert,data.tz_localize,data.unstack,data.update,data.value_counts,data.values,data.var,data.where,data.xs
Verbose 18:09:24: Jupyter results for data. at pos 0:5 with trigger: .
   backfill,compare,flags,pad,set_flags,to_xml,value_counts
Info 18:09:28: Execute Cells request 2
Info 18:09:28: Execute Cell 2 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:09:29: Cell 2 executed with state Success
Info 18:09:36: Execute Cells request 0
Info 18:09:36: Execute Cell 0 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:09:36: Cell 0 executed with state Success
Info 18:09:37: Execute Cells request 2
Info 18:09:37: Execute Cell 2 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:09:44: Cell 2 executed with state Success
Verbose 18:10:14: notebook communications already initialized for editor untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:10:14: Execute Cells request 1
Info 18:10:14: Execute Cell 1 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:10:16: Cell 1 executed with state Success
Info 18:10:19: Execute Cells request 2
Info 18:10:19: Execute Cell 2 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:10:25: Cell 2 executed with state Success
Verbose 18:10:49: Jupyter completions for prit at pos 0:1 with trigger: undefined
   %page,pass,%pastebin,pd,%pdb,%pdef,%pdoc,%%perl,%pfile,%pinfo,%pinfo2,pio,%pip,%popd,pow,%pprint,%precision,print,property,%prun,%%prun,%psearch,%psource,%pushd,%pwd,px,%pycat,%pylab,%%pypy,%%python,%%python2,%%python3
Verbose 18:10:49: Jupyter results for prit at pos 0:1 with trigger: undefined
   %page,%pastebin,%pdb,%pdef,%pdoc,%%perl,%pfile,%pinfo,%pinfo2,%pip,%popd,%pprint,%precision,%prun,%%prun,%psearch,%psource,%pushd,%pwd,%pycat,%pylab,%%pypy,%%python,%%python2,%%python3
Info 18:11:22: Execute Cells request 3
Info 18:11:22: Execute Cell 3 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:12:19: Cell 3 executed with state Success
Info 18:13:09: Execute Cells request 0
Info 18:13:09: Execute Cell 0 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:13:09: Execute Cells request 1
Info 18:13:09: Execute Cell 1 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:13:09: Execute Cells request 2
Info 18:13:09: Execute Cell 2 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:13:09: Execute Cells request 3
Info 18:13:09: Execute Cell 3 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:13:10: Cell 0 executed with state Success
Info 18:13:11: Cell 1 executed with state Success
Info 18:13:17: Cell 2 executed with state Success
Info 18:13:37: Cell 3 executed with state Success
Info 18:14:09: Execute Cells request 0
Info 18:14:09: Execute Cell 0 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:14:09: Execute Cells request 1
Info 18:14:09: Execute Cell 1 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:14:09: Execute Cells request 2
Info 18:14:09: Execute Cell 2 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:14:09: Execute Cells request 3
Info 18:14:09: Execute Cell 3 untitled:Untitled-1.ipynb?jupyter-notebook
Info 18:14:11: Cell 0 executed with state Success
Info 18:14:11: Cell 1 executed with state Success
Info 18:14:17: Cell 2 executed with state Success
Info 18:14:35: Cell 3 executed with state Success

Few other things I've observed:

(1) In the second log above, I see there are some lines like Jupyter results for prit at pos 0:1 with trigger: undefined in it, but I was not always able to reproduce that output.

(2) Here are some additional logs that I observed while it took 15 minutes to execute a cell with a simple print statement (but it was executed after cells with the dataframes and plots):

Log from `Log (Window)`
[2021-12-03 13:45:25.040] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:45:25.041] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:45:25.041] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:45:25.042] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:45:34.053] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:45:34.559] [renderer3] [error] write EPIPE: Error: write EPIPE
	at WriteWrap.onWriteComplete [as oncomplete] (internal/stream_base_commons.js:94:16)
[2021-12-03 13:45:54.571] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:45:54.607] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:45:54.607] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:45:54.607] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:46:01.789] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:46:21.795] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:46:21.836] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:46:21.841] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:46:21.841] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:46:31.873] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:46:51.876] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:46:51.916] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:46:51.916] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:46:51.916] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:47:01.133] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:47:21.679] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:47:21.710] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:47:21.710] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:47:21.710] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:47:30.679] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:47:51.556] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:47:51.582] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:47:51.583] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:47:51.583] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:48:00.299] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:48:29.355] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:48:29.356] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:48:29.356] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:48:29.357] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:48:37.139] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:48:37.471] [renderer3] [error] write EPIPE: Error: write EPIPE
	at WriteWrap.onWriteComplete [as oncomplete] (internal/stream_base_commons.js:94:16)
[2021-12-03 13:48:57.639] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:48:57.674] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:48:57.674] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:48:57.675] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:49:05.051] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:49:25.565] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:49:25.608] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:49:25.609] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:49:25.609] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:49:34.929] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:49:55.044] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:49:55.085] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:49:55.085] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:49:55.085] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:50:06.555] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:50:26.737] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:50:26.737] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:50:26.738] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:50:26.738] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:50:36.206] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:50:56.685] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:50:56.685] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:50:56.686] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:50:56.686] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:51:06.709] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:51:27.058] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:51:27.094] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:51:27.096] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:51:27.096] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:51:51.498] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:52:31.686] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:52:31.728] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:52:31.731] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:52:31.731] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:52:41.132] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:53:01.899] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:53:01.924] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:53:01.925] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:53:01.925] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:53:10.558] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:53:11.028] [renderer3] [error] write EPIPE: Error: write EPIPE
	at WriteWrap.onWriteComplete [as oncomplete] (internal/stream_base_commons.js:94:16)
[2021-12-03 13:53:31.880] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:53:31.918] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:53:31.919] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:53:31.919] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:53:42.620] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:54:02.810] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:54:02.846] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:54:02.847] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:54:02.847] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:54:11.334] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:54:31.677] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:54:31.720] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:54:31.720] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:54:31.720] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:54:40.333] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:54:47.916] [renderer3] [error] write EPIPE: Error: write EPIPE
	at WriteWrap.onWriteComplete [as oncomplete] (internal/stream_base_commons.js:94:16)
[2021-12-03 13:55:08.937] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:55:08.978] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:55:08.978] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:55:08.978] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:55:16.140] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:55:17.185] [renderer3] [error] write EPIPE: Error: write EPIPE
	at WriteWrap.onWriteComplete [as oncomplete] (internal/stream_base_commons.js:94:16)
[2021-12-03 13:55:39.949] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:55:39.991] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:55:39.991] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:55:39.992] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:55:45.574] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:56:07.839] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:56:07.881] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:56:07.882] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:56:07.882] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:56:15.008] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
Log from `Remote - SSH`
[13:17:55.387] Log Level: 1
[13:17:55.389] [email protected]
[13:17:55.389] darwin x64
[13:17:55.398] SSH Resolver called for "ssh-remote+jupyter", attempt 1
[13:17:55.398] "remote.SSH.useLocalServer": true
[13:17:55.398] "remote.SSH.path": undefined
[13:17:55.398] "remote.SSH.configFile": undefined
[13:17:55.398] "remote.SSH.useFlock": true
[13:17:55.398] "remote.SSH.lockfilesInTmp": false
[13:17:55.399] "remote.SSH.localServerDownload": auto
[13:17:55.399] "remote.SSH.remoteServerListenOnSocket": false
[13:17:55.399] "remote.SSH.showLoginTerminal": false
[13:17:55.399] "remote.SSH.defaultExtensions": []
[13:17:55.399] "remote.SSH.loglevel": 1
[13:17:55.399] SSH Resolver called for host: jupyter
[13:17:55.399] Setting up SSH remote "jupyter"
[13:17:55.403] Acquiring local install lock: /var/folders/kr/c3k_91_n3dx86p9g75qzhyz80000gp/T/vscode-remote-ssh-24647144-install.lock
[13:17:55.411] Looking for existing server data file at /Users/murphy/Library/Application Support/Code - Insiders/User/globalStorage/ms-vscode-remote.remote-ssh/vscode-ssh-host-24647144-cca3e817a2608225d4ae8715308195c91f765bad-0.66.1/data.json
[13:17:55.412] Found local server running: {"remoteListeningOn":{"port":43377},"osReleaseId":"amzn","arch":"x86_64","webUiAccessToken":"","sshAuthSock":"/tmp/ssh-krlcytS4Y5jL/agent.4984","display":"","tmpDir":"/run/user/1000","platform":"linux","connectionToken":"1111a111-1a11-11aa-aa11-11a11aa1a111","pid":59641,"ipcHandlePath":"/var/folders/kr/c3k_91_n3dx86p9g75qzhyz80000gp/T/vscode-ssh-askpass-d695b37f45cb2330186b4143ba9e7c454851acc4.sock","socksPort":53121,"startupTime":1638552613330}
[13:17:55.419] Found running server - short-circuiting install
[13:17:55.423] Starting forwarding server. localPort 53902 -> socksPort 53121 -> remotePort 43377
[13:17:55.423] Forwarding server listening on 53902
[13:17:55.423] Waiting for ssh tunnel to be ready
[13:17:55.425] [Forwarding server 53902] Got connection 0
[13:17:55.426] Tunneled 43377 to local port 53902
[13:17:55.426] Resolved "ssh-remote+jupyter" to "127.0.0.1:53902"
[13:17:55.426] Updating $SSH_AUTH_SOCK: ln -f -s "/tmp/ssh-krlcytS4Y5jL/agent.4984" "/run/user/1000/vscode-ssh-auth-sock-207536050"
[13:17:55.427] Using cwd: vscode-remote://ssh-remote%2Bjupyter/
[13:17:55.427] Remote extension host environment: {"SSH_AUTH_SOCK":"/run/user/1000/vscode-ssh-auth-sock-207536050"}
[13:17:55.427] Updating terminal environments: {"SSH_AUTH_SOCK":"/run/user/1000/vscode-ssh-auth-sock-207536050"}
[13:17:55.432] TELEMETRY: {"eventName":"resolver","properties":{"osReleaseId":"amzn","arch":"x86_64","askedPw":"0","askedPassphrase":"0","asked2fa":"0","askedHostKey":"0","remoteInConfigFile":"1","gotUnrecognizedPrompt":"0","dynamicForwarding":"1","localServer":"1","didLocalDownload":"0","installUnpackCode":"0","outcome":"success"},"measures":{"resolveAttempts":1,"retries":1,"timing.totalResolveTime":34}}
[13:17:55.435] ------




[13:17:55.443] [Forwarding server 53902] Got connection 1
[13:17:55.615] [Forwarding server 53902] Got connection 2
[13:17:57.349] "Update SSH_AUTH_SOCK" terminal command done
[13:17:58.340] TELEMETRY: {"eventName":"extraInfo","properties":{"remoteCloud":"aws"},"measures":{}}
[13:18:07.002] [Forwarding server 53902] Got connection 3
[13:18:07.003] [Forwarding server 53902] Got connection 4
[13:22:42.118] [Forwarding server 53902] Got connection 5
[13:45:25.047] [Forwarding server 53902] Got connection 6
[13:45:54.609] [Forwarding server 53902] Got connection 7
[13:46:21.842] [Forwarding server 53902] Got connection 8
[13:46:51.918] [Forwarding server 53902] Got connection 9

@DonJayamanne
Copy link
Contributor

Thanks for the logs.
Transferring to VS Code due to connectivity issues with remtoe SSH

[2021-12-03 13:55:39.949] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:55:39.991] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:55:39.991] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:55:39.992] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:55:45.574] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!
[2021-12-03 13:56:07.839] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] received socket timeout event.
[2021-12-03 13:56:07.881] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] starting reconnecting loop. You can get more information with the trace log level.
[2021-12-03 13:56:07.882] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] resolving connection...
[2021-12-03 13:56:07.882] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] connecting to 127.0.0.1:53902...
[2021-12-03 13:56:15.008] [renderer3] [info] [remote-connection][ExtensionHost][548fa…][reconnect] reconnected!

@DonJayamanne DonJayamanne removed their assignment Dec 9, 2021
@DonJayamanne DonJayamanne transferred this issue from microsoft/vscode-jupyter Dec 9, 2021
@rebornix rebornix removed their assignment Dec 16, 2021
@NixGD
Copy link

NixGD commented Jan 8, 2022

Also having this issue with jupyter over ssh, in a notebook that has a decent number of plots.

@MohamedAliRashad
Copy link

This issue happened with me also (especially when i am insider a docker container inside the remote machine)

@MalteMederacke
Copy link

MalteMederacke commented Apr 7, 2022

I have the same issue, even when I freshly open a notebook with some outputs it is very slow and unresponsive.

image

I have a stable connection to the server:

image

@StephaneKazmierczak
Copy link

StephaneKazmierczak commented Apr 11, 2022

Same problem, sometimes it works fine, sometimes it is unusable, stable and insider
image

@greazer greazer added under-discussion Issue is under discussion for relevance, priority, approach notebook-perf bug Issue identified by VS Code Team member as probable bug labels Apr 11, 2022
@greazer
Copy link
Member

greazer commented Apr 14, 2022

After discussion in sync. Possibly add logging for every message that's only turned on if the user explicitly is told to (for debugging purposes).

@greazer greazer removed under-discussion Issue is under discussion for relevance, priority, approach notebook-triage labels Apr 14, 2022
@svechinsky
Copy link

experiencing similar issues when working on jupyter via ssh to an ubuntu server from an ubuntu pc

@Jumabek
Copy link

Jumabek commented Aug 27, 2022

ALso had the issue where running a cell lags(or starts after some time) for jupyter notebooks when using remote SSH

  • aws
  • azure

@wyf0912
Copy link

wyf0912 commented Aug 30, 2022

Still the same problem

@johnros-pagaya
Copy link

I also experience the same. I observed that:

  • It happens with Jupyter notebooks, but not in the Interactive window.
  • It does not happen if you connect to a Jupyter kernel that is not managed by VScode.
  • It happens when your notebooks has many figures.

I hope these may help diagnose the problem.

@roblourens
Copy link
Member

roblourens commented Jan 12, 2023

I pushed a change which should mostly fix this slowness with large outputs. I think there are more optimizations we could do. The issue comes when a save happens, and this blocks the remote extension host process for some period of time. Then a symptom of that might be that an execution appears to hang or take a long time.

Would appreciate anyone trying this out in tomorrow's vscode insiders build.

If you're seeing an issue related to the variables view, that wouldn't be fixed here, however I recently pushed a different fix that may help with that, and you could try it out in insiders + the jupyter pre-release extension.

@vscodenpa vscodenpa added insiders-released Patch has been released in VS Code Insiders and removed unreleased Patch has not yet been released in VS Code Insiders labels Jan 13, 2023
@paul-brenner
Copy link

I have been experiencing these slowdowns (specifically on an AWS server) and I don't use the variables view. I definitely found that clearing cell outputs eliminates the hangs.

I'm confused how this is caused by saving though, because I just found that the notebook I was working on wasn't being saved. I was doing some work tethered to my phone as I rode a bus into remote mountains. Certainly noticed that the bad connectivity meant even more waiting for things to execute than usual.

Unfortunately, when I got back to stronger internet vscode was complaining it couldn't connect and was hanging when it tried to save. I figured it was fine since I saw @roblourens 's comment and naively interpreted that as meaning I should feel comfortable that my notebook was getting saved each time it hung. Nope. When I finally had to reload window to get things back to working I found that all the code I wrote on the bus was no longer in the notebook (no worries, I had copied it out just in case, so no loss).

I'm trying the 2023-01-13T07:49:05.588Z insiders build right now (is the "tomorrow's" build that should have the changes?). I think it is still too soon for me to be able to offer an opinion on how much of an improvement this fix might be. It is hard because the problem is erratic: sometimes cells execute immediately and sometimes it takes 20 seconds or longer.

@roblourens
Copy link
Member

Yeah, that version has the latest fix, you might have hit a different issue. And the issue might still have been kicked off by the notebook attempting to save. I'll leave this issue open to track

I think there are more optimizations we could do.

namely, we still send notebook data between the processes more often than we technically have to, and I think that can be improved.

When I finally had to reload window to get things back to working I found that all the code I wrote on the bus was no longer in the notebook

Sorry about that, glad you had it saved, the intent is that the unsaved changes would have been persisted on the frontend.

@roblourens roblourens reopened this Jan 17, 2023
@vscodenpa vscodenpa removed the insiders-released Patch has been released in VS Code Insiders label Jan 17, 2023
@paul-brenner
Copy link

I can confirm that this change is a big improvement for my use case. If those additional optimizations ever get prioritized that would be amazing and appreciated, but I'm already grateful for this improvement. Thank you!

@roblourens
Copy link
Member

On second thought, closing this issue, and moving other work to #172345

@rebornix rebornix added the verified Verification succeeded label Jan 26, 2023
@vincenzoml
Copy link

I have tried the insiders version on a podman container accessed on a machine over ssh (so I do vscode remote via ssh to the machine and then I use remote containers to access the container).

It does not open a terminal and seems to be spinning forever with the following error in the logs:

2023-01-27 10:40:00.790 [info] [LocalProcess0][resolveAuthority(attached-container,27)][1013ms] waiting...
2023-01-27 10:40:00.818 [error] Shell server failed: Error: stream ended with:4 but wanted:9
at u (c:\Users\vince.vscode-insiders\extensions\ms-vscode-remote.remote-containers-0.273.0\dist\extension\extension.js:11:65948)
at c:\Users\vince.vscode-insiders\extensions\ms-vscode-remote.remote-containers-0.273.0\dist\extension\extension.js:11:66129
at s (c:\Users\vince.vscode-insiders\extensions\ms-vscode-remote.remote-containers-0.273.0\dist\extension\extension.js:14:5342)
at Socket. (c:\Users\vince.vscode-insiders\extensions\ms-vscode-remote.remote-containers-0.273.0\dist\extension\extension.js:14:5512)
at Socket.emit (node:events:538:35)
at endReadableNT (node:internal/streams/readable:1345:12)
at process.processTicksAndRejections (node:internal/process/task_queues:83:21)

May this be related to the recent changes or shall I seek for another bug report and eventually open a new one?

@roblourens
Copy link
Member

Not related to this change- you can open an issue against the Remote Containers extension, I'm not sure how to interpet that error

@bo44arov
Copy link

Hello,
I have installed latest Insider's VS Code, maybe it's a bit better now, but in general issue is still there.
Very slooow Jupyter Notebooks on large content.

I'm using jupyter/r-notebook docker image to run on AWS EC2 instance and have 2 options to acces it:

  • from browser on 8888 port to web version of Jupyter notebooks. It works lightning fast and responsive.
  • over SSH to EC2 instance and then "Attach to running container"

So if I compare those 2 options there is a huge difference in UI when notebook has some data and charts.
For me it feels like VS Code continuously sends data to server. Even dropdown for intellisence takes few seconds to load.

It's so sad that I'm unable to use VS Code Jupyter Notebooks for my work.

But if I create a brand new empty notebook in VS Code - everything works like a charm!

@github-actions github-actions bot locked and limited conversation to collaborators Mar 11, 2023
@rebornix rebornix reopened this Jun 29, 2023
@rebornix
Copy link
Member

@bo44arov @paul-brenner We have added experimental saving logic for Remote SSH, it would be great if you can give this a try and see if it improves the performance on large notebooks

  • Install latest VS Code Insiders
  • Remote SSH into your remote machine
  • Add "notebook.experimental.remoteSave": true to your Remote/User settings
  • Reload the window
  • Then test the scenarios which used to slow or block the network

Thanks in advance!

@microsoft microsoft unlocked this conversation Jun 29, 2023
@ma-ji
Copy link

ma-ji commented Jul 1, 2023

@rebornix The improvements are noticeable, thank you for your great work! However, the latency is still there and appears to be significant from time to time.

I love this ssh-notebook function, and I believe there must be more folks like me. Hope your team can prioritize this issue.

Appreciate!

@bo44arov
Copy link

bo44arov commented Jul 13, 2023 via email

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 notebook-perf verified Verification succeeded
Projects
None yet
Development

Successfully merging a pull request may close this issue.