[D 2024-12-21 21:36:23.976 JupyterHub reflector:390] pods watcher timeout
[D 2024-12-21 21:36:23.976 JupyterHub reflector:304] Connecting pods watcher
[D 2024-12-21 21:36:24.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.62ms
[D 2024-12-21 21:36:26.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.62ms
[I 2024-12-21 21:36:27.075 JupyterHub log:192] 302 GET / -> /hub/ (@2600:1f18:96c:2f03::2d99) 0.66ms
[D 2024-12-21 21:36:27.212 JupyterHub base:411] Refreshing auth for admin
[D 2024-12-21 21:36:27.213 JupyterHub user:496] Creating <class 'kubespawner.spawner.KubeSpawner'> for admin:
[I 2024-12-21 21:36:27.215 JupyterHub log:192] 302 GET /hub/ -> /hub/spawn (admin@2600:1f18:96c:2f03::2d99) 12.76ms
[D 2024-12-21 21:36:27.349 JupyterHub scopes:1010] Checking access to /hub/spawn via scope servers!server=admin/
[D 2024-12-21 21:36:27.349 JupyterHub pages:216] Triggering spawn with default options for admin
[D 2024-12-21 21:36:27.349 JupyterHub base:1095] Initiating spawn for admin
[D 2024-12-21 21:36:27.349 JupyterHub base:1099] 0/64 concurrent spawns
[D 2024-12-21 21:36:27.349 JupyterHub base:1104] 0 active servers
[I 2024-12-21 21:36:27.366 JupyterHub provider:661] Creating oauth client jupyterhub-user-admin
[D 2024-12-21 21:36:27.387 JupyterHub user:913] Calling Spawner.start for admin
[I 2024-12-21 21:36:27.388 JupyterHub log:192] 302 GET /hub/spawn -> /hub/spawn-pending/admin (admin@2600:1f18:96c:2f03::2d99) 43.30ms
[I 2024-12-21 21:36:27.388 JupyterHub spawner:2931] Attempting to create pvc claim-admin, with timeout 3
[I 2024-12-21 21:36:27.408 JupyterHub spawner:2947] PVC claim-admin already exists, so did not create new pvc.
[I 2024-12-21 21:36:27.409 JupyterHub spawner:2890] Attempting to create pod jupyter-admin, with timeout 3
[D 2024-12-21 21:36:27.520 JupyterHub scopes:1010] Checking access to /hub/spawn-pending/admin via scope servers!server=admin/
[I 2024-12-21 21:36:27.520 JupyterHub pages:397] admin is pending spawn
[I 2024-12-21 21:36:27.521 JupyterHub log:192] 200 GET /hub/spawn-pending/admin (admin@2600:1f18:96c:2f03::2d99) 5.23ms
[D 2024-12-21 21:36:27.690 JupyterHub log:192] 200 GET /hub/static/css/style.min.css?v=de37c927476e635f657bafc35a5e1ae9c0f3ce691c5779b0d3be84fa5e2ec3813214270a51f83fc93b945e5d7e418046af1e370e9538ea8a44d49d472ee7dffe (@2600:1f18:96c:2f03::2d99) 0.95ms
[D 2024-12-21 21:36:28.198 JupyterHub reflector:390] events watcher timeout
[D 2024-12-21 21:36:28.198 JupyterHub reflector:304] Connecting events watcher
[D 2024-12-21 21:36:28.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.92ms
[D 2024-12-21 21:36:28.292 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.96ms
[D 2024-12-21 21:36:28.376 JupyterHub log:192] 304 GET /hub/static/components/@fortawesome/fontawesome-free/webfonts/fa-solid-900.woff2 (@2600:1f18:96c:2f03::2d99) 0.59ms
[D 2024-12-21 21:36:28.388 JupyterHub scopes:1010] Checking access to /hub/api/users/admin/server/progress via scope read:servers!server=admin/
[D 2024-12-21 21:36:28.391 JupyterHub spawner:2672] progress generator: jupyter-admin
[D 2024-12-21 21:36:30.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.78ms
[D 2024-12-21 21:36:32.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.67ms
[D 2024-12-21 21:36:33.984 JupyterHub reflector:390] pods watcher timeout
[D 2024-12-21 21:36:33.984 JupyterHub reflector:304] Connecting pods watcher
[D 2024-12-21 21:36:34.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.75ms
[D 2024-12-21 21:36:36.290 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.68ms
[I 2024-12-21 21:36:36.745 JupyterHub log:192] 200 GET /hub/api (@2600:1f18:96c:2f03:ca57::f) 0.75ms
[D 2024-12-21 21:36:36.799 JupyterHub base:366] Recording first activity for <APIToken('3d26...', user='admin', client_id='jupyterhub')>
[D 2024-12-21 21:36:36.806 JupyterHub scopes:1010] Checking access to /hub/api/users/admin/activity via scope users:activity!user=admin
[D 2024-12-21 21:36:36.809 JupyterHub users:1006] Activity for user admin: 2024-12-21T21:36:36.726237Z
[D 2024-12-21 21:36:36.809 JupyterHub users:1024] Activity on server admin/: 2024-12-21T21:36:36.726237Z
[I 2024-12-21 21:36:36.816 JupyterHub log:192] 200 POST /hub/api/users/admin/activity (admin@2600:1f18:96c:2f03:ca57::f) 19.11ms
[D 2024-12-21 21:36:38.207 JupyterHub reflector:390] events watcher timeout
[D 2024-12-21 21:36:38.207 JupyterHub reflector:304] Connecting events watcher
[D 2024-12-21 21:36:38.292 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.97ms
[D 2024-12-21 21:36:38.292 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.99ms
[D 2024-12-21 21:36:40.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.66ms
[D 2024-12-21 21:36:40.359 JupyterHub spawner:3254] pod data-platform-jupyterhub/jupyter-admin events before launch: 2024-12-21T21:36:27.451297Z [Normal] Successfully assigned data-platform-jupyterhub/jupyter-admin to ip-10-82-74-182.ec2.internal
2024-12-21T21:36:29Z [Normal] AttachVolume.Attach succeeded for volume "pvc-bed29366-131e-442c-b81e-6d512557b348"
2024-12-21T21:36:34Z [Normal] Container image "quay.io/jupyterhub/k8s-network-tools:4.0.0" already present on machine
2024-12-21T21:36:34Z [Normal] Created container block-cloud-metadata
2024-12-21T21:36:34Z [Normal] Started container block-cloud-metadata
2024-12-21T21:36:35Z [Normal] Container image "quay.io/jupyterhub/k8s-singleuser-sample:4.0.0" already present on machine
2024-12-21T21:36:35Z [Normal] Created container notebook
2024-12-21T21:36:35Z [Normal] Started container notebook
[D 2024-12-21 21:36:40.366 JupyterHub spawner:1475] Polling subprocess every 30s
[D 2024-12-21 21:36:40.367 JupyterHub utils:292] Waiting 300s for server at http://[2600:1f18:96c:2f03:ca57::f]:8888/user/admin/api
[D 2024-12-21 21:36:40.372 JupyterHub utils:328] Server at http://[2600:1f18:96c:2f03:ca57::f]:8888/user/admin/api responded in 0.00s
[D 2024-12-21 21:36:40.372 JupyterHub _version:73] jupyterhub and jupyterhub-singleuser both on version 5.2.1
[I 2024-12-21 21:36:40.372 JupyterHub base:1124] User admin took 13.023 seconds to start
[I 2024-12-21 21:36:40.372 JupyterHub proxy:331] Adding user admin to proxy /user/admin/ => http://[2600:1f18:96c:2f03:ca57::f]:8888
[D 2024-12-21 21:36:40.372 JupyterHub proxy:925] Proxy: Fetching POST http://proxy-api:8001/api/routes/user/admin
[I 2024-12-21 21:36:40.377 JupyterHub users:899] Server admin is ready
[I 2024-12-21 21:36:40.378 JupyterHub log:192] 200 GET /hub/api/users/admin/server/progress?_xsrf=[secret] (admin@2600:1f18:96c:2f03::2d99) 11993.89ms
[D 2024-12-21 21:36:40.542 JupyterHub scopes:1010] Checking access to /hub/spawn-pending/admin via scope servers!server=admin/
[I 2024-12-21 21:36:40.542 JupyterHub log:192] 302 GET /hub/spawn-pending/admin -> /user/admin/ (admin@2600:1f18:96c:2f03::2d99) 4.43ms
[I 2024-12-21 21:36:40.670 JupyterHub log:192] 302 GET /user/admin/ -> /hub/user/admin/ (@2600:1f18:96c:2f03::2d99) 0.64ms
[I 2024-12-21 21:36:40.801 JupyterHub log:192] 302 GET /hub/user/admin/ -> /user/admin/?redirects=1 (admin@2600:1f18:96c:2f03::2d99) 4.08ms
[I 2024-12-21 21:36:40.930 JupyterHub log:192] 302 GET /user/admin/?redirects=1 -> /hub/user/admin/?redirects=1 (@2600:1f18:96c:2f03::2d99) 0.60ms
[W 2024-12-21 21:36:41.063 JupyterHub base:1844] Redirect loop detected on /hub/user/admin/?redirects=1
[D 2024-12-21 21:36:42.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.69ms
[I 2024-12-21 21:36:43.064 JupyterHub log:192] 302 GET /hub/user/admin/?redirects=1 -> /user/admin/?redirects=2 (admin@2600:1f18:96c:2f03::2d99) 2005.75ms
[I 2024-12-21 21:36:43.196 JupyterHub log:192] 302 GET /user/admin/?redirects=2 -> /hub/user/admin/?redirects=2 (@2600:1f18:96c:2f03::2d99) 0.61ms
[W 2024-12-21 21:36:43.329 JupyterHub base:1844] Redirect loop detected on /hub/user/admin/?redirects=2
[D 2024-12-21 21:36:43.992 JupyterHub reflector:390] pods watcher timeout
[D 2024-12-21 21:36:43.992 JupyterHub reflector:304] Connecting pods watcher
[D 2024-12-21 21:36:44.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.75ms
[D 2024-12-21 21:36:46.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.73ms
[I 2024-12-21 21:36:47.331 JupyterHub log:192] 302 GET /hub/user/admin/?redirects=2 -> /user/admin/?redirects=3 (admin@2600:1f18:96c:2f03::2d99) 4005.76ms
[I 2024-12-21 21:36:47.462 JupyterHub log:192] 302 GET /user/admin/?redirects=3 -> /hub/user/admin/?redirects=3 (@2600:1f18:96c:2f03::2d99) 0.66ms
[W 2024-12-21 21:36:47.595 JupyterHub base:1844] Redirect loop detected on /hub/user/admin/?redirects=3
[D 2024-12-21 21:36:48.215 JupyterHub reflector:390] events watcher timeout
[D 2024-12-21 21:36:48.216 JupyterHub reflector:304] Connecting events watcher
[D 2024-12-21 21:36:48.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.91ms
[D 2024-12-21 21:36:48.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 1.00ms
[D 2024-12-21 21:36:50.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.71ms
[D 2024-12-21 21:36:52.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.71ms
[D 2024-12-21 21:36:54.000 JupyterHub reflector:390] pods watcher timeout
[D 2024-12-21 21:36:54.001 JupyterHub reflector:304] Connecting pods watcher
[D 2024-12-21 21:36:54.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.64ms
[I 2024-12-21 21:36:55.596 JupyterHub log:192] 302 GET /hub/user/admin/?redirects=3 -> /user/admin/?redirects=4 (admin@2600:1f18:96c:2f03::2d99) 8005.68ms
[I 2024-12-21 21:36:55.725 JupyterHub log:192] 302 GET /user/admin/?redirects=4 -> /hub/user/admin/?redirects=4 (@2600:1f18:96c:2f03::2d99) 0.57ms
[W 2024-12-21 21:36:55.855 JupyterHub web:1873] 500 GET /hub/user/admin/?redirects=4 (2600:1f18:96c:2f03::2d99): Redirect loop detected.
[D 2024-12-21 21:36:55.855 JupyterHub base:1519] Using default error template for 500
[E 2024-12-21 21:36:55.856 JupyterHub log:184] {
"Host": "data-platform-jupyterhub.k8s.dev.303net.net",
"X-Forwarded-For": "10.99.80.110,2600:1f18:96c:2f03::2d99",
"X-Forwarded-Proto": "https",
"X-Forwarded-Port": "443",
"X-Amzn-Trace-Id": "Root=1-676734f7-0a91bc851bbcd6772dc05fa2",
"Cache-Control": "max-age=0",
"Upgrade-Insecure-Requests": "1",
"User-Agent": "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Edg/131.0.0.0",
"Accept": "text/html,application/xhtml+xml,application/xml;q=0.9,image/avif,image/webp,image/apng,*/*;q=0.8,application/signed-exchange;v=b3;q=0.7",
"Sec-Fetch-Site": "same-origin",
"Sec-Fetch-Mode": "navigate",
"Sec-Fetch-Dest": "document",
"Sec-Ch-Ua": "\"Microsoft Edge\";v=\"131\", \"Chromium\";v=\"131\", \"Not_A Brand\";v=\"24\"",
"Sec-Ch-Ua-Mobile": "?0",
"Sec-Ch-Ua-Platform": "\"macOS\"",
"Referer": "https://data-platform-jupyterhub.k8s.dev.303net.net/hub/spawn-pending/admin",
"Accept-Encoding": "gzip, deflate, br, zstd",
"Accept-Language": "en-GB,en;q=0.9,en-US;q=0.8",
"Priority": "u=0, i",
"Cookie": "jupyterhub-hub-login=[secret]; _xsrf=[secret]; apt.uid=[secret]; jupyterhub-session-id=[secret]",
"X-Envoy-External-Address": "10.99.80.110",
"X-Request-Id": "cffe3f24-9147-4ec0-8553-b12183b8c048",
"X-Envoy-Decorator-Operation": "hub.data-platform-jupyterhub.svc.cluster.local:8081/*",
"X-Envoy-Peer-Metadata-Id": "router~2600:1f18:96c:2f03:f817::~istio-ingressgateway-bb4598595-8dbj7.istio-system~istio-system.svc.cluster.local",
"X-Envoy-Peer-Metadata": "CiwKCkNMVVNURVJfSUQSHhocZW5nLWs4cy1kZXYtdXMtZWFzdC0xLXYzLWVrcwqZAQoGTEFCRUxTEo4BKosBCh0KA2FwcBIWGhRpc3Rpby1pbmdyZXNzZ2F0ZXdheQo5Ch9zZXJ2aWNlLmlzdGlvLmlvL2Nhbm9uaWNhbC1uYW1lEhYaFGlzdGlvLWluZ3Jlc3NnYXRld2F5Ci8KI3NlcnZpY2UuaXN0aW8uaW8vY2Fub25pY2FsLXJldmlzaW9uEggaBjEuMjQuMAouCgROQU1FEiYaJGlzdGlvLWluZ3Jlc3NnYXRld2F5LWJiNDU5ODU5NS04ZGJqNwobCglOQU1FU1BBQ0USDhoMaXN0aW8tc3lzdGVtCl0KBU9XTkVSElQaUmt1YmVybmV0ZXM6Ly9hcGlzL2FwcHMvdjEvbmFtZXNwYWNlcy9pc3Rpby1zeXN0ZW0vZGVwbG95bWVudHMvaXN0aW8taW5ncmVzc2dhdGV3YXkKJwoNV09SS0xPQURfTkFNRRIWGhRpc3Rpby1pbmdyZXNzZ2F0ZXdheQ==",
"X-Envoy-Attempt-Count": "1"
}
[E 2024-12-21 21:36:55.856 JupyterHub log:192] 500 GET /hub/user/admin/?redirects=4 (admin@2600:1f18:96c:2f03::2d99) 5.07ms
[D 2024-12-21 21:36:56.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.63ms
[D 2024-12-21 21:36:58.225 JupyterHub reflector:390] events watcher timeout
[D 2024-12-21 21:36:58.225 JupyterHub reflector:304] Connecting events watcher
[D 2024-12-21 21:36:58.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.93ms
[D 2024-12-21 21:36:58.292 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.94ms
[D 2024-12-21 21:37:00.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.63ms
[D 2024-12-21 21:37:02.220 JupyterHub proxy:925] Proxy: Fetching GET http://proxy-api:8001/api/routes
[D 2024-12-21 21:37:02.246 JupyterHub proxy:392] Checking routes
[D 2024-12-21 21:37:02.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.63ms
[I 2024-12-21 21:37:02.501 JupyterHub log:192] 200 GET /hub/api/ (jupyterhub-idle-culler@::1) 7.12ms
[D 2024-12-21 21:37:02.504 JupyterHub scopes:1010] Checking access to /hub/api/users via scope list:users
[I 2024-12-21 21:37:02.512 JupyterHub log:192] 200 GET /hub/api/users?state=[secret] (jupyterhub-idle-culler@::1) 8.94ms
[D 2024-12-21 21:37:04.009 JupyterHub reflector:390] pods watcher timeout
[D 2024-12-21 21:37:04.009 JupyterHub reflector:304] Connecting pods watcher
[D 2024-12-21 21:37:04.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.68ms
[D 2024-12-21 21:37:06.290 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.70ms
[D 2024-12-21 21:37:08.235 JupyterHub reflector:390] events watcher timeout
[D 2024-12-21 21:37:08.235 JupyterHub reflector:304] Connecting events watcher
[D 2024-12-21 21:37:08.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.85ms
[D 2024-12-21 21:37:08.292 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.92ms
[D 2024-12-21 21:37:10.291 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.76ms
[D 2024-12-21 21:37:11.310 JupyterHub log:192] 304 GET /hub/home (admin@2600:1f18:96c:2f03::2d99) 10.23ms
[D 2024-12-21 21:37:12.292 JupyterHub log:192] 200 GET /hub/health (@2600:1f18:96c:2f04::4ca0) 0.72ms
[D 2024-12-21 21:37:12.469 JupyterHub scopes:1010] Checking access to /hub/api/users/admin/server via scope delete:servers!server=admin/
[I 2024-12-21 21:37:12.472 JupyterHub proxy:356] Removing user admin from proxy (/user/admin/)
[D 2024-12-21 21:37:12.472 JupyterHub proxy:925] Proxy: Fetching DELETE http://proxy-api:8001/api/routes/user/admin
[D 2024-12-21 21:37:12.476 JupyterHub user:1100] Stopping admin
[I 2024-12-21 21:37:12.476 JupyterHub spawner:3282] Deleting pod data-platform-jupyterhub/jupyter-admin
[D 2024-12-21 21:37:13.991 JupyterHub user:1122] Deleting oauth client jupyterhub-user-admin
[D 2024-12-21 21:37:13.998 JupyterHub user:1125] Finished stopping admin
[I 2024-12-21 21:37:14.005 JupyterHub base:1333] User admin server took 1.533 seconds to stop
[I 2024-12-21 21:37:14.006 JupyterHub log:192] 204 DELETE /hub/api/users/admin/server?_xsrf=[secret] (admin@2600:1f18:96c:2f03::2d99) 1540.45ms
[D 2024-12-21 21:37:14.018 JupyterHub reflector:390] pods watcher timeout```