2020-10-16T18:53:25.9881990Z | Library | TRACE | Connecting to EndpointManager\nOperation context: <json>{"clientRequestId":null,"correlationRequestId":null,"requestId":null,"userSubscriptionId":null,"startTime":"2020-10-16T18:53:25.0929460Z","userAgent":"VSCode/1.0.120201007","requestHttpMethod":null,"requestUri":null,"version":"1.0.20201007.4","requestHeaders":{},"loggingProperties":{"applicationName":"Library","deviceOperatingSystem":"Darwin 20.1.0 Darwin Kernel Version 20.1.0: Thu Sep 24 20:22:06 PDT 2020; root:xnu-7195.40.89.100.3~1/RELEASE_X86_64","framework":".NET Core 3.1.8","macAddressHash":"8921c2b390668a560e30250648011714e8997c5edb8da88b9b47c0d1fb5c8d7a","processId":54202,"targetEnvironment":"Production"}}</json>
2020-10-16T18:53:26.0012310Z | Library | TRACE | EndpointManager is not running: 'Can't assign requested address /Users/simyco/.bridge/EndpointManager/EndpointManagerSocket'
2020-10-16T18:53:26.0016830Z | Library | TRACE | EndpointManager is not running.
2020-10-16T18:53:26.0400740Z | Library | TRACE | DetermineCurrentUser returned exit code 0 and result 'simyco'
2020-10-16T18:53:26.0650630Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:26.0654660Z | Library | TRACE | EndpointManager is not running: 'Can't assign requested address /Users/simyco/.bridge/EndpointManager/EndpointManagerSocket'
2020-10-16T18:53:26.5670880Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:26.5678890Z | Library | TRACE | EndpointManager is not running: 'Can't assign requested address /Users/simyco/.bridge/EndpointManager/EndpointManagerSocket'
2020-10-16T18:53:27.5694670Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:27.5713780Z | Library | TRACE | EndpointManager is not running: 'Can't assign requested address /Users/simyco/.bridge/EndpointManager/EndpointManagerSocket'
2020-10-16T18:53:29.5734570Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:29.5746120Z | Library | TRACE | EndpointManager is not running: 'Can't assign requested address /Users/simyco/.bridge/EndpointManager/EndpointManagerSocket'
2020-10-16T18:53:33.5762610Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:33.5876720Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:53:33.6041480Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:53:33.6145790Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:33.6152620Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:53:33.6162000Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:53:33.6261500Z | Library | TRACE | Event: ConnectManagementClient-StartEndpointManager <json>{"properties":{"result":"Failed"},"metrics":{"durationInMs":7637.0}}</json>\nOperation context: <json>{"clientRequestId":null,"correlationRequestId":null,"requestId":null,"userSubscriptionId":null,"startTime":"2020-10-16T18:53:25.0929460Z","userAgent":"VSCode/1.0.120201007","requestHttpMethod":null,"requestUri":null,"version":"1.0.20201007.4","requestHeaders":{},"loggingProperties":{"applicationName":"Library","deviceOperatingSystem":"Darwin 20.1.0 Darwin Kernel Version 20.1.0: Thu Sep 24 20:22:06 PDT 2020; root:xnu-7195.40.89.100.3~1/RELEASE_X86_64","framework":".NET Core 3.1.8","macAddressHash":"8921c2b390668a560e30250648011714e8997c5edb8da88b9b47c0d1fb5c8d7a","processId":54202,"targetEnvironment":"Production","isRoutingEnabled":true}}</json>
2020-10-16T18:53:34.5486820Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"ListPodsInNamespaceAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:34.6803350Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"ListDeploymentsInNamespaceAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:34.6835160Z | Library | TRACE | Another debug session is in progress and Routing manager is already present. Skipping updating the Routing manager in namespace 'test' to avoid any disruptions to existing users.\nOperation context: <json>{"clientRequestId":null,"correlationRequestId":null,"requestId":null,"userSubscriptionId":null,"startTime":"2020-10-16T18:53:25.0929460Z","userAgent":"VSCode/1.0.120201007","requestHttpMethod":null,"requestUri":null,"version":"1.0.20201007.4","requestHeaders":{},"loggingProperties":{"applicationName":"Library","deviceOperatingSystem":"Darwin 20.1.0 Darwin Kernel Version 20.1.0: Thu Sep 24 20:22:06 PDT 2020; root:xnu-7195.40.89.100.3~1/RELEASE_X86_64","framework":".NET Core 3.1.8","macAddressHash":"8921c2b390668a560e30250648011714e8997c5edb8da88b9b47c0d1fb5c8d7a","processId":54202,"targetEnvironment":"Production","isRoutingEnabled":true}}</json>
2020-10-16T18:53:34.6844940Z | Library | TRACE | Event: RoutingManagementClient-DeployRoutingManager <json>{"properties":{"result":"Succeeded"},"metrics":{"durationInMs":1045.0}}</json>
2020-10-16T18:53:35.0817090Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1ServiceAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:35.1615920Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"ListPodsInNamespaceAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:35.1621690Z | Library | TRACE | Successfully got pod and container from service.
2020-10-16T18:53:35.1624000Z | Library | TRACE | Event: KubernetesRemoteEnvironmentManager-GetPodAndContainerFromService <json>{"properties":{"result":"Succeeded"},"metrics":{"durationInMs":460.0}}</json>
2020-10-16T18:53:35.2260060Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:35.2309600Z | Library | TRACE | Successfully set routing label and annotation on devhost agent pod.
2020-10-16T18:53:35.2785420Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:35.4039420Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"CreateV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:35.4707600Z | Library | TRACE | Service account already exists, leaving it untouched.
2020-10-16T18:53:35.4710410Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"CreateServiceAccountIfNotExists","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:35.4714030Z | Library | TRACE | Service account created/refreshed
2020-10-16T18:53:35.5966430Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"CreateOrReplaceV1RoleInNamespaceAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:35.5972330Z | Library | TRACE | Role created/refreshed
2020-10-16T18:53:35.7258300Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"CreateOrReplaceV1RoleBindingInNamespaceAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:35.7265840Z | Library | TRACE | Role binding created/refreshed
2020-10-16T18:53:35.7267600Z | Library | TRACE | Event: RemoteRestoreJobDeployer-EnsureRbacResources <json>{"properties":{"rbacEnabled":"true","result":"Succeeded"},"metrics":{"durationInMs":319.0}}</json>
2020-10-16T18:53:35.7908980Z | Library | TRACE | Invoking kubectl Delete command: --kubeconfig="/var/folders/zn/mt74y8vx0l50cj5h_vsl0j3c0000gn/T/tmpjyrhAu.tmp" delete job,secret -n test -l mindaro.io/instance=e5d159953b
2020-10-16T18:53:35.7923050Z | Library | TRACE | Using kubectl found at: '/Users/simyco/Library/Application Support/Code/User/globalStorage/mindaro.mindaro/file-downloader-downloads/binaries/kubectl/osx/kubectl'
2020-10-16T18:53:36.1442760Z | Library | TRACE | Invoked kubectl Delete command: '--kubeconfig="/var/folders/zn/mt74y8vx0l50cj5h_vsl0j3c0000gn/T/tmpjyrhAu.tmp" delete job,secret -n test -l mindaro.io/instance=e5d159953b' exited with 0 in 350ms
2020-10-16T18:53:36.1458810Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"Delete","success":true,"duration":null,"properties":{"exitCode":"0","output":"No resources found\n"}}</json>
2020-10-16T18:53:36.3064880Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"CreateNamespacedSecretAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:36.3066770Z | Library | TRACE | Created restore job secret
2020-10-16T18:53:36.4149080Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"CreateNamespacedJobAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:36.4150600Z | Library | TRACE | Created restore job workload
2020-10-16T18:53:36.4152700Z | Library | TRACE | Event: RemoteRestoreJobDeployer-Deploy <json>{"properties":{"result":"Succeeded"},"metrics":{"durationInMs":1009.0}}</json>
2020-10-16T18:53:36.4663960Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:36.4672540Z | Library | TRACE | Waiting for test/simyco-ecconext-eccopec-api-69688db7b6-hvjwk to run.
2020-10-16T18:53:36.6121260Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:36.6122960Z | Library | TRACE | Waiting for test/simyco-ecconext-eccopec-api-69688db7b6-hvjwk to run.
2020-10-16T18:53:36.7602840Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:36.7609240Z | Library | TRACE | Waiting for test/simyco-ecconext-eccopec-api-69688db7b6-hvjwk to run.
2020-10-16T18:53:36.9085880Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:36.9092070Z | Library | TRACE | Waiting for test/simyco-ecconext-eccopec-api-69688db7b6-hvjwk to run.
2020-10-16T18:53:37.0587010Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:37.0591940Z | Library | TRACE | Waiting for test/simyco-ecconext-eccopec-api-69688db7b6-hvjwk to run.
2020-10-16T18:53:37.2069140Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:37.2074010Z | Library | TRACE | Waiting for test/simyco-ecconext-eccopec-api-69688db7b6-hvjwk to run.
2020-10-16T18:53:37.3572270Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:37.3576640Z | Library | TRACE | Waiting for test/simyco-ecconext-eccopec-api-69688db7b6-hvjwk to run.
2020-10-16T18:53:37.5068050Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:37.5069610Z | Library | TRACE | Waiting for test/simyco-ecconext-eccopec-api-69688db7b6-hvjwk to run.
2020-10-16T18:53:37.6535260Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:37.6536700Z | Library | TRACE | Waiting for test/simyco-ecconext-eccopec-api-69688db7b6-hvjwk to run.
2020-10-16T18:53:37.7992220Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:37.7996410Z | Library | TRACE | Waiting for test/simyco-ecconext-eccopec-api-69688db7b6-hvjwk to run.
2020-10-16T18:53:37.9465060Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:37.9466670Z | Library | TRACE | Waiting for test/simyco-ecconext-eccopec-api-69688db7b6-hvjwk to run.
2020-10-16T18:53:38.0942680Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1PodAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:38.0967270Z | Library | TRACE | Found container 'ecconext-eccopec-api' in pod 'simyco-ecconext-eccopec-api-69688db7b6-hvjwk'.
2020-10-16T18:53:38.1010900Z | Library | TRACE | Preparing to run test/ecconext-eccopec-api...
2020-10-16T18:53:38.1121890Z | Library | TRACE | PortListener created on 54908
2020-10-16T18:53:38.1124070Z | Library | TRACE | PortListener started on 54908
2020-10-16T18:53:38.1125050Z | Library | TRACE | Starting listening 54908 : 50051
2020-10-16T18:53:38.1652080Z | Library | TRACE | Accept 54908 to 50051
2020-10-16T18:53:38.1681870Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54909 : Run send loop
2020-10-16T18:53:38.1760440Z | Library | TRACE | Creating web socket for simyco-ecconext-eccopec-api-69688db7b6-hvjwk 50051
2020-10-16T18:53:38.6157990Z | Library | TRACE | Web socket for simyco-ecconext-eccopec-api-69688db7b6-hvjwk 50051 created.
2020-10-16T18:53:38.6193440Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54909 : Run receive loop
2020-10-16T18:53:38.8058210Z | Library | TRACE | Accept 54908 to 50051
2020-10-16T18:53:38.8060210Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54911 : Run send loop
2020-10-16T18:53:38.8061400Z | Library | TRACE | Creating web socket for simyco-ecconext-eccopec-api-69688db7b6-hvjwk 50051
2020-10-16T18:53:39.2133610Z | Library | TRACE | Web socket for simyco-ecconext-eccopec-api-69688db7b6-hvjwk 50051 created.
2020-10-16T18:53:39.2136460Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54911 : Run receive loop
2020-10-16T18:53:39.7251030Z | Library | TRACE | Reset completed
2020-10-16T18:53:40.0752850Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetV1ServiceAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:40.1488150Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"ListServicesInNamespaceAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:40.1951310Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"ListServicesInNamespaceAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:40.1984300Z | Library | TRACE | Event: KubernetesRemoteEnvironmentManager-GetReachableServices <json>{"properties":{"result":"Succeeded"},"metrics":{"durationInMs":112.0}}</json>
2020-10-16T18:53:40.2150280Z | Library | TRACE | Event: KubernetesRemoteEnvironmentManager-StartRemoteAgent <json>{"properties":{"result":"Succeeded"},"metrics":{"durationInMs":5048.0}}</json>
2020-10-16T18:53:40.2152170Z | Library | TRACE | Event: ConnectManagementClient-StartRemoteAgent <json>{"properties":{"agentHostingMode":"1","sourceEntityType":"0","namespaceName":"test","containerName":"ecconext-eccopec-api","serviceName":"ecconext-eccopec-api","deploymentName":"","podName":"ecconext-eccopec-api-69688db7b6-hvjwk","routingHeaderValue":"simyco-fd70","localProcessConfigFilePath":"KubernetesLocalProcessConfig.yaml","result":"Succeeded"},"metrics":{"durationInMs":5525.0}}</json>
2020-10-16T18:53:40.2714200Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"GetFirstNamespacedPodWithLabelAsync","success":true,"duration":null,"properties":{}}</json>
2020-10-16T18:53:40.2890020Z | Library | TRACE | Invoking kubectl PortForward command: --kubeconfig="/var/folders/zn/mt74y8vx0l50cj5h_vsl0j3c0000gn/T/tmpTchysM.tmp" port-forward service/routingmanager-service --pod-running-timeout=1s 54932:80 --namespace test
2020-10-16T18:53:40.2892890Z | Library | TRACE | Using kubectl found at: '/Users/simyco/Library/Application Support/Code/User/globalStorage/mindaro.mindaro/file-downloader-downloads/binaries/kubectl/osx/kubectl'
2020-10-16T18:53:40.3006540Z | Library | TRACE | Invoked long running kubectl PortForward command: '--kubeconfig="/var/folders/zn/mt74y8vx0l50cj5h_vsl0j3c0000gn/T/tmpTchysM.tmp" port-forward service/routingmanager-service --pod-running-timeout=1s 54932:80 --namespace test'
2020-10-16T18:53:41.1623890Z | Library | TRACE | Port forward to routing manager output : 'Forwarding from 127.0.0.1:54932 -> 80'
2020-10-16T18:53:41.1628900Z | Library | TRACE | Port forward to routing manager output : 'Forwarding from [::1]:54932 -> 80'
2020-10-16T18:53:41.1680130Z | Library | TRACE | Port forward to routing manager output : 'Handling connection for 54932'
2020-10-16T18:53:45.8299740Z | Library | TRACE | Event: RoutingManagementClient-GetStatus <json>{"properties":{"result":"Succeeded"},"metrics":{"durationInMs":5611.0}}</json>
2020-10-16T18:53:45.8319450Z | Library | TRACE | Dependency: Kubernetes <json>{"target":"PortForward","success":true,"duration":null,"properties":{"exitCode":"0"}}</json>
2020-10-16T18:53:45.8446620Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:45.8478660Z | Library | TRACE | Sending request: '{"apiname":"Version"}'
2020-10-16T18:53:45.8556610Z | Library | TRACE | Received response: '{"value":"1.0.20201007.4","isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:53:45.8594860Z | Library | TRACE | Found EndpointManager version '1.0.20201007.4' which is equal to expected version '1.0.20201007.4'
2020-10-16T18:53:45.8598880Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:45.8606580Z | Library | TRACE | Sending request: '{"apiname":"SystemCheck"}'
2020-10-16T18:53:45.8700790Z | Library | TRACE | Received response: '{"value":{"serviceMessages":[],"portBinding":{}},"isSuccess":true,"errorMessage":"","errorType":""}'
2020-10-16T18:53:45.8781640Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:45.8789490Z | Library | TRACE | Sending request: '{"apiname":"Version"}'
2020-10-16T18:53:45.8799990Z | Library | TRACE | Received response: '{"value":"1.0.20201007.4","isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:53:45.8802530Z | Library | TRACE | Found EndpointManager version '1.0.20201007.4' which is equal to expected version '1.0.20201007.4'
2020-10-16T18:53:45.8805240Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:45.8883250Z | Library | TRACE | Sending request: '{"argument":[{"80":55049},{"80":55049},{"80":55049},{"443":55049}],"apiname":"AllocateIP"}'
2020-10-16T18:53:46.0065440Z | Library | TRACE | Received response: '{"value":["127.1.1.1","127.1.1.2","127.1.1.3","127.1.1.4"],"isSuccess":true,"errorMessage":"","errorType":""}'
2020-10-16T18:53:46.0251970Z | Library | TRACE | ServicePortForwarder started on ecconext-eccopec-api:80
2020-10-16T18:53:46.0253370Z | Library | TRACE | ServicePortForwarder started on ecconext-eccopec-api-cloned-routing-svc:80
2020-10-16T18:53:46.0255780Z | Library | TRACE | ServicePortForwarder started on routingmanager-service:80
2020-10-16T18:53:46.0256760Z | Library | TRACE | ServicePortForwarder started on kubernetes:443
2020-10-16T18:53:46.0259860Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:46.0265800Z | Library | TRACE | Sending request: '{"apiname":"Version"}'
2020-10-16T18:53:46.0274620Z | Library | TRACE | Received response: '{"value":"1.0.20201007.4","isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:53:46.0275970Z | Library | TRACE | Found EndpointManager version '1.0.20201007.4' which is equal to expected version '1.0.20201007.4'
2020-10-16T18:53:46.0280030Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:53:46.0324680Z | Library | TRACE | Sending request: '{"argument":[{"names":["ecconext-eccopec-api"],"ip":"127.1.1.1","namespaceName":"test","addUnqualifiedServiceName":true},{"names":["ecconext-eccopec-api-cloned-routing-svc"],"ip":"127.1.1.2","namespaceName":"test","addUnqualifiedServiceName":true},{"names":["routingmanager-service"],"ip":"127.1.1.3","namespaceName":"test","addUnqualifiedServiceName":true},{"names":["kubernetes"],"ip":"127.1.1.4","namespaceName":"default","addUnqualifiedServiceName":false}],"apiname":"AddHostsFileEntry"}'
2020-10-16T18:53:46.0535210Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":"","errorType":""}'
2020-10-16T18:53:46.0548130Z | Library | TRACE | Event: LocalEnvironmentManager-StartServiceRouters <json>{"properties":{"hostsFileEntryCount":"4","result":"Succeeded"},"metrics":{"durationInMs":214.0}}</json>
2020-10-16T18:53:46.0712220Z | Library | TRACE | Event: LocalEnvironmentManager-StartWorkload <json>{"properties":{"envVarCount":"33","result":"Succeeded"},"metrics":{"durationInMs":11.0}}</json>
2020-10-16T18:53:46.0714690Z | Library | TRACE | Event: ConnectManagementClient-ConfigureLocalHost <json>{"properties":{"container":"6355b1481c5c","numOfReachableServices":"4","numOfEnvironmentVariables":"8","numOfContainerPorts":"1","numOfVolumeMounts":"1","result":"Succeeded"},"metrics":{"durationInMs":236.0}}</json>
2020-10-16T18:53:46.0829250Z | Library | TRACE | Accept 54908 to 50051
2020-10-16T18:53:46.0833660Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54976 : Run send loop
2020-10-16T18:53:46.0836380Z | Library | TRACE | Creating web socket for simyco-ecconext-eccopec-api-69688db7b6-hvjwk 50051
2020-10-16T18:53:46.4934900Z | Library | TRACE | Web socket for simyco-ecconext-eccopec-api-69688db7b6-hvjwk 50051 created.
2020-10-16T18:53:46.4941630Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54976 : Run receive loop
2020-10-16T18:53:46.5833110Z | Library | TRACE | Accept 54908 to 50051
2020-10-16T18:53:46.5835390Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54987 : Run send loop
2020-10-16T18:53:46.5837320Z | Library | TRACE | Creating web socket for simyco-ecconext-eccopec-api-69688db7b6-hvjwk 50051
2020-10-16T18:53:47.0093700Z | Library | TRACE | Web socket for simyco-ecconext-eccopec-api-69688db7b6-hvjwk 50051 created.
2020-10-16T18:53:47.0327190Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54987 : Run receive loop
2020-10-16T18:54:16.0581910Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:54:16.0611130Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:54:16.0644430Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:54:46.0545020Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:54:46.0556700Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:54:46.0576530Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:55:16.0570170Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:55:16.0586720Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:55:16.0618420Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:55:39.5806240Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54909 : Remote stream finished. Closed
2020-10-16T18:55:39.5808070Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54909 : RemoteConnection closed.
2020-10-16T18:55:46.0544920Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:55:46.0553570Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:55:46.0564730Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:55:47.5703710Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54976 : RemoteConnection closed.
2020-10-16T18:55:47.5703710Z | Library | TRACE | Port forward simyco-ecconext-eccopec-api-69688db7b6-hvjwk 54908:50051 54976 : Remote stream finished. Closed
2020-10-16T18:56:16.0555000Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:56:16.0566520Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:56:16.0585990Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:56:46.0564230Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:56:46.0572220Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:56:46.0581460Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:57:16.0654260Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:57:16.0786660Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:57:16.0798500Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:57:46.0536560Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:57:46.0547150Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:57:46.0556580Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:58:16.0527760Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:58:16.0534120Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:58:16.0541070Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:58:46.0538960Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:58:46.0550190Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:58:46.0566280Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:59:16.0525880Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:59:16.0533400Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:59:16.0540820Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T18:59:46.0532460Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T18:59:46.0551230Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T18:59:46.0568330Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:00:16.0553480Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:00:16.0559260Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:00:16.0567320Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:00:46.0518370Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:00:46.0525550Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:00:46.0535340Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:01:16.0519950Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:01:16.0526350Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:01:16.0534200Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:01:46.0517790Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:01:46.0525020Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:01:46.0531920Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:02:16.0510650Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:02:16.0515910Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:02:16.0521740Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:02:46.0524650Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:02:46.0540420Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:02:46.0546310Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:03:16.0503470Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:03:16.0514080Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:03:16.0519400Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:03:46.0511440Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:03:46.0523190Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:03:46.0529510Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:04:16.0508080Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:04:16.0516270Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:04:16.0524350Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:04:46.0501460Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:04:46.0519210Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:04:46.0533030Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:05:16.0502950Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:05:16.0514840Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:05:16.0521870Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:05:46.0498300Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:05:46.0513860Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:05:46.0520750Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:06:16.0603800Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:06:16.1098730Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:06:16.1208040Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:06:28.7312590Z | Library | TRACE | Sent 1166 bytes to workload.
2020-10-16T19:06:35.9362700Z | Library | TRACE | ReversePortForwarder receive 1387 bytes from port 80
2020-10-16T19:06:46.0497130Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:06:46.0515660Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:06:46.0532270Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:07:16.0494100Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:07:16.0502750Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:07:16.0511690Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:07:20.8329000Z | Library | TRACE | Sent 1166 bytes to workload.
2020-10-16T19:07:21.3264440Z | Library | TRACE | ReversePortForwarder receive 1387 bytes from port 80
2020-10-16T19:07:46.0492170Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:07:46.0498500Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:07:46.0504690Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:07:47.6644310Z | Library | TRACE | Sent 1166 bytes to workload.
2020-10-16T19:07:48.4481450Z | Library | TRACE | ReversePortForwarder receive 1822 bytes from port 80
2020-10-16T19:07:48.5321130Z | Library | TRACE | ReversePortForwarder receive 5 bytes from port 80
2020-10-16T19:07:48.7309280Z | Library | TRACE | Sent 1040 bytes to workload.
2020-10-16T19:08:03.7403370Z | Library | TRACE | Closing socket 1
2020-10-16T19:08:03.8009860Z | Library | TRACE | StartReceivingAsync 80 id 1 exception System.IO.IOException: Unable to read data from the transport connection: Operation canceled.\n ---> System.Net.Sockets.SocketException (89): Operation canceled\n   --- End of inner exception stack trace ---\n   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)\n   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.GetResult(Int16 token)\n   at System.Threading.Tasks.ValueTask`1.ValueTaskSourceAsTask.<>c.<.cctor>b__4_0(Object state)\n--- End of stack trace from previous location where exception was thrown ---\n   at Microsoft.DevSpaces.Common.PortForward.ReversePortForwardManager.ReversePortForwardInstance.StartReceivingAsync(TcpClient tcpClient, Int32 streamId, CancellationToken cancellation)
2020-10-16T19:08:16.0491550Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:08:16.0506110Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:08:16.0514020Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:08:46.0488240Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:08:46.0522550Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:08:46.0543060Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:09:16.0510360Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:09:16.0514570Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:09:16.0519410Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:09:46.0483750Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:09:46.0490240Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:09:46.0496580Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:10:13.2775090Z | Library | TRACE | Sent 1040 bytes to workload.
2020-10-16T19:10:16.0503530Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:10:16.0526650Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:10:16.0536920Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:10:28.2773770Z | Library | TRACE | Closing socket 2
2020-10-16T19:10:28.2788170Z | Library | TRACE | StartReceivingAsync 80 id 2 exception System.IO.IOException: Unable to read data from the transport connection: Operation canceled.\n ---> System.Net.Sockets.SocketException (89): Operation canceled\n   --- End of inner exception stack trace ---\n   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)\n   at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.GetResult(Int16 token)\n   at System.Threading.Tasks.ValueTask`1.ValueTaskSourceAsTask.<>c.<.cctor>b__4_0(Object state)\n--- End of stack trace from previous location where exception was thrown ---\n   at Microsoft.DevSpaces.Common.PortForward.ReversePortForwardManager.ReversePortForwardInstance.StartReceivingAsync(TcpClient tcpClient, Int32 streamId, CancellationToken cancellation)
2020-10-16T19:10:46.0530900Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:10:46.0610650Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:10:46.0618270Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:11:16.0497130Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:11:16.0504690Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:11:16.0518670Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:11:46.0500780Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:11:46.0520080Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:11:46.0539240Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:12:16.0482100Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:12:16.0493630Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:12:16.0504320Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:12:46.0474120Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:12:46.0488840Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:12:46.0514180Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:13:16.0468900Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:13:16.0490040Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:13:16.0497840Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:13:46.0504730Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:13:46.0600640Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:13:46.0642840Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:14:16.0467760Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:14:16.0479630Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:14:16.0485850Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:14:46.0505850Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:14:46.0622620Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:14:46.0728770Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:15:16.0459300Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:15:16.0465000Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:15:16.0474160Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:15:46.0608010Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:15:46.0623900Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:15:46.0641110Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:16:16.0614910Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:16:16.0630760Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:16:16.0646780Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:16:46.0609320Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:16:46.0615720Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:16:46.0637750Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:17:16.0628170Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:17:16.0645700Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:17:16.0663330Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:17:46.0612450Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:17:46.0633870Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:17:46.0677770Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'
2020-10-16T19:18:16.0600670Z | Library | TRACE | Connecting to EndpointManager
2020-10-16T19:18:16.0638430Z | Library | TRACE | Sending request: '{"apiname":"Ping"}'
2020-10-16T19:18:16.0655940Z | Library | TRACE | Received response: '{"isSuccess":true,"errorMessage":null,"errorType":null}'