How to debug "Server at ... didn't respond in 30 seconds" error

JupyterLab uses Binder for PR review installing the JupyterLab version with modifications from given PR on Binder. Recently the builds stopped spawning with:

Spawn failed: Server at [...] didn't respond in 30 seconds.

How can I debug this? I’ve opened an issue on our side: Binder dev builds are broken slowing down review process · Issue #14033 · jupyterlab/jupyterlab · GitHub

We do have a number of custom options:

An example log (while it lasts): blob:https://mybinder.org/d3351412-56fe-4402-a225-c6ecd8a5a43e - there are no obvious errors, the relevant part is:

Pushing image
Successfully pushed 2lmrrh8f.gra7.container-registry.ovh.net/mybinder-builds/r2d-g5b5b759jasonweill-2djupyterlab-81beb2:f4e8164e1e8a77fc9b57bbe26fac46e4a6f893dbBuilt image, launching...
Launching server...
Server requested
2023-02-18T10:33:52.382390Z [Normal] Successfully assigned ovh2/jupyter-jasonweill-2djupyterlab-2d20l6lqls to user-202211a-node-34f309
2023-02-18T10:33:53Z [Normal] Container image "jupyterhub/mybinder.org-tc-init:2020.12.4-0.dev.git.4289.h140cef5" already present on machine
2023-02-18T10:33:53Z [Normal] Created container tc-init
2023-02-18T10:33:53Z [Normal] Started container tc-init
2023-02-18T10:33:53Z [Normal] Pulling image "2lmrrh8f.gra7.container-registry.ovh.net/mybinder-builds/r2d-g5b5b759jasonweill-2djupyterlab-81beb2:f4e8164e1e8a77fc9b57bbe26fac46e4a6f893db"
2023-02-18T10:35:33Z [Normal] Successfully pulled image "2lmrrh8f.gra7.container-registry.ovh.net/mybinder-builds/r2d-g5b5b759jasonweill-2djupyterlab-81beb2:f4e8164e1e8a77fc9b57bbe26fac46e4a6f893db" in 1m39.720531713s
2023-02-18T10:35:33Z [Normal] Created container notebook
2023-02-18T10:35:33Z [Normal] Started container notebook
Spawn failed: Server at http://10.2.27.207:8888/user/jasonweill-jupyterlab-20l6lqls/ didn't respond in 30 seconds
Launch attempt 1 failed, retrying...
Server requested
2023-02-18T10:36:23.812187Z [Normal] Successfully assigned ovh2/jupyter-jasonweill-2djupyterlab-2dgxkt2p39 to user-202211a-node-34f309
2023-02-18T10:36:24Z [Normal] Container image "jupyterhub/mybinder.org-tc-init:2020.12.4-0.dev.git.4289.h140cef5" already present on machine
2023-02-18T10:36:24Z [Normal] Created container tc-init
2023-02-18T10:36:24Z [Normal] Started container tc-init
2023-02-18T10:36:25Z [Normal] Container image "2lmrrh8f.gra7.container-registry.ovh.net/mybinder-builds/r2d-g5b5b759jasonweill-2djupyterlab-81beb2:f4e8164e1e8a77fc9b57bbe26fac46e4a6f893db" already present on machine
2023-02-18T10:36:25Z [Normal] Created container notebook
2023-02-18T10:36:25Z [Normal] Started container notebook
Spawn failed: Server at http://10.2.27.210:8888/user/jasonweill-jupyterlab-gxkt2p39/ didn't respond in 30 seconds
Launch attempt 2 failed, retrying...
Server requested
2023-02-18T10:37:16.166928Z [Normal] Successfully assigned ovh2/jupyter-jasonweill-2djupyterlab-2dst9pcb34 to user-202211a-node-34f309
2023-02-18T10:37:16Z [Normal] Container image "jupyterhub/mybinder.org-tc-init:2020.12.4-0.dev.git.4289.h140cef5" already present on machine
2023-02-18T10:37:16Z [Normal] Created container tc-init
2023-02-18T10:37:16Z [Normal] Started container tc-init
2023-02-18T10:37:17Z [Normal] Container image "2lmrrh8f.gra7.container-registry.ovh.net/mybinder-builds/r2d-g5b5b759jasonweill-2djupyterlab-81beb2:f4e8164e1e8a77fc9b57bbe26fac46e4a6f893db" already present on machine
2023-02-18T10:37:17Z [Normal] Created container notebook
2023-02-18T10:37:17Z [Normal] Started container notebook
Spawn failed: Server at http://10.2.27.214:8888/user/jasonweill-jupyterlab-st9pcb34/ didn't respond in 30 seconds
Launch attempt 3 failed, retrying...
Server requested
2023-02-18T10:38:16.127401Z [Normal] Successfully assigned ovh2/jupyter-jasonweill-2djupyterlab-2dkoupg04s to user-202211a-node-34f309
2023-02-18T10:38:17Z [Normal] Container image "jupyterhub/mybinder.org-tc-init:2020.12.4-0.dev.git.4289.h140cef5" already present on machine
2023-02-18T10:38:17Z [Normal] Created container tc-init
2023-02-18T10:38:17Z [Normal] Started container tc-init
2023-02-18T10:38:18Z [Normal] Container image "2lmrrh8f.gra7.container-registry.ovh.net/mybinder-builds/r2d-g5b5b759jasonweill-2djupyterlab-81beb2:f4e8164e1e8a77fc9b57bbe26fac46e4a6f893db" already present on machine
2023-02-18T10:38:18Z [Normal] Created container notebook
2023-02-18T10:38:18Z [Normal] Started container notebook
Spawn failed: Server at http://10.2.27.218:8888/user/jasonweill-jupyterlab-koupg04s/ didn't respond in 30 seconds
Spawn failed: Server at http://10.2.27.218:8888/user/jasonweill-jupyterlab-koupg04s/ didn't respond in 30 seconds

Is it related to jupyter-server 2?

2 Likes

yerp… i’ve been sheepishly doing this in postBuild:

cp \
  ${NB_PYTHON_PREFIX}/bin/jupyter-lab \
  ${NB_PYTHON_PREFIX}/bin/jupyter-notebook

:blush:

1 Like

Whoa, didn’t mean to delete that, and now running afoul of the bot. Here’s the PR:

Here’s that again:

@krassowski I’ve got a PR nearly up (was saturating CI with stuff, so trying to be a little cautious) that gets stuff back up and running.

The main culprit was missing jupyter-server-fileid and jupyterlab-rtc, which don’t have pretty failure modes. I was slightly off on my version locally, and some sqlite stuff failed. Ick. Of course, the collaborative stuff is still not working for some reason, but at least there’s a running server to debug against.

Will post a link when I have something, but you could try it while I’m still flailing: Binder.

Presently, as the aforementioned PRs are trying to tighten up time-to-moon-animation against a cold cache, i’m going to make this pr do a full minimized dev build, and put the webpack-bundle-analyzer report in the root of the launched binder.

1 Like