Part 2 - JupyterHubLog
[D 2022-02-28 15:18:25.663 JupyterHub Spawner:273] ecs:DescribeTasks = {'tasks': [{'attachments': [], 'attributes': [{'name': 'ecs.cpu-architecture', 'value': 'x86_64'}], 'availabilityZone': 'ap-southeast-2b','clusterArn': 'arn:aws:ecs:ap-southeast-2:035973170720:cluster/dap-bdc-nonprod-dev-01-datalab-ECSCluster', 'connectivity': 'CONNECTED', 'connectivityAt': datetime.datetime(2022, 2, 28, 15, 18, 21, 490000, tzinfo=tzlocal()), 'containerInstanceArn': 'arn:aws:ecs:ap-southeast-2:035973170720:container-instance/dap-bdc-nonprod-dev-01-datalab-ECSCluster/f027356e1ba74be1a68809cb009ad713', 'containers': [{'containerArn': 'arn:aws:ecs:ap-southeast-2:035973170720:container/dap-bdc-nonprod-dev-01-datalab-ECSCluster/173200683d784b5885cf5b5af32abb99/4f09ff3e-7855-43be-9ed4-08a70dc692aa', 'taskArn': 'arn:aws:ecs:ap-southeast-2:035973170720:task/dap-bdc-nonprod-dev-01-datalab-ECSCluster/173200683d784b5885cf5b5af32abb99', 'name': 'jupyternotebook', 'image': '035973170720.dkr.ecr.ap-southeast-2.amazonaws.com/dap-bdc-nonprod-dev-01-datalab-ecr:0.87', 'imageDigest': 'sha256:7afde9c9aa82c7083699c060b35da8a793db55d2808c30cf2b9955b60d531aff', 'runtimeId': '19eee3a87f382be993d265584060cdfafc5975b2398c8c69a8b06201b0ff98cc', 'lastStatus': 'RUNNING', 'networkBindings': [{'bindIP': '0.0.0.0', 'containerPort': 8888, 'hostPort': 49240, 'protocol': 'tcp'}], 'networkInterfaces': [], 'healthStatus': 'UNKNOWN', 'cpu': '0'}], 'cpu': '1024', 'createdAt': datetime.datetime(2022, 2, 28, 15, 18, 21, 490000, tzinfo=tzlocal()), 'desiredStatus': 'RUNNING', 'enableExecuteCommand': False, 'group': 'family:JupyterNotebook', 'healthStatus': 'UNKNOWN', 'lastStatus': 'RUNNING', 'launchType':'EC2', 'memory': '512', 'overrides': {'containerOverrides': [{'name': 'jupyternotebook', 'environment': [{'name': 'PATH', 'value': '/sbin:/bin:/usr/sbin:/usr/bin:/usr/local/bin:/usr/local/bin'}, {'name': 'LANG', 'value': 'en_US.UTF-8'}, {'name': 'JUPYTERHUB_API_TOKEN', 'value': 'e69d40ff8b124da19c8e2365b10485c0'}, {'name': 'JPY_API_TOKEN', 'value': 'e69d40ff8b124da19c8e2365b10485c0'}, {'name': 'JUPYTERHUB_ADMIN_ACCESS', 'value': '1'}, {'name': 'JUPYTERHUB_CLIENT_ID', 'value': 'jupyterhub-user-jovyan-meena'}, {'name': 'JUPYTERHUB_HOST', 'value': ''}, {'name': 'JUPYTERHUB_OAUTH_CALLBACK_URL', 'value': '/user/jovyan/meena/oauth_callback'}, {'name': 'JUPYTERHUB_OAUTH_SCOPES', 'value': '["access:servers!server=jovyan/meena", "access:servers!user=jovyan"]'}, {'name': 'JUPYTERHUB_USER', 'value': 'jovyan'}, {'name': 'JUPYTERHUB_SERVER_NAME', 'value': 'meena'}, {'name': 'JUPYTERHUB_API_URL', 'value': 'http://10.86.124.187:8081/hub/api'}, {'name': 'JUPYTERHUB_ACTIVITY_URL', 'value': 'http://10.86.124.187:8081/hub/api/users/jovyan/activity'}, {'name': 'JUPYTERHUB_BASE_URL', 'value': '/user/jovyan/meena/'}, {'name': 'JUPYTERHUB_SERVICE_PREFIX', 'value': 'http://10.86.124.187:8081/'}, {'name': 'JUPYTERHUB_SERVICE_URL', 'value': 'http://:10.86.124.187:8081/user/jovyan/meena/'}, {'name': 'JUPYTERHUB_ROOT_DIR', 'value': '/home/jovyan'}, {'name': 'JUPYTERHUB_DEBUG', 'value': '1'}]}], 'inferenceAcceleratorOverrides': []}, 'startedAt': datetime.datetime(2022, 2, 28, 15, 18, 24, 771000, tzinfo=tzlocal()), 'startedBy': 'jovyan', 'tags': [], 'taskArn': 'arn:aws:ecs:ap-southeast-2:035973170720:task/dap-bdc-nonprod-dev-01-datalab-ECSCluster/173200683d784b5885cf5b5af32abb99','taskDefinitionArn': 'arn:aws:ecs:ap-southeast-2:035973170720:task-definition/JupyterNotebook:87', 'version': 2}], 'failures': [], 'ResponseMetadata': {'RequestId': '5ab0c01f-e11c-48a2-a05a-a7fc21eee271', 'HTTPStatusCode': 200, 'HTTPHeaders': {'x-amzn-requestid': '5ab0c01f-e11c-48a2-a05a-a7fc21eee271', 'content-type': 'application/x-amz-json-1.1', 'content-length': '3107', 'date': 'Mon, 28 Feb 2022 02:18:25 GMT'}, 'RetryAttempts': 0}}
[D 2022-02-28 15:18:25.663 JupyterHub Spawner:291] ecs:DescribeTasks = {'tasks': [{'attachments': [], 'attributes': [{'name': 'ecs.cpu-architecture', 'value': 'x86_64'}], 'availabilityZone': 'ap-southeast-2b','clusterArn': 'arn:aws:ecs:ap-southeast-2:035973170720:cluster/dap-bdc-nonprod-dev-01-datalab-ECSCluster', 'connectivity': 'CONNECTED', 'connectivityAt': datetime.datetime(2022, 2, 28, 15, 18, 21, 490000, tzinfo=tzlocal()), 'containerInstanceArn': 'arn:aws:ecs:ap-southeast-2:035973170720:container-instance/dap-bdc-nonprod-dev-01-datalab-ECSCluster/f027356e1ba74be1a68809cb009ad713', 'containers': [{'containerArn': 'arn:aws:ecs:ap-southeast-2:035973170720:container/dap-bdc-nonprod-dev-01-datalab-ECSCluster/173200683d784b5885cf5b5af32abb99/4f09ff3e-7855-43be-9ed4-08a70dc692aa', 'taskArn': 'arn:aws:ecs:ap-southeast-2:035973170720:task/dap-bdc-nonprod-dev-01-datalab-ECSCluster/173200683d784b5885cf5b5af32abb99', 'name': 'jupyternotebook', 'image': '035973170720.dkr.ecr.ap-southeast-2.amazonaws.com/dap-bdc-nonprod-dev-01-datalab-ecr:0.87', 'imageDigest': 'sha256:7afde9c9aa82c7083699c060b35da8a793db55d2808c30cf2b9955b60d531aff', 'runtimeId': '19eee3a87f382be993d265584060cdfafc5975b2398c8c69a8b06201b0ff98cc', 'lastStatus': 'RUNNING', 'networkBindings': [{'bindIP': '0.0.0.0', 'containerPort': 8888, 'hostPort': 49240, 'protocol': 'tcp'}], 'networkInterfaces': [], 'healthStatus': 'UNKNOWN', 'cpu': '0'}], 'cpu': '1024', 'createdAt': datetime.datetime(2022, 2, 28, 15, 18, 21, 490000, tzinfo=tzlocal()), 'desiredStatus': 'RUNNING', 'enableExecuteCommand': False, 'group': 'family:JupyterNotebook', 'healthStatus': 'UNKNOWN', 'lastStatus': 'RUNNING', 'launchType':'EC2', 'memory': '512', 'overrides': {'containerOverrides': [{'name': 'jupyternotebook', 'environment': [{'name': 'PATH', 'value': '/sbin:/bin:/usr/sbin:/usr/bin:/usr/local/bin:/usr/local/bin'}, {'name': 'LANG', 'value': 'en_US.UTF-8'}, {'name': 'JUPYTERHUB_API_TOKEN', 'value': 'e69d40ff8b124da19c8e2365b10485c0'}, {'name': 'JPY_API_TOKEN', 'value': 'e69d40ff8b124da19c8e2365b10485c0'}, {'name': 'JUPYTERHUB_ADMIN_ACCESS', 'value': '1'}, {'name': 'JUPYTERHUB_CLIENT_ID', 'value': 'jupyterhub-user-jovyan-meena'}, {'name': 'JUPYTERHUB_HOST', 'value': ''}, {'name': 'JUPYTERHUB_OAUTH_CALLBACK_URL', 'value': '/user/jovyan/meena/oauth_callback'}, {'name': 'JUPYTERHUB_OAUTH_SCOPES', 'value': '["access:servers!server=jovyan/meena", "access:servers!user=jovyan"]'}, {'name': 'JUPYTERHUB_USER', 'value': 'jovyan'}, {'name': 'JUPYTERHUB_SERVER_NAME', 'value': 'meena'}, {'name': 'JUPYTERHUB_API_URL', 'value': 'http://10.86.124.187:8081/hub/api'}, {'name': 'JUPYTERHUB_ACTIVITY_URL', 'value': 'http://10.86.124.187:8081/hub/api/users/jovyan/activity'}, {'name': 'JUPYTERHUB_BASE_URL', 'value': '/user/jovyan/meena/'}, {'name': 'JUPYTERHUB_SERVICE_PREFIX', 'value': 'http://10.86.124.187:8081/'}, {'name': 'JUPYTERHUB_SERVICE_URL', 'value': 'http://:10.86.124.187:8081/user/jovyan/meena/'}, {'name': 'JUPYTERHUB_ROOT_DIR', 'value': '/home/jovyan'}, {'name': 'JUPYTERHUB_DEBUG', 'value': '1'}]}], 'inferenceAcceleratorOverrides': []}, 'startedAt': datetime.datetime(2022, 2, 28, 15, 18, 24, 771000, tzinfo=tzlocal()), 'startedBy': 'jovyan', 'tags': [], 'taskArn': 'arn:aws:ecs:ap-southeast-2:035973170720:task/dap-bdc-nonprod-dev-01-datalab-ECSCluster/173200683d784b5885cf5b5af32abb99','taskDefinitionArn': 'arn:aws:ecs:ap-southeast-2:035973170720:task-definition/JupyterNotebook:87', 'version': 2}], 'failures': [], 'ResponseMetadata': {'RequestId': '5ab0c01f-e11c-48a2-a05a-a7fc21eee271', 'HTTPStatusCode': 200, 'HTTPHeaders': {'x-amzn-requestid': '5ab0c01f-e11c-48a2-a05a-a7fc21eee271', 'content-type': 'application/x-amz-json-1.1', 'content-length': '3107', 'date': 'Mon, 28 Feb 2022 02:18:25 GMT'}, 'RetryAttempts': 0}}
[I 2022-02-28 15:18:25.663 JupyterHub Spawner:301] Container running on port 49240
[D 2022-02-28 15:18:25.686 JupyterHub Spawner:307] ecs:DescribeContainerInstances = {'containerInstances': [{'containerInstanceArn': 'arn:aws:ecs:ap-southeast-2:035973170720:container-instance/dap-bdc-nonprod-dev-01-datalab-ECSCluster/f027356e1ba74be1a68809cb009ad713', 'ec2InstanceId': 'i-093a2a7c7ad9ecf69', 'version': 11408, 'versionInfo': {'agentVersion': '1.57.1', 'agentHash': '089b7b64', 'dockerVersion': 'DockerVersion: 20.10.12'}, 'remainingResources': [{'name': 'CPU', 'type': 'INTEGER', 'doubleValue': 0.0, 'longValue': 0, 'integerValue': 3072}, {'name': 'MEMORY', 'type': 'INTEGER', 'doubleValue': 0.0, 'longValue': 0, 'integerValue': 30523}, {'name': 'PORTS', 'type': 'STRINGSET', 'doubleValue': 0.0, 'longValue': 0, 'integerValue': 0, 'stringSetValue': ['22', '2376', '2375', '51678', '51679']}, {'name': 'PORTS_UDP', 'type':'STRINGSET', 'doubleValue': 0.0, 'longValue': 0, 'integerValue': 0, 'stringSetValue': []}], 'registeredResources': [{'name': 'CPU', 'type': 'INTEGER', 'doubleValue': 0.0, 'longValue': 0, 'integerValue': 4096},{'name': 'MEMORY', 'type': 'INTEGER', 'doubleValue': 0.0, 'longValue': 0, 'integerValue': 31547}, {'name': 'PORTS', 'type': 'STRINGSET', 'doubleValue': 0.0, 'longValue': 0, 'integerValue': 0, 'stringSetValue':['22', '2376', '2375', '51678', '51679']}, {'name': 'PORTS_UDP', 'type': 'STRINGSET', 'doubleValue': 0.0, 'longValue': 0, 'integerValue': 0, 'stringSetValue': []}], 'status': 'ACTIVE', 'agentConnected': True, 'runningTasksCount': 2, 'pendingTasksCount': 0, 'attributes': [{'name': 'ecs.capability.secrets.asm.environment-variables'}, {'name': 'ecs.capability.branch-cni-plugin-version', 'value': '199bfc65-'}, {'name': 'ecs.ami-id', 'value': 'ami-0885384fdf0a73e7e'}, {'name': 'ecs.capability.secrets.asm.bootstrap.log-driver'}, {'name': 'ecs.capability.task-eia.optimized-cpu'}, {'name': 'ecs.capability.ecr-endpoint'}, {'name':'ecs.capability.docker-plugin.local'}, {'name': 'ecs.capability.task-cpu-mem-limit'}, {'name': 'ecs.capability.secrets.ssm.bootstrap.log-driver'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.30'}, {'name': 'ecs.capability.full-sync'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.31'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.32'}, {'name': 'ecs.capability.firelens.options.config.file'}, {'name': 'ecs.capability.logging-driver.awsfirelens.log-driver-buffer-limit'}, {'name': 'ecs.availability-zone', 'value': 'ap-southeast-2b'}, {'name': 'ecs.capability.aws-appmesh'}, {'name': 'com.amazonaws.ecs.capability.logging-driver.awslogs'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.24'}, {'name': 'ecs.capability.task-eni-trunking'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.25'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.26'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.27'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.28'}, {'name': 'com.amazonaws.ecs.capability.privileged-container'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.29'}, {'name': 'ecs.cpu-architecture', 'value': 'x86_64'}, {'name': 'com.amazonaws.ecs.capability.ecr-auth'}, {'name': 'ecs.capability.firelens.fluentbit'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.20'}, {'name': 'ecs.os-type', 'value': 'linux'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.21'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.22'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.23'}, {'name': 'ecs.capability.private-registry-authentication.secretsmanager'}, {'name': 'ecs.capability.task-eia'}, {'name': 'com.amazonaws.ecs.capability.logging-driver.awsfirelens'}, {'name': 'ecs.capability.firelens.options.config.s3'}, {'name': 'com.amazonaws.ecs.capability.logging-driver.json-file'}, {'name': 'ecs.capability.execution-role-awslogs'}, {'name': 'ecs.vpc-id', 'value': 'vpc-0aa2edd2d3c8607ba'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.17'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.18'}, {'name': 'com.amazonaws.ecs.capability.docker-remote-api.1.19'}, {'name': 'ecs.capability.task-eni'}, {'name': 'ecs.capability.firelens.fluentd'}, {'name': 'ecs.capability.efs'}, {'name': 'ecs.capability.execution-role-ecr-pull'}, {'name': 'ecs.capability.task-eni.ipv6'}, {'name': 'ecs.capability.container-health-check'}, {'name': 'ecs.os-family', 'value': 'LINUX'}, {'name': 'ecs.subnet-id', 'value': 'subnet-0de64cea50664c480'}, {'name': 'ecs.instance-type', 'value': 'r5.xlarge'}, {'name': 'com.amazonaws.ecs.capability.task-iam-role-network-host'}, {'name': 'ecs.capability.container-ordering'}, {'name': 'ecs.capability.cni-plugin-version', 'value': '55b2ae77-2020.09.0'}, {'name': 'ecs.capability.env-files.s3'}, {'name': 'ecs.capability.secrets.ssm.environment-variables'}, {'name': 'ecs.capability.pid-ipc-namespace-sharing'}, {'name': 'com.amazonaws.ecs.capability.task-iam-role'}], 'registeredAt': datetime.datetime(2021, 12, 16, 16, 15, 43, 183000, tzinfo=tzlocal()), 'attachments': [], 'tags': []}], 'failures': [], 'ResponseMetadata': {'RequestId': '688c350d-467c-46c0-979f-849b9dcf726f', 'HTTPStatusCode': 200, 'HTTPHeaders': {'x-amzn-requestid': '688c350d-467c-46c0-979f-849b9dcf726f', 'content-type': 'application/x-amz-json-1.1', 'content-length': '4693', 'date': 'Mon, 28 Feb 2022 02:18:25 GMT'}, 'RetryAttempts': 0}}
[I 2022-02-28 15:18:25.686 JupyterHub Spawner:314] Fetching IP addresses of instance: i-093a2a7c7ad9ecf69
[D 2022-02-28 15:18:25.803 JupyterHub Spawner:316] ec2:DescribeInstances = {'Reservations': [{'Groups': [], 'Instances': [{'AmiLaunchIndex': 0, 'ImageId': 'ami-0885384fdf0a73e7e', 'InstanceId': 'i-093a2a7c7ad9ecf69', 'InstanceType': 'r5.xlarge', 'LaunchTime': datetime.datetime(2021, 12, 16, 3, 15, 6, tzinfo=tzutc()), 'Monitoring': {'State': 'enabled'}, 'Placement': {'AvailabilityZone': 'ap-southeast-2b', 'GroupName': '', 'Tenancy': 'default'}, 'PrivateDnsName': 'ip-10-86-124-110.ap-southeast-2.compute.internal', 'PrivateIpAddress': '10.86.124.110', 'ProductCodes': [], 'PublicDnsName': '', 'State': {'Code': 16, 'Name': 'running'}, 'StateTransitionReason': '', 'SubnetId': 'subnet-0de64cea50664c480', 'VpcId': 'vpc-0aa2edd2d3c8607ba', 'Architecture': 'x86_64', 'BlockDeviceMappings': [{'DeviceName': '/dev/sda1', 'Ebs': {'AttachTime': datetime.datetime(2021, 12, 16, 3, 15, 6, tzinfo=tzutc()), 'DeleteOnTermination': True, 'Status': 'attached', 'VolumeId': 'vol-08ba6aef8ef0ee97d'}}, {'DeviceName': '/dev/xvda', 'Ebs': {'AttachTime': datetime.datetime(2021, 12, 16, 3, 15, 6, tzinfo=tzutc()), 'DeleteOnTermination': True, 'Status': 'attached', 'VolumeId': 'vol-096c2ef57d1de8dfa'}}], 'ClientToken': '5b45f704-f1d0-f526-0696-22668b636e4e', 'EbsOptimized': False, 'EnaSupport': True, 'Hypervisor': 'xen', 'IamInstanceProfile': {'Arn': 'arn:aws:iam::035973170720:instance-profile/dap-bdc-nonprod-dev-01-datalab-ECSClusterRoleInstanceProfile', 'Id': 'AIPAQQYBLAYQCZAKREBD3'}, 'NetworkInterfaces': [{'Attachment': {'AttachTime': datetime.datetime(2021, 12, 16, 3, 15, 6, tzinfo=tzutc()), 'AttachmentId': 'eni-attach-03f1c8cfce11e4143', 'DeleteOnTermination': True, 'DeviceIndex': 0, 'Status': 'attached', 'NetworkCardIndex': 0}, 'Description': '', 'Groups': [{'GroupName': 'VPC Endpoints', 'GroupId': 'sg-01561680b48a23c28'}, {'GroupName': 'Core Services', 'GroupId': 'sg-057423a4fadfb55a0'}, {'GroupName': 'dap-bdc-nonprod-dev-01-datalab-ECSClusterSG', 'GroupId': 'sg-0dd11436fa3d3f4ef'}], 'Ipv6Addresses': [], 'MacAddress': '06:a8:e4:0a:81:72', 'NetworkInterfaceId': 'eni-0b636bbfd6f84ab86', 'OwnerId': '035973170720', 'PrivateDnsName': 'ip-10-86-124-110.ap-southeast-2.compute.internal', 'PrivateIpAddress': '10.86.124.110', 'PrivateIpAddresses': [{'Primary': True, 'PrivateDnsName': 'ip-10-86-124-110.ap-southeast-2.compute.internal', 'PrivateIpAddress': '10.86.124.110'}], 'SourceDestCheck': True, 'Status': 'in-use', 'SubnetId': 'subnet-0de64cea50664c480', 'VpcId': 'vpc-0aa2edd2d3c8607ba', 'InterfaceType': 'interface'}], 'RootDeviceName': '/dev/sda1', 'RootDeviceType': 'ebs', 'SecurityGroups': [{'GroupName': 'VPC Endpoints', 'GroupId': 'sg-01561680b48a23c28'}, {'GroupName': 'Core Services', 'GroupId': 'sg-057423a4fadfb55a0'}, {'GroupName': 'dap-bdc-nonprod-dev-01-datalab-ECSClusterSG', 'GroupId': 'sg-0dd11436fa3d3f4ef'}], 'SourceDestCheck': True, 'Tags': [{'Key': 'backup_policy', 'Value': 'NONE'}, {'Key': 'aws:autoscaling:groupName', 'Value': 'dap-bdc-nonprod-dev-01-datalab-ECSLaunchConfig20211216031457370000000001'}], 'VirtualizationType': 'hvm', 'CpuOptions': {'CoreCount': 2, 'ThreadsPerCore': 2}, 'CapacityReservationSpecification': {'CapacityReservationPreference': 'open'}, 'HibernationOptions': {'Configured': False}, 'MetadataOptions': {'State': 'applied', 'HttpTokens': 'optional', 'HttpPutResponseHopLimit': 1, 'HttpEndpoint': 'enabled', 'HttpProtocolIpv6': 'disabled', 'InstanceMetadataTags': 'disabled'}, 'EnclaveOptions': {'Enabled': False}, 'PlatformDetails': 'Red Hat Enterprise Linux', 'UsageOperation': 'RunInstances:0010', 'UsageOperationUpdateTime': datetime.datetime(2021, 12, 16, 3, 15, 6, tzinfo=tzutc()), 'PrivateDnsNameOptions': {'HostnameType': 'ip-name', 'EnableResourceNameDnsARecord': False, 'EnableResourceNameDnsAAAARecord': False}}], 'OwnerId': '035973170720', 'RequesterId': '081202882002', 'ReservationId': 'r-05a7d81b147035c7b'}], 'ResponseMetadata': {'RequestId': '3180879b-84e1-4e60-b587-5345d94a49e3', 'HTTPStatusCode': 200, 'HTTPHeaders': {'x-amzn-requestid': '3180879b-84e1-4e60-b587-5345d94a49e3', 'cache-control': 'no-cache, no-store', 'strict-transport-security': 'max-age=31536000; includeSubDomains', 'vary': 'accept-encoding', 'content-type': 'text/xml;charset=UTF-8', 'transfer-encoding': 'chunked', 'date': 'Mon, 28 Feb 2022 02:18:24 GMT', 'server': 'AmazonEC2'}, 'RetryAttempts': 0}}
[I 2022-02-28 15:18:25.803 JupyterHub Spawner:325] Container running on ip: 10.86.124.110
[I 2022-02-28 15:18:25.803 JupyterHub Spawner:327] Finished with the start method
[D 2022-02-28 15:18:25.807 JupyterHub spawner:1221] Polling subprocess every 300s
[I 2022-02-28 15:18:26.274 JupyterHub log:189] 200 GET /hub/api (@10.86.124.110) 0.74ms
[D 2022-02-28 15:18:26.286 JupyterHub base:281] Recording first activity for <APIToken('e69d...', user='jovyan', client_id='jupyterhub')>
[D 2022-02-28 15:18:26.291 JupyterHub scopes:301] Authenticated with token <APIToken('e69d...', user='jovyan', client_id='jupyterhub')>
[D 2022-02-28 15:18:26.294 JupyterHub scopes:491] Checking access via scope users:activity
[D 2022-02-28 15:18:26.294 JupyterHub scopes:402] Argument-based access to /hub/api/users/jovyan/activity via users:activity
[D 2022-02-28 15:18:26.295 JupyterHub users:855] Activity for user jovyan: 2022-02-28T02:18:26.013604Z
[D 2022-02-28 15:18:26.295 JupyterHub users:873] Activity on server jovyan/meena: 2022-02-28T02:18:26.013604Z
[I 2022-02-28 15:18:26.300 JupyterHub log:189] 200 POST /hub/api/users/jovyan/activity (jovyan@10.86.124.110) 18.20ms
[D 2022-02-28 15:18:27.618 JupyterHub utils:230] Server at http://10.86.124.110:49240/user/jovyan/meena/ responded with 302
[D 2022-02-28 15:18:27.618 JupyterHub _version:74] jupyterhub and jupyterhub-singleuser both on version 2.1.1
[I 2022-02-28 15:18:27.618 JupyterHub base:944] User jovyan:meena took 6.293 seconds to start
[I 2022-02-28 15:18:27.618 JupyterHub proxy:286] Adding user jovyan to proxy /user/jovyan/meena/ => http://10.86.124.110:49240
[D 2022-02-28 15:18:27.619 JupyterHub proxy:821] Proxy: Fetching POST http://127.0.0.1:8001/api/routes/user/jovyan/meena
15:18:27.620 [ConfigProxy] info: Adding route /user/jovyan/meena -> http://10.86.124.110:49240
15:18:27.621 [ConfigProxy] info: Route added /user/jovyan/meena -> http://10.86.124.110:49240
15:18:27.621 [ConfigProxy] info: 201 POST /api/routes/user/jovyan/meena
[I 2022-02-28 15:18:27.622 JupyterHub users:749] Server jovyan:meena is ready
[I 2022-02-28 15:18:27.622 JupyterHub log:189] 200 GET /hub/api/users/jovyan/servers/meena/progress (jovyan@::ffff:10.86.124.143) 4439.32ms
[D 2022-02-28 15:18:28.007 JupyterHub scopes:491] Checking access via scope servers
[D 2022-02-28 15:18:28.007 JupyterHub scopes:389] Unrestricted access to /hub/spawn-pending/jovyan/meena via servers
[I 2022-02-28 15:18:28.008 JupyterHub log:189] 302 GET /hub/spawn-pending/jovyan/meena -> /user/jovyan/meena (jovyan@::ffff:10.86.124.143) 3.53ms
[I 2022-02-28 15:18:29.759 JupyterHub log:189] 302 GET /api/oauth2/authorize?client_id=jupyterhub-user-jovyan-meena&redirect_uri=%2Fuser%2Fjovyan%2Fmeena%2Foauth_callback&response_type=code&state=[secret] -> /hub/api/oauth2/authorize?client_id=jupyterhub-user-jovyan-meena&redirect_uri=%2Fuser%2Fjovyan%2Fmeena%2Foauth_callback&response_type=code&state=[secret] (@10.67.118.148) 1.00ms
[W 2022-02-28 15:18:30.098 JupyterHub base:393] Invalid or expired cookie token
[I 2022-02-28 15:18:30.099 JupyterHub log:189] 302 GET /hub/api/oauth2/authorize?client_id=jupyterhub-user-jovyan-meena&redirect_uri=%2Fuser%2Fjovyan%2Fmeena%2Foauth_callback&response_type=code&state=[secret] -> /hub/login?next=%2Fhub%2Fapi%2Foauth2%2Fauthorize%3Fclient_id%3Djupyterhub-user-jovyan-meena%26redirect_uri%3D%252Fuser%252Fjovyan%252Fmeena%252Foauth_callback%26response_type%3Dcode%26state%3DeyJ1dWlkIjogImIyOGU1Zjg0MDZmYzRmZWU5NjVjNjllZmVkNmNmZDUwIiwgIm5leHRfdXJsIjogIi91c2VyL2pvdnlhbi9tZWVuYS90cmVlLz8ifQ (@10.67.118.148) 1.33ms
[I 2022-02-28 15:18:30.716 JupyterHub log:189] 200 GET /hub/login?next=%2Fhub%2Fapi%2Foauth2%2Fauthorize%3Fclient_id%3Djupyterhub-user-jovyan-meena%26redirect_uri%3D%252Fuser%252Fjovyan%252Fmeena%252Foauth_callback%26response_type%3Dcode%26state%3DeyJ1dWlkIjogImIyOGU1Zjg0MDZmYzRmZWU5NjVjNjllZmVkNmNmZDUwIiwgIm5leHRfdXJsIjogIi91c2VyL2pvdnlhbi9tZWVuYS90cmVlLz8ifQ (@10.67.118.148) 2.04ms
[D 2022-02-28 15:18:31.530 JupyterHub log:189] 304 GET /hub/logo (@10.67.118.148) 0.72ms
[I 2022-02-28 15:18:32.625 JupyterHub log:189] 200 GET /hub/login (@::ffff:10.86.124.92) 1.94ms
[I 2022-02-28 15:18:46.003 JupyterHub log:189] 200 GET /hub/login (@::ffff:10.86.124.143) 1.91ms
[I 2022-02-28 15:19:02.642 JupyterHub log:189] 200 GET /hub/login (@::ffff:10.86.124.92) 1.90ms
[I 2022-02-28 15:19:16.032 JupyterHub log:189] 200 GET /hub/login (@::ffff:10.86.124.143) 2.08ms
[I 2022-02-28 15:19:32.673 JupyterHub log:189] 200 GET /hub/login (@::ffff:10.86.124.92) 1.88ms
[D 2022-02-28 15:19:41.789 JupyterHub roles:450] Assigning default role to User jovyan
[D 2022-02-28 15:19:41.793 JupyterHub base:559] Setting cookie jupyterhub-session-id: {'httponly': True, 'path': '/'}
[D 2022-02-28 15:19:41.794 JupyterHub base:563] Setting cookie for jovyan: jupyterhub-hub-login
[D 2022-02-28 15:19:41.794 JupyterHub base:559] Setting cookie jupyterhub-hub-login: {'httponly': True, 'path': '/hub/'}
[I 2022-02-28 15:19:41.794 JupyterHub base:797] User logged in: jovyan
[I 2022-02-28 15:19:41.795 JupyterHub log:189] 302 POST /hub/login?next=%2Fhub%2Fapi%2Foauth2%2Fauthorize%3Fclient_id%3Djupyterhub-user-jovyan-meena%26redirect_uri%3D%252Fuser%252Fjovyan%252Fmeena%252Foauth_callback%26response_type%3Dcode%26state%3DeyJ1dWlkIjogImIyOGU1Zjg0MDZmYzRmZWU5NjVjNjllZmVkNmNmZDUwIiwgIm5leHRfdXJsIjogIi91c2VyL2pvdnlhbi9tZWVuYS90cmVlLz8ifQ -> /hub/api/oauth2/authorize?client_id=jupyterhub-user-jovyan-meena&redirect_uri=%2Fuser%2Fjovyan%2Fmeena%2Foauth_callback&response_type=code&state=[secret] (jovyan@10.67.118.148) 30.50ms
[D 2022-02-28 15:19:42.162 JupyterHub provider:411] Validating client id jupyterhub-user-jovyan-meena
[D 2022-02-28 15:19:42.165 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:363] Validating redirection uri /user/jovyan/meena/oauth_callback for client jupyterhub-user-jovyan-meena.
[D 2022-02-28 15:19:42.165 oauthlib.oauth2.rfc6749.grant_types.base base:230] Using provided redirect_uri /user/jovyan/meena/oauth_callback
[D 2022-02-28 15:19:42.165 JupyterHub provider:486] validate_redirect_uri: client_id=jupyterhub-user-jovyan-meena, redirect_uri=/user/jovyan/meena/oauth_callback
[D 2022-02-28 15:19:42.166 oauthlib.oauth2.rfc6749.grant_types.base base:171] Validating access to scopes [] for client 'jupyterhub-user-jovyan-meena' (<OAuthClient(identifier='jupyterhub-user-jovyan-meena')>).
[D 2022-02-28 15:19:42.167 JupyterHub provider:570] Allowing request for role(s) for jupyterhub-user-jovyan-meena: []
[D 2022-02-28 15:19:42.168 JupyterHub auth:287] Skipping oauth confirmation for <User(jovyan 1/2 running)> accessing Server at /user/jovyan/meena/
[D 2022-02-28 15:19:42.168 oauthlib.oauth2.rfc6749.endpoints.authorization authorization:98] Dispatching response_type code request to <oauthlib.oauth2.rfc6749.grant_types.authorization_code.AuthorizationCodeGrant object at 0x7f7c56ecaeb0>.
[D 2022-02-28 15:19:42.168 JupyterHub provider:411] Validating client id jupyterhub-user-jovyan-meena
[D 2022-02-28 15:19:42.169 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:363] Validating redirection uri /user/jovyan/meena/oauth_callback for client jupyterhub-user-jovyan-meena.
[D 2022-02-28 15:19:42.169 oauthlib.oauth2.rfc6749.grant_types.base base:230] Using provided redirect_uri /user/jovyan/meena/oauth_callback
[D 2022-02-28 15:19:42.169 JupyterHub provider:486] validate_redirect_uri: client_id=jupyterhub-user-jovyan-meena, redirect_uri=/user/jovyan/meena/oauth_callback
[D 2022-02-28 15:19:42.170 oauthlib.oauth2.rfc6749.grant_types.base base:171] Validating access to scopes [] for client 'jupyterhub-user-jovyan-meena' (<OAuthClient(identifier='jupyterhub-user-jovyan-meena')>).
[D 2022-02-28 15:19:42.171 JupyterHub provider:570] Allowing request for role(s) for jupyterhub-user-jovyan-meena: []
[D 2022-02-28 15:19:42.171 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:246] Pre resource owner authorization validation ok for <oauthlib.Request SANITIZED>.
[D 2022-02-28 15:19:42.171 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:171] Created authorization code grant {'code': 'Ftqp1sWG75oILvsr7Qd00q5OEXe8cf', 'state': 'eyJ1dWlkIjogImIyOGU1Zjg0MDZmYzRmZWU5NjVjNjllZmVkNmNmZDUwIiwgIm5leHRfdXJsIjogIi91c2VyL2pvdnlhbi9tZWVuYS90cmVlLz8ifQ'} for request <oauthlib.Request SANITIZED>.
[D 2022-02-28 15:19:42.171 oauthlib.oauth2.rfc6749.grant_types.authorization_code authorization_code:278] Saving grant {'code': 'Ftqp1sWG75oILvsr7Qd00q5OEXe8cf', 'state': 'eyJ1dWlkIjogImIyOGU1Zjg0MDZmYzRmZWU5NjVjNjllZmVkNmNmZDUwIiwgIm5leHRfdXJsIjogIi91c2VyL2pvdnlhbi9tZWVuYS90cmVlLz8ifQ'} for <oauthlib.Request SANITIZED>.
[D 2022-02-28 15:19:42.171 JupyterHub provider:237] Saving authorization code jupyterhub-user-jovyan-meena, Ftq..., (), {}
[I 2022-02-28 15:19:42.179 JupyterHub log:189] 302 GET /hub/api/oauth2/authorize?client_id=jupyterhub-user-jovyan-meena&redirect_uri=%2Fuser%2Fjovyan%2Fmeena%2Foauth_callback&response_type=code&state=[secret] -> /user/jovyan/meena/oauth_callback?code=[secret]&state=[secret] (jovyan@10.67.118.148) 23.49ms
[I 2022-02-28 15:19:42.528 JupyterHub log:189] 302 GET /user/jovyan/meena/oauth_callback?code=[secret]&state=[secret] -> /hub/user/jovyan/meena/oauth_callback?code=[secret]&state=[secret] (@10.67.118.148) 0.79ms
[D 2022-02-28 15:19:42.875 JupyterHub scopes:491] Checking access via scope access:servers
[D 2022-02-28 15:19:42.875 JupyterHub scopes:389] Unrestricted access to /hub/user/jovyan/meena/oauth_callback via access:servers
[I 2022-02-28 15:19:42.876 JupyterHub log:189] 302 GET /hub/user/jovyan/meena/oauth_callback?code=[secret]&state=[secret] -> /user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=1 (jovyan@10.67.118.148) 3.99ms
[I 2022-02-28 15:19:43.221 JupyterHub log:189] 302 GET /user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=1 -> /hub/user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=1 (@10.67.118.148) 0.77ms
[D 2022-02-28 15:19:43.563 JupyterHub scopes:491] Checking access via scope access:servers
[D 2022-02-28 15:19:43.563 JupyterHub scopes:389] Unrestricted access to /hub/user/jovyan/meena/oauth_callback via access:servers
[W 2022-02-28 15:19:43.563 JupyterHub base:1600] Redirect loop detected on /hub/user/jovyan/meena/oauth_callback?code=Ftqp1sWG75oILvsr7Qd00q5OEXe8cf&state=eyJ1dWlkIjogImIyOGU1Zjg0MDZmYzRmZWU5NjVjNjllZmVkNmNmZDUwIiwgIm5leHRfdXJsIjogIi91c2VyL2pvdnlhbi9tZWVuYS90cmVlLz8ifQ&redirects=1
[I 2022-02-28 15:19:45.566 JupyterHub log:189] 302 GET /hub/user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=1 -> /user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=2 (jovyan@10.67.118.148) 2005.47ms
[I 2022-02-28 15:19:45.906 JupyterHub log:189] 302 GET /user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=2 -> /hub/user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=2 (@10.67.118.148) 0.79ms
[I 2022-02-28 15:19:46.060 JupyterHub log:189] 200 GET /hub/login (@::ffff:10.86.124.143) 2.23ms
[D 2022-02-28 15:19:46.253 JupyterHub scopes:491] Checking access via scope access:servers
[D 2022-02-28 15:19:46.253 JupyterHub scopes:389] Unrestricted access to /hub/user/jovyan/meena/oauth_callback via access:servers
[W 2022-02-28 15:19:46.254 JupyterHub base:1600] Redirect loop detected on /hub/user/jovyan/meena/oauth_callback?code=Ftqp1sWG75oILvsr7Qd00q5OEXe8cf&state=eyJ1dWlkIjogImIyOGU1Zjg0MDZmYzRmZWU5NjVjNjllZmVkNmNmZDUwIiwgIm5leHRfdXJsIjogIi91c2VyL2pvdnlhbi9tZWVuYS90cmVlLz8ifQ&redirects=2
[I 2022-02-28 15:19:50.260 JupyterHub log:189] 302 GET /hub/user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=2 -> /user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=3 (jovyan@10.67.118.148) 4008.84ms
[I 2022-02-28 15:19:50.620 JupyterHub log:189] 302 GET /user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=3 -> /hub/user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=3 (@10.67.118.148) 0.80ms
[D 2022-02-28 15:19:50.964 JupyterHub scopes:491] Checking access via scope access:servers
[D 2022-02-28 15:19:50.964 JupyterHub scopes:389] Unrestricted access to /hub/user/jovyan/meena/oauth_callback via access:servers
[W 2022-02-28 15:19:50.965 JupyterHub base:1600] Redirect loop detected on /hub/user/jovyan/meena/oauth_callback?code=Ftqp1sWG75oILvsr7Qd00q5OEXe8cf&state=eyJ1dWlkIjogImIyOGU1Zjg0MDZmYzRmZWU5NjVjNjllZmVkNmNmZDUwIiwgIm5leHRfdXJsIjogIi91c2VyL2pvdnlhbi9tZWVuYS90cmVlLz8ifQ&redirects=3
[I 2022-02-28 15:19:58.970 JupyterHub log:189] 302 GET /hub/user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=3 -> /user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=4 (jovyan@10.67.118.148) 8007.33ms
[I 2022-02-28 15:19:59.310 JupyterHub log:189] 302 GET /user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=4 -> /hub/user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=4 (@10.67.118.148) 0.77ms
[D 2022-02-28 15:19:59.651 JupyterHub scopes:491] Checking access via scope access:servers
[D 2022-02-28 15:19:59.651 JupyterHub scopes:389] Unrestricted access to /hub/user/jovyan/meena/oauth_callback via access:servers
[W 2022-02-28 15:19:59.652 JupyterHub web:1787] 500 GET /hub/user/jovyan/meena/oauth_callback?code=Ftqp1sWG75oILvsr7Qd00q5OEXe8cf&state=eyJ1dWlkIjogImIyOGU1Zjg0MDZmYzRmZWU5NjVjNjllZmVkNmNmZDUwIiwgIm5leHRfdXJsIjogIi91c2VyL2pvdnlhbi9tZWVuYS90cmVlLz8ifQ&redirects=4 (10.67.118.148): Redirect loop detected.
[D 2022-02-28 15:19:59.652 JupyterHub base:1323] No template for 500
[E 2022-02-28 15:19:59.676 JupyterHub log:181] {
"Host": "10.86.124.187:8081",
"Connection": "keep-alive",
"Cache-Control": "max-age=0",
"Upgrade-Insecure-Requests": "1",
"User-Agent": "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/98.0.4758.80 Safari/537.36",
"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.9",
"Referer": "http://10.86.124.187:8081/hub/login?next=%2Fhub%2Fapi%2Foauth2%2Fauthorize%3Fclient_id%3Djupyterhub-user-jovyan-meena%26redirect_uri%3D%252Fuser%252Fjovyan%252Fmeena%252Foauth_callback%26response_type%3Dcode%26state%3DeyJ1dWlkIjogImIyOGU1Zjg0MDZmYzRmZWU5NjVjNjllZmVkNmNmZDUwIiwgIm5leHRfdXJsIjogIi91c2VyL2pvdnlhbi9tZWVuYS90cmVlLz8ifQ",
"Accept-Encoding": "gzip, deflate",
"Accept-Language": "en-GB,en-US;q=0.9,en;q=0.8",
"Cookie": "jupyterhub-hub-login=[secret]; jupyterhub-session-id=[secret]"
}
[E 2022-02-28 15:19:59.676 JupyterHub log:189] 500 GET /hub/user/jovyan/meena/oauth_callback?code=[secret]&state=[secret]&redirects=4 (jovyan@10.67.118.148) 26.95ms
[I 2022-02-28 15:20:02.680 JupyterHub log:189] 200 GET /hub/login (@::ffff:10.86.124.92) 1.93ms