Skip to content

Instantly share code, notes, and snippets.

@bbrauns
Last active January 18, 2022 08:26
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save bbrauns/1ede551b253c246b523edef968161d81 to your computer and use it in GitHub Desktop.
Save bbrauns/1ede551b253c246b523edef968161d81 to your computer and use it in GitHub Desktop.
root@jupyterlab-test-1:/gwdgrepo# docker logs jupyterhub_hub.1.ohm5gl1kjii7gvfkgefbc9lg0
[D 2022-01-18 08:03:54.209 JupyterHub application:731] Looking for /srv/jupyterhub/jupyterhub_config in /srv/jupyterhub
[D 2022-01-18 08:03:54.211 JupyterHub application:753] Loaded config file: /srv/jupyterhub/jupyterhub_config.py
[I 2022-01-18 08:03:54.223 JupyterHub app:2766] Running JupyterHub version 2.0.2
[I 2022-01-18 08:03:54.223 JupyterHub app:2796] Using Authenticator: oauthenticator.generic.GenericOAuthenticator-14.2.0
[I 2022-01-18 08:03:54.224 JupyterHub app:2796] Using Spawner: dockerspawner.swarmspawner.SwarmSpawner-12.1.0
[I 2022-01-18 08:03:54.224 JupyterHub app:2796] Using Proxy: jupyterhub.proxy.ConfigurableHTTPProxy-2.0.2
[D 2022-01-18 08:03:54.290 JupyterHub app:1773] Connecting to db: sqlite:////data/jupyterhub.sqlite
[D 2022-01-18 08:03:54.333 JupyterHub orm:955] database schema version found: 833da8570507
[D 2022-01-18 08:03:54.386 JupyterHub app:2019] Loading roles into database
[I 2022-01-18 08:03:54.398 JupyterHub app:1924] Not using allowed_users. Any authenticated user will be allowed.
[D 2022-01-18 08:03:54.403 JupyterHub app:2278] Purging expired APITokens
[D 2022-01-18 08:03:54.406 JupyterHub app:2278] Purging expired OAuthCodes
[D 2022-01-18 08:03:54.408 JupyterHub app:2111] Loading role assignments from config
[D 2022-01-18 08:03:54.419 JupyterHub app:2424] Initializing spawners
[D 2022-01-18 08:03:54.421 JupyterHub app:2555] Loaded users:
[I 2022-01-18 08:03:54.422 JupyterHub app:2835] Initialized 0 spawners in 0.003 seconds
[W 2022-01-18 08:03:54.424 JupyterHub proxy:687] Running JupyterHub without SSL. I hope there is SSL termination happening somewhere else...
[I 2022-01-18 08:03:54.424 JupyterHub proxy:691] Starting proxy @ http://:1080/
[D 2022-01-18 08:03:54.424 JupyterHub proxy:692] Proxy cmd: ['configurable-http-proxy', '--ip', '', '--port', '1080', '--api-ip', '127.0.0.1', '--api-port', '8001', '--error-target', 'http://jupyterhub:8080/hub/error']
[D 2022-01-18 08:03:54.433 JupyterHub proxy:610] Writing proxy pid file: jupyterhub-proxy.pid
08:03:55.493 [ConfigProxy] info: Proxying http://*:1080 to (no default)
08:03:55.501 [ConfigProxy] info: Proxy API at http://127.0.0.1:8001/api/routes
[D 2022-01-18 08:03:55.537 JupyterHub proxy:728] Proxy started and appears to be up
[D 2022-01-18 08:03:55.538 JupyterHub proxy:821] Proxy: Fetching GET http://127.0.0.1:8001/api/routes
08:03:55.546 [ConfigProxy] info: 200 GET /api/routes
[I 2022-01-18 08:03:55.546 JupyterHub app:3084] Hub API listening on http://0.0.0.0:8080/hub/
[I 2022-01-18 08:03:55.547 JupyterHub app:3086] Private Hub API connect url http://jupyterhub:8080/hub/
[D 2022-01-18 08:03:55.547 JupyterHub proxy:343] Fetching routes to check
[D 2022-01-18 08:03:55.547 JupyterHub proxy:821] Proxy: Fetching GET http://127.0.0.1:8001/api/routes
08:03:55.548 [ConfigProxy] info: 200 GET /api/routes
[D 2022-01-18 08:03:55.548 JupyterHub proxy:346] Checking routes
[I 2022-01-18 08:03:55.548 JupyterHub proxy:431] Adding route for Hub: / => http://jupyterhub:8080
[D 2022-01-18 08:03:55.548 JupyterHub proxy:821] Proxy: Fetching POST http://127.0.0.1:8001/api/routes/
08:03:55.550 [ConfigProxy] info: Adding route / -> http://jupyterhub:8080
08:03:55.550 [ConfigProxy] info: Route added / -> http://jupyterhub:8080
08:03:55.551 [ConfigProxy] info: 201 POST /api/routes/
[I 2022-01-18 08:03:55.551 JupyterHub app:3150] JupyterHub is now running at http://:1080/
[D 2022-01-18 08:03:55.552 JupyterHub app:2759] It took 1.352 seconds for the Hub to start
[I 2022-01-18 08:04:02.670 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.78ms
[I 2022-01-18 08:04:22.661 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 5.08ms
[I 2022-01-18 08:04:42.659 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.38ms
[I 2022-01-18 08:05:02.659 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.53ms
[I 2022-01-18 08:05:22.659 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.31ms
[I 2022-01-18 08:05:42.658 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.23ms
[I 2022-01-18 08:06:02.660 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 5.22ms
[I 2022-01-18 08:06:22.658 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 3.67ms
[I 2022-01-18 08:06:42.658 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 3.60ms
[I 2022-01-18 08:07:02.663 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 8.39ms
[I 2022-01-18 08:07:22.659 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.41ms
[I 2022-01-18 08:07:42.661 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 6.88ms
[I 2022-01-18 08:08:02.660 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.74ms
[I 2022-01-18 08:08:22.659 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.39ms
[I 2022-01-18 08:08:42.660 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.77ms
[I 2022-01-18 08:08:53.564 JupyterHub log:189] 302 GET / -> /hub/ (@::ffff:192.168.0.2) 1.11ms
[D 2022-01-18 08:08:53.616 JupyterHub base:325] Refreshing auth for 0216020
[D 2022-01-18 08:08:53.627 JupyterHub user:347] Creating <class 'dockerspawner.swarmspawner.SwarmSpawner'> for 0216020:
[I 2022-01-18 08:08:53.631 JupyterHub log:189] 302 GET /hub/ -> /hub/spawn (0216020@::ffff:192.168.0.2) 35.44ms
[D 2022-01-18 08:08:53.662 JupyterHub pages:214] Triggering spawn with default options for 0216020
[D 2022-01-18 08:08:53.663 JupyterHub base:914] Initiating spawn for 0216020
[D 2022-01-18 08:08:53.663 JupyterHub base:918] 0/100 concurrent spawns
[D 2022-01-18 08:08:53.663 JupyterHub base:923] 0 active servers
[D 2022-01-18 08:08:53.669 JupyterHub roles:472] Checking token permissions against requested role server
[I 2022-01-18 08:08:53.672 JupyterHub roles:477] Adding role server to token: <APIToken('cacc...', user='0216020', client_id='jupyterhub')>
[I 2022-01-18 08:08:53.688 JupyterHub provider:607] Creating oauth client jupyterhub-user-0216020
[I 2022-01-18 08:08:53.712 JupyterHub jupyterhub_config:41] checking home path for user: 0216020, escaped_name: 0216020
[I 2022-01-18 08:08:53.713 JupyterHub jupyterhub_config:47] user home path exists: /home/0216020
[D 2022-01-18 08:08:53.713 JupyterHub user:675] Calling Spawner.start for 0216020
[D 2022-01-18 08:08:53.714 JupyterHub dockerspawner:1190] Skipping pull of <removed>/jupyter/notebook
[D 2022-01-18 08:08:53.714 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[I 2022-01-18 08:08:53.797 JupyterHub dockerspawner:988] Service 'jupyter-0216020' is gone
[I 2022-01-18 08:08:53.815 JupyterHub dockerspawner:1272] Created service jupyter-0216020 (id: q6p8zwz) from image <removed>/jupyter/notebook
[I 2022-01-18 08:08:53.815 JupyterHub dockerspawner:1296] Starting service jupyter-0216020 (id: q6p8zwz)
[D 2022-01-18 08:08:53.815 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:08:53.815 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:08:53.839 JupyterHub swarmspawner:280] Service q6p8zwz state: pending
[W 2022-01-18 08:08:54.664 JupyterHub base:187] Rolling back dirty objects IdentitySet([<Server(0.0.0.0:8888)>])
[I 2022-01-18 08:08:54.668 JupyterHub log:189] 302 GET /hub/spawn -> /hub/spawn-pending/0216020 (0216020@::ffff:192.168.0.2) 1005.05ms
[I 2022-01-18 08:08:54.699 JupyterHub pages:400] 0216020 is pending spawn
[I 2022-01-18 08:08:54.754 JupyterHub log:189] 200 GET /hub/spawn-pending/0216020 (0216020@::ffff:192.168.0.2) 60.79ms
[D 2022-01-18 08:08:54.840 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:08:54.840 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:08:54.870 JupyterHub swarmspawner:280] Service q6p8zwz state: starting
[W 2022-01-18 08:08:54.974 JupyterHub base:93] Blocking Cross Origin API request. Referer: https://<removed>/hub/spawn-pending/0216020, Host: <removed>, Host URL: http://<removed>/hub/
[D 2022-01-18 08:08:54.974 JupyterHub scopes:488] Checking access via scope read:servers
[D 2022-01-18 08:08:54.974 JupyterHub scopes:383] No access to /hub/api/users/0216020/server/progress via read:servers
[W 2022-01-18 08:08:54.974 JupyterHub scopes:496] Not authorizing access to /hub/api/users/0216020/server/progress. Requires any of [read:servers], not derived from scopes []
[W 2022-01-18 08:08:54.974 JupyterHub web:1787] 403 GET /hub/api/users/0216020/server/progress (::ffff:192.168.0.2): Action is not authorized with current scopes; requires any of [read:servers]
[W 2022-01-18 08:08:54.975 JupyterHub log:189] 403 GET /hub/api/users/0216020/server/progress (@::ffff:192.168.0.2) 2.85ms
[D 2022-01-18 08:08:55.552 JupyterHub proxy:821] Proxy: Fetching GET http://127.0.0.1:8001/api/routes
08:08:55.554 [ConfigProxy] info: 200 GET /api/routes
[D 2022-01-18 08:08:55.555 JupyterHub proxy:346] Checking routes
[D 2022-01-18 08:08:56.371 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:08:56.371 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:08:56.383 JupyterHub swarmspawner:280] Service q6p8zwz state: running
[D 2022-01-18 08:08:56.400 JupyterHub spawner:1221] Polling subprocess every 30s
[D 2022-01-18 08:08:57.311 JupyterHub utils:230] Server at http://jupyter-0216020:8888/user/0216020/ responded with 302
[W 2022-01-18 08:08:57.317 JupyterHub _version:68] jupyterhub version 2.0.2 != jupyterhub-singleuser version 1.4.0. This could cause failure to authenticate and result in redirect loops!
[I 2022-01-18 08:08:57.317 JupyterHub base:943] User 0216020 took 3.655 seconds to start
[I 2022-01-18 08:08:57.318 JupyterHub proxy:286] Adding user 0216020 to proxy /user/0216020/ => http://jupyter-0216020:8888
[D 2022-01-18 08:08:57.318 JupyterHub proxy:821] Proxy: Fetching POST http://127.0.0.1:8001/api/routes/user/0216020
[I 2022-01-18 08:08:57.320 JupyterHub log:189] 200 GET /hub/api (@192.168.8.14) 0.93ms
08:08:57.320 [ConfigProxy] info: Adding route /user/0216020 -> http://jupyter-0216020:8888
08:08:57.321 [ConfigProxy] info: Route added /user/0216020 -> http://jupyter-0216020:8888
08:08:57.321 [ConfigProxy] info: 201 POST /api/routes/user/0216020
[D 2022-01-18 08:08:57.348 JupyterHub base:280] Recording first activity for <APIToken('cacc...', user='0216020', client_id='jupyterhub')>
[D 2022-01-18 08:08:57.354 JupyterHub scopes:298] Authenticated with token <APIToken('cacc...', user='0216020', client_id='jupyterhub')>
[D 2022-01-18 08:08:57.357 JupyterHub scopes:488] Checking access via scope users:activity
[D 2022-01-18 08:08:57.358 JupyterHub scopes:399] Argument-based access to /hub/api/users/0216020/activity via users:activity
[D 2022-01-18 08:08:57.361 JupyterHub users:845] Activity for user 0216020: 2022-01-18T08:08:56.170309Z
[D 2022-01-18 08:08:57.361 JupyterHub users:863] Activity on server 0216020/: 2022-01-18T08:08:56.170309Z
[I 2022-01-18 08:08:57.368 JupyterHub log:189] 200 POST /hub/api/users/0216020/activity (0216020@192.168.8.14) 30.16ms
root@jupyterlab-test-1:/repo# docker logs jupyterhub_hub.1.ohm5gl1kjii7gvfkgefbc9lg0
[D 2022-01-18 08:03:54.209 JupyterHub application:731] Looking for /srv/jupyterhub/jupyterhub_config in /srv/jupyterhub
[D 2022-01-18 08:03:54.211 JupyterHub application:753] Loaded config file: /srv/jupyterhub/jupyterhub_config.py
[I 2022-01-18 08:03:54.223 JupyterHub app:2766] Running JupyterHub version 2.0.2
[I 2022-01-18 08:03:54.223 JupyterHub app:2796] Using Authenticator: oauthenticator.generic.GenericOAuthenticator-14.2.0
[I 2022-01-18 08:03:54.224 JupyterHub app:2796] Using Spawner: dockerspawner.swarmspawner.SwarmSpawner-12.1.0
[I 2022-01-18 08:03:54.224 JupyterHub app:2796] Using Proxy: jupyterhub.proxy.ConfigurableHTTPProxy-2.0.2
[D 2022-01-18 08:03:54.290 JupyterHub app:1773] Connecting to db: sqlite:////data/jupyterhub.sqlite
[D 2022-01-18 08:03:54.333 JupyterHub orm:955] database schema version found: 833da8570507
[D 2022-01-18 08:03:54.386 JupyterHub app:2019] Loading roles into database
[I 2022-01-18 08:03:54.398 JupyterHub app:1924] Not using allowed_users. Any authenticated user will be allowed.
[D 2022-01-18 08:03:54.403 JupyterHub app:2278] Purging expired APITokens
[D 2022-01-18 08:03:54.406 JupyterHub app:2278] Purging expired OAuthCodes
[D 2022-01-18 08:03:54.408 JupyterHub app:2111] Loading role assignments from config
[D 2022-01-18 08:03:54.419 JupyterHub app:2424] Initializing spawners
[D 2022-01-18 08:03:54.421 JupyterHub app:2555] Loaded users:
[I 2022-01-18 08:03:54.422 JupyterHub app:2835] Initialized 0 spawners in 0.003 seconds
[W 2022-01-18 08:03:54.424 JupyterHub proxy:687] Running JupyterHub without SSL. I hope there is SSL termination happening somewhere else...
[I 2022-01-18 08:03:54.424 JupyterHub proxy:691] Starting proxy @ http://:1080/
[D 2022-01-18 08:03:54.424 JupyterHub proxy:692] Proxy cmd: ['configurable-http-proxy', '--ip', '', '--port', '1080', '--api-ip', '127.0.0.1', '--api-port', '8001', '--error-target', 'http://jupyterhub:8080/hub/error']
[D 2022-01-18 08:03:54.433 JupyterHub proxy:610] Writing proxy pid file: jupyterhub-proxy.pid
08:03:55.493 [ConfigProxy] info: Proxying http://*:1080 to (no default)
08:03:55.501 [ConfigProxy] info: Proxy API at http://127.0.0.1:8001/api/routes
[D 2022-01-18 08:03:55.537 JupyterHub proxy:728] Proxy started and appears to be up
[D 2022-01-18 08:03:55.538 JupyterHub proxy:821] Proxy: Fetching GET http://127.0.0.1:8001/api/routes
08:03:55.546 [ConfigProxy] info: 200 GET /api/routes
[I 2022-01-18 08:03:55.546 JupyterHub app:3084] Hub API listening on http://0.0.0.0:8080/hub/
[I 2022-01-18 08:03:55.547 JupyterHub app:3086] Private Hub API connect url http://jupyterhub:8080/hub/
[D 2022-01-18 08:03:55.547 JupyterHub proxy:343] Fetching routes to check
[D 2022-01-18 08:03:55.547 JupyterHub proxy:821] Proxy: Fetching GET http://127.0.0.1:8001/api/routes
08:03:55.548 [ConfigProxy] info: 200 GET /api/routes
[D 2022-01-18 08:03:55.548 JupyterHub proxy:346] Checking routes
[I 2022-01-18 08:03:55.548 JupyterHub proxy:431] Adding route for Hub: / => http://jupyterhub:8080
[D 2022-01-18 08:03:55.548 JupyterHub proxy:821] Proxy: Fetching POST http://127.0.0.1:8001/api/routes/
08:03:55.550 [ConfigProxy] info: Adding route / -> http://jupyterhub:8080
08:03:55.550 [ConfigProxy] info: Route added / -> http://jupyterhub:8080
08:03:55.551 [ConfigProxy] info: 201 POST /api/routes/
[I 2022-01-18 08:03:55.551 JupyterHub app:3150] JupyterHub is now running at http://:1080/
[D 2022-01-18 08:03:55.552 JupyterHub app:2759] It took 1.352 seconds for the Hub to start
[I 2022-01-18 08:04:02.670 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.78ms
[I 2022-01-18 08:04:22.661 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 5.08ms
[I 2022-01-18 08:04:42.659 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.38ms
[I 2022-01-18 08:05:02.659 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.53ms
[I 2022-01-18 08:05:22.659 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.31ms
[I 2022-01-18 08:05:42.658 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.23ms
[I 2022-01-18 08:06:02.660 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 5.22ms
[I 2022-01-18 08:06:22.658 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 3.67ms
[I 2022-01-18 08:06:42.658 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 3.60ms
[I 2022-01-18 08:07:02.663 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 8.39ms
[I 2022-01-18 08:07:22.659 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.41ms
[I 2022-01-18 08:07:42.661 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 6.88ms
[I 2022-01-18 08:08:02.660 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.74ms
[I 2022-01-18 08:08:22.659 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.39ms
[I 2022-01-18 08:08:42.660 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 4.77ms
[I 2022-01-18 08:08:53.564 JupyterHub log:189] 302 GET / -> /hub/ (@::ffff:192.168.0.2) 1.11ms
[D 2022-01-18 08:08:53.616 JupyterHub base:325] Refreshing auth for 0216020
[D 2022-01-18 08:08:53.627 JupyterHub user:347] Creating <class 'dockerspawner.swarmspawner.SwarmSpawner'> for 0216020:
[I 2022-01-18 08:08:53.631 JupyterHub log:189] 302 GET /hub/ -> /hub/spawn (0216020@::ffff:192.168.0.2) 35.44ms
[D 2022-01-18 08:08:53.662 JupyterHub pages:214] Triggering spawn with default options for 0216020
[D 2022-01-18 08:08:53.663 JupyterHub base:914] Initiating spawn for 0216020
[D 2022-01-18 08:08:53.663 JupyterHub base:918] 0/100 concurrent spawns
[D 2022-01-18 08:08:53.663 JupyterHub base:923] 0 active servers
[D 2022-01-18 08:08:53.669 JupyterHub roles:472] Checking token permissions against requested role server
[I 2022-01-18 08:08:53.672 JupyterHub roles:477] Adding role server to token: <APIToken('cacc...', user='0216020', client_id='jupyterhub')>
[I 2022-01-18 08:08:53.688 JupyterHub provider:607] Creating oauth client jupyterhub-user-0216020
[I 2022-01-18 08:08:53.712 JupyterHub jupyterhub_config:41] checking home path for user: 0216020, escaped_name: 0216020
[I 2022-01-18 08:08:53.713 JupyterHub jupyterhub_config:47] user home path exists: /home/0216020
[D 2022-01-18 08:08:53.713 JupyterHub user:675] Calling Spawner.start for 0216020
[D 2022-01-18 08:08:53.714 JupyterHub dockerspawner:1190] Skipping pull of <removed>/jupyter/notebook
[D 2022-01-18 08:08:53.714 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[I 2022-01-18 08:08:53.797 JupyterHub dockerspawner:988] Service 'jupyter-0216020' is gone
[I 2022-01-18 08:08:53.815 JupyterHub dockerspawner:1272] Created service jupyter-0216020 (id: q6p8zwz) from image <removed>/jupyter/notebook
[I 2022-01-18 08:08:53.815 JupyterHub dockerspawner:1296] Starting service jupyter-0216020 (id: q6p8zwz)
[D 2022-01-18 08:08:53.815 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:08:53.815 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:08:53.839 JupyterHub swarmspawner:280] Service q6p8zwz state: pending
[W 2022-01-18 08:08:54.664 JupyterHub base:187] Rolling back dirty objects IdentitySet([<Server(0.0.0.0:8888)>])
[I 2022-01-18 08:08:54.668 JupyterHub log:189] 302 GET /hub/spawn -> /hub/spawn-pending/0216020 (0216020@::ffff:192.168.0.2) 1005.05ms
[I 2022-01-18 08:08:54.699 JupyterHub pages:400] 0216020 is pending spawn
[I 2022-01-18 08:08:54.754 JupyterHub log:189] 200 GET /hub/spawn-pending/0216020 (0216020@::ffff:192.168.0.2) 60.79ms
[D 2022-01-18 08:08:54.840 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:08:54.840 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:08:54.870 JupyterHub swarmspawner:280] Service q6p8zwz state: starting
[W 2022-01-18 08:08:54.974 JupyterHub base:93] Blocking Cross Origin API request. Referer: https://<removed>/hub/spawn-pending/0216020, Host: <removed>, Host URL: http://<removed>/hub/
[D 2022-01-18 08:08:54.974 JupyterHub scopes:488] Checking access via scope read:servers
[D 2022-01-18 08:08:54.974 JupyterHub scopes:383] No access to /hub/api/users/0216020/server/progress via read:servers
[W 2022-01-18 08:08:54.974 JupyterHub scopes:496] Not authorizing access to /hub/api/users/0216020/server/progress. Requires any of [read:servers], not derived from scopes []
[W 2022-01-18 08:08:54.974 JupyterHub web:1787] 403 GET /hub/api/users/0216020/server/progress (::ffff:192.168.0.2): Action is not authorized with current scopes; requires any of [read:servers]
[W 2022-01-18 08:08:54.975 JupyterHub log:189] 403 GET /hub/api/users/0216020/server/progress (@::ffff:192.168.0.2) 2.85ms
[D 2022-01-18 08:08:55.552 JupyterHub proxy:821] Proxy: Fetching GET http://127.0.0.1:8001/api/routes
08:08:55.554 [ConfigProxy] info: 200 GET /api/routes
[D 2022-01-18 08:08:55.555 JupyterHub proxy:346] Checking routes
[D 2022-01-18 08:08:56.371 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:08:56.371 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:08:56.383 JupyterHub swarmspawner:280] Service q6p8zwz state: running
[D 2022-01-18 08:08:56.400 JupyterHub spawner:1221] Polling subprocess every 30s
[D 2022-01-18 08:08:57.311 JupyterHub utils:230] Server at http://jupyter-0216020:8888/user/0216020/ responded with 302
[W 2022-01-18 08:08:57.317 JupyterHub _version:68] jupyterhub version 2.0.2 != jupyterhub-singleuser version 1.4.0. This could cause failure to authenticate and result in redirect loops!
[I 2022-01-18 08:08:57.317 JupyterHub base:943] User 0216020 took 3.655 seconds to start
[I 2022-01-18 08:08:57.318 JupyterHub proxy:286] Adding user 0216020 to proxy /user/0216020/ => http://jupyter-0216020:8888
[D 2022-01-18 08:08:57.318 JupyterHub proxy:821] Proxy: Fetching POST http://127.0.0.1:8001/api/routes/user/0216020
[I 2022-01-18 08:08:57.320 JupyterHub log:189] 200 GET /hub/api (@192.168.8.14) 0.93ms
08:08:57.320 [ConfigProxy] info: Adding route /user/0216020 -> http://jupyter-0216020:8888
08:08:57.321 [ConfigProxy] info: Route added /user/0216020 -> http://jupyter-0216020:8888
08:08:57.321 [ConfigProxy] info: 201 POST /api/routes/user/0216020
[D 2022-01-18 08:08:57.348 JupyterHub base:280] Recording first activity for <APIToken('cacc...', user='0216020', client_id='jupyterhub')>
[D 2022-01-18 08:08:57.354 JupyterHub scopes:298] Authenticated with token <APIToken('cacc...', user='0216020', client_id='jupyterhub')>
[D 2022-01-18 08:08:57.357 JupyterHub scopes:488] Checking access via scope users:activity
[D 2022-01-18 08:08:57.358 JupyterHub scopes:399] Argument-based access to /hub/api/users/0216020/activity via users:activity
[D 2022-01-18 08:08:57.361 JupyterHub users:845] Activity for user 0216020: 2022-01-18T08:08:56.170309Z
[D 2022-01-18 08:08:57.361 JupyterHub users:863] Activity on server 0216020/: 2022-01-18T08:08:56.170309Z
[I 2022-01-18 08:08:57.368 JupyterHub log:189] 200 POST /hub/api/users/0216020/activity (0216020@192.168.8.14) 30.16ms
[I 2022-01-18 08:09:02.664 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 9.13ms
[I 2022-01-18 08:09:22.661 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 5.58ms
[D 2022-01-18 08:09:26.403 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:09:26.403 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:09:26.419 JupyterHub swarmspawner:144] Service q6p8zwz status: {'ContainerStatus': {'ContainerID': 'd97995b2682445c7af99e36e807aaaf6990dd792027e3eb5c6f5594767bdbd4e',
'ExitCode': 0,
'PID': 4135424},
'Message': 'started',
'PortStatus': {},
'State': 'running',
'Timestamp': '2022-01-18T08:08:54.967944267Z'}
[I 2022-01-18 08:09:42.660 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 5.02ms
[D 2022-01-18 08:09:56.402 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:09:56.402 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:09:56.419 JupyterHub swarmspawner:144] Service q6p8zwz status: {'ContainerStatus': {'ContainerID': 'd97995b2682445c7af99e36e807aaaf6990dd792027e3eb5c6f5594767bdbd4e',
'ExitCode': 0,
'PID': 4135424},
'Message': 'started',
'PortStatus': {},
'State': 'running',
'Timestamp': '2022-01-18T08:08:54.967944267Z'}
[I 2022-01-18 08:10:02.661 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 6.06ms
[I 2022-01-18 08:10:22.664 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 9.35ms
[D 2022-01-18 08:10:26.401 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:10:26.401 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:10:26.413 JupyterHub swarmspawner:144] Service q6p8zwz status: {'ContainerStatus': {'ContainerID': 'd97995b2682445c7af99e36e807aaaf6990dd792027e3eb5c6f5594767bdbd4e',
'ExitCode': 0,
'PID': 4135424},
'Message': 'started',
'PortStatus': {},
'State': 'running',
'Timestamp': '2022-01-18T08:08:54.967944267Z'}
[I 2022-01-18 08:10:42.661 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 6.79ms
[D 2022-01-18 08:10:56.401 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:10:56.402 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:10:56.417 JupyterHub swarmspawner:144] Service q6p8zwz status: {'ContainerStatus': {'ContainerID': 'd97995b2682445c7af99e36e807aaaf6990dd792027e3eb5c6f5594767bdbd4e',
'ExitCode': 0,
'PID': 4135424},
'Message': 'started',
'PortStatus': {},
'State': 'running',
'Timestamp': '2022-01-18T08:08:54.967944267Z'}
[I 2022-01-18 08:11:02.662 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 6.90ms
[I 2022-01-18 08:11:22.661 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 6.11ms
[D 2022-01-18 08:11:26.402 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:11:26.402 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:11:26.415 JupyterHub swarmspawner:144] Service q6p8zwz status: {'ContainerStatus': {'ContainerID': 'd97995b2682445c7af99e36e807aaaf6990dd792027e3eb5c6f5594767bdbd4e',
'ExitCode': 0,
'PID': 4135424},
'Message': 'started',
'PortStatus': {},
'State': 'running',
'Timestamp': '2022-01-18T08:08:54.967944267Z'}
[I 2022-01-18 08:11:42.662 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 7.31ms
[D 2022-01-18 08:11:56.402 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:11:56.403 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:11:56.417 JupyterHub swarmspawner:144] Service q6p8zwz status: {'ContainerStatus': {'ContainerID': 'd97995b2682445c7af99e36e807aaaf6990dd792027e3eb5c6f5594767bdbd4e',
'ExitCode': 0,
'PID': 4135424},
'Message': 'started',
'PortStatus': {},
'State': 'running',
'Timestamp': '2022-01-18T08:08:54.967944267Z'}
[I 2022-01-18 08:11:57.652 JupyterHub log:189] 302 GET /hub/spawn-pending/0216020 -> /user/0216020/ (0216020@::ffff:192.168.0.2) 11.19ms
[D 2022-01-18 08:11:57.760 JupyterHub provider:411] Validating client id jupyterhub-user-0216020
[D 2022-01-18 08:11:57.762 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:359] Validating redirection uri /user/0216020/oauth_callback for client jupyterhub-user-0216020.
[D 2022-01-18 08:11:57.762 oauthlib.oauth2.rfc6749.grant_types.base base:230] Using provided redirect_uri /user/0216020/oauth_callback
[D 2022-01-18 08:11:57.762 JupyterHub provider:486] validate_redirect_uri: client_id=jupyterhub-user-0216020, redirect_uri=/user/0216020/oauth_callback
[D 2022-01-18 08:11:57.766 oauthlib.oauth2.rfc6749.grant_types.base base:171] Validating access to scopes [] for client 'jupyterhub-user-0216020' (<OAuthClient(identifier='jupyterhub-user-0216020')>).
[D 2022-01-18 08:11:57.768 JupyterHub provider:570] Allowing request for role(s) for jupyterhub-user-0216020: []
[D 2022-01-18 08:11:57.769 JupyterHub auth:287] Skipping oauth confirmation for <User(0216020 1/1 running)> accessing Server at /user/0216020/
[D 2022-01-18 08:11:57.770 oauthlib.oauth2.rfc6749.endpoints.authorization authorization:98] Dispatching response_type code request to <oauthlib.oauth2.rfc6749.grant_types.authorization_code.AuthorizationCodeGrant object at 0x7fce67e8bdf0>.
[D 2022-01-18 08:11:57.771 JupyterHub provider:411] Validating client id jupyterhub-user-0216020
[D 2022-01-18 08:11:57.771 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:359] Validating redirection uri /user/0216020/oauth_callback for client jupyterhub-user-0216020.
[D 2022-01-18 08:11:57.772 oauthlib.oauth2.rfc6749.grant_types.base base:230] Using provided redirect_uri /user/0216020/oauth_callback
[D 2022-01-18 08:11:57.772 JupyterHub provider:486] validate_redirect_uri: client_id=jupyterhub-user-0216020, redirect_uri=/user/0216020/oauth_callback
[D 2022-01-18 08:11:57.774 oauthlib.oauth2.rfc6749.grant_types.base base:171] Validating access to scopes [] for client 'jupyterhub-user-0216020' (<OAuthClient(identifier='jupyterhub-user-0216020')>).
[D 2022-01-18 08:11:57.774 JupyterHub provider:570] Allowing request for role(s) for jupyterhub-user-0216020: []
[D 2022-01-18 08:11:57.775 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:245] Pre resource owner authorization validation ok for <oauthlib.Request SANITIZED>.
[D 2022-01-18 08:11:57.776 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:170] Created authorization code grant {'code': '<removed>', 'state': '<removed>'} for request <oauthlib.Request SANITIZED>.
[D 2022-01-18 08:11:57.776 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:275] Saving grant {'code': '<removed>', 'state': '<removed>'} for <oauthlib.Request SANITIZED>.
[D 2022-01-18 08:11:57.776 JupyterHub provider:237] Saving authorization code jupyterhub-user-0216020, egl..., (), {}
[I 2022-01-18 08:11:57.786 JupyterHub log:189] 302 GET /hub/api/oauth2/authorize?client_id=jupyterhub-user-0216020&redirect_uri=%2Fuser%2F0216020%2Foauth_callback&response_type=code&state=[secret] -> /user/0216020/oauth_callback?code=[secret]&state=[secret] (0216020@::ffff:192.168.0.2) 29.76ms
[D 2022-01-18 08:11:57.832 JupyterHub scopes:298] Authenticated with token <APIToken('cacc...', user='0216020', client_id='jupyterhub')>
[D 2022-01-18 08:11:57.833 oauthlib.oauth2.rfc6749.endpoints.token token:112] Dispatching grant_type authorization_code request to <oauthlib.oauth2.rfc6749.grant_types.authorization_code.AuthorizationCodeGrant object at 0x7fce67e8bdf0>.
[D 2022-01-18 08:11:57.834 JupyterHub provider:53] authenticate_client <oauthlib.Request SANITIZED>
[D 2022-01-18 08:11:57.842 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:530] Using provided redirect_uri /user/0216020/oauth_callback
[D 2022-01-18 08:11:57.842 JupyterHub provider:112] confirm_redirect_uri: client_id=jupyterhub-user-0216020, redirect_uri=/user/0216020/oauth_callback
[D 2022-01-18 08:11:57.843 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:299] Token request validation ok for <oauthlib.Request SANITIZED>.
[D 2022-01-18 08:11:57.843 JupyterHub provider:335] Saving bearer token {'access_token': 'REDACTED', 'expires_in': 1209600, 'token_type': 'Bearer', 'scope': '', 'refresh_token': 'REDACTED'}
[D 2022-01-18 08:11:57.852 JupyterHub provider:195] Deleting oauth code egl... for jupyterhub-user-0216020
[I 2022-01-18 08:11:57.863 JupyterHub log:189] 200 POST /hub/api/oauth2/token (0216020@192.168.8.14) 38.68ms
[D 2022-01-18 08:11:57.870 JupyterHub scopes:298] Authenticated with token <APIToken('cacc...', user='0216020', client_id='jupyterhub')>
[W 2022-01-18 08:11:57.870 JupyterHub auth:30] /authorizations/token/:token endpoint is deprecated in JupyterHub 2.0. Use /api/user
[I 2022-01-18 08:11:57.878 JupyterHub log:189] 200 GET /hub/api/authorizations/token/[secret] (0216020@192.168.8.14) 9.90ms
[D 2022-01-18 08:12:00.240 JupyterHub swarmspawner:155] Getting task of service 'jupyter-0216020'
[D 2022-01-18 08:12:00.240 JupyterHub dockerspawner:982] Getting service 'jupyter-0216020'
[D 2022-01-18 08:12:00.255 JupyterHub swarmspawner:144] Service q6p8zwz status: {'ContainerStatus': {'ContainerID': 'd97995b2682445c7af99e36e807aaaf6990dd792027e3eb5c6f5594767bdbd4e',
'ExitCode': 0,
'PID': 4135424},
'Message': 'started',
'PortStatus': {},
'State': 'running',
'Timestamp': '2022-01-18T08:08:54.967944267Z'}
[I 2022-01-18 08:12:00.268 JupyterHub log:189] 200 GET /hub/home (0216020@::ffff:192.168.0.2) 31.72ms
[D 2022-01-18 08:12:00.425 JupyterHub log:189] 200 GET /hub/static/js/home.js?v=20220118080354 (@::ffff:192.168.0.2) 1.59ms
[D 2022-01-18 08:12:00.495 JupyterHub log:189] 200 GET /hub/static/js/jhapi.js?v=20220118080354 (@::ffff:192.168.0.2) 1.56ms
[D 2022-01-18 08:12:00.496 JupyterHub log:189] 200 GET /hub/static/components/moment/moment.js?v=20220118080354 (@::ffff:192.168.0.2) 2.16ms
[D 2022-01-18 08:12:00.525 JupyterHub log:189] 200 GET /hub/static/js/utils.js?v=20220118080354 (@::ffff:192.168.0.2) 0.98ms
[W 2022-01-18 08:12:02.008 JupyterHub base:93] Blocking Cross Origin API request. Referer: https://<removed>/hub/home, Host: <removed>, Host URL: http://<removed>/hub/
[D 2022-01-18 08:12:02.008 JupyterHub scopes:488] Checking access via scope delete:servers
[D 2022-01-18 08:12:02.009 JupyterHub scopes:383] No access to /hub/api/users/0216020/server via delete:servers
[W 2022-01-18 08:12:02.009 JupyterHub scopes:496] Not authorizing access to /hub/api/users/0216020/server. Requires any of [delete:servers], not derived from scopes []
[W 2022-01-18 08:12:02.009 JupyterHub web:1787] 403 DELETE /hub/api/users/0216020/server (::ffff:192.168.0.2): Action is not authorized with current scopes; requires any of [delete:servers]
[W 2022-01-18 08:12:02.010 JupyterHub log:189] 403 DELETE /hub/api/users/0216020/server (@::ffff:192.168.0.2) 4.11ms
[I 2022-01-18 08:12:02.662 JupyterHub log:189] 200 GET /hub/metrics (@::ffff:192.168.0.2) 6.86ms
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment