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.

Sign up for GitHub

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

Already on GitHub? Sign in to your account

Jump to bottom

Wrong elapsed time reported #12399

Closed
1 of 2 tasks
joaomoreno opened this issue Dec 14, 2022 · 14 comments
Closed
1 of 2 tasks

Wrong elapsed time reported #12399

joaomoreno opened this issue Dec 14, 2022 · 14 comments
Assignees
@DonJayamanne
Labels
bug Issue identified by VS Code Team member as probable bug debt Code quality issues notebook-kernel Kernels issues (start/restart/switch/execution, install ipykernel) polish Cleanup and polish issue

Comments

@joaomoreno
Copy link
Member

Applies To

  • Notebooks (.ipynb files)
  • Interactive Window and/or Cell Scripts (.py files with #%% markers)

What happened?

When I press play on this cell, the elapsed time is ~24 seconds off.

Recording 2022-12-14 at 12 14 00

VS Code Version

1.75.0-insider

Jupyter Extension Version

v2023.1.1003441034

Jupyter logs

Visual Studio Code - Insiders (1.75.0-insider, codespaces, desktop)
Jupyter Extension Version: 2023.1.1003441034.
Python Extension Version: 2022.21.13431008.
Workspace folder /workspaces/aoc2022
User belongs to experiment group 'jupyterTestcf'
User belongs to experiment group 'jupyterEnhancedDataViewer'
info 10:30:33.874: LSP Notebooks experiment is enabled
info 10:30:37.082: Find preferred kernel for /workspaces/aoc2022/12/12.ipynb with metadata {"kernelspec":{"display_name":"Python 3.9.15 ('base')","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
info 10:30:37.092: Find preferred kernel for /workspaces/aoc2022/12/12.ipynb with metadata {"kernelspec":{"display_name":"Python 3.9.15 ('base')","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
info 10:30:37.286: Find preferred kernel for /workspaces/aoc2022/12/12.ipynb with metadata {"kernelspec":{"display_name":"Python 3.9.15 ('base')","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
info 10:30:37.289: Preferred kernel .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher is exact match or top match for non python kernels, (true, [object Object], true, false)
info 10:30:37.292: PreferredConnection: .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher found for NotebookDocument: /workspaces/aoc2022/12/12.ipynb
info 10:30:37.308: Find preferred kernel for /workspaces/aoc2022/12/12.ipynb with metadata {"kernelspec":{"display_name":"Python 3.9.15 ('base')","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
info 10:30:39.288: Find preferred kernel for /workspaces/aoc2022/13/13.ipynb with metadata {"kernelspec":{"display_name":"Python 3.9.15 ('base')","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
info 10:30:44.230: Find preferred kernel for /workspaces/aoc2022/13/14.ipynb with metadata {"kernelspec":{"display_name":"Python 3.9.15 ('base')","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
info 10:30:44.231: Preferred kernel .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher is exact match or top match for non python kernels, (false, [object Object], true, false)
info 10:30:44.231: PreferredConnection: .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher found for NotebookDocument: /workspaces/aoc2022/13/14.ipynb
error 10:30:45.106: Execing shell command, Class name = f, completed in 7793ms, has a falsy return value, Arg 1: "~/.vscode-remote/extensions/ms-toolsai.jupyter-2023.1.1003441034/pythonFiles/printEnvVariables.py", Arg 2: "" Error: Command failed: conda activate base && echo 'e8b39361-0157-4923-80e1-22d70d46dee6' && python /home/vscode/.vscode-remote/extensions/ms-toolsai.jupyter-2023.1.1003441034/pythonFiles/printEnvVariables.py

CommandNotFoundError: Your shell has not been properly configured to use 'conda activate'.
To initialize your shell, run

    $ conda init <SHELL_NAME>

Currently supported shells are:
  - bash
  - fish
  - tcsh
  - xonsh
  - zsh
  - powershell

See 'conda init --help' for more information and options.

IMPORTANT: You may need to close and restart your shell after running 'conda init'.



    at ChildProcess.exithandler (node:child_process:399:12)
    at ChildProcess.emit (node:events:526:28)
    at maybeClose (node:internal/child_process:1092:16)
    at Process.ChildProcess._handle.onexit (node:internal/child_process:302:5) {
  killed: false,
  code: 1,
  signal: null,
  cmd: "conda activate base && echo 'e8b39361-0157-4923-80e1-22d70d46dee6' && python /home/vscode/.vscode-remote/extensions/ms-toolsai.jupyter-2023.1.1003441034/pythonFiles/printEnvVariables.py"
}
info 10:30:45.129: Process Execution: > /opt/conda/bin/python -m pip list
> /opt/conda/bin/python -m pip list
info 10:30:45.569: Preferred kernel .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher is exact match or top match for non python kernels, (false, [object Object], false, false)
info 10:30:45.570: PreferredConnection: .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher found for NotebookDocument: /workspaces/aoc2022/12/12.ipynb
info 10:30:45.622: Process Execution: > / -m pip list
> / -m pip list
error 10:30:45.623: interpreterPackages getPackageInformation, Class name = r, completed in 52ms, has a falsy return value, Arg 1: {"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"fsPath":"/[object Object]","path":"/[object Object]","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"vscode-remote","authority":"codespaces+joaomoreno-zany-disco-rpv7x47vc5ggg"},"envName":"base","displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"},"uri":{"$mid":1,"fsPath":"/","external":"file:///","path":"/","scheme":"file"}}} Error: spawn / EACCES
    at Process.ChildProcess._handle.onexit (node:internal/child_process:283:19)
    at onErrorNT (node:internal/child_process:478:16)
    at processTicksAndRejections (node:internal/process/task_queues:83:21) {
  errno: -13,
  code: 'EACCES',
  syscall: 'spawn /',
  path: '/',
  spawnargs: [ '-m', 'pip', 'list' ]
}
info 10:33:37.581: Starting Jupyter Session startUsingPythonInterpreter, .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher (Python Path: /opt/conda, EnvType: Conda, EnvName: 'base', Version: 3.9.15 (main, Nov 24 2022, 14:31:59) 
[GCC 11.2.0]) for '/workspaces/aoc2022/13/14.ipynb' (disableUI=false)
info 10:33:37.825: Process Execution: > /opt/conda/bin/python -c "import ipykernel; print(ipykernel.__version__); print("5dc3a68c-e34e-4080-9c3e-2a532b2ccb4d"); print(ipykernel.__file__)"
> /opt/conda/bin/python -c "import ipykernel; print(ipykernel.__version__); print("5dc3a68c-e34e-4080-9c3e-2a532b2ccb4d"); print(ipykernel.__file__)"
info 10:33:38.033: Process Execution: > /opt/conda/bin/python -m ipykernel_launcher --ip=127.0.0.1 --stdin=9003 --control=9001 --hb=9000 --Session.signature_scheme="hmac-sha256" --Session.key=b"18954357-0278-4716-8f23-dd6bfe5c3401" --shell=9002 --transport="tcp" --iopub=9004 --f=/home/vscode/.local/share/jupyter/runtime/kernel-v2-1085JfngzJzXw6o2.json
> /opt/conda/bin/python -m ipykernel_launcher --ip=127.0.0.1 --stdin=9003 --control=9001 --hb=9000 --Session.signature_scheme="hmac-sha256" --Session.key=b"18954357-0278-4716-8f23-dd6bfe5c3401" --shell=9002 --transport="tcp" --iopub=9004 --f=/home/vscode/.local/share/jupyter/runtime/kernel-v2-1085JfngzJzXw6o2.json
info 10:33:38.033: Process Execution: cwd: /workspaces/aoc2022/13
cwd: /workspaces/aoc2022/13
info 10:33:40.670: ipykernel version & path 6.15.2, /opt/conda/lib/python3.9/site-packages/ipykernel/__init__.py for /opt/conda/bin/python
warn 10:33:44.840: StdErr from Kernel Process /opt/conda/lib/python3.9/site-packages/traitlets/traitlets.py:2202: FutureWarning: Supporting extra quotes around strings is deprecated in traitlets 5.0. You can use 'hmac-sha256' instead of '"hmac-sha256"' if you require traitlets >=5.
  warn(

warn 10:33:44.841: StdErr from Kernel Process /opt/conda/lib/python3.9/site-packages/traitlets/traitlets.py:2157: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use '18954357-0278-4716-8f23-dd6bfe5c3401' instead of 'b"18954357-0278-4716-8f23-dd6bfe5c3401"'.
  warn(

info 10:33:45.229: Started Kernel base (Python 3.9.15) (pid: 2063)
info 10:33:45.513: Process Execution: > /opt/conda/bin/python ~/.vscode-remote/extensions/ms-toolsai.jupyter-2023.1.1003441034/pythonFiles/printJupyterDataDir.py
> /opt/conda/bin/python ~/.vscode-remote/extensions/ms-toolsai.jupyter-2023.1.1003441034/pythonFiles/printJupyterDataDir.py
info 10:34:09.721: Find preferred kernel for /workspaces/aoc2022/11/11.ipynb with metadata {"kernelspec":{"display_name":"Python 3.9.15 ('base')","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
info 10:34:09.722: Preferred kernel .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher is exact match or top match for non python kernels, (false, [object Object], true, false)
info 10:34:09.724: PreferredConnection: .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher found for NotebookDocument: /workspaces/aoc2022/11/11.ipynb
info 10:35:44.440: Cancel all remaining cells true || Error || undefined
info 10:35:53.381: Cancel all remaining cells true || Error || undefined
info 10:36:05.866: Cancel all remaining cells true || Error || undefined
info 10:36:13.474: Cancel all remaining cells true || Error || undefined
info 10:36:16.654: Cancel all remaining cells true || Error || undefined
info 10:43:21.333: Cancel all remaining cells true || Error || undefined
info 10:43:36.231: Cancel all remaining cells true || Error || undefined
info 10:43:52.642: Cancel all remaining cells true || Error || undefined
info 10:44:55.119: Cancel all remaining cells true || Error || undefined
info 10:45:30.959: Cancel all remaining cells true || Error || undefined
info 10:46:02.443: Cancel all remaining cells true || Error || undefined
info 10:46:11.536: Cancel all remaining cells true || Error || undefined
info 10:46:34.389: Cancel all remaining cells true || Error || undefined
info 10:46:44.676: Cancel all remaining cells true || Error || undefined
info 10:46:55.782: Cancel all remaining cells true || Error || undefined
info 10:47:18.529: Cancel all remaining cells true || Error || undefined
info 10:47:30.444: Cancel all remaining cells true || Error || undefined
info 10:47:34.082: Restart kernel execution
info 10:47:34.083: Restart requested file:///workspaces/aoc2022/13/14.ipynb
info 10:47:34.083: Restarting 61377f03-be9e-41c4-9aee-07c2c5c61100
info 10:47:34.137: Process Execution: > /opt/conda/bin/python -c "import ipykernel; print(ipykernel.__version__); print("5dc3a68c-e34e-4080-9c3e-2a532b2ccb4d"); print(ipykernel.__file__)"
> /opt/conda/bin/python -c "import ipykernel; print(ipykernel.__version__); print("5dc3a68c-e34e-4080-9c3e-2a532b2ccb4d"); print(ipykernel.__file__)"
info 10:47:34.304: Process Execution: > /opt/conda/bin/python -m ipykernel_launcher --ip=127.0.0.1 --stdin=9008 --control=9006 --hb=9005 --Session.signature_scheme="hmac-sha256" --Session.key=b"572afd6f-331e-41dc-8f3d-19449a105d9a" --shell=9007 --transport="tcp" --iopub=9009 --f=/home/vscode/.local/share/jupyter/runtime/kernel-v2-10859adPXEnvajgD.json
> /opt/conda/bin/python -m ipykernel_launcher --ip=127.0.0.1 --stdin=9008 --control=9006 --hb=9005 --Session.signature_scheme="hmac-sha256" --Session.key=b"572afd6f-331e-41dc-8f3d-19449a105d9a" --shell=9007 --transport="tcp" --iopub=9009 --f=/home/vscode/.local/share/jupyter/runtime/kernel-v2-10859adPXEnvajgD.json
info 10:47:34.304: Process Execution: cwd: /workspaces/aoc2022/13
cwd: /workspaces/aoc2022/13
info 10:47:34.845: ipykernel version & path 6.15.2, /opt/conda/lib/python3.9/site-packages/ipykernel/__init__.py for /opt/conda/bin/python
warn 10:47:36.163: StdErr from Kernel Process /opt/conda/lib/python3.9/site-packages/traitlets/traitlets.py:2202: FutureWarning: Supporting extra quotes around strings is deprecated in traitlets 5.0. You can use 'hmac-sha256' instead of '"hmac-sha256"' if you require traitlets >=5.
  warn(

warn 10:47:36.164: StdErr from Kernel Process /opt/conda/lib/python3.9/site-packages/traitlets/traitlets.py:2157: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use '572afd6f-331e-41dc-8f3d-19449a105d9a' instead of 'b"572afd6f-331e-41dc-8f3d-19449a105d9a"'.
  warn(

info 10:47:36.382: Got new session 4e663329-a48a-4123-8702-99d66dc3ca73
info 10:47:36.382: Started new restart session
info 10:47:36.383: Dispose Kernel process 2063.
info 11:03:39.164: Cancel all remaining cells true || Error || undefined
info 11:09:59.969: Cancel all remaining cells true || Error || undefined
info 11:10:14.751: Cancel all remaining cells true || Error || undefined
info 11:10:18.839: Cancel all remaining cells true || Error || undefined
info 11:10:51.716: Cancel all remaining cells true || Error || undefined
info 11:11:16.523: Interrupt kernel execution
info 11:11:16.523: Interrupt requested /workspaces/aoc2022/13/14.ipynb
info 11:11:16.524: Interrupt kernel execution
info 11:11:16.524: Interrupting kernel: pythonjvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe
info 11:11:16.524: Interrupting kernel via SIGINT
info 11:11:19.234: Cancel all remaining cells true || Success || undefined
info 11:11:19.235: Interrupt requested & sent for /workspaces/aoc2022/13/14.ipynb in notebookEditor.
info 11:12:02.483: Disposing kernel .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher for notebook /workspaces/aoc2022/13/14.ipynb due to selection of another kernel or closing of the notebook
info 11:12:02.483: Dispose Kernel '/workspaces/aoc2022/13/14.ipynb' associated with '/workspaces/aoc2022/13/14.ipynb'
info 11:12:02.487: Dispose Kernel process 6062.
info 11:12:02.814: Find preferred kernel for /workspaces/aoc2022/14/14.ipynb with metadata {"kernelspec":{"display_name":"Python 3.9.15 ('base')","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
info 11:12:02.817: Preferred kernel .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher is exact match or top match for non python kernels, (false, [object Object], true, false)
info 11:12:02.819: PreferredConnection: .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher found for NotebookDocument: /workspaces/aoc2022/14/14.ipynb
info 11:12:10.013: Find preferred kernel for /workspaces/aoc2022/13 copy/13.ipynb with metadata {"kernelspec":{"display_name":"Python 3.9.15 ('base')","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
info 11:12:10.015: Preferred kernel .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher is exact match or top match for non python kernels, (false, [object Object], true, false)
info 11:12:10.016: PreferredConnection: .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher found for NotebookDocument: /workspaces/aoc2022/13 copy/13.ipynb
info 11:12:22.461: Find preferred kernel for /workspaces/aoc2022/13/13.ipynb with metadata {"kernelspec":{"display_name":"Python 3.9.15 ('base')","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
info 11:12:22.462: Preferred kernel .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher is exact match or top match for non python kernels, (false, [object Object], true, false)
info 11:12:22.464: PreferredConnection: .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher found for NotebookDocument: /workspaces/aoc2022/13/13.ipynb
info 11:12:34.301: Find preferred kernel for /workspaces/aoc2022/12/12.ipynb with metadata {"kernelspec":{"display_name":"Python 3.9.15 ('base')","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
info 11:12:34.302: Preferred kernel .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher is exact match or top match for non python kernels, (false, [object Object], true, false)
info 11:12:34.302: PreferredConnection: .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher found for NotebookDocument: /workspaces/aoc2022/12/12.ipynb
info 11:12:50.713: Process Execution: > /opt/conda/bin/python -m pip list
> /opt/conda/bin/python -m pip list
info 11:12:54.621: Starting Jupyter Session startUsingPythonInterpreter, .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher (Python Path: /opt/conda, EnvType: Conda, EnvName: 'base', Version: 3.9.15 (main, Nov 24 2022, 14:31:59) 
[GCC 11.2.0]) for '/workspaces/aoc2022/14/14.ipynb' (disableUI=false)
info 11:12:54.644: Process Execution: > /opt/conda/bin/python -c "import ipykernel; print(ipykernel.__version__); print("5dc3a68c-e34e-4080-9c3e-2a532b2ccb4d"); print(ipykernel.__file__)"
> /opt/conda/bin/python -c "import ipykernel; print(ipykernel.__version__); print("5dc3a68c-e34e-4080-9c3e-2a532b2ccb4d"); print(ipykernel.__file__)"
info 11:12:54.718: Process Execution: > /opt/conda/bin/python -m ipykernel_launcher --ip=127.0.0.1 --stdin=9003 --control=9001 --hb=9000 --Session.signature_scheme="hmac-sha256" --Session.key=b"48896517-7899-408d-bdfb-831e17c12800" --shell=9002 --transport="tcp" --iopub=9004 --f=/home/vscode/.local/share/jupyter/runtime/kernel-v2-1085KtFvwA2NBFsC.json
> /opt/conda/bin/python -m ipykernel_launcher --ip=127.0.0.1 --stdin=9003 --control=9001 --hb=9000 --Session.signature_scheme="hmac-sha256" --Session.key=b"48896517-7899-408d-bdfb-831e17c12800" --shell=9002 --transport="tcp" --iopub=9004 --f=/home/vscode/.local/share/jupyter/runtime/kernel-v2-1085KtFvwA2NBFsC.json
info 11:12:54.718: Process Execution: cwd: /workspaces/aoc2022/14
cwd: /workspaces/aoc2022/14
info 11:12:55.336: ipykernel version & path 6.15.2, /opt/conda/lib/python3.9/site-packages/ipykernel/__init__.py for /opt/conda/bin/python
warn 11:12:55.958: StdErr from Kernel Process /opt/conda/lib/python3.9/site-packages/traitlets/traitlets.py:2202: FutureWarning: Supporting extra quotes around strings is deprecated in traitlets 5.0. You can use 'hmac-sha256' instead of '"hmac-sha256"' if you require traitlets >=5.
  warn(

warn 11:12:55.958: StdErr from Kernel Process /opt/conda/lib/python3.9/site-packages/traitlets/traitlets.py:2157: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use '48896517-7899-408d-bdfb-831e17c12800' instead of 'b"48896517-7899-408d-bdfb-831e17c12800"'.
  warn(

info 11:12:56.174: Started Kernel base (Python 3.9.15) (pid: 15280)

Coding Language and Runtime Version

Python 3.9.15

Language Extension Version (if applicable)

v2022.21.13481008

Anaconda Version (if applicable)

No response

Running Jupyter locally or remotely?

None
@joaomoreno joaomoreno added the bug Issue identified by VS Code Team member as probable bug label Dec 14, 2022
@DonJayamanne
Copy link
Contributor

DonJayamanne commented Dec 20, 2022 •
edited
Loading

Thanks, I believe we've encountered this in the past, its due to network latency.
will see what we can do here

We could start the timer when we get the first packet for the request (however this would still have the same issue as we might be slow/busy processing other messages when the execution has completed)

@DonJayamanne DonJayamanne added notebook-kernel Kernels issues (start/restart/switch/execution, install ipykernel) debt Code quality issues polish Cleanup and polish issue labels Dec 22, 2022
@DonJayamanne
Copy link
Contributor

@joaomoreno
please could you do me a favour:

  • Please enable verbose logging for the Jupyter extension in settings
  • Reload VS Code
  • Clear the logs
  • Replicate this issue
  • Provide the logs

Basically I would like to see how long different parts of the extension take to run.
Based on the screen recording, it looks like you have edited the recording (which is acceptable & understandable). However I would like to confirm a few things at my end.

@kieferrm kieferrm mentioned this issue Jan 2, 2023
Closed
20 tasks
@joaomoreno
Copy link
Member Author

@DonJayamanne Here's the notebook which reproduces it: https://github.com/joaomoreno/aoc2022/blob/main/13/13.ipynb

You can fork this repo, install Jupyter and Python and open the notebook against the base (Python 3.9.15) kernel. Then, run the cells. Notice cells start at around ~37 seconds, when pressing Play on each.

@DonJayamanne
Copy link
Contributor

DonJayamanne commented Jan 3, 2023 •
edited
Loading

@joaomoreno Thanks for the info, I've done the same thing, and opened in github codespaces desktop (which is what I believe you are using)
I don't see similar delays, in fact i can see the ticker go to 0.3s and then fall back to 0.1s. but not in the range of 37s.
I believe there is some network latency, hence the request for the verbose logs

Please could your provide the following two:

  • Provide the network latency report using the command Developer: Measure Extension host latency
  • Provide the output from jupyter using verbose logging as follows
    Please could you enable logging as follows:
    • Open settings & go into Jupyter->Logging
    • Change the value to verbose
    • Reload VS Code,
    • Attempt to repro the issue & then copy the output from the Jupyter output panel.

@joaomoreno
Copy link
Member Author 

Roundtrip latency: 0.640ms
Up: 1088.4 Mbps
Down: 4651.2 Mbps


Roundtrip latency: 0.720ms
Up: 2035.6 Mbps
Down: 1484.2 Mbps


Authority: codespaces+joaomoreno-zany-disco-rpv7x47vc5ggg
Roundtrip latency: 32.520ms
Up: 22.1 Mbps
Down: 538.4 Mbps
Jupyter Output 
Visual Studio Code - Insiders (1.75.0-insider, codespaces, desktop)
Jupyter Extension Version: 2023.1.1003661022.
Python Extension Version: 2022.21.13601008.
Workspace folder /workspaces/aoc2022
User belongs to experiment group 'jupyterTest'
User belongs to experiment group 'jupyterEnhancedDataViewer'
debug 08:16:36.424: Updating cache with Python kernels 
, Added = 
, Updated = 
, Removed = 
debug 08:16:36.425: Get Custom Env Variables, Class name = S (started execution), Arg 1: undefined, Arg 2: "RunPythonCode"
debug 08:16:36.428: Start refreshing Kernel Picker (1672992996428)
debug 08:16:36.429: Get Active Interpreter, Class name = x (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb"
debug 08:16:36.429: Get Active Interpreter, Class name = x (started execution), Arg 1: undefined
debug 08:16:36.447: Get Active Interpreter, Class name = x (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb"
info 08:16:36.449: LSP Notebooks experiment is enabled
debug 08:16:36.449: Get Active Interpreter, Class name = x (started execution), Arg 1: "/workspaces/aoc2022"
debug 08:16:36.517: Get Active Interpreter, Class name = x (started execution), Arg 1: undefined
debug 08:16:36.518: Updating cache with Python kernels startUsingPythonInterpreter:'.jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher (interpreter id = /opt/conda/bin/python)', startUsingPythonInterpreter:'.jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher (interpreter id = /usr/bin/python3)'
, Added = startUsingPythonInterpreter:'.jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher (interpreter id = /opt/conda/bin/python)', startUsingPythonInterpreter:'.jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher (interpreter id = /usr/bin/python3)'
, Updated = 
, Removed = 
debug 08:16:36.909: Get Active Interpreter, Class name = x (started execution), Arg 1: undefined
debug 08:16:36.915: Get Custom Env Variables, Class name = S, completed in 490ms, has a truthy return value, Arg 1: undefined, Arg 2: "RunPythonCode"
debug 08:16:36.917: Get Jupyter Sub Paths, Class name = j, completed in 492ms, has a truthy return value, Arg 1: "", Arg 2: "kernels", Return Value: 
debug 08:16:36.917: Get Jupyter Kernel Paths, Class name = j, completed in 492ms, has a truthy return value, Arg 1: "", Return Value: 
debug 08:16:36.918: Get KernelSpec root path, Class name = j, completed in 492ms, has a truthy return value, Arg 1: {"a":false,"b":null}, Return Value: <Uri:/usr/share/jupyter/kernels>, <Uri:/usr/local/share/jupyter/kernels>, <Uri:/home/vscode/.local/share/jupyter/kernels>
debug 08:16:36.929: Get Active Interpreter, Class name = x, completed in 500ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Return Value: {"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}
debug 08:16:36.929: Get Active Interpreter, Class name = x, completed in 500ms, has a truthy return value, Arg 1: undefined, Return Value: {"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}
debug 08:16:36.930: Get Active Interpreter, Class name = x, completed in 483ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Return Value: {"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}
debug 08:16:36.930: Rank Kernels, Class name = p (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "", Arg 3: {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}}, Arg 4: <Uri:/opt/conda/bin/python>, Arg 5: "", Arg 6: undefined
info 08:16:36.930: Find preferred kernel for /workspaces/aoc2022/13/13.ipynb with metadata {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
debug 08:16:36.931: Rank Kernels, Class name = p, completed in 1ms, has a falsy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "", Arg 3: {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}}, Arg 4: <Uri:/opt/conda/bin/python>, Arg 5: "", Arg 6: undefined
debug 08:16:36.931: Rank Kernels, Class name = p (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "", Arg 3: {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}}, Arg 4: <Uri:/opt/conda/bin/python>, Arg 5: "", Arg 6: undefined
info 08:16:36.931: Find preferred kernel for /workspaces/aoc2022/13/13.ipynb with metadata {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
debug 08:16:36.932: Rank Kernels, Class name = p, completed in 1ms, has a falsy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "", Arg 3: {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}}, Arg 4: <Uri:/opt/conda/bin/python>, Arg 5: "", Arg 6: undefined
debug 08:16:36.932: Compute Preferred Controller, Class name = P, completed in 486ms, has a truthy return value, Arg 1: <Uri:/workspaces/aoc2022/13/13.ipynb>, Arg 2: undefined, Arg 3: {"a":false,"b":{"j":false,"q":{"a":{},"b":{},"c":0}}}, Return Value: {}
debug 08:16:36.932: Get Active Interpreter, Class name = x, completed in 483ms, has a truthy return value, Arg 1: "/workspaces/aoc2022", Return Value: {"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}
debug 08:16:36.933: Get Active Interpreter, Class name = x, completed in 416ms, has a truthy return value, Arg 1: undefined, Return Value: {"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}
debug 08:16:36.933: Get Active Interpreter, Class name = x, completed in 24ms, has a truthy return value, Arg 1: undefined, Return Value: {"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}
debug 08:16:36.933: interpreterPackages getPackageInformation, Class name = r (started execution), Arg 1: {"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}}
debug 08:16:36.933: Create activated Env, Class name = y (started execution), Arg 1: {"allowEnvironmentFetchExceptions":true,"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}}
debug 08:16:36.934: Getting activated env variables, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022", Arg 2: <Uri:/opt/conda/bin/python>, Arg 3: true
debug 08:16:36.934: Getting activated env variables impl, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:36.934: Getting activated env variables from Python, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:36.934: Get Custom Env Variables, Class name = S (started execution), Arg 1: "/workspaces/aoc2022", Arg 2: "RunPythonCode"
debug 08:16:36.935: Get Custom Env Variables, Class name = S, completed in 1ms, has a truthy return value, Arg 1: "/workspaces/aoc2022", Arg 2: "RunPythonCode"
debug 08:16:36.946: Not Searching for kernels as path does not exist, /usr/share/jupyter/kernels
debug 08:16:36.946: Not Searching for kernels as path does not exist, /usr/local/share/jupyter/kernels
debug 08:16:36.946: Not Searching for kernels as path does not exist, /home/vscode/.local/share/jupyter/kernels
debug 08:16:36.968: Creating notebook controller for startUsingPythonInterpreter & view jupyter-notebook (id='.jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher') with name 'base (Python 3.9.15)'
debug 08:16:36.970: Get Active Interpreter, Class name = x (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb"
debug 08:16:36.971: Get Active Interpreter, Class name = x, completed in 1ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Return Value: {"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}
debug 08:16:36.972: Rank Kernels, Class name = p (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "", Arg 3: {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}}, Arg 4: <Uri:/opt/conda/bin/python>, Arg 5: "", Arg 6: undefined
info 08:16:36.972: Find preferred kernel for /workspaces/aoc2022/13/13.ipynb with metadata {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
debug 08:16:36.972: Rank Kernels, Class name = p, completed in 0ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "", Arg 3: {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}}, Arg 4: <Uri:/opt/conda/bin/python>, Arg 5: "", Arg 6: undefined
info 08:16:36.973: Preferred kernel .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher is exact match or top match for non python kernels, (true, [object Object], true, false)
info 08:16:36.974: PreferredConnection: .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher found for NotebookDocument: /workspaces/aoc2022/13/13.ipynb
debug 08:16:36.974: TargetController found ID: startUsingPythonInterpreter:.jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher for document /workspaces/aoc2022/13/13.ipynb
debug 08:16:36.975: Found 2 cached controllers
debug 08:16:36.975: Creating 2 controllers
debug 08:16:36.975: Creating notebook controller for startUsingPythonInterpreter & view interactive (id='.jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher') with name 'base (Python 3.9.15)'
debug 08:16:36.976: Creating notebook controller for startUsingPythonInterpreter & view jupyter-notebook (id='.jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher') with name 'Python 3.9.2'
debug 08:16:36.977: Creating notebook controller for startUsingPythonInterpreter & view interactive (id='.jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher') with name 'Python 3.9.2'
debug 08:16:36.977: Get Active Interpreter, Class name = x (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb"
debug 08:16:36.978: Get Active Interpreter, Class name = x, completed in 1ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Return Value: {"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}
debug 08:16:36.978: Rank Kernels, Class name = p (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "", Arg 3: {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}}, Arg 4: <Uri:/opt/conda/bin/python>, Arg 5: "", Arg 6: undefined
info 08:16:36.978: Find preferred kernel for /workspaces/aoc2022/13/13.ipynb with metadata {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}} & preferred interpreter /opt/conda/bin/python
debug 08:16:36.979: Found 2 cached controllers
debug 08:16:36.979: Creating 2 controllers
debug 08:16:36.984: Rank Kernels, Class name = p, completed in 6ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "", Arg 3: {"kernelspec":{"display_name":"base","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.9.15"},"orig_nbformat":4,"vscode":{"interpreter":{"hash":"d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe"}}}, Arg 4: <Uri:/opt/conda/bin/python>, Arg 5: "", Arg 6: undefined
info 08:16:36.985: Preferred kernel .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher is exact match or top match for non python kernels, (false, [object Object], true, false)
info 08:16:36.985: PreferredConnection: .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher found for NotebookDocument: /workspaces/aoc2022/13/13.ipynb
debug 08:16:36.985: TargetController found ID: startUsingPythonInterpreter:.jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher for document /workspaces/aoc2022/13/13.ipynb
debug 08:16:36.999: Compute Preferred Controller, Class name = P, completed in 29ms, has a truthy return value, Arg 1: <Uri:/workspaces/aoc2022/13/13.ipynb>, Arg 2: undefined, Arg 3: {"a":true,"b":null}, Return Value: {}
debug 08:16:37.000: Compute Preferred Controller, Class name = P, completed in 23ms, has a truthy return value, Arg 1: <Uri:/workspaces/aoc2022/13/13.ipynb>, Arg 2: undefined, Arg 3: {"a":false,"b":{"j":false,"q":{"a":{},"b":{},"c":0}}}, Return Value: <Return value cannot be serialized for logging>
debug 08:16:37.081: KernelProvider switched kernel to id = .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher
debug 08:16:37.083: Initialize notebook communications for editor /workspaces/aoc2022/13/13.ipynb
debug 08:16:37.083: Resolving notebook UI Comms (resolve) for /workspaces/aoc2022/13/13.ipynb
debug 08:16:37.083: initialize CommonMessageCoordinator
debug 08:16:37.105: Registering commtarget jupyter.widget
debug 08:16:37.105: IPyWidgetMessageDispatcher.initialize
debug 08:16:37.106: Controller selection change completed
debug 08:16:37.107: Storing Preferred remote kernel for /workspaces/aoc2022/13/13.ipynb is undefined
debug 08:16:37.333: IPyWidgetMessageDispatcher.initialize
debug 08:16:37.406: Conda file returned by Python Extension is conda
debug 08:16:37.475: Python API env change detected, update => '/opt/conda/bin/python}'
debug 08:16:37.516: Full interpreter list after refreshing (total 25ms, resolve 1ms, refresh 24ms) is length: 2, /usr/bin/python3:undefined::/usr/bin/python3:/usr/bin/python3, /opt/conda/bin/python:base:Conda:/opt/conda/bin/python:/opt/conda/bin/python
debug 08:16:37.516: Python environment for /opt/conda/bin/python is /opt/conda/bin/python from Python Extension API is {"internal":{"path":"/opt/conda/bin/python","id":"/opt/conda/bin/python","executable":{"uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"bitness":"64-bit","sysPrefix":"/opt/conda"},"environment":{"type":"Conda","name":"base","folderUri":{"$mid":1,"path":"/opt/conda","scheme":"file"}},"version":{"major":3,"minor":9,"micro":15,"release":{"level":"final","serial":0},"sysVersion":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"},"tools":["Conda"]},"id":"/opt/conda/bin/python"} and translated is {"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}
debug 08:16:37.544: loadData after detecting changes to interpreters
debug 08:16:37.544: Updating cache with Python kernels startUsingPythonInterpreter:'.jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher (interpreter id = /opt/conda/bin/python)', startUsingPythonInterpreter:'.jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher (interpreter id = /usr/bin/python3)'
, Added = 
, Updated = 
, Removed = 
debug 08:16:37.544: refreshData after detecting changes to interpreters
debug 08:16:37.544: Listing Python kernels for Interpreter /opt/conda/bin/python
debug 08:16:37.544: Get Active Interpreter, Class name = x (started execution), Arg 1: "/workspaces/aoc2022"
debug 08:16:37.545: Listing Python kernels for Interpreter /usr/bin/python3
debug 08:16:37.545: Get Active Interpreter, Class name = x (started execution), Arg 1: "/workspaces/aoc2022"
debug 08:16:37.546: Python environment for /usr/bin/python3 is /usr/bin/python3 from Python Extension API is {"internal":{"path":"/usr/bin/python3","id":"/usr/bin/python3","executable":{"uri":{"$mid":1,"path":"/usr/bin/python3","scheme":"file"},"bitness":"64-bit","sysPrefix":"/usr"},"version":{"major":3,"minor":9,"micro":2,"release":{"level":"final","serial":0},"sysVersion":"3.9.2 (default, Feb 28 2021, 17:03:44) \n[GCC 10.2.1 20210110]"},"tools":[]},"id":"/usr/bin/python3"} and translated is {"id":"/usr/bin/python3","sysPrefix":"/usr","displayPath":{"$mid":1,"path":"/usr/bin/python3","scheme":"file"},"envName":"","uri":{"$mid":1,"fsPath":"/usr/bin/python3","external":"file:///usr/bin/python3","path":"/usr/bin/python3","scheme":"file"},"displayName":"","envType":"Unknown","version":{"major":3,"minor":9,"patch":2,"raw":"3.9.2 (default, Feb 28 2021, 17:03:44) \n[GCC 10.2.1 20210110]"}}
debug 08:16:37.547: Get Jupyter Kernel Paths, Class name = j, completed in 2ms, has a truthy return value, Arg 1: "", Return Value: 
debug 08:16:37.547: Get Jupyter Kernel Paths, Class name = j, completed in 2ms, has a truthy return value, Arg 1: "", Return Value: 
debug 08:16:37.547: Get KernelSpec root path, Class name = j, completed in 2ms, has a truthy return value, Arg 1: {"a":false,"b":null}, Return Value: <Uri:/usr/share/jupyter/kernels>, <Uri:/usr/local/share/jupyter/kernels>, <Uri:/home/vscode/.local/share/jupyter/kernels>
debug 08:16:37.547: Get KernelSpec root path, Class name = j, completed in 2ms, has a truthy return value, Arg 1: {"a":false,"b":null}, Return Value: <Uri:/usr/share/jupyter/kernels>, <Uri:/usr/local/share/jupyter/kernels>, <Uri:/home/vscode/.local/share/jupyter/kernels>
debug 08:16:37.547: Full interpreter list is length: 2, /opt/conda/bin/python:base:Conda:/opt/conda/bin/python, /usr/bin/python3::Unknown:/usr/bin/python3
debug 08:16:37.547: Refreshed Environments
debug 08:16:37.548: Get Active Interpreter, Class name = x, completed in 4ms, has a truthy return value, Arg 1: "/workspaces/aoc2022", Return Value: {"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}
debug 08:16:37.548: Get Active Interpreter, Class name = x, completed in 3ms, has a truthy return value, Arg 1: "/workspaces/aoc2022", Return Value: {"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}
debug 08:16:37.560: Loading kernelspec from /opt/conda/share/jupyter/kernels/python3/kernel.json for /opt/conda/bin/python
debug 08:16:37.566: Hiding default kernel spec 'Python 3 (ipykernel)', 'pythonjvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe', /opt/conda/bin/python
debug 08:16:37.566: Updating cache with Python kernels startUsingPythonInterpreter:'.jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher (interpreter id = /opt/conda/bin/python)', startUsingPythonInterpreter:'.jvsc74a57bd031f2aee4e71d21fbe5cf8b01ff0e069b9275f58929596ceb00d14d90e3e16cd6./usr/bin/python3./usr/bin/python3.-m#ipykernel_launcher (interpreter id = /usr/bin/python3)'
, Added = 
, Updated = 
, Removed = 
debug 08:16:38.047: _jupyterPythonPathFunction: "file:///workspaces/aoc2022" is not a notebook
debug 08:16:38.049: _jupyterPythonPathFunction: Giving Pylance "/opt/conda/bin/python" as python path for "file:///workspaces/aoc2022/13/13.ipynb"
debug 08:16:38.550: End refreshing Kernel Picker (1672992996428)
debug 08:16:38.871: Got env vars with python /opt/conda/bin/python, with env var count 56 and custom env var count 50 in 1937ms
debug 08:16:38.871: Getting activated env variables from Python, Class name = _, completed in 1937ms, has a truthy return value, Arg 1: "/workspaces/aoc2022", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:38.871: Got env vars with python /opt/conda/bin/python in 1937ms with 56 variables
debug 08:16:38.871: Getting activated env variables impl, Class name = _, completed in 1937ms, has a truthy return value, Arg 1: "/workspaces/aoc2022", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:38.872: Getting activated env variables, Class name = _, completed in 1937ms, has a truthy return value, Arg 1: "/workspaces/aoc2022", Arg 2: <Uri:/opt/conda/bin/python>, Arg 3: true
debug 08:16:38.872: Create activated Env, Class name = y, completed in 1939ms, has a truthy return value, Arg 1: {"allowEnvironmentFetchExceptions":true,"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}},"resource":{"$mid":1,"fsPath":"/workspaces/aoc2022","external":"file:///workspaces/aoc2022","path":"/workspaces/aoc2022","scheme":"file"}}
info 08:16:38.886: Process Execution: > /opt/conda/bin/python -m pip list
> /opt/conda/bin/python -m pip list
debug 08:16:39.445: interpreterPackages getPackageInformation, Class name = r, completed in 2512ms, has a falsy return value, Arg 1: {"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}}
debug 08:16:40.578: VSCodeNotebookController::handleExecution, Class name = x (started execution)
debug 08:16:40.579: Execute Cell 1 /workspaces/aoc2022/13/13.ipynb
debug 08:16:40.580: start the kernel, options.disableUI=false for /workspaces/aoc2022/13/13.ipynb
debug 08:16:40.581: Execute Cell 3 /workspaces/aoc2022/13/13.ipynb
debug 08:16:40.581: start the kernel, options.disableUI=false for /workspaces/aoc2022/13/13.ipynb
debug 08:16:40.581: Execute Cell 5 /workspaces/aoc2022/13/13.ipynb
debug 08:16:40.581: start the kernel, options.disableUI=false for /workspaces/aoc2022/13/13.ipynb
debug 08:16:40.581: Execute Cell 6 /workspaces/aoc2022/13/13.ipynb
debug 08:16:40.581: start the kernel, options.disableUI=false for /workspaces/aoc2022/13/13.ipynb
debug 08:16:40.581: Start Jupyter Session in kernel.ts with disableUI = false
info 08:16:40.582: Starting Jupyter Session startUsingPythonInterpreter, .jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher (Python Path: /opt/conda, EnvType: Conda, EnvName: 'base', Version: 3.9.15 (main, Nov 24 2022, 14:31:59) 
[GCC 11.2.0]) for '/workspaces/aoc2022/13/13.ipynb' (disableUI=false)
debug 08:16:40.583: Creating raw notebook for resource '/workspaces/aoc2022/13/13.ipynb'
debug 08:16:40.583: Computing working directory for resource '/workspaces/aoc2022/13/13.ipynb'
debug 08:16:40.585: Kernel Launcher. launch, Class name = r (started execution), Arg 1: {"id":".jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher","kernelSpec":{"interpreterPath":"/opt/conda/bin/python","isRegisteredByVSC":"registeredByNewVersionOfExt","name":"pythonjvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe","argv":["python","-m","ipykernel_launcher","-f","{connection_file}"],"language":"python","executable":"python","display_name":"base","metadata":{"interpreter":{"path":"/opt/conda/bin/python"}},"env":{}},"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":"/opt/conda","displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"},"path":"/opt/conda/bin/python"},"kind":"startUsingPythonInterpreter"}, Arg 2: 60000, Arg 3: "/workspaces/aoc2022/13/13.ipynb", Arg 4: "/workspaces/aoc2022/13", Arg 5: {"a":false,"b":null}
debug 08:16:40.585: Create activated Env, Class name = y (started execution), Arg 1: {"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}},"resource":{"$mid":1,"fsPath":"/workspaces/aoc2022/13/13.ipynb","external":"file:///workspaces/aoc2022/13/13.ipynb","path":"/workspaces/aoc2022/13/13.ipynb","scheme":"file"}}
debug 08:16:40.585: Getting activated env variables, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>, Arg 3: undefined
debug 08:16:40.585: Getting activated env variables impl, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.585: Getting activated env variables from Python, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.585: Get Custom Env Variables, Class name = S (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "RunPythonCode"
debug 08:16:40.587: Get Custom Env Variables, Class name = S, completed in 2ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "RunPythonCode"
debug 08:16:40.587: Got env vars with python /opt/conda/bin/python, with env var count 56 and custom env var count 50 in 2ms
debug 08:16:40.587: Getting activated env variables from Python, Class name = _, completed in 2ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.587: Got env vars with python /opt/conda/bin/python in 2ms with 56 variables
debug 08:16:40.588: Getting activated env variables impl, Class name = _, completed in 3ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.588: Getting activated env variables, Class name = _, completed in 3ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>, Arg 3: undefined
debug 08:16:40.588: Create activated Env, Class name = y, completed in 3ms, has a truthy return value, Arg 1: {"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}},"resource":{"$mid":1,"fsPath":"/workspaces/aoc2022/13/13.ipynb","external":"file:///workspaces/aoc2022/13/13.ipynb","path":"/workspaces/aoc2022/13/13.ipynb","scheme":"file"}}
info 08:16:40.598: Process Execution: > /opt/conda/bin/python -c "import ipykernel; print(ipykernel.__version__); print("5dc3a68c-e34e-4080-9c3e-2a532b2ccb4d"); print(ipykernel.__file__)"
> /opt/conda/bin/python -c "import ipykernel; print(ipykernel.__version__); print("5dc3a68c-e34e-4080-9c3e-2a532b2ccb4d"); print(ipykernel.__file__)"
debug 08:16:40.606: Launching kernel in kernelProcess.ts, Class name = x (started execution), Arg 1: "/workspaces/aoc2022/13", Arg 2: ""
debug 08:16:40.606: Create activated Env, Class name = y (started execution), Arg 1: {"resource":{"$mid":1,"fsPath":"/workspaces/aoc2022/13/13.ipynb","external":"file:///workspaces/aoc2022/13/13.ipynb","path":"/workspaces/aoc2022/13/13.ipynb","scheme":"file"},"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}}
debug 08:16:40.606: Getting activated env variables, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>, Arg 3: undefined
debug 08:16:40.606: Getting activated env variables impl, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.606: Getting activated env variables from Python, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.606: Get Custom Env Variables, Class name = S (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "RunPythonCode"
debug 08:16:40.607: Getting activated env variables, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>, Arg 3: false
debug 08:16:40.607: Getting activated env variables impl, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.607: Getting activated env variables from Python, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.607: Get Custom Env Variables, Class name = S (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "RunPythonCode"
debug 08:16:40.607: Get Custom Env Variables, Class name = S, completed in 1ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "RunPythonCode"
debug 08:16:40.607: Get Custom Env Variables, Class name = S, completed in 0ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "RunPythonCode"
debug 08:16:40.608: Got env vars with python /opt/conda/bin/python, with env var count 56 and custom env var count 50 in 2ms
debug 08:16:40.609: Got env vars with python /opt/conda/bin/python, with env var count 56 and custom env var count 50 in 2ms
debug 08:16:40.609: Getting activated env variables from Python, Class name = _, completed in 3ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.609: Got env vars with python /opt/conda/bin/python in 3ms with 56 variables
debug 08:16:40.609: Getting activated env variables from Python, Class name = _, completed in 2ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.609: Got env vars with python /opt/conda/bin/python in 2ms with 56 variables
debug 08:16:40.609: Getting activated env variables impl, Class name = _, completed in 3ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.609: Getting activated env variables impl, Class name = _, completed in 2ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:40.609: Getting activated env variables, Class name = _, completed in 3ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>, Arg 3: undefined
debug 08:16:40.609: Getting activated env variables, Class name = _, completed in 2ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>, Arg 3: false
debug 08:16:40.609: Create activated Env, Class name = y, completed in 3ms, has a truthy return value, Arg 1: {"resource":{"$mid":1,"fsPath":"/workspaces/aoc2022/13/13.ipynb","external":"file:///workspaces/aoc2022/13/13.ipynb","path":"/workspaces/aoc2022/13/13.ipynb","scheme":"file"},"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}}
info 08:16:40.621: Process Execution: > /opt/conda/bin/python -m ipykernel_launcher --ip=127.0.0.1 --stdin=9003 --control=9001 --hb=9000 --Session.signature_scheme="hmac-sha256" --Session.key=b"f602c9a8-ad92-44c4-b002-e6a8990e70d2" --shell=9002 --transport="tcp" --iopub=9004 --f=/home/vscode/.local/share/jupyter/runtime/kernel-v2-2320v6RAlvPawwNP.json
> /opt/conda/bin/python -m ipykernel_launcher --ip=127.0.0.1 --stdin=9003 --control=9001 --hb=9000 --Session.signature_scheme="hmac-sha256" --Session.key=b"f602c9a8-ad92-44c4-b002-e6a8990e70d2" --shell=9002 --transport="tcp" --iopub=9004 --f=/home/vscode/.local/share/jupyter/runtime/kernel-v2-2320v6RAlvPawwNP.json
info 08:16:40.621: Process Execution: cwd: /workspaces/aoc2022/13
cwd: /workspaces/aoc2022/13
debug 08:16:40.621: Launching kernel in kernelProcess.ts, Class name = x, completed in 15ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13", Arg 2: ""
info 08:16:40.746: ipykernel version & path 6.15.2, /opt/conda/lib/python3.9/site-packages/ipykernel/__init__.py for /opt/conda/bin/python
debug 08:16:41.055: KernelProcess error: /opt/conda/lib/python3.9/site-packages/traitlets/traitlets.py:2202: FutureWarning: Supporting extra quotes around strings is deprecated in traitlets 5.0. You can use 'hmac-sha256' instead of '"hmac-sha256"' if you require traitlets >=5.
  warn(

warn 08:16:41.056: StdErr from Kernel Process /opt/conda/lib/python3.9/site-packages/traitlets/traitlets.py:2202: FutureWarning: Supporting extra quotes around strings is deprecated in traitlets 5.0. You can use 'hmac-sha256' instead of '"hmac-sha256"' if you require traitlets >=5.
  warn(

debug 08:16:41.056: KernelProcess error: /opt/conda/lib/python3.9/site-packages/traitlets/traitlets.py:2157: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use 'f602c9a8-ad92-44c4-b002-e6a8990e70d2' instead of 'b"f602c9a8-ad92-44c4-b002-e6a8990e70d2"'.
  warn(

warn 08:16:41.056: StdErr from Kernel Process /opt/conda/lib/python3.9/site-packages/traitlets/traitlets.py:2157: FutureWarning: Supporting extra quotes around Bytes is deprecated in traitlets 5.0. Use 'f602c9a8-ad92-44c4-b002-e6a8990e70d2' instead of 'b"f602c9a8-ad92-44c4-b002-e6a8990e70d2"'.
  warn(

debug 08:16:41.065: KernelProcess output: NOTE: When using the `ipython kernel` entry point, Ctrl-C will not work.

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

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


To connect another client to this kernel, use:
    --existing kernel-v2-2320v6RAlvPawwNP.json

debug 08:16:41.065: Kernel Output: To connect another client to this kernel, use: --existing kernel-v2-2320v6RAlvPawwNP.json

debug 08:16:41.233: Kernel Launcher. launch, Class name = r, completed in 647ms, has a truthy return value, Arg 1: {"id":".jvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe./opt/conda/python./opt/conda/python.-m#ipykernel_launcher","kernelSpec":{"interpreterPath":"/opt/conda/bin/python","isRegisteredByVSC":"registeredByNewVersionOfExt","name":"pythonjvsc74a57bd0d4d1e4263499bec80672ea0156c357c1ee493ec2b1c70f0acce89fc37c4a6abe","argv":["python","-m","ipykernel_launcher","-f","{connection_file}"],"language":"python","executable":"python","display_name":"base","metadata":{"interpreter":{"path":"/opt/conda/bin/python"}},"env":{}},"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":"/opt/conda","displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"},"path":"/opt/conda/bin/python"},"kind":"startUsingPythonInterpreter"}, Arg 2: 60000, Arg 3: "/workspaces/aoc2022/13/13.ipynb", Arg 4: "/workspaces/aoc2022/13", Arg 5: <argument cannot be serialized for logging>
debug 08:16:41.241: Waiting for Raw Session to be ready in postStartRawSession
debug 08:16:41.241: Waiting for Raw session to be ready, currently connected
debug 08:16:41.241: Raw session connected
debug 08:16:41.241: Waiting for Raw session to be ready for 30s
debug 08:16:41.242: Waited for Raw session to be ready & got connected
debug 08:16:41.242: Successfully waited for Raw Session to be ready in postStartRawSession
debug 08:16:41.242: Kernel status before requesting kernel info and after ready is unknown
debug 08:16:41.242: Sending request for kernelinfo
debug 08:16:41.249: Got response for requestKernelInfo
debug 08:16:41.249: Successfully compelted postStartRawSession
info 08:16:41.251: Started Kernel base (Python 3.9.15) (pid: 2534)
debug 08:16:41.251: Started running kernel initialization for /workspaces/aoc2022/13/13.ipynb
debug 08:16:41.255: Executing silently Code (idle) = import os as _VSCODE_os\nimport sys as _VSCODE_sys\n%cd "//workspaces/aoc2022/13"\nif _VSCODE_os.getcwd
debug 08:16:41.272: Executing silently Code (completed) = import os as _VSCODE_os\nimport sys as _VSCODE_sys\n%cd "//workspaces/aoc2022/13"\nif _VSCODE_os.getcwd with 1 output(s)
debug 08:16:41.272: Not executing startup session: Object, code: 
debug 08:16:41.272: Requesting Kernel info
debug 08:16:41.272: Got Kernel info
debug 08:16:41.272: End running kernel initialization, now waiting for idle
debug 08:16:41.272: Waiting for 60000ms idle on (kernel): f4dd7f30-3e1f-421b-a40e-393e92528ed5 -> idle
debug 08:16:41.273: Finished waiting for idle on (kernel): f4dd7f30-3e1f-421b-a40e-393e92528ed5 -> idle
debug 08:16:41.273: End running kernel initialization, session is idle
debug 08:16:41.275: IPyWidgetScriptSource.initialize
debug 08:16:41.275: IPyWidgetMessageDispatcher.initialize
debug 08:16:41.276: getDataDirsImpl, Class name = j (started execution), Arg 1: {"resource":{"$mid":1,"fsPath":"/workspaces/aoc2022/13/13.ipynb","external":"file:///workspaces/aoc2022/13/13.ipynb","path":"/workspaces/aoc2022/13/13.ipynb","scheme":"file"},"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}}
debug 08:16:41.276: Get Custom Env Variables, Class name = S (started execution), Arg 1: undefined, Arg 2: "RunPythonCode"
debug 08:16:41.279: Get Custom Env Variables, Class name = S, completed in 3ms, has a truthy return value, Arg 1: undefined, Arg 2: "RunPythonCode"
debug 08:16:41.279: Get Jupyter Sub Paths, Class name = j, completed in 3ms, has a truthy return value, Arg 1: undefined, Return Value: 
debug 08:16:41.279: Get Jupyter Paths, Class name = j, completed in 3ms, has a truthy return value, , Return Value: 
debug 08:16:41.279: Create activated Env, Class name = y (started execution), Arg 1: {"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}},"resource":{"$mid":1,"fsPath":"/workspaces/aoc2022/13/13.ipynb","external":"file:///workspaces/aoc2022/13/13.ipynb","path":"/workspaces/aoc2022/13/13.ipynb","scheme":"file"},"allowEnvironmentFetchExceptions":true}
debug 08:16:41.279: Getting activated env variables, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>, Arg 3: true
debug 08:16:41.279: Getting activated env variables impl, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:41.279: Getting activated env variables from Python, Class name = _ (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:41.279: Get Custom Env Variables, Class name = S (started execution), Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "RunPythonCode"
debug 08:16:41.280: Get Custom Env Variables, Class name = S, completed in 1ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: "RunPythonCode"
debug 08:16:41.280: Got env vars with python /opt/conda/bin/python, with env var count 56 and custom env var count 50 in 1ms
debug 08:16:41.281: Getting activated env variables from Python, Class name = _, completed in 2ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:41.281: Got env vars with python /opt/conda/bin/python in 2ms with 56 variables
debug 08:16:41.281: Start Jupyter Session in kernel.ts with disableUI = false
debug 08:16:41.281: Start Jupyter Session in kernel.ts with disableUI = false
debug 08:16:41.281: Start Jupyter Session in kernel.ts with disableUI = false
debug 08:16:41.281: Start Jupyter Session in kernel.ts with disableUI = false
debug 08:16:41.283: Getting activated env variables impl, Class name = _, completed in 4ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>
debug 08:16:41.286: Getting activated env variables, Class name = _, completed in 7ms, has a truthy return value, Arg 1: "/workspaces/aoc2022/13/13.ipynb", Arg 2: <Uri:/opt/conda/bin/python>, Arg 3: true
debug 08:16:41.287: Create activated Env, Class name = y, completed in 8ms, has a truthy return value, Arg 1: {"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}},"resource":{"$mid":1,"fsPath":"/workspaces/aoc2022/13/13.ipynb","external":"file:///workspaces/aoc2022/13/13.ipynb","path":"/workspaces/aoc2022/13/13.ipynb","scheme":"file"},"allowEnvironmentFetchExceptions":true}
info 08:16:41.299: Process Execution: > /opt/conda/bin/python ~/.vscode-remote/extensions/ms-toolsai.jupyter-2023.1.1003661022/pythonFiles/printJupyterDataDir.py
> /opt/conda/bin/python ~/.vscode-remote/extensions/ms-toolsai.jupyter-2023.1.1003661022/pythonFiles/printJupyterDataDir.py
debug 08:16:41.325: getDataDirsImpl, Class name = j, completed in 49ms, has a truthy return value, Arg 1: {"resource":{"$mid":1,"fsPath":"/workspaces/aoc2022/13/13.ipynb","external":"file:///workspaces/aoc2022/13/13.ipynb","path":"/workspaces/aoc2022/13/13.ipynb","scheme":"file"},"interpreter":{"id":"/opt/conda/bin/python","sysPrefix":"/opt/conda","envPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"displayPath":{"$mid":1,"fsPath":"/opt/conda","path":"/opt/conda","scheme":"file"},"envName":"base","uri":{"$mid":1,"fsPath":"/opt/conda/bin/python","external":"file:///opt/conda/bin/python","path":"/opt/conda/bin/python","scheme":"file"},"displayName":"base","envType":"Conda","version":{"major":3,"minor":9,"patch":15,"raw":"3.9.15 (main, Nov 24 2022, 14:31:59) \n[GCC 11.2.0]"}}}
debug 08:16:41.340: Widget Message: Received IPyWidgets_kernelOptions in ScriptManager
debug 08:16:41.341: Widget Message: Received IPyWidgetMessages.IPyWidgets_kernelOptions
debug 08:16:41.348: IPyWidgetMessageDispatcher.initialize
debug 08:16:41.348: IPyWidgetMessageDispatcher.initialize
debug 08:16:41.366: Widget Message: data-base-url set to https://vscode-remote%2Bcodespaces-002bjoaomoreno-002dzany-002ddisco-002drpv7x47vc5ggg.vscode-resource.vscode-cdn.net/home/vscode/.vscode-remote/extensions/ms-toolsai.jupyter-2023.1.1003661022/tmp/scripts/7df0c2453e49ed05d3d0f3794defcebd56ad42641b177491fc43aece0cf55ce2/jupyter
debug 08:16:41.374: Cell 1 executed with state Success
debug 08:16:41.462: Cell 3 executed with state Success
debug 08:16:41.560: Cell 5 executed with state Success
debug 08:16:41.653: Cell 6 executed with state Success
debug 08:16:41.653: VSCodeNotebookController::handleExecution, Class name = x, completed in 1076ms, has a falsy return value
debug 08:16:43.183: VSCodeNotebookController::handleExecution, Class name = x (started execution)
debug 08:16:43.184: Execute Cell 5 /workspaces/aoc2022/13/13.ipynb
debug 08:16:43.184: start the kernel, options.disableUI=false for /workspaces/aoc2022/13/13.ipynb
debug 08:16:43.185: Start Jupyter Session in kernel.ts with disableUI = false
debug 08:16:43.248: Cell 5 executed with state Success
debug 08:16:43.248: VSCodeNotebookController::handleExecution, Class name = x, completed in 65ms, has a falsy return value
debug 08:16:43.819: VSCodeNotebookController::handleExecution, Class name = x (started execution)
debug 08:16:43.819: Execute Cell 5 /workspaces/aoc2022/13/13.ipynb
debug 08:16:43.819: start the kernel, options.disableUI=false for /workspaces/aoc2022/13/13.ipynb
debug 08:16:43.820: Start Jupyter Session in kernel.ts with disableUI = false
debug 08:16:43.882: Cell 5 executed with state Success
debug 08:16:43.882: VSCodeNotebookController::handleExecution, Class name = x, completed in 63ms, has a falsy return value
debug 08:16:44.516: VSCodeNotebookController::handleExecution, Class name = x (started execution)
debug 08:16:44.517: Execute Cell 5 /workspaces/aoc2022/13/13.ipynb
debug 08:16:44.517: start the kernel, options.disableUI=false for /workspaces/aoc2022/13/13.ipynb
debug 08:16:44.518: Start Jupyter Session in kernel.ts with disableUI = false
debug 08:16:44.603: Cell 5 executed with state Success
debug 08:16:44.604: VSCodeNotebookController::handleExecution, Class name = x, completed in 88ms, has a falsy return value
info 08:16:49.313: Loading webview. View is notset
info 08:16:49.314: Loading web view...
info 08:16:49.315: Webview panel created.
info 08:16:50.595: Web view react rendered
debug 08:16:50.651: Executing silently Code (idle) = def _VSCODE_getVariable(what_to_get, is_debugging, *args):\n# Query Jupyter server for the info
debug 08:16:50.653: Executing silently Code (idle) = def _VSCODE_getVariable(what_to_get, is_debugging, *args):\n# Query Jupyter server for the info
debug 08:16:50.698: Executing silently Code (completed) = def _VSCODE_getVariable(what_to_get, is_debugging, *args):\n# Query Jupyter server for the info with 1 output(s)
debug 08:16:50.700: Executing silently Code (completed) = def _VSCODE_getVariable(what_to_get, is_debugging, *args):\n# Query Jupyter server for the info with 1 output(s)

@DonJayamanne DonJayamanne mentioned this issue Jan 22, 2023
Merged
@DonJayamanne
Copy link
Contributor

DonJayamanne commented Jan 23, 2023 •
edited
Loading

@joaomoreno
I've been unable to repro this and have added more logging to narrow this issue down./
Please could you try this once again with the latest insiders and pre-release versions of Jupyter & Python extension.
Please remember to enable logging as follows:

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

Also, does the timer start from 0 and then gradually go to 37s, or does the timer start half way.
I assumed the screen recording had a chunk of the recording chopped off just to highlight the delay.

@roblourens
Copy link
Member

roblourens commented Jan 23, 2023 •
edited
Loading

Besides network latency, a busy extension host can also cause this, so the installed extensions might be relevant. trace-level VS Code window logs can also tell us what happened on vscode's side. I will try to repro it.

@joaomoreno
Copy link
Member Author

The timer started half way. I did not do any post processing of the recording.

I also can't repro today, feel free to close.

@roblourens
Copy link
Member

roblourens commented Jan 23, 2023 •
edited
Loading

Having a bad time with codespaces... I enabled settings sync and now have just been in a reconnection loop all morning

image

Starting half-way would be the same symptom as changing time at the end - the start message took a while to get to the frontend

@DonJayamanne
Copy link
Contributor

I also can't repro today, feel free to close.
Starting half-way would be the same symptom as changing time at the end - the start message took a while to get to the frontend

Closing based on the following two messages
Thanks everyone

@roblourens
Copy link
Member

fwiw I also finally got my 130 extensions installed and can't repro the issue. But probably some extension was hogging the EH cpu when this happened.

@joaomoreno
Copy link
Member Author

Well it happened many times. Pretty much every day in December, as this was my Advent of Code repo and my notebooks all had that problem.

@roblourens
Copy link
Member

Thanks for selfhosting notebooks! If you see it again please grab logs and check EH CPU. I'll watch for it too.

@alxmke
Copy link

alxmke commented Apr 14, 2023

i've been getting the exact same behavior as the OP, but my timer starts at 41.2 seconds on every cell, and then reports the correct run time once it completes

@skwde skwde mentioned this issue Aug 17, 2023
Closed
2 tasks
@github-actions github-actions bot locked as resolved and limited conversation to collaborators Sep 3, 2023
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Assignees

@DonJayamanne DonJayamanne

Labels
bug Issue identified by VS Code Team member as probable bug debt Code quality issues notebook-kernel Kernels issues (start/restart/switch/execution, install ipykernel) polish Cleanup and polish issue
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@joaomoreno @roblourens @DonJayamanne @alxmke