Skip to content

Instantly share code, notes, and snippets.

@jcb91

jcb91/hub_first_successful_spawn_and_redirect.log

Created May 17, 2018 18:21
Show Gist options
  • Save jcb91/acf8f64b0ea902eef1a1740f9abbb9f7 to your computer and use it in GitHub Desktop.
Save jcb91/acf8f64b0ea902eef1a1740f9abbb9f7 to your computer and use it in GitHub Desktop.
Download ZIP
500: "failed to check authorization" on second start of single-user server
Raw
hub_first_successful_spawn_and_redirect.log
[I 2018-05-17 17:28:29.242 JupyterHub log:122] 302 GET /hub/oauth_login?next= → https://login.microsoftonline.com/36dcc9bb-8e53-421f-8c83-3a8f4f66ee0d/oauth2/authorize?redirect_uri=https%3A%2F%2Fexample.com%2Fhub%2Foauth_callback&client_id=d22e4e92-0c35-437d-8b33-7a2be1bab4a2&response_type=code&state=
[D 2018-05-17 17:28:39.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:28:39.452 - info: [ConfigProxy] 200 GET /api/routes
[I 2018-05-17 17:28:47.015 JupyterHub azuread:57] ID3: REDACTED AAD_TENANT_ID
[D 2018-05-17 17:28:47.474 JupyterHub base:268] Setting cookie for josh.barnes: jupyter-hub-token, {'secure': True, 'httponly': True}
[I 2018-05-17 17:28:47.475 JupyterHub base:346] User logged in: josh.barnes
[I 2018-05-17 17:28:47.475 JupyterHub log:122] 302 GET /hub/oauth_callback?code=AQABAAIAAADX8GCi6Js6SK82TsD2Pb7riQM7X-KdhxRNyfOZBq-rzwxa2CRj3U7lUcXddnanmc1QvernCqb5j5Zas1Iqz0jDS4P3Wiyvygw0R9mqlZIlRQ8x3ANEH49IG2qcpNMBFoDs-_dJX4G_pkErM5k0RTH3AQtPxCn22rTyBYYqbX5gTYr2IV-9aCPI58UEblsRa4ujSrXLyScjB-EdgtnVfOz8XEfkYmopRjvsI
[D 2018-05-17 17:28:47.515 JupyterHub pages:54] User is not running: /hub/home
[I 2018-05-17 17:28:47.516 JupyterHub log:122] 302 GET /hub/ → /hub/home (josh.barnes@::ffff:93.184.216.34) 1.88ms
[I 2018-05-17 17:28:47.558 JupyterHub log:122] 200 GET /hub/home (josh.barnes@::ffff:93.184.216.34) 6.43ms
[D 2018-05-17 17:28:47.656 JupyterHub log:122] 200 GET /hub/static/components/font-awesome/fonts/fontawesome-webfont.woff2?v=4.7.0 (@::ffff:93.184.216.34) 1.31ms
[D 2018-05-17 17:28:47.670 JupyterHub log:122] 200 GET /hub/static/js/home.js?v=(& (@::ffff:93.184.216.34) 1.04ms
[D 2018-05-17 17:28:47.722 JupyterHub log:122] 200 GET /hub/static/js/jhapi.js?v=(& (@::ffff:93.184.216.34) 1.04ms
[D 2018-05-17 17:28:47.761 JupyterHub log:122] 200 GET /hub/static/js/utils.js?v=(& (@::ffff:93.184.216.34) 1.03ms
[D 2018-05-17 17:28:54.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:28:54.451 - info: [ConfigProxy] 200 GET /api/routes
[I 2018-05-17 17:28:56.531 JupyterHub log:122] 200 GET /hub/spawn (josh.barnes@::ffff:93.184.216.34) 7.77ms
[D 2018-05-17 17:28:56.629 JupyterHub base:421] Initiating spawn for josh.barnes
[D 2018-05-17 17:28:56.666 JupyterHub base:427] 0/100 concurrent spawns
[D 2018-05-17 17:28:56.670 JupyterHub base:430] 0 active servers
[I 2018-05-17 17:28:56.827 JupyterHub spawner:479] server ec2.Instance(id='i-0d46698b6327ac66b') has been terminated
[I 2018-05-17 17:28:56.833 JupyterHub spawner:301] server does not exist for user josh.barnes
[D 2018-05-17 17:29:09.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:29:09.452 - info: [ConfigProxy] 200 GET /api/routes
[D 2018-05-17 17:29:24.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:29:24.452 - info: [ConfigProxy] 200 GET /api/routes
[I 2018-05-17 17:29:28.589 JupyterHub spawner:530] setting up user josh.barnes
[I 2018-05-17 17:29:31.200 JupyterHub spawner:512] about to check if notebook is running before launching
[D 2018-05-17 17:29:31.200 JupyterHub spawner:419] is_notebook_running for user josh.barnes, attempt 1/1...
[D 2018-05-17 17:29:31.252 JupyterHub spawner:429] Notebook for user josh.barnes not running...
[I 2018-05-17 17:29:32.256 JupyterHub spawner:611] Configuring notebook service for user josh.barnes
[D 2018-05-17 17:29:32.766 JupyterHub spawner:657] user josh.barnes running command: systemctl restart jupyter-notebook.service
[I 2018-05-17 17:29:33.878 JupyterHub spawner:661] Just started the notebook for user josh.barnes, waiting.
[D 2018-05-17 17:29:33.878 JupyterHub spawner:419] is_notebook_running for user josh.barnes, attempt 1/30...
[D 2018-05-17 17:29:33.903 JupyterHub spawner:425] the following notebook is definitely running:
[D 2018-05-17 17:29:33.903 JupyterHub spawner:426] josh.ba+ 1813 1 17 17:29 ? 00:00:00 /usr/bin/python3 /usr/local/bin/jupyterhub-singleuser --ip=10.10.230.190 --port=4444
[D 2018-05-17 17:29:33.903 JupyterHub spawner:306] started: 10.10.230.190 , 4444
[W 2018-05-17 17:29:37.415 JupyterHub base:103] Rolling back dirty objects IdentitySet([<Server(10.10.230.190:4444)>])
[I 2018-05-17 17:29:37.416 JupyterHub log:122] 200 GET /hub/api (@10.10.230.190) 1.74ms
[D 2018-05-17 17:29:39.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:29:39.451 - info: [ConfigProxy] 200 GET /api/routes
[D 2018-05-17 17:29:43.905 JupyterHub spawner:727] Polling subprocess every 10s
[D 2018-05-17 17:29:43.916 JupyterHub utils:171] Server at http://10.10.230.190:4444/user/josh.barnes/ responded with 302
[D 2018-05-17 17:29:43.917 JupyterHub _version:46] jupyterhub and jupyterhub-singleuser both on version 0.8.1
[I 2018-05-17 17:29:43.917 JupyterHub base:447] User josh.barnes took 47.288 seconds to start
[I 2018-05-17 17:29:43.918 JupyterHub proxy:231] Adding user josh.barnes to proxy /user/josh.barnes/ => http://10.10.230.190:4444
[D 2018-05-17 17:29:43.918 JupyterHub proxy:552] Proxy: Fetching POST http://10.10.0.47:8001/api/routes/user/josh.barnes
17:29:43.958 - info: [ConfigProxy] Adding route /user/josh.barnes -> http://10.10.230.190:4444
17:29:43.959 - info: [ConfigProxy] 201 POST /api/routes/user/josh.barnes
[I 2018-05-17 17:29:43.963 JupyterHub log:122] 302 POST /hub/spawn → /user/josh.barnes/ (josh.barnes@::ffff:93.184.216.34) 47335.39ms
[I 2018-05-17 17:29:44.090 JupyterHub log:122] 302 GET /hub/api/oauth2/authorize?response_type=code&redirect_uri=%2Fuser%2Fjosh.barnes%2Foauth_callback&client_id=user-josh.barnes&state=eyJ1dWlkIjogIjhlYmZjMmMwNTI0MDQ3Zjk4OWYxMzgzYWM2NWI4NjRhIiwgIm5leHRfdXJsIjogIi91c2VyL2pvc2guYmFybmVzL3RyZWU_In0 → /user/josh.barnes/
[I 2018-05-17 17:29:44.155 JupyterHub log:122] 200 POST /hub/api/oauth2/token (josh.barnes@10.10.230.190) 18.76ms
[I 2018-05-17 17:29:44.161 JupyterHub log:122] 200 GET /hub/api/authorizations/token/[secret] (josh.barnes@10.10.230.190) 4.85ms
[I 2018-05-17 17:29:44.215 JupyterHub log:122] 200 GET /hub/api/authorizations/token/[secret] (josh.barnes@10.10.230.190) 2.83ms
[D 2018-05-17 17:29:53.977 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[D 2018-05-17 17:29:54.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:29:54.452 - info: [ConfigProxy] 200 GET /api/routes
[D 2018-05-17 17:29:55.058 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[I 2018-05-17 17:29:55.098 JupyterHub log:122] 200 GET /hub/home (josh.barnes@::ffff:93.184.216.34) 117.39ms
[D 2018-05-17 17:30:03.969 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[D 2018-05-17 17:30:09.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:30:09.452 - info: [ConfigProxy] 200 GET /api/routes
[D 2018-05-17 17:30:13.970 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[D 2018-05-17 17:30:23.978 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[D 2018-05-17 17:30:24.448 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:30:24.452 - info: [ConfigProxy] 200 GET /api/routes
[D 2018-05-17 17:30:33.977 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[D 2018-05-17 17:30:39.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:30:39.451 - info: [ConfigProxy] 200 GET /api/routes
[D 2018-05-17 17:30:44.027 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[D 2018-05-17 17:30:46.267 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[I 2018-05-17 17:30:46.293 JupyterHub proxy:254] Removing user josh.barnes from proxy (/user/josh.barnes/)
[D 2018-05-17 17:30:46.293 JupyterHub proxy:552] Proxy: Fetching DELETE http://10.10.0.47:8001/api/routes/user/josh.barnes
17:30:46.296 - info: [ConfigProxy] Removing route /user/josh.barnes
17:30:46.297 - info: [ConfigProxy] 204 DELETE /api/routes/user/josh.barnes
[D 2018-05-17 17:30:46.360 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[I 2018-05-17 17:30:46.387 JupyterHub spawner:317] Stopping user josh.barnes instance
[I 2018-05-17 17:30:46.467 JupyterHub base:548] User josh.barnes server took 0.173 seconds to stop
[I 2018-05-17 17:30:46.469 JupyterHub log:122] 204 DELETE /hub/api/users/josh.barnes/server (josh.barnes@::ffff:93.184.216.34) 277.73ms
Raw
hub_initial_start.log
Started jupyterhub server.
[I 2018-05-17 17:26:39.155 JupyterHub app:834] Loading cookie_secret from /etc/jupyterhub/cookie_secret
[D 2018-05-17 17:26:39.155 JupyterHub app:886] Connecting to db: sqlite:////etc/jupyterhub/jupyterhub.sqlite
[D 2018-05-17 17:26:39.167 JupyterHub orm:504] database schema version found: 3ec6993fe20c
[I 2018-05-17 17:26:39.189 JupyterHub app:983] Not using whitelist. Any authenticated user will be allowed.
[D 2018-05-17 17:26:39.197 JupyterHub user:184] Creating <class 'spawner.InstanceSpawner'> for josh.barnes:
[D 2018-05-17 17:26:39.199 JupyterHub app:1211] Loading state for josh.barnes from db
[D 2018-05-17 17:26:39.199 JupyterHub app:1236] josh.barnes not running
[D 2018-05-17 17:26:39.207 JupyterHub user:184] Creating <class 'spawner.InstanceSpawner'> for clark.kent:
[D 2018-05-17 17:26:39.208 JupyterHub app:1211] Loading state for clark.kent from db
[D 2018-05-17 17:26:39.208 JupyterHub app:1236] clark.kent not running
[D 2018-05-17 17:26:39.219 JupyterHub app:1241] Loaded users:
josh.barnes admin
clark.kent
[I 2018-05-17 17:26:39.224 JupyterHub app:1528] Hub API listening on http://10.10.0.47:8081/hub/
[I 2018-05-17 17:26:39.225 JupyterHub proxy:458] Starting proxy @ https://*:443/
[D 2018-05-17 17:26:39.225 JupyterHub proxy:459] Proxy cmd: ['configurable-http-proxy', '--redirect-port', '80', '--ip', '', '--port', '443', '--api-ip', '10.10.0.47', '--api-port', '8001', '--error-target', 'http://10.10.0.47:8081/hub/error', '--ssl-key', '/etc/jupyterhub/ssl/private/example.com_Wildcard.key',
17:26:39.354 - info: [ConfigProxy] Proxying https://*:443 to (no default)
17:26:39.357 - info: [ConfigProxy] Proxy API at http://10.10.0.47:8001/api/routes
[D 2018-05-17 17:26:39.383 JupyterHub proxy:491] Proxy started and appears to be up
[D 2018-05-17 17:26:39.384 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
[W 2018-05-17 17:26:39.400 JupyterHub proxy:304] Adding missing default route
[I 2018-05-17 17:26:39.400 JupyterHub proxy:370] Adding default route for Hub: / => http://10.10.0.47:8081
[D 2018-05-17 17:26:39.400 JupyterHub proxy:552] Proxy: Fetching POST http://10.10.0.47:8001/api/routes/
17:26:39.401 - info: [ConfigProxy] 200 GET /api/routes
17:26:39.446 - info: [ConfigProxy] Adding route / -> http://10.10.0.47:8081
17:26:39.447 - info: [ConfigProxy] 201 POST /api/routes/
[I 2018-05-17 17:26:39.448 JupyterHub app:1581] JupyterHub is now running at https://:443/
[D 2018-05-17 17:26:54.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:26:54.452 - info: [ConfigProxy] 200 GET /api/routes
[I 2018-05-17 17:27:42.254 JupyterHub log:122] 302 GET / → /hub (@::ffff:93.184.216.34) 0.90ms
[I 2018-05-17 17:27:42.292 JupyterHub log:122] 302 GET /hub → /hub/login (@::ffff:93.184.216.34) 0.48ms
[I 2018-05-17 17:27:42.353 JupyterHub log:122] 200 GET /hub/login (@::ffff:93.184.216.34) 23.27ms
[D 2018-05-17 17:27:42.406 JupyterHub log:122] 200 GET /hub/static/css/style.min.css?v=14dc0b5a8b791d573b687aa626aa2600 (@::ffff:93.184.216.34) 3.49ms
[D 2018-05-17 17:27:42.478 JupyterHub log:122] 200 GET /hub/static/components/requirejs/require.js?v=e7199843dfd445bb66ec816e98a03214 (@::ffff:93.184.216.34) 1.24ms
[D 2018-05-17 17:27:42.539 JupyterHub log:122] 200 GET /hub/static/components/jquery/dist/jquery.min.js?v=c9f5aeeca3ad37bf2aa006139b935f0a (@::ffff:93.184.216.34) 1.41ms
[D 2018-05-17 17:27:42.598 JupyterHub log:122] 200 GET /hub/static/components/bootstrap/dist/js/bootstrap.min.js?v=5869c96cc8f19086aee625d670d741f9 (@::ffff:93.184.216.34) 0.95ms
[D 2018-05-17 17:27:42.648 JupyterHub log:122] 200 GET /hub/logo (@::ffff:93.184.216.34) 1.03ms
[D 2018-05-17 17:28:24.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:28:24.451 - info: [ConfigProxy] 200 GET /api/routes
[I 2018-05-17 17:28:29.241 JupyterHub oauth2:82] OAuth redirect: 'https://example.com/hub/oauth_callback'
Raw
hub_second_unsuccessful_redirect.log
[I 2018-05-17 17:31:46.412 JupyterHub log:122] 302 GET /user/josh.barnes/ → /hub/user/josh.barnes/ (@::ffff:93.184.216.34) 0.59ms
[I 2018-05-17 17:31:46.456 JupyterHub log:122] 302 GET /hub/user/josh.barnes/ → /hub/spawn?next=%2Fhub%2Fuser%2Fjosh.barnes%2F (josh.barnes@::ffff:93.184.216.34) 4.67ms
[I 2018-05-17 17:31:46.498 JupyterHub log:122] 200 GET /hub/spawn?next=%2Fhub%2Fuser%2Fjosh.barnes%2F (josh.barnes@::ffff:93.184.216.34) 4.52ms
[D 2018-05-17 17:31:46.678 JupyterHub base:421] Initiating spawn for josh.barnes
[D 2018-05-17 17:31:46.720 JupyterHub base:427] 0/100 concurrent spawns
[D 2018-05-17 17:31:46.720 JupyterHub base:430] 0 active servers
[I 2018-05-17 17:31:46.822 JupyterHub spawner:267] Starting user josh.barnes instance
[D 2018-05-17 17:31:54.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:31:54.452 - info: [ConfigProxy] 200 GET /api/routes
[D 2018-05-17 17:32:09.448 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:32:09.451 - info: [ConfigProxy] 200 GET /api/routes
[I 2018-05-17 17:32:13.705 JupyterHub spawner:512]
about to check if notebook is running before launching
[D 2018-05-17 17:32:13.706 JupyterHub spawner:419] is_notebook_running for user josh.barnes, attempt 1/1...
[D 2018-05-17 17:32:13.766 JupyterHub spawner:425] the following notebook is definitely running:
[D 2018-05-17 17:32:13.766 JupyterHub spawner:426] josh.ba+ 1164 1 21 17:32 ? 00:00:00 /usr/bin/python3 /usr/local/bin/jupyterhub-singleuser --ip=10.10.230.190 --port=4444
[D 2018-05-17 17:32:13.766 JupyterHub spawner:277] restarted: 10.10.230.190 , 4444
[I 2018-05-17 17:32:14.201 JupyterHub log:122] 200 GET /hub/api (@10.10.230.190) 0.62ms
[D 2018-05-17 17:32:23.769 JupyterHub spawner:727] Polling subprocess every 10s
[D 2018-05-17 17:32:23.779 JupyterHub utils:171] Server at http://10.10.230.190:4444/user/josh.barnes/ responded with 302
[D 2018-05-17 17:32:23.780 JupyterHub _version:46] jupyterhub and jupyterhub-singleuser both on version 0.8.1
[I 2018-05-17 17:32:23.780 JupyterHub base:447] User josh.barnes took 37.102 seconds to start
[I 2018-05-17 17:32:23.781 JupyterHub proxy:231] Adding user josh.barnes to proxy /user/josh.barnes/ => http://10.10.230.190:4444
[D 2018-05-17 17:32:23.781 JupyterHub proxy:552] Proxy: Fetching POST http://10.10.0.47:8001/api/routes/user/josh.barnes
17:32:23.822 - info: [ConfigProxy] Adding route /user/josh.barnes -> http://10.10.230.190:4444
17:32:23.822 - info: [ConfigProxy] 201 POST /api/routes/user/josh.barnes
[I 2018-05-17 17:32:23.826 JupyterHub log:122] 302 POST /hub/spawn?next=%2Fhub%2Fuser%2Fjosh.barnes%2F → /hub/user/josh.barnes/ (josh.barnes@::ffff:93.184.216.34) 37149.15ms
[D 2018-05-17 17:32:23.939 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[I 2018-05-17 17:32:23.969 JupyterHub log:122] 302 GET /hub/user/josh.barnes/ → /user/josh.barnes/?redirects=1 (josh.barnes@::ffff:93.184.216.34) 102.86ms
[I 2018-05-17 17:32:24.092 JupyterHub log:122] 302 GET /hub/api/oauth2/authorize?redirect_uri=%2Fuser%2Fjosh.barnes%2Foauth_callback&client_id=user-josh.barnes&response_type=code&state=eyJuZXh0X3VybCI6ICIvdXNlci9qb3NoLmJhcm5lcy90cmVlP3JlZGlyZWN0cz0xIiwgInV1aWQiOiAiODQzNTgwMmJiNmRhNDRhY2I5NDFjYTA4Nzg5OWExYjgifQ → /us
[W 2018-05-17 17:32:24.145 JupyterHub log:122] 400 POST /hub/api/oauth2/token (@10.10.230.190) 5.97ms
[D 2018-05-17 17:32:24.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:32:24.452 - info: [ConfigProxy] 200 GET /api/routes
[D 2018-05-17 17:32:33.837 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[D 2018-05-17 17:32:39.449 JupyterHub proxy:552] Proxy: Fetching GET http://10.10.0.47:8001/api/routes
17:32:39.451 - info: [ConfigProxy] 200 GET /api/routes
[D 2018-05-17 17:32:43.840 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[D 2018-05-17 17:32:48.654 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[D 2018-05-17 17:32:48.683 JupyterHub log:122] 304 GET /hub/home (josh.barnes@::ffff:93.184.216.34) 95.38ms
[D 2018-05-17 17:32:50.943 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[I 2018-05-17 17:32:50.969 JupyterHub proxy:254] Removing user josh.barnes from proxy (/user/josh.barnes/)
[D 2018-05-17 17:32:50.970 JupyterHub proxy:552] Proxy: Fetching DELETE http://10.10.0.47:8001/api/routes/user/josh.barnes
17:32:50.972 - info: [ConfigProxy] Removing route /user/josh.barnes
17:32:50.972 - info: [ConfigProxy] 204 DELETE /api/routes/user/josh.barnes
[D 2018-05-17 17:32:51.045 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[I 2018-05-17 17:32:51.071 JupyterHub spawner:317] Stopping user josh.barnes instance
[I 2018-05-17 17:32:51.163 JupyterHub base:548] User josh.barnes server took 0.192 seconds to stop
[D 2018-05-17 17:32:48.654 JupyterHub spawner:419] is_notebook_running for user josh.barnes, attempt 1/1...
[D 2018-05-17 17:32:48.679 JupyterHub spawner:425] the following notebook is definitely running:
[D 2018-05-17 17:32:48.679 JupyterHub spawner:426] josh.ba+ 1164 1 1 17:32 ? 00:00:00 /usr/bin/python3 /usr/local/bin/jupyterhub-singleuser --ip=10.10.230.190 --port=4444
[D 2018-05-17 17:32:48.679 JupyterHub spawner:384] poll: notebook is running for user josh.barnes
[D 2018-05-17 17:32:48.683 JupyterHub log:122] 304 GET /hub/home (josh.barnes@::ffff:93.184.216.34) 95.38ms
[D 2018-05-17 17:32:50.943 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[I 2018-05-17 17:32:50.969 JupyterHub proxy:254] Removing user josh.barnes from proxy (/user/josh.barnes/)
[D 2018-05-17 17:32:50.970 JupyterHub proxy:552] Proxy: Fetching DELETE http://10.10.0.47:8001/api/routes/user/josh.barnes
17:32:50.972 - info: [ConfigProxy] Removing route /user/josh.barnes
17:32:50.972 - info: [ConfigProxy] 204 DELETE /api/routes/user/josh.barnes
[D 2018-05-17 17:32:51.045 JupyterHub spawner:364] poll: notebook is running for user josh.barnes
[I 2018-05-17 17:32:51.071 JupyterHub spawner:317] Stopping user josh.barnes instance
[I 2018-05-17 17:32:51.163 JupyterHub base:548] User josh.barnes server took 0.192 seconds to stop
[I 2018-05-17 17:32:51.165 JupyterHub log:122] 204 DELETE /hub/api/users/josh.barnes/server (josh.barnes@::ffff:93.184.216.34) 288.68ms
Raw
singleuser_first_successful_start.log
[I 2018-05-17 17:29:44.161 SingleUserNotebookApp auth:818] Logged-in user {'pending': None, 'server': '/user/josh.barnes/', 'kind': 'user', 'admin': True, 'last_activity': '2018-05-17T17:29:43.905760', 'name': 'josh.barnes', 'groups': []}
[I 2018-05-17 17:29:44.162 SingleUserNotebookApp log:122] 302 GET /user/josh.barnes/oauth_callback?code=4816656f-cbb6-4b82-8ce7-b41a1869b8b3&state=eyJ1dWlkIjogIjhlYmZjMmMwNTI0MDQ3Zjk4OWYxMzgzYWM2NWI4NjRhIiwgIm5leHRfdXJsIjogIi91c2VyL2pvc2guYmFybmVzL3RyZWU_In0 → /user/josh.barnes/tree? (@::ffff:93.184.216.34) 36.21ms
[I 2018-05-17 17:29:44.695 SingleUserNotebookApp log:122] 200 GET /user/josh.barnes/tree? (josh.barnes@::ffff:93.184.216.34) 489.92ms
[I 2018-05-17 17:29:45.110 SingleUserNotebookApp log:122] 200 GET /user/josh.barnes/api/config/tree?_=1526578184991 (josh.barnes@::ffff:93.184.216.34) 8.50ms
[I 2018-05-17 17:29:45.141 SingleUserNotebookApp log:122] 200 GET /user/josh.barnes/api/config/common?_=1526578184992 (josh.barnes@::ffff:93.184.216.34) 14.09ms
[I 2018-05-17 17:29:45.143 SingleUserNotebookApp log:122] 200 GET /user/josh.barnes/api/terminals?_=1526578184993 (josh.barnes@::ffff:93.184.216.34) 0.74ms
[I 2018-05-17 17:29:45.151 SingleUserNotebookApp log:122] 200 GET /user/josh.barnes/api/sessions?_=1526578184994 (josh.barnes@::ffff:93.184.216.34) 7.45ms
[I 2018-05-17 17:29:45.271 SingleUserNotebookApp log:122] 200 GET /user/josh.barnes/api/kernelspecs (josh.barnes@::ffff:93.184.216.34) 119.00ms
[I 2018-05-17 17:29:45.277 SingleUserNotebookApp log:122] 200 GET /user/josh.barnes/api/terminals?_=1526578184995 (josh.barnes@::ffff:93.184.216.34) 4.88ms
[I 2018-05-17 17:29:45.280 SingleUserNotebookApp log:122] 200 GET /user/josh.barnes/api/contents?type=directory&_=1526578184996 (josh.barnes@::ffff:93.184.216.34) 1.93ms
Raw
singleuser_second_unsuccessful_login.log
Started jupyter notebook server.
[I 2018-05-17 17:32:13.802 SingleUserNotebookApp singleuser:365] Starting jupyterhub-singleuser server version 0.8.1
[I 2018-05-17 17:32:13.814 SingleUserNotebookApp notebookapp:1585] Serving notebooks from local directory: /home/josh.barnes
[I 2018-05-17 17:32:13.815 SingleUserNotebookApp notebookapp:1585] 0 active kernels
[I 2018-05-17 17:32:13.815 SingleUserNotebookApp notebookapp:1585] The Jupyter Notebook is running at:
[I 2018-05-17 17:32:13.815 SingleUserNotebookApp notebookapp:1585] http://10.10.230.190:4444/user/josh.barnes/
[I 2018-05-17 17:32:13.815 SingleUserNotebookApp notebookapp:1586] Use Control-C to stop this server and shut down all kernels (twice to skip confirmation).
[I 2018-05-17 17:32:23.779 SingleUserNotebookApp log:122] 302 GET /user/josh.barnes/ → /user/josh.barnes/tree? (@10.10.0.47) 0.68ms
[I 2018-05-17 17:32:24.005 SingleUserNotebookApp log:122] 302 GET /user/josh.barnes/?redirects=1 → /user/josh.barnes/tree?redirects=1 (@::ffff:93.184.216.34) 0.54ms
[I 2018-05-17 17:32:24.045 SingleUserNotebookApp log:122] 302 GET /user/josh.barnes/tree?redirects=1 → /hub/api/oauth2/authorize?redirect_uri=%2Fuser%2Fjosh.barnes%2Foauth_callback&client_id=user-josh.barnes&response_type=code&state=eyJuZXh0X3VybCI6ICIvdXNlci9qb3NoLmJhcm5lcy90cmVlP3JlZGlyZWN0cz0xIiwgInV1aWQiOiAiODQz
[W 2018-05-17 17:32:24.145 SingleUserNotebookApp auth:269] Failed to check authorization: [400] Bad Request
[W 2018-05-17 17:32:24.251 SingleUserNotebookApp auth:270] {"error_description": "Invalid client credentials", "error": "invalid_client"}
[W 2018-05-17 17:32:24.252 SingleUserNotebookApp web:1618] 500 GET /user/josh.barnes/oauth_callback?code=ee4796ed-28ff-434b-8c6b-25f700d18ec9&state=eyJuZXh0X3VybCI6ICIvdXNlci9qb3NoLmJhcm5lcy90cmVlP3JlZGlyZWN0cz0xIiwgInV1aWQiOiAiODQzNTgwMmJiNmRhNDRhY2I5NDFjYTA4Nzg5OWExYjgifQ (::ffff:93.184.216.34): Failed to check authorization
[E 2018-05-17 17:32:24.296 SingleUserNotebookApp log:114] {
"Cookie": "user-josh.barnes=2|1:0|10:1526578184|16:user-josh.barnes|48:OTM0YmJkNjEtOWY5Yy00OGFkLWFiODctYmU3ZTAwZTIzMDIx|5c33481bc636567c290286763c8f5e696364ab9d84152dbf2205f5c0065ef67a; user-josh.barnes-oauth-state=2|1:0|10:1526578344|28:user-josh.barnes-oauth-state|168:ZXlKdVpYaDBYM1Z5YkNJNklDSXZkWE5sY2k5cWIz
"Connection": "close",
"X-Forwarded-Port": "443",
"X-Forwarded-Host": "example.com",
"Accept-Language": "en-GB,en-US;q=0.9,en;q=0.8",
"User-Agent": "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/66.0.3359.181 Safari/537.36",
"Dnt": "1",
"Host": "example.com",
"Accept-Encoding": "gzip, deflate, br",
"Accept": "text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,image/apng,*/*;q=0.8",
"Upgrade-Insecure-Requests": "1",
"X-Forwarded-Proto": "https",
"X-Forwarded-For": "::ffff:93.184.216.34",
"Cache-Control": "max-age=0",
"Referer": "https://example.com/hub/spawn?next=%2Fhub%2Fuser%2Fjosh.barnes%2F"
}
[E 2018-05-17 17:32:24.299 SingleUserNotebookApp log:122] 500 GET /user/josh.barnes/oauth_callback?code=ee4796ed-28ff-434b-8c6b-25f700d18ec9&state=eyJuZXh0X3VybCI6ICIvdXNlci9qb3NoLmJhcm5lcy90cmVlP3JlZGlyZWN0cz0xIiwgInV1aWQiOiAiODQzNTgwMmJiNmRhNDRhY2I5NDFjYTA4Nzg5OWExYjgifQ (@::ffff:93.184.216.34) 162.10ms
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment