[VS Code] RPC session error

Product and Version [VSCode]:  1.40.2
OS Version [macOS Catalina]: 10.15.1
Live Share Extension Version: 1.0.1293
Steps to Reproduce / Scenario:

Connection mode direct
5990-5999 ports opened
Firewall settings done for vsls-agent
Guest trying to connect
Throws error and suggest relay connection. But I want to use direct connection.

[2019-12-07 08:09:21.816 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:2e4fffb8> connectionId: ...  } properties:email=< ... >|name= ... |status=1|avatarUri=https://avatars0.githubusercontent.com/u/ ... ?v=4|liveshareStatus=2
[2019-12-07 08:09:21.816 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:2e4fffb8>
[2019-12-07 08:09:22.675 Agent.Ssh V] (9113) SshServerSession Close(ConnectionLost, )
[2019-12-07 08:09:22.676 Agent E] RPC session error: SSH connection failed [ProtocolError]: Encryption failed

Thank you for reporting this issue. We are looking into it now.

@IlyaBiryukov what could be the cause of this?

@jasongin RPC session error: SSH connection failed [ProtocolError]: Encryption failed: this comes from EncryptAsync() SshSession.cs,  when the NewKeysMessage doesn't arrive, but we get DisconnectMessage. Does that mean the connection was closed on the other side, i.e. the host has ended the session?
@mavci if you can get the Live Share logs from the host machine where you were trying to join to, that may help investigate if there was an issue on the host.
Also, does the issue reproduce consistently with the same RPC session error: SSH connection failed [ProtocolError]: Encryption failed error?

It means either the connection was closed by the other side or the connection was lost for some other reason before the session was encrypted.
@mavci Are the host and guest on the same network? Are either connected via a VPN? I have seen some cases where VPN gateways cause strange connection issues like this, by accepting a TCP connection before it is fully connected on the other end.
In order for direct connections to succeed, the host must have an IP address that the guest can see, either because it's a public IP address or they are both on the same private network. Live Share does not support NAT traversal for direct connections yet.

@jasongin host and guest on different networks. I just want to use live share with direct remote connection. My ports open and guest machine can access my public ip and port.

@IlyaBiryukov Host agent log:
[2019-12-13 14:44:11.872 Agent I] Accepted incoming connection from <:e78a289c:>
[2019-12-13 14:44:11.939 Agent.Ssh V] (9101) Sending #0 KeyExchangeInitMessage
[2019-12-13 14:44:11.944 Agent.Ssh V] (9002) System.IO.IOException: Unable to read data from the transport connection: Broken pipe. ---> System.Net.Sockets.SocketException: Broken pipe
   --- End of inner exception stack trace ---
   at Microsoft.VisualStudio.Ssh.IO.SshProtocol.WriteAsync(Buffer data, Boolean flush, CancellationToken cancellation)
[2019-12-13 14:44:11.946 Agent E] RPC session error: SSH connection failed [ConnectionLost]: Connection lost.
[2019-12-13 14:46:32.928 Agent I] Accepted incoming connection from <:62b71920:>
[2019-12-13 14:46:32.930 Agent.Ssh V] (9101) Sending #0 KeyExchangeInitMessage
[2019-12-13 14:46:32.959 Agent.Ssh V] (9001) System.IO.IOException: Unable to read data from the transport connection: Connection reset by peer. ---> System.Net.Sockets.SocketException: Connection reset by peer
   --- End of inner exception stack trace ---
   at Microsoft.VisualStudio.Ssh.IO.SshProtocol.ReadAsync(Buffer buffer, CancellationToken cancellation)
[2019-12-13 14:46:32.964 Agent.Ssh V] (9113) SshServerSession Close(ConnectionLost, Connection lost.)
[2019-12-13 14:46:32.971 Agent E] RPC session error: SSH connection failed [ProtocolError]: Encryption failed
[2019-12-13 14:49:31.773 Agent I] Accepted incoming connection from <:d7a7c271:>
[2019-12-13 14:49:31.774 Agent.Ssh V] (9101) Sending #0 KeyExchangeInitMessage
[2019-12-13 14:49:31.796 Agent.Ssh V] (9113) SshServerSession Close(ConnectionLost, )
[2019-12-13 14:49:31.797 Agent E] RPC session error: SSH connection failed [ProtocolError]: Encryption failed
[2019-12-13 14:50:01.883 Agent V] > #2 {Id=87 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:02.036 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:1d18d881> connectionId:JdiDSoAdzXzIsa5PM4G8IAd637ca2c1 } properties:status=3
[2019-12-13 14:50:02.037 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:1d18d881>
[2019-12-13 14:50:02.041 Agent V] < #2 {Id=87}
[2019-12-13 14:50:02.669 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:02.669 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:02.670 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:02.670 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:02.670 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:02.671 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:02.671 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:02.671 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:02.671 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:02.672 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:02.672 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:02.672 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:04.428 Agent.Rpc.File V] > #2 {Id=88 Name=file.list}
[2019-12-13 14:50:04.428 Agent.Rpc.File V] < #2 {Id=88}
[2019-12-13 14:50:04.479 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:04.480 Agent.Rpc.Workspace V] > #2 {Name=languageServerProvider-any.notified}
[2019-12-13 14:50:06.248 Agent.Rpc.Workspace V] > #2 {Id=91 Name=workspace.registerServices}
[2019-12-13 14:50:06.249 Agent.Rpc.Workspace V] < #2 {Id=91}
[2019-12-13 14:50:06.250 Agent.Rpc.Workspace V] > #2 {Id=92 Name=workspace.registerServices}
[2019-12-13 14:50:06.250 Agent.Rpc.Workspace V] < #2 {Id=92}
[2019-12-13 14:50:06.251 Agent.Rpc.Workspace V] > #2 {Id=93 Name=workspace.registerServices}
[2019-12-13 14:50:06.251 Agent.Rpc.Workspace V] < #2 {Id=93}
[2019-12-13 14:50:06.252 Agent.Rpc.Workspace V] > #2 {Id=94 Name=workspace.unshareWorkspace}
[2019-12-13 14:50:06.252 Agent-ObjectTracker V] Remove item:SharedWorkspace Id:ED77EAB288B972049C5BE6B895D35935AAC0 count:1 others:[RpcSession #2]
[2019-12-13 14:50:06.253 Agent V] Listener stopped because the workspace was unshared.
[2019-12-13 14:50:06.254 Agent.Rpc.Workspace V] < #2 {Name=workspace.connectionStatusChanged}
[2019-12-13 14:50:06.254 Agent.Rpc.Workspace V] < #2 {Name=workspace.servicesChanged}
[2019-12-13 14:50:06.256 Agent.Http V] < GET <https://prod.liveshare.vsengsaas.visualstudio.com/f7befae9>
[2019-12-13 14:50:06.256 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:07.051 Agent.Http V] > GET <https://prod.liveshare.vsengsaas.visualstudio.com/f7befae9> (c794ms s<null>ms) => 200 OK
[2019-12-13 14:50:07.052 Agent.Http V] < PUT <https://prod.liveshare.vsengsaas.visualstudio.com/f7befae9>
[2019-12-13 14:50:07.052 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:07.256 Agent.Http V] > PUT <https://prod.liveshare.vsengsaas.visualstudio.com/f7befae9> (c203ms s<null>ms) => 200 OK
[2019-12-13 14:50:07.257 Agent.Rpc.Workspace V] < #2 {Id=94}
[2019-12-13 14:50:07.257 Agent.Http V] < DELETE <https://prod.liveshare.vsengsaas.visualstudio.com/f7befae9>
[2019-12-13 14:50:07.257 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:07.259 Agent.Rpc.Workspace V] > #2 {Id=95 Name=workspace.registerServices}
[2019-12-13 14:50:07.261 Agent.Rpc.Workspace E] System.ArgumentException: Not currently sharing or joined to a workspace.
   at Microsoft.Cascade.Agent.WorkspaceService.RegisterServicesAsync(String[] serviceNames, CollectionChangeType changeType, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Agent\WorkspaceService.cs:line 1685
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.<>c__DisplayClass24_0.<<CreateDispatchMethod>b__0>d.MoveNext() in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 259
--- End of stack trace from previous location where exception was thrown ---
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.HandleRequestAsync(RequestMessage request, IProgress`1 progress, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 102
   at Microsoft.Cascade.Rpc.RpcSession.RequestAsync(IRpcService service, RequestMessage message, CancellationToken cancellation) in E:\A\_work\96\s\src\Rpc\RpcSession.cs:line 702
[2019-12-13 14:50:07.261 Agent.Rpc.Workspace V] < #2 {Id=95 Error={Code=-32602 Message=Not currently sharing or joined to a workspace. Data=(StackTrace=System.ArgumentException: Not currently sharing or joined to a workspace.
   at Microsoft.Cascade.Agent.WorkspaceService.RegisterServicesAsync(String[] serviceNames, CollectionChangeType changeType, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Agent\WorkspaceService.cs:line 1685
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.<>c__DisplayClass24_0.<<CreateDispatchMethod>b__0>d.MoveNext() in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 259
--- End of stack trace from previous location where exception was thrown ---
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.HandleRequestAsync(RequestMessage request, IProgress`1 progress, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 102
   at Microsoft.Cascade.Rpc.RpcSession.RequestAsync(IRpcService service, RequestMessage message, CancellationToken cancellation) in E:\A\_work\96\s\src\Rpc\RpcSession.cs:line 702)}}
[2019-12-13 14:50:07.263 Agent.Rpc.Workspace V] > #2 {Id=96 Name=workspace.registerServices}
[2019-12-13 14:50:07.264 Agent.Rpc.Workspace E] System.ArgumentException: Not currently sharing or joined to a workspace.
   at Microsoft.Cascade.Agent.WorkspaceService.RegisterServicesAsync(String[] serviceNames, CollectionChangeType changeType, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Agent\WorkspaceService.cs:line 1685
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.<>c__DisplayClass24_0.<<CreateDispatchMethod>b__0>d.MoveNext() in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 259
--- End of stack trace from previous location where exception was thrown ---
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.HandleRequestAsync(RequestMessage request, IProgress`1 progress, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 102
   at Microsoft.Cascade.Rpc.RpcSession.RequestAsync(IRpcService service, RequestMessage message, CancellationToken cancellation) in E:\A\_work\96\s\src\Rpc\RpcSession.cs:line 702
[2019-12-13 14:50:07.264 Agent.Rpc.Workspace V] < #2 {Id=96 Error={Code=-32602 Message=Not currently sharing or joined to a workspace. Data=(StackTrace=System.ArgumentException: Not currently sharing or joined to a workspace.
   at Microsoft.Cascade.Agent.WorkspaceService.RegisterServicesAsync(String[] serviceNames, CollectionChangeType changeType, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Agent\WorkspaceService.cs:line 1685
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.<>c__DisplayClass24_0.<<CreateDispatchMethod>b__0>d.MoveNext() in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 259
--- End of stack trace from previous location where exception was thrown ---
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.HandleRequestAsync(RequestMessage request, IProgress`1 progress, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 102
   at Microsoft.Cascade.Rpc.RpcSession.RequestAsync(IRpcService service, RequestMessage message, CancellationToken cancellation) in E:\A\_work\96\s\src\Rpc\RpcSession.cs:line 702)}}
[2019-12-13 14:50:07.265 Agent.Rpc.Workspace V] > #2 {Id=97 Name=workspace.registerServices}
[2019-12-13 14:50:07.266 Agent.Rpc.Workspace E] System.ArgumentException: Not currently sharing or joined to a workspace.
   at Microsoft.Cascade.Agent.WorkspaceService.RegisterServicesAsync(String[] serviceNames, CollectionChangeType changeType, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Agent\WorkspaceService.cs:line 1685
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.<>c__DisplayClass24_0.<<CreateDispatchMethod>b__0>d.MoveNext() in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 259
--- End of stack trace from previous location where exception was thrown ---
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.HandleRequestAsync(RequestMessage request, IProgress`1 progress, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 102
   at Microsoft.Cascade.Rpc.RpcSession.RequestAsync(IRpcService service, RequestMessage message, CancellationToken cancellation) in E:\A\_work\96\s\src\Rpc\RpcSession.cs:line 702
[2019-12-13 14:50:07.267 Agent.Rpc.Workspace V] < #2 {Id=97 Error={Code=-32602 Message=Not currently sharing or joined to a workspace. Data=(StackTrace=System.ArgumentException: Not currently sharing or joined to a workspace.
   at Microsoft.Cascade.Agent.WorkspaceService.RegisterServicesAsync(String[] serviceNames, CollectionChangeType changeType, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Agent\WorkspaceService.cs:line 1685
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.<>c__DisplayClass24_0.<<CreateDispatchMethod>b__0>d.MoveNext() in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 259
--- End of stack trace from previous location where exception was thrown ---
   at Microsoft.Cascade.Rpc.RpcDispatcher`1.HandleRequestAsync(RequestMessage request, IProgress`1 progress, CancellationToken cancellationToken) in E:\A\_work\96\s\src\Rpc\RpcDispatcher.cs:line 102
   at Microsoft.Cascade.Rpc.RpcSession.RequestAsync(IRpcService service, RequestMessage message, CancellationToken cancellation) in E:\A\_work\96\s\src\Rpc\RpcSession.cs:line 702)}}
[2019-12-13 14:50:07.267 Agent V] > #2 {Id=98 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:07.267 Agent V] < #2 {Id=98}
[2019-12-13 14:50:07.267 Agent V] > #2 {Id=99 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:07.268 Agent V] < #2 {Id=99}
[2019-12-13 14:50:07.268 Agent V] > #2 {Id=100 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:07.268 ContactService-LiveShare V] Remove subscription for contact Id:<36:3ea7cb70>
[2019-12-13 14:50:07.269 Agent V] > #2 {Id=101 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:07.269 Agent V] < #2 {Id=101}
[2019-12-13 14:50:07.269 Agent V] > #2 {Id=102 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:07.269 ContactService-LiveShare V] Remove subscription for contact Id:<36:bb3d624c>
[2019-12-13 14:50:07.270 Agent V] > #2 {Id=103 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:07.270 Agent.Rpc.Auth V] > #2 {Id=104 Name=auth.getCurrentUser}
[2019-12-13 14:50:07.270 Agent.Http V] < GET <https://prod.liveshare.vsengsaas.visualstudio.com/38df5373>
[2019-12-13 14:50:07.271 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:07.271 Agent V] > #2 {Id=105 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:07.421 Agent V] < #2 {Id=100}
[2019-12-13 14:50:07.426 Agent V] < #2 {Id=102}
[2019-12-13 14:50:07.427 Agent.Rpc.Auth V] > #2 {Id=106 Name=auth.getCurrentUser}
[2019-12-13 14:50:07.427 Agent.Http V] < GET <https://prod.liveshare.vsengsaas.visualstudio.com/38df5373>
[2019-12-13 14:50:07.428 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:07.431 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:1d18d881> connectionId:JdiDSoAdzXzIsa5PM4G8IAd637ca2c1 } properties:liveshareStatus=4
[2019-12-13 14:50:07.431 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:1d18d881>
[2019-12-13 14:50:07.454 Agent.Http V] > DELETE <https://prod.liveshare.vsengsaas.visualstudio.com/f7befae9> (c196ms s<null>ms) => 200 OK
[2019-12-13 14:50:07.563 Agent V] < #2 {Id=103}
[2019-12-13 14:50:07.563 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:1d18d881> connectionId:JdiDSoAdzXzIsa5PM4G8IAd637ca2c1 } properties:status=1
[2019-12-13 14:50:07.563 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:1d18d881>
[2019-12-13 14:50:07.564 Agent V] < #2 {Id=105}
[2019-12-13 14:50:07.956 Agent.Http V] > GET <https://prod.liveshare.vsengsaas.visualstudio.com/38df5373> (c685ms s<null>ms) => 200 OK
[2019-12-13 14:50:07.957 Agent.Rpc.Auth V] < #2 {Id=104}
[2019-12-13 14:50:07.959 Agent V] > #2 {Id=107 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:07.960 Agent V] < #2 {Id=107}
[2019-12-13 14:50:07.961 Agent V] > #2 {Id=108 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:07.961 Agent V] < #2 {Id=108}
[2019-12-13 14:50:08.045 Agent.Http V] > GET <https://prod.liveshare.vsengsaas.visualstudio.com/38df5373> (c617ms s<null>ms) => 200 OK
[2019-12-13 14:50:08.046 Agent.Rpc.Auth V] < #2 {Id=106}
[2019-12-13 14:50:08.048 Agent V] > #2 {Id=109 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:08.048 Agent V] < #2 {Id=109}
[2019-12-13 14:50:08.049 Agent V] > #2 {Id=110 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:08.049 ContactService-LiveShare V] Request subscription for -> id:<36:3ea7cb70>
[2019-12-13 14:50:08.049 Agent V] > #2 {Id=111 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:08.049 ContactService-LiveShare V] Request subscription for -> id:<36:bb3d624c>
[2019-12-13 14:50:08.202 ContactService-LiveShare V] Request subscription result for contact Id:<36:3ea7cb70> properties:_Id=<36:3ea7cb70>
[2019-12-13 14:50:08.202 Agent V] < #2 {Id=110}
[2019-12-13 14:50:08.205 ContactService-LiveShare V] Request subscription result for contact Id:<36:bb3d624c> properties:status=1|liveshareStatus=2|name= GUEST NAME |avatarUri=https://avatars0.githubusercontent.com/u/ GUEST ID ?v=4|email=<1bd87719@gmail.com>|_Id=<36:bb3d624c>
[2019-12-13 14:50:08.205 Agent V] < #2 {Id=111}
[2019-12-13 14:50:09.390 Agent I] #2 Disconnected: Reached end of stream.
[2019-12-13 14:50:09.390 Agent I] RPC session #2 disconnected: Reached end of stream.
[2019-12-13 14:50:09.390 HubConnectionService V] StopAsync
[2019-12-13 14:50:09.391 Agent V] Emptying send queue of 0 messages.
[2019-12-13 14:50:09.391 Agent I] RpcSession #2 is disposing.
[2019-12-13 14:50:09.391 Agent V] <= ProcessMessagesInternalAsync session #:2
[2019-12-13 14:50:09.391 Agent V] KeepAlive-> reason:RpcSessionDisconnect delay:5000
[2019-12-13 14:50:09.392 Agent-ObjectTracker V] Add item:KeepAliveAgent reason:RpcSessionDisconnect delay:5000 count:2
[2019-12-13 14:50:09.392 HubConnectionService V] OnClosedAsync exception:<null>
[2019-12-13 14:50:09.392 Agent V] ConnectionStateChanged IsConnected:False
[2019-12-13 14:50:14.392 Agent-ObjectTracker V] Remove item:KeepAliveAgent reason:RpcSessionDisconnect delay:5000 count:1 others:[RpcSession #2]
[2019-12-13 14:50:14.392 Agent-ObjectTracker V] Remove item:RpcSession #2 count:0 others:[]
[2019-12-13 14:50:14.393 Agent I] Shutting down because there are no active sessions or shared or joined workspaces.

[2019-12-13 14:50:18.030 Agent I] vsls-agent v1.0.1377.64440 (pid: 42944)
[2019-12-13 14:50:18.031 Agent I] Reminder: You may only use this software with Visual Studio and Visual Studio Code, as described in the license (https://go.microsoft.com/fwlink/?linkid=872556).
[2019-12-13 14:50:18.031 Agent I] Start time: 12/13/19 2:50:17 PM
[2019-12-13 14:50:18.031 Agent I] Using VSLS service: https://prod.liveshare.vsengsaas.visualstudio.com/
[2019-12-13 14:50:18.093 Agent I] VSLSAgent/1.0.1377.64440 VSLS/2.2 PlatformName/Darwin 19.0.0 PlatformVersion/<IP_ADDRESS>
[2019-12-13 14:50:18.093 Agent I]
[2019-12-13 14:50:18.101 Agent V] Loaded assembly: Microsoft.VisualStudio.LiveShare.Agent.PresenceService v1.0.1377.64440
[2019-12-13 14:50:18.467 Agent.Proxy I] Proxy settings from env variable: http_proxy was null and https_proxy was null
[2019-12-13 14:50:18.475 Agent V] KeepAlive-> reason:AgentStarted delay:10000
[2019-12-13 14:50:18.476 Agent-ObjectTracker V] Add item:KeepAliveAgent reason:AgentStarted delay:10000 count:1
[2019-12-13 14:50:18.480 Agent I] (1001) Listening on pipe "vscode.b617c5cf81ef701b90f8d07b7f963f04"
[2019-12-13 14:50:18.542 Agent I] Accepted incoming pipe connection.
[2019-12-13 14:50:18.566 Agent I] RPC session #1 established.
[2019-12-13 14:50:18.583 Agent V] => ProcessMessagesInternalAsync session #:1
[2019-12-13 14:50:18.853 Agent V] > #1 {Id=0 Name=agentSessionService.initializeSession}
[2019-12-13 14:50:18.916 Agent-ObjectTracker V] Add item:RpcSession #1 count:2
[2019-12-13 14:50:18.933 Agent V] Creating Hub connection service with uri:https://signlr-prod.core.vsengsaas.visualstudio.com/api/signalr/signalrhub
[2019-12-13 14:50:19.042 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:19.771 Agent V] Create service from factory Name: contactService-LivesharePresence Type: Agent.PresenceService.PresenceServiceProviderFactory Service Type: IContactServiceProvider Priority: 150
[2019-12-13 14:50:19.776 Agent V] Adding runtime service of type:IContactServiceProvider
[2019-12-13 14:50:19.777 Agent V] Adding collab dispatch service:contactService-LivesharePresence
[2019-12-13 14:50:19.784 Agent V] < #1 {Id=0}
[2019-12-13 14:50:19.867 Agent V] > #1 {Id=1 Name=version.exchangeVersions}
[2019-12-13 14:50:19.873 Agent V] < #1 {Id=1}
[2019-12-13 14:50:19.876 Agent V] > #1 {Id=2 Name=version.exchangeSettings}
[2019-12-13 14:50:19.891 Agent V] < #1 {Id=2}
[2019-12-13 14:50:19.893 Agent V] > #1 {Id=3 Name=hubConnection.setEnabled}
[2019-12-13 14:50:19.895 Agent V] < #1 {Id=3}
[2019-12-13 14:50:19.925 Agent.Rpc.Auth V] > #1 {Id=4 Name=auth.login}
[2019-12-13 14:50:19.944 Agent.Auth I] isValidExpirationTime=True (12/28/19 6:31:46 PM - 12/14/19 2:50:19 PM)
[2019-12-13 14:50:19.944 Agent.Auth I] isValidIssuedAtTime=True (12/4/19 6:31:46 PM - 24)
[2019-12-13 14:50:19.945 Agent.Auth I] isValidAudience=True (https://insiders.liveshare.vsengsaas.visualstudio.com/api/v1.2/ - https://insiders.liveshare.vsengsaas.visualstudio.com/)
[2019-12-13 14:50:19.945 Agent.Auth I] Passed token validation.
[2019-12-13 14:50:19.949 Agent V] SetUserInfoAsync -> <null>
[2019-12-13 14:50:19.955 Agent.Rpc.Auth V] < #1 {Name=auth.authenticationChanged}
[2019-12-13 14:50:19.988 Agent.Http V] < GET <https://prod.liveshare.vsengsaas.visualstudio.com/38df5373>
[2019-12-13 14:50:19.990 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:20.003 Agent V] > #1 {Id=5 Name=sessionContext.getServiceNames}
[2019-12-13 14:50:20.005 Agent V] < #1 {Id=5}
[2019-12-13 14:50:20.016 Agent V] > #1 {Id=6 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:20.052 Agent V] < #1 {Id=6}
[2019-12-13 14:50:20.622 Agent.Http V] > GET <https://prod.liveshare.vsengsaas.visualstudio.com/38df5373> (c633ms s<null>ms) => 200 OK
[2019-12-13 14:50:20.671 Agent V] SetUserInfoAsync -> <36:1d18d881>
[2019-12-13 14:50:20.672 Agent V] UpdateHubClientConnection->StartAsync
[2019-12-13 14:50:20.673 HubConnectionService V] StartAsync
[2019-12-13 14:50:20.679 HubConnectionService V] hubConnection.StartAsync -> retries:0
[2019-12-13 14:50:20.753 Agent.Rpc.Auth V] < #1 {Name=auth.authenticationChanged}
[2019-12-13 14:50:20.775 Agent.Auth V] SecKeychainFindGenericPassword(<8:670d0e51>, <28:95790fbc>) => Success
[2019-12-13 14:50:20.800 Agent.Auth V] SecKeychainItemDelete(<8:670d0e51>, <28:95790fbc>) => Success
[2019-12-13 14:50:20.848 Agent.Auth V] SecKeychainAddGenericPassword(<8:670d0e51>, <28:95790fbc>) => Success
[2019-12-13 14:50:20.848 Agent.Rpc.Auth V] < #1 {Id=4}
[2019-12-13 14:50:20.851 Agent.Rpc.Auth V] > #1 {Id=7 Name=auth.getCurrentUser}
[2019-12-13 14:50:20.851 Agent.Http V] < GET <https://prod.liveshare.vsengsaas.visualstudio.com/38df5373>
[2019-12-13 14:50:20.851 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:20.855 Agent.Rpc.Auth V] > #1 {Id=8 Name=auth.getCurrentUser}
[2019-12-13 14:50:20.855 Agent.Http V] < GET <https://prod.liveshare.vsengsaas.visualstudio.com/38df5373>
[2019-12-13 14:50:20.855 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:21.031 Agent.Http V] > GET <https://prod.liveshare.vsengsaas.visualstudio.com/38df5373> (c179ms s<null>ms) => 200 OK
[2019-12-13 14:50:21.032 Agent.Rpc.Auth V] < #1 {Id=7}
[2019-12-13 14:50:21.475 Agent.Http V] > GET <https://prod.liveshare.vsengsaas.visualstudio.com/38df5373> (c619ms s<null>ms) => 200 OK
[2019-12-13 14:50:21.476 Agent.Rpc.Auth V] < #1 {Id=8}
[2019-12-13 14:50:23.028 HubConnectionService V] Succesfully connected...
[2019-12-13 14:50:23.034 Agent V] ConnectionStateChanged IsConnected:True
[2019-12-13 14:50:23.037 ContactService-LiveShare V] PublishUserInfoAsync -> id:<36:1d18d881><EMAIL_ADDRESS>[2019-12-13 14:50:23.071 Agent V] > #1 {Id=9 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:23.082 ContactService-LiveShare V] Request subscription for -> id:<36:1d18d881>
[2019-12-13 14:50:23.252 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:1d18d881> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 }<EMAIL_ADDRESS>HOST NAME |status=1|avatarUri=https://avatars2.githubusercontent.com/u/ HOST ID ?v=4|liveshareStatus=2
[2019-12-13 14:50:23.255 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:1d18d881>
[2019-12-13 14:50:23.281 ContactService-LiveShare V] RegisterInfo -> contactId=<36:1d18d881>|connectiondId=aErCZxoN6836mKZT0TZ8dw2baf7f311|serviceId=598f390c-462b-4001-a2fe-2ab7a7856cf3|stamp=use
[2019-12-13 14:50:23.283 ContactService-LiveShare V] Request subscription result for contact Id:<36:1d18d881> properties:status=1|liveshareStatus=2|name= HOST NAME |avatarUri=https://avatars2.githubusercontent.com/u/ HOST ID ?v=4|email=<00841ff1@gmail.com>|_Id=<36:1d18d881>
[2019-12-13 14:50:23.287 Agent V] < #1 {Id=9}
[2019-12-13 14:50:23.290 Agent V] > #1 {Id=10 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:23.290 ContactService-LiveShare V] Request subscription for -> id:<36:3ea7cb70>
[2019-12-13 14:50:23.439 ContactService-LiveShare V] Request subscription result for contact Id:<36:3ea7cb70> properties:_Id=<36:3ea7cb70>
[2019-12-13 14:50:23.439 Agent V] < #1 {Id=10}
[2019-12-13 14:50:23.441 Agent V] > #1 {Id=11 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:23.442 ContactService-LiveShare V] Request subscription for -> id:<36:bb3d624c>
[2019-12-13 14:50:23.595 ContactService-LiveShare V] Request subscription result for contact Id:<36:bb3d624c> properties:status=1|liveshareStatus=2|name= GUEST NAME |avatarUri=https://avatars0.githubusercontent.com/u/ GUEST ID ?v=4|email=<1bd87719@gmail.com>|_Id=<36:bb3d624c>
[2019-12-13 14:50:23.595 Agent V] < #1 {Id=11}
[2019-12-13 14:50:23.597 Agent V] > #1 {Id=12 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:23.597 Agent V] < #1 {Id=12}
[2019-12-13 14:50:25.272 Agent V] > #1 {Id=13 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:25.277 Agent.Rpc.Firewall V] > #1 {Id=14 Name=firewall.getFirewallStatus}
[2019-12-13 14:50:25.278 Agent.Rpc.Firewall V] < #1 {Id=14}
[2019-12-13 14:50:25.280 Agent.Rpc.Workspace V] > #1 {Id=15 Name=workspace.shareWorkspace}
[2019-12-13 14:50:25.349 Agent.Workspace V] Using local IP addresses: [(<IP_ADDRESS>, <IP_ADDRESS>), (<IP_ADDRESS>, <IP_ADDRESS>)]
[2019-12-13 14:50:25.425 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:1d18d881> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 } properties:liveshareStatus=5
[2019-12-13 14:50:25.425 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:1d18d881>
[2019-12-13 14:50:25.452 Agent V] < #1 {Id=13}
[2019-12-13 14:50:26.047 Agent.Workspace I] Creating new workspace: 0246016A84581D8C005A (<4:5c7b09af>)
[2019-12-13 14:50:26.055 Agent.Http V] < POST <https://prod.liveshare.vsengsaas.visualstudio.com/04337a48>
[2019-12-13 14:50:26.055 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:26.262 Agent.Http V] > POST <https://prod.liveshare.vsengsaas.visualstudio.com/04337a48> (c207ms s<null>ms) => 200 OK
[2019-12-13 14:50:26.291 Agent.Rpc.Workspace V] < #1 {Name=workspace.progressUpdated}
[2019-12-13 14:50:26.723 Agent.Rpc.Workspace V] < #1 {Name=workspace.progressUpdated}
[2019-12-13 14:50:26.730 Agent I] Listening on port 5990
[2019-12-13 14:50:26.731 Agent.Rpc.Workspace V] < #1 {Name=workspace.progressUpdated}
[2019-12-13 14:50:26.733 Agent-ObjectTracker V] Add item:SharedWorkspace Id:775F8536E4BE878C6A67134B13D1AFFABA82 count:3
[2019-12-13 14:50:26.746 Agent.Rpc.Workspace V] < #1 {Name=workspace.servicesChanged}
[2019-12-13 14:50:26.760 Agent.Rpc.Workspace V] < #1 {Id=15}
[2019-12-13 14:50:26.789 Agent W] Service not available: sourceControl
[2019-12-13 14:50:26.794 Agent.Rpc.File V] < #1 {Name=file.filesChanged}
[2019-12-13 14:50:26.808 Agent.Rpc.File V] < #1 {Name=file.filesChanged}
[2019-12-13 14:50:26.808 Agent.Rpc.File V] < #1 {Name=file.filesChanged}
[2019-12-13 14:50:26.817 Agent.Rpc.Workspace V] > #1 {Id=17 Name=workspace.registerServices}
[2019-12-13 14:50:26.830 Agent.Rpc.Workspace V] < #1 {Id=17}
[2019-12-13 14:50:26.833 Agent.Rpc.Workspace V] > #1 {Id=19 Name=workspace.registerServices}
[2019-12-13 14:50:26.834 Agent.Rpc.Workspace V] < #1 {Id=19}
[2019-12-13 14:50:26.834 Agent.WorkspaceSession.AccessControl V] > #1 {Id=20 Name=workspaceAccessControl.getAccessControl}
[2019-12-13 14:50:26.835 Agent.WorkspaceSession.AccessControl V] < #1 {Id=20}
[2019-12-13 14:50:26.835 Agent.Rpc.Workspace V] > #1 {Id=21 Name=workspace.registerServices}
[2019-12-13 14:50:26.836 Agent.Rpc.Workspace V] < #1 {Id=21}
[2019-12-13 14:50:26.836 Agent.Rpc.Workspace V] > #1 {Id=22 Name=workspace.registerServices}
[2019-12-13 14:50:26.836 Agent.Rpc.Workspace V] < #1 {Id=22}
[2019-12-13 14:50:26.837 Agent.Rpc.Workspace V] > #1 {Id=23 Name=workspace.registerServices}
[2019-12-13 14:50:26.837 Agent.Rpc.Workspace V] < #1 {Id=23}
[2019-12-13 14:50:26.838 Agent.Rpc.Workspace V] > #1 {Id=24 Name=workspace.registerServices}
[2019-12-13 14:50:26.838 Agent.Rpc.Workspace V] < #1 {Id=24}
[2019-12-13 14:50:26.838 Agent.Rpc.File V] > #1 {Id=25 Name=file.list}
[2019-12-13 14:50:26.873 Agent.Rpc.File V] < #1 {Id=25}
[2019-12-13 14:50:26.876 Agent V] > #1 {Id=26 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:26.877 Agent V] > #1 {Id=27 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:26.877 Agent.Rpc.Workspace V] > #1 {Id=28 Name=workspace.registerServices}
[2019-12-13 14:50:26.877 Agent.Rpc.Workspace V] < #1 {Id=28}
[2019-12-13 14:50:26.925 Agent.File I] Workspace indexing time: 0.1924937 seconds
[2019-12-13 14:50:27.026 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:1d18d881> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 } properties:liveshareStatus=6
[2019-12-13 14:50:27.026 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:1d18d881>
[2019-12-13 14:50:27.033 Agent V] < #1 {Id=26}
[2019-12-13 14:50:27.034 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:1d18d881> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 } properties:status=3
[2019-12-13 14:50:27.034 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:1d18d881>
[2019-12-13 14:50:27.044 Agent V] < #1 {Id=27}
[2019-12-13 14:50:27.076 Agent.Rpc.ServerSharing V] > #1 {Id=29 Name=serverSharing.getSharedServers}
[2019-12-13 14:50:27.082 Agent.Rpc.ServerSharing V] < #1 {Id=29}
[2019-12-13 14:50:27.084 Agent.Rpc.Terminal V] > #1 {Id=30 Name=terminal.getRunningTerminals}
[2019-12-13 14:50:27.088 Agent.Rpc.Terminal V] < #1 {Id=30}
[2019-12-13 14:50:28.485 Agent-ObjectTracker V] Remove item:KeepAliveAgent reason:AgentStarted delay:10000 count:2 others:[RpcSession #1,SharedWorkspace Id:775F8536E4BE878C6A67134B13D1AFFABA82]
[2019-12-13 14:50:31.730 Agent V] > #1 {Id=31 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:31.938 Agent V] < #1 {Id=31}
[2019-12-13 14:50:32.101 Agent-PresenceServiceProxy V] MessageReceived-> targetContact:{ Id:<36:1d18d881> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 } fromContact:{ Id:<36:bb3d624c> connectionId:SXBIBY4m6kzgOsTbOF38bw2baf7f311 } messageType:inviteStatusChanged body:{}
[2019-12-13 14:50:35.660 Agent-PresenceServiceProxy V] MessageReceived-> targetContact:{ Id:<36:1d18d881> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 } fromContact:{ Id:<36:bb3d624c> connectionId:SXBIBY4m6kzgOsTbOF38bw2baf7f311 } messageType:inviteStatusChanged body:{}
[2019-12-13 14:50:35.906 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:bb3d624c> connectionId:SXBIBY4m6kzgOsTbOF38bw2baf7f311 } properties:liveshareStatus=7
[2019-12-13 14:50:35.906 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:bb3d624c>
[2019-12-13 14:50:36.888 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:bb3d624c> connectionId:SXBIBY4m6kzgOsTbOF38bw2baf7f311 } properties:liveshareStatus=4
[2019-12-13 14:50:36.888 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:bb3d624c>
[2019-12-13 14:50:54.332 Agent-PresenceServiceProxy V] ConnectionChanged-> contact:{ Id:<36:bb3d624c> connectionId:xi6zifsNbaXzFTaBtbQDwAc464ad9b1 } changeType:Added
[2019-12-13 14:50:54.332 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:bb3d624c> connectionId:xi6zifsNbaXzFTaBtbQDwAc464ad9b1 }<EMAIL_ADDRESS>GUEST NAME |status=1|avatarUri=https://avatars0.githubusercontent.com/u/ GUEST ID ?v=4|liveshareStatus=7
[2019-12-13 14:50:54.332 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:bb3d624c>
[2019-12-13 14:50:54.813 Agent-PresenceServiceProxy V] MessageReceived-> targetContact:{ Id:<36:1d18d881> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 } fromContact:{ Id:<36:bb3d624c> connectionId:xi6zifsNbaXzFTaBtbQDwAc464ad9b1 } messageType:inviteStatusChanged body:{}
[2019-12-13 14:50:54.981 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:bb3d624c> connectionId:xi6zifsNbaXzFTaBtbQDwAc464ad9b1 } properties:liveshareStatus=4
[2019-12-13 14:50:54.981 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:bb3d624c>
[2019-12-13 14:50:55.864 Agent.Rpc.Workspace V] > #1 {Name=sourceControl.sourceControlChanged}
[2019-12-13 14:51:00.568 Agent-PresenceServiceProxy V] ConnectionChanged-> contact:{ Id:<36:bb3d624c> connectionId:SXBIBY4m6kzgOsTbOF38bw2baf7f311 } changeType:Removed
[2019-12-13 14:51:09.958 Agent-PresenceServiceProxy V] ConnectionChanged-> contact:{ Id:<36:bb3d624c> connectionId:xi6zifsNbaXzFTaBtbQDwAc464ad9b1 } changeType:Removed
[2019-12-13 14:51:09.959 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:bb3d624c> connectionId:xi6zifsNbaXzFTaBtbQDwAc464ad9b1 } properties:name=<null>|liveshareStatus=<null>|email=<null>|status=<null>|avatarUri=<null>
[2019-12-13 14:51:09.959 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:bb3d624c>
[2019-12-13 14:51:12.827 Agent-PresenceServiceProxy V] ConnectionChanged-> contact:{ Id:<36:bb3d624c> connectionId:idcQYgywLlbxHYoe1cfa0gf037b7951 } changeType:Added
[2019-12-13 14:51:12.828 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:bb3d624c> connectionId:idcQYgywLlbxHYoe1cfa0gf037b7951 }<EMAIL_ADDRESS>GUEST NAME |status=1|avatarUri=https://avatars0.githubusercontent.com/u/ GUEST ID ?v=4|liveshareStatus=2
[2019-12-13 14:51:12.828 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:bb3d624c>
[2019-12-13 14:51:20.344 Agent.Rpc.Workspace V] > #1 {Name=sourceControl.sourceControlChanged}

Host vscode log:
[2019-12-13 14:50:01.882 Contact.Manager V] onActivityStatusChanged-> id:idle newStatus:available publishedPresenceStatus:undefined
[2019-12-13 14:50:01.882 Contact.Service V] Request 'publishPresence' serviceId:LivesharePresence
[2019-12-13 14:50:01.883 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:02.037 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:02.037 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:02.041 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:02.668 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:02.668 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:02.668 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:02.669 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:02.669 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:02.669 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:02.669 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:02.668 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:02.669 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:02.669 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:02.669 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:02.669 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:04.420 Client.CoEditing.VSCodeEvent V] onDidChangeTextEditorSelection (</<redacted>/<redacted>.json>): [{"start":{"line":0,"character":0},"end":{"line":0,"character":0},"active":{"line":0,"character":0},"anchor":{"line":0,"character":0}}]
[2019-12-13 14:50:04.420 Client.CoEditing.VSCodeEvent V] onDidChangeTextEditorVisibleRanges (</<redacted>/<redacted>.json>): [[{"line":0,"character":0},{"line":1,"character":0}]]
[2019-12-13 14:50:04.421 Client.CoEditing.VSCodeEvent V] onDidChangeVisibleTextEditors (new active viewcolumns: 1)
[2019-12-13 14:50:04.425 Client.CoEditing.VSCodeEvent I] onDidChangeActiveTextEditor (</<redacted>/<redacted>.php>)
[2019-12-13 14:50:04.426 Client.CoEditing.VSCodeEvent V] onDidChangeTextEditorSelection (</<redacted>/<redacted>.php>): [{"start":{"line":1,"character":0},"end":{"line":1,"character":0},"active":{"line":1,"character":0},"anchor":{"line":1,"character":0}}]
[2019-12-13 14:50:04.427 Client.Rpc.SourceEvent V] < sourceEvent.fireEvent()
[2019-12-13 14:50:04.427 Client.Rpc.File V] < file.list()
[2019-12-13 14:50:04.426 Client.CoEditing.BufferManager V] Undo: Clearing Local Undo State due to document close: </<redacted>/<redacted>.json>
[2019-12-13 14:50:04.427 Client.Rpc.SourceEvent V] < sourceEvent.fireEvent()
[2019-12-13 14:50:04.429 Client.Rpc.SourceEvent V] > sourceEvent.event:
[2019-12-13 14:50:04.429 Client.Rpc.File V] > file.list() succeeded
[2019-12-13 14:50:04.430 Client.Rpc.SourceEvent V] > sourceEvent.fireEvent() succeeded
[2019-12-13 14:50:04.430 Client.Rpc.SourceEvent V] > sourceEvent.event:
[2019-12-13 14:50:04.430 Client.Rpc.SourceEvent V] > sourceEvent.fireEvent() succeeded
[2019-12-13 14:50:04.479 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:04.479 Client.LSP V] sendNotification-> languageServerProvider-any.notified:
[2019-12-13 14:50:06.247 Debug.Rpc.Host V] < workspace.registerServices()
[2019-12-13 14:50:06.249 Debug.Rpc.Host V] > workspace.registerServices() succeeded
[2019-12-13 14:50:06.249 Client.Rpc.Workspace V] < workspace.registerServices()
[2019-12-13 14:50:06.250 Client.Rpc.Workspace V] > workspace.registerServices() succeeded
[2019-12-13 14:50:06.250 Client.Rpc.Workspace V] < workspace.registerServices()
[2019-12-13 14:50:06.251 Client.Rpc.Workspace V] > workspace.registerServices() succeeded
[2019-12-13 14:50:06.251 Client.Rpc.Workspace V] < workspace.unshareWorkspace()
[2019-12-13 14:50:06.254 Client.Rpc.Workspace V] > workspace.connectionStatusChanged:
[2019-12-13 14:50:06.254 Client.Rpc.Workspace V] > workspace.servicesChanged:
[2019-12-13 14:50:07.052 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:07.257 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:07.259 Client.Rpc.Workspace V] > workspace.unshareWorkspace() succeeded
[2019-12-13 14:50:07.259 Client.Rpc.Workspace V] < workspace.registerServices()
[2019-12-13 14:50:07.262 Client.Rpc.Workspace E] > workspace.registerServices() error: undefined
[2019-12-13 14:50:07.263 Client.Rpc.Workspace V] < workspace.registerServices()
[2019-12-13 14:50:07.264 Contact.Manager V] ->loadRecentCollaborators:onStateChanged
[2019-12-13 14:50:07.264 Contact.Service V] Request 'contactPresenceDispose' serviceId:LivesharePresence
[2019-12-13 14:50:07.264 Contact.Service V] Request 'contactPresenceDispose' serviceId:LivesharePresence
[2019-12-13 14:50:07.264 Contact.Service V] Request 'contactPresenceDispose' serviceId:LivesharePresence
[2019-12-13 14:50:07.264 Contact.Service V] Request 'contactPresenceDispose' serviceId:LivesharePresence
[2019-12-13 14:50:07.264 Contact.Service V] Request 'contactPresenceDispose' serviceId:LivesharePresence
[2019-12-13 14:50:07.265 Client.Rpc.Workspace V] < workspace.registerServices()
[2019-12-13 14:50:07.265 API V] ^onDidChangeSession(None)
[2019-12-13 14:50:07.265 API V] unshareService(eamodio.gitlens.proxy)
[2019-12-13 14:50:07.265 API V] ^onDidChangeIsServiceAvailable(eamodio.gitlens.proxy, false)
[2019-12-13 14:50:07.266 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:07.266 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:07.266 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:07.266 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:07.266 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:07.266 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:07.266 Client.Rpc.Auth V] < auth.getCurrentUser()
[2019-12-13 14:50:07.266 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:07.264 Contact.Service V] Request 'publishPresence' serviceId:LivesharePresence
[2019-12-13 14:50:07.265 Contact.Manager V] onActivityStatusChanged-> id:liveshare-State newStatus:unknown publishedPresenceStatus:undefined
[2019-12-13 14:50:07.265 Contact.Service V] Request 'publishPresence' serviceId:LivesharePresence
[2019-12-13 14:50:07.267 Client.Rpc.Workspace E] > workspace.registerServices() error: undefined
[2019-12-13 14:50:07.267 Client.Rpc.Workspace E] > workspace.registerServices() error: undefined
[2019-12-13 14:50:07.268 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:07.268 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:07.270 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:07.422 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:07.426 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:07.427 Client.Rpc.Auth V] < auth.getCurrentUser()
[2019-12-13 14:50:07.432 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:07.432 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:07.455 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:07.563 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:07.564 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:07.564 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:07.564 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:07.957 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:07.958 Client.Rpc.Auth V] > auth.getCurrentUser() succeeded
[2019-12-13 14:50:07.958 Contact.Manager V] SelfInviteProvider-> status:true
[2019-12-13 14:50:07.958 Contact.Service V] onNotified-> serviceId:selfInvite name:suggestedUsers
[2019-12-13 14:50:07.958 Contact.Manager I] onSuggestedUsersUpdate-> serviceId:selfInvite count:1
[2019-12-13 14:50:07.958 Contact.Manager V] ->updateRecentContacts -> count:0
[2019-12-13 14:50:07.958 Contact.Manager V] <-updateRecentContacts
[2019-12-13 14:50:07.959 Contact.Manager V] ->updateSuggestedUsers
[2019-12-13 14:50:07.959 Contact.Service V] Request 'contactPresenceDispose' serviceId:LivesharePresence
[2019-12-13 14:50:07.959 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:07.960 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:07.960 Contact.Manager V] suggest contacts from provider:git ids: emails:
[2019-12-13 14:50:07.960 Contact.Manager V] suggest contacts from provider:selfInvite ids:<36:<redacted>> emails:<<red@acted>>
[2019-12-13 14:50:07.960 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:07.960 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:07.960 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:07.961 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:07.962 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:07.962 Contact.Manager I] <- updateSuggestedUsers count:1
[2019-12-13 14:50:08.046 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:08.047 Client.Rpc.Auth V] > auth.getCurrentUser() succeeded
[2019-12-13 14:50:08.047 Contact.Manager V] loading recent collaborators count:3
[2019-12-13 14:50:08.047 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<9:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:08.047 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:08.047 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:08.047 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<11:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:08.047 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:08.047 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:08.047 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:08.047 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:08.047 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:08.047 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:08.047 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:08.047 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<13:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:08.049 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:08.049 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:08.203 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:08.203 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:08.206 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:08.206 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:09.386 API V] unshareService(eamodio.gitlens.proxy)
[2019-12-13 14:50:09.394 Client I] Client deactivation requested.
[2019-12-13 14:50:09.394 Contact.Service V] Request 'dispose' serviceId:LivesharePresence
[2019-12-13 14:50:17.527 Client.Rpc V] RPCClient.init retryCount:0 currentRetryInterval:0

[2019-12-13 14:50:17.531 Debug.Rpc.Host V] TrackDebugManager created
[2019-12-13 14:50:17.542 Client.Rpc V] Agent connection not completed: Error: connect ENOENT /var/folders/f6/w2qs6k9d73xc1rg118hty93r0000gn/T/CoreFxPipe_vscode.b617c5cf81ef701b90f8d07b7f963f04; Retrying...
[2019-12-13 14:50:17.543 Client.Rpc V] RPCClient.init retryCount:1 currentRetryInterval:100
[2019-12-13 14:50:17.544 Client.Agent V] Agent lock on:</<redacted>/<redacted>.lock>
[2019-12-13 14:50:17.546 Client.Agent I] starting agent with pipe:vscode.b617c5cf81ef701b90f8d07b7f963f04
[2019-12-13 14:50:17.546 Client.Agent I] signalR service -> uri:https://signlr-prod.core.vsengsaas.visualstudio.com/api/signalr/signalrhub
[2019-12-13 14:50:18.535 Client.Agent V] Finished agent startup...
[2019-12-13 14:50:18.536 Client.Agent V] Agent unlocked
[2019-12-13 14:50:18.537 Client.Rpc I] Agent connection success - net.pipe://localhost/vscode.b617c5cf81ef701b90f8d07b7f963f04
[2019-12-13 14:50:19.858 Client.Rpc V] finish initialize agent session with rpcConnectId:null hostSessionId:1
[2019-12-13 14:50:19.859 Client.Rpc.Auth V] < version.exchangeVersions()
[2019-12-13 14:50:19.874 Client.Rpc.Auth V] > version.exchangeVersions() succeeded
[2019-12-13 14:50:19.875 Client I] Client version: 1.0.1377 (VSLS/2.2), agent version: 1.0.1377.64440 (VSLS/2.2)
[2019-12-13 14:50:19.875 Client.Rpc.Auth V] < version.exchangeSettings()
[2019-12-13 14:50:19.892 Client.Rpc.Auth V] > version.exchangeSettings() succeeded
[2019-12-13 14:50:19.893 Client I] Hub connection is being enabled...
[2019-12-13 14:50:19.893 Hub.Connection V] < hubConnection.setEnabled()
[2019-12-13 14:50:19.896 Hub.Connection V] > hubConnection.setEnabled() succeeded
[2019-12-13 14:50:19.899 Command:Object I] Decorator: Telemetry Starting
[2019-12-13 14:50:19.899 Command:Object I] Decorator: EnableVSCodeAccountAuth Starting
[2019-12-13 14:50:19.900 Command:Object I] Decorator: EnableVSCodeAccountAuth Completed Success (1ms)
[2019-12-13 14:50:19.900 Command:Object I] Command []: EnableVSCodeAccountAuth Success (1ms)
[2019-12-13 14:50:19.900 Command:Object I] Decorator: Telemetry Completed Success (1ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:19.900 Client I] No workspaceId found in workspace settings.
[2019-12-13 14:50:19.914 API I] Initializing Live Share API 0.3.1291 for<EMAIL_ADDRESS>[2019-12-13 14:50:19.919 Client I] No workspace url found in cascade.json file.
[2019-12-13 14:50:19.920 Command:Object I] Decorator: CancellationDecorator Starting
[2019-12-13 14:50:19.920 Command:Object I] Decorator: ErrorNotification Starting
[2019-12-13 14:50:19.920 Command:Object I] Decorator: Telemetry Starting
[2019-12-13 14:50:19.920 Command:Object I] Decorator: TelemetryStatus Starting
[2019-12-13 14:50:19.920 Command:Object I] Decorator: Validation Starting
[2019-12-13 14:50:19.920 Command:Object I] Decorator: SessionStateTransitions Starting
[2019-12-13 14:50:19.922 Command:Object I] SessionContext: State update from "Initializing" (undefined) to "SigningIn" (AttemptSignIn).
[2019-12-13 14:50:19.922 Command:Object I] Decorator: Authentication Starting
[2019-12-13 14:50:19.923 Command:Object I] SessionContext: Status update from "SigninCheckStarting" to "AttemptDefaultLoginStarting".
[2019-12-13 14:50:19.923 Command:Object I] SessionContext: Status update from "undefined" to "SigninCheckStarting".
[2019-12-13 14:50:19.924 Command:Object I] SessionContext: Status update from "AttemptDefaultLoginStarting" to "AttemptLoginStarting".
[2019-12-13 14:50:19.925 Client.Rpc.Auth V] < auth.login()
[2019-12-13 14:50:19.933 Contact.Manager I] registerContactProvider-> serviceId:git
[2019-12-13 14:50:19.933 Contact.Service V] _Initialize:git
[2019-12-13 14:50:19.933 Contact.Service V] Request 'initialize' serviceId:git
[2019-12-13 14:50:19.938 Contact.Manager I] registerContactProvider-> serviceId:selfInvite
[2019-12-13 14:50:19.939 Contact.Service V] _Initialize:selfInvite
[2019-12-13 14:50:19.939 Contact.Service V] Request 'initialize' serviceId:selfInvite
[2019-12-13 14:50:19.939 Client.Rpc.SessionContext V] < sessionContext.getServiceNames()
[2019-12-13 14:50:19.974 Client.Rpc.Auth V] > auth.authenticationChanged:
[2019-12-13 14:50:20.014 Client.Rpc.SessionContext V] > sessionContext.getServiceNames() succeeded
[2019-12-13 14:50:20.014 Contact.Manager I] registerContactProvider-> serviceId:LivesharePresence
[2019-12-13 14:50:20.015 Contact.Service V] Request 'initialize' serviceId:LivesharePresence
[2019-12-13 14:50:20.015 Contact.Service V] _Initialize:LivesharePresence
[2019-12-13 14:50:20.015 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:20.035 Contact.Service V] onNotified-> serviceId:git name:suggestedUsers
[2019-12-13 14:50:20.035 Contact.Manager I] onSuggestedUsersUpdate-> serviceId:git count:0
[2019-12-13 14:50:20.035 Contact.Manager V] ->updateRecentContacts -> count:0
[2019-12-13 14:50:20.035 Contact.Manager V] <-updateRecentContacts
[2019-12-13 14:50:20.036 Contact.Manager V] ->updateSuggestedUsers
[2019-12-13 14:50:20.036 Contact.Manager V] suggest contacts from provider:git ids: emails:
[2019-12-13 14:50:20.036 Contact.Manager I] <- updateSuggestedUsers count:0
[2019-12-13 14:50:20.062 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:20.635 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:20.753 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:20.753 Contact.Service V] onNotified-> serviceId:LivesharePresence name:signedStateChanged
[2019-12-13 14:50:20.758 Client.Rpc.Auth V] > auth.authenticationChanged:
[2019-12-13 14:50:20.848 Client.Rpc.Auth V] > auth.login() succeeded
[2019-12-13 14:50:20.849 Command:Object I] SessionContext: Status update from "AttemptLoginStarting" to "AttemptLoginComplete".
[2019-12-13 14:50:20.849 Command:Object I] SessionContext: Status update from "AttemptLoginComplete" to "AttemptDefaultLoginComplete".
[2019-12-13 14:50:20.849 Command:Object I] SessionContext: State update from "SigningIn" (AttemptSignIn) to "SignedIn" (SignInSuccess).
[2019-12-13 14:50:20.850 Contact.Manager V] ->loadRecentCollaborators:onStateChanged
[2019-12-13 14:50:20.850 Command:Object I] SessionContext: Status update from "AttemptDefaultLoginComplete" to "SigninCheckCompleted".
[2019-12-13 14:50:20.850 Client.Rpc.Auth V] < auth.getCurrentUser()
[2019-12-13 14:50:20.851 Command:Object I] Decorator: Authentication Completed Success (928ms abs=927ms pre=-23ms post=950)
[2019-12-13 14:50:20.851 Client.Rpc.Auth V] < auth.getCurrentUser()
[2019-12-13 14:50:20.851 Command:Object I] Decorator: SessionStateTransitions Completed Success (931ms abs=3ms pre=2ms post=1)
[2019-12-13 14:50:20.851 Command:Object I] Decorator: Validation Completed Success (931ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:20.851 Command:Object I] Decorator: TelemetryStatus Completed Success (931ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:20.851 Command:Object I] Command [Signing in]: ExplicitSignIn Success (931ms)
[2019-12-13 14:50:20.851 Command:Object I] Decorator: Telemetry Completed Success (931ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:20.851 Command:Object I] Decorator: ErrorNotification Completed Success (931ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:20.851 Command:Object I] Decorator: CancellationDecorator Completed Success (931ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:21.032 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:21.033 Client.Rpc.Auth V] > auth.getCurrentUser() succeeded
[2019-12-13 14:50:21.033 Contact.Manager V] SelfInviteProvider-> status:true
[2019-12-13 14:50:21.033 Contact.Service V] onNotified-> serviceId:selfInvite name:suggestedUsers
[2019-12-13 14:50:21.033 Contact.Manager I] onSuggestedUsersUpdate-> serviceId:selfInvite count:1
[2019-12-13 14:50:21.033 Contact.Manager V] ->updateRecentContacts -> count:0
[2019-12-13 14:50:21.034 Contact.Manager V] <-updateRecentContacts
[2019-12-13 14:50:21.034 Contact.Manager V] ->updateSuggestedUsers
[2019-12-13 14:50:21.034 Contact.Manager V] suggest contacts from provider:git ids: emails:
[2019-12-13 14:50:21.034 Contact.Manager V] suggest contacts from provider:selfInvite ids:<36:<redacted>> emails:<<red@acted>>
[2019-12-13 14:50:21.034 Contact.Manager I] <- updateSuggestedUsers count:1
[2019-12-13 14:50:21.476 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:21.476 Client.Rpc.Auth V] > auth.getCurrentUser() succeeded
[2019-12-13 14:50:21.477 Contact.Manager V] loading recent collaborators count:3
[2019-12-13 14:50:21.477 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<9:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:21.477 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<11:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:21.477 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<13:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:23.030 Hub.Connection V] > hubConnection.hubConnectionAttempted:
[2019-12-13 14:50:23.070 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:23.070 Contact.Service V] onNotified-> serviceId:LivesharePresence name:capabilitiesUpdate
[2019-12-13 14:50:23.070 Contact.Manager I] onCapabilitiesChanged-> serviceId:LivesharePresence old:{"supportsDispose":false,"supportsPresence":false,"supportsPublishPresence":false,"supportsInviteLink":false,"supportsContactPresenceRequest":false,"supportsAvailableContacts":null,"supportsSelfContact":false,"supportsSearchContacts":null,"supportsMessageRequest":false,"supportedPresenceStatus":["available","doNotDisturb","away"],"supportsSignedStateRequest":true} new:{"supportsDispose":true,"supportsPresence":true,"supportsPublishPresence":true,"supportsInviteLink":true,"supportsContactPresenceRequest":true,"supportsAvailableContacts":null,"supportsSelfContact":true,"supportsSearchContacts":null,"supportsMessageRequest":true,"supportedPresenceStatus":["available","doNotDisturb","away"],"supportsSignedStateRequest":true}
[2019-12-13 14:50:23.070 Contact.Manager V] ->updateRecentContacts -> count:3
[2019-12-13 14:50:23.070 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<9:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:23.071 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:23.071 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:23.071 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:23.071 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:23.071 Contact.Service V] onNotified-> serviceId:LivesharePresence name:signedStateChanged
[2019-12-13 14:50:23.071 Hub.Connection V] > hubConnection.hubConnectionStateChanged:
[2019-12-13 14:50:23.258 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:23.258 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:23.270 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:23.270 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactChanged
[2019-12-13 14:50:23.271 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:23.271 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:23.283 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:23.283 Contact.Service V] onNotified-> serviceId:LivesharePresence name:selfContact
[2019-12-13 14:50:23.289 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:23.289 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:23.290 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<11:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:23.290 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:23.290 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:23.290 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:23.440 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:23.440 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:23.440 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<13:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:23.440 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:23.440 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:23.441 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:23.595 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:23.596 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:23.596 Contact.Manager V] <-updateRecentContacts
[2019-12-13 14:50:23.596 Contact.Manager V] ->updateSuggestedUsers
[2019-12-13 14:50:23.596 Contact.Manager V] suggest contacts from provider:git ids: emails:
[2019-12-13 14:50:23.596 Contact.Manager V] suggest contacts from provider:selfInvite ids:<36:<redacted>> emails:<<red@acted>>
[2019-12-13 14:50:23.596 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:23.596 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:23.596 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:23.598 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:23.598 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:23.598 Contact.Manager I] <- updateSuggestedUsers count:0
[2019-12-13 14:50:25.265 Command:Object I] Decorator: CancellationDecorator Starting
[2019-12-13 14:50:25.266 Command:Object I] Decorator: ErrorNotification Starting
[2019-12-13 14:50:25.266 Command:Object I] Decorator: FirstRunTracking Starting
[2019-12-13 14:50:25.267 Command:Object I] HasRunPreviously: Starting collaboration session -> true
[2019-12-13 14:50:25.267 Command:Object I] Decorator: Telemetry Starting
[2019-12-13 14:50:25.267 Command:Object I] Decorator: TelemetryStatus Starting
[2019-12-13 14:50:25.267 Command:Object I] Decorator: Validation Starting
[2019-12-13 14:50:25.267 Command:Object I] Decorator: SessionStateTransitions Starting
[2019-12-13 14:50:25.267 Contact.Service V] Request 'publishPresence' serviceId:LivesharePresence
[2019-12-13 14:50:25.268 Command:Object I] SessionContext: State update from "SignedIn" (SignInSuccess) to "SharingInProgress" (AttemptSharing).
[2019-12-13 14:50:25.268 Command:Object I] Decorator: Progress Starting
[2019-12-13 14:50:25.269 Command:Object I] Decorator: InfoPage Starting
[2019-12-13 14:50:25.270 Command:Object I] Decorator: GaNotification Starting
[2019-12-13 14:50:25.270 Command:Object I] Decorator: ShareCommand Starting
[2019-12-13 14:50:25.271 Command:Object I] SessionContext: Status update from "SigninCheckCompleted" to "FirewallCheckStarting".
[2019-12-13 14:50:25.271 Client.Rpc V] < firewall.getFirewallStatus()
[2019-12-13 14:50:25.269 Command:Object I] Decorator: Authentication Starting
[2019-12-13 14:50:25.270 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:25.268 Progress I] Progress notifier opened: 0.
[2019-12-13 14:50:25.279 Client.Rpc V] > firewall.getFirewallStatus() succeeded
[2019-12-13 14:50:25.279 Command:Object I] SessionContext: Status update from "FirewallCheckStarting" to "FirewallCheckComplete".
[2019-12-13 14:50:25.279 Command:Object I] SessionContext: Status update from "FirewallCheckComplete" to "ShareWorkspaceStarting".
[2019-12-13 14:50:25.279 Client.Rpc.Workspace V] < workspace.shareWorkspace()
[2019-12-13 14:50:25.426 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:25.426 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:25.452 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:26.263 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:26.292 Client.Rpc.SessionContext V] > sessionContext.sessionStatusUpdate:
[2019-12-13 14:50:26.292 Command:Object I] SessionContext: Status update from "ShareWorkspaceStarting" to "CreatedWorkspace".
[2019-12-13 14:50:26.294 Client.Rpc.Workspace V] > workspace.progressUpdated:
[2019-12-13 14:50:26.724 Client.Rpc.SessionContext V] > sessionContext.sessionStatusUpdate:
[2019-12-13 14:50:26.724 Command:Object I] SessionContext: Status update from "CreatedWorkspace" to "InitializedAgentServices".
[2019-12-13 14:50:26.724 Client.Rpc.Workspace V] > workspace.progressUpdated:
[2019-12-13 14:50:26.731 Client.Rpc.SessionContext V] > sessionContext.sessionStatusUpdate:
[2019-12-13 14:50:26.731 Command:Object I] SessionContext: Status update from "InitializedAgentServices" to "InitializedRelayAndTCP".
[2019-12-13 14:50:26.732 Client.Rpc.Workspace V] > workspace.progressUpdated:
[2019-12-13 14:50:26.750 Client.Rpc.Workspace V] > workspace.servicesChanged:
[2019-12-13 14:50:26.776 Client.Rpc.Workspace V] > workspace.shareWorkspace() succeeded
[2019-12-13 14:50:26.776 Command:Object I] SessionContext: Status update from "InitializedRelayAndTCP" to "ShareWorkspaceComplete".
[2019-12-13 14:50:26.778 Client.CoEditing I] Name tag behavior: Activity
[2019-12-13 14:50:26.780 Client.CoEditing I] Creating file client for </<redacted>/<redacted>.php>
[2019-12-13 14:50:26.782 BreakpointManager V] initialize
[2019-12-13 14:50:26.783 BreakpointManager V] updateCollaborationBreakpoints total:0
[2019-12-13 14:50:26.787 Command:Object I] SessionContext: Status update from "ShareWorkspaceComplete" to "InitBreakpointComplete".
[2019-12-13 14:50:26.787 Command:Object I] SessionContext: Status update from "InitBreakpointComplete" to "InitCommentManagerComplete".
[2019-12-13 14:50:26.788 Client.Rpc.Workspace V] < workspace.registerServices()
[2019-12-13 14:50:26.788 Client.Rpc.SourceEvent V] < sourceEvent.setSourceData()
[2019-12-13 14:50:26.788 Debug.Rpc.Host V] < workspace.registerServices()
[2019-12-13 14:50:26.788 Client.Workspace.AccessControl V] < workspaceAccessControl.getAccessControl()
[2019-12-13 14:50:26.788 Client.Rpc.Workspace V] < workspace.registerServices()
[2019-12-13 14:50:26.788 Client.Rpc.Workspace V] < workspace.registerServices()
[2019-12-13 14:50:26.788 Client.Rpc.Workspace V] < workspace.registerServices()
[2019-12-13 14:50:26.788 Command:Object I] SessionContext: Status update from "InitTextSearchServiceComplete" to "InitTaskComplete".
[2019-12-13 14:50:26.788 Command:Object I] SessionContext: Status update from "InitTaskComplete" to "InitCoeditingComplete".
[2019-12-13 14:50:26.789 Client.Rpc.Workspace V] < workspace.registerServices()
[2019-12-13 14:50:26.787 Command:Object I] SessionContext: Status update from "InitCommentManagerComplete" to "InitTextSearchServiceComplete".
[2019-12-13 14:50:26.787 Client.Rpc.SourceEvent V] < sourceEvent.fireEvent()
[2019-12-13 14:50:26.787 SourceControl V] sendNotification-> sourceControl.sourceControlChanged:
[2019-12-13 14:50:26.793 Client.Rpc.File V] < file.list()
[2019-12-13 14:50:26.797 Client.Rpc.File V] > file.filesChanged:
[2019-12-13 14:50:26.811 Command:Object I] SessionContext: Status update from "InitCoeditingComplete" to "InitCommandComplete".
[2019-12-13 14:50:26.817 Client.Rpc.File V] > file.filesChanged:
[2019-12-13 14:50:26.830 Client.Rpc.File V] > file.filesChanged:
[2019-12-13 14:50:26.833 Client.Rpc.SourceEvent V] > sourceEvent.event:
[2019-12-13 14:50:26.834 Client.Rpc.SourceEvent V] > sourceEvent.fireEvent() succeeded
[2019-12-13 14:50:26.835 Client.Rpc.Workspace V] > workspace.registerServices() succeeded
[2019-12-13 14:50:26.837 Command:Object I] SessionContext: Status update from "InitCommandComplete" to "InitLspComplete".
[2019-12-13 14:50:26.837 Client.Rpc.SourceEvent V] > sourceEvent.setSourceData() succeeded
[2019-12-13 14:50:26.837 Command:Object I] SessionContext: Status update from "InitLspComplete" to "InitBreakpointComplete".
[2019-12-13 14:50:26.838 Debug.Rpc.Host V] > workspace.registerServices() succeeded
[2019-12-13 14:50:26.838 Command:Object I] SessionContext: Status update from "InitBreakpointComplete" to "InitDebuggingComplete".
[2019-12-13 14:50:26.838 Client.Workspace.AccessControl V] > workspaceAccessControl.getAccessControl() succeeded
[2019-12-13 14:50:26.838 Command:Object I] SessionContext: Status update from "InitDebuggingComplete" to "InitAccessControlComplete".
[2019-12-13 14:50:26.839 Client.Rpc.Workspace V] > workspace.registerServices() succeeded
[2019-12-13 14:50:26.839 Command:Object I] SessionContext: Status update from "InitAccessControlComplete" to "InitSourceControlServiceComplete".
[2019-12-13 14:50:26.868 Client.Rpc.Workspace V] > workspace.registerServices() succeeded
[2019-12-13 14:50:26.868 Command:Object I] SessionContext: Status update from "InitSourceControlServiceComplete" to "InitCommentServiceComplete".
[2019-12-13 14:50:26.868 Client.Rpc.Workspace V] > workspace.registerServices() succeeded
[2019-12-13 14:50:26.868 Command:Object I] SessionContext: Status update from "InitCommentServiceComplete" to "InitSyncServiceComplete".
[2019-12-13 14:50:26.870 Command:Object I] Decorator: ShareCommand Completed Success (1600ms abs=669ms pre=-5350ms post=6019)
[2019-12-13 14:50:26.870 Command:Object I] Decorator: GaNotification Completed Success (1600ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:26.870 Command:Object I] Decorator: Authentication Completed Success (1601ms abs=1ms pre=1ms post=0)
[2019-12-13 14:50:26.871 Command:Object I] Decorator: InfoPage Completed Success (1602ms abs=1ms pre=0ms post=1)
[2019-12-13 14:50:26.871 Progress I] Progress notifier finished: 0.
[2019-12-13 14:50:26.871 Command:Object I] Decorator: Progress Completed Success (1603ms abs=1ms pre=1ms post=0)
[2019-12-13 14:50:26.873 Contact.Service V] Request 'publishPresence' serviceId:LivesharePresence
[2019-12-13 14:50:26.873 Contact.Manager V] onActivityStatusChanged-> id:liveshare-State newStatus:doNotDisturb publishedPresenceStatus:undefined
[2019-12-13 14:50:26.873 Contact.Service V] Request 'publishPresence' serviceId:LivesharePresence
[2019-12-13 14:50:26.873 Command:Object I] SessionContext: State update from "SharingInProgress" (AttemptSharing) to "Shared" (SharingSuccess).
[2019-12-13 14:50:26.874 API V] ^onDidChangeSession(Host)
[2019-12-13 14:50:26.874 API V] shareService(eamodio.gitlens.proxy)
[2019-12-13 14:50:26.875 Command:Object I] Decorator: SessionStateTransitions Completed Success (1608ms abs=5ms pre=1ms post=4)
[2019-12-13 14:50:26.875 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:26.875 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:26.875 Command:Object I] Decorator: Validation Completed Success (1608ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:26.875 Command:Object I] Decorator: TelemetryStatus Completed Success (1608ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:26.875 Client.Rpc.Workspace V] < workspace.registerServices()
[2019-12-13 14:50:26.875 Command:Object I] Command [Starting collaboration session]: Share Success (1608ms)
[2019-12-13 14:50:26.875 Command:Object I] Decorator: Telemetry Completed Success (1608ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:26.876 Command:Object I] Decorator: FirstRunTracking Completed Success (1610ms abs=2ms pre=1ms post=1)
[2019-12-13 14:50:26.876 Command:Object I] Decorator: ErrorNotification Completed Success (1610ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:26.876 Command:Object I] Decorator: CancellationDecorator Completed Success (1611ms abs=1ms pre=1ms post=0)
[2019-12-13 14:50:26.876 Client.Rpc.Workspace V] > workspace.registerServices() succeeded
[2019-12-13 14:50:26.882 Client.Rpc.File V] > file.list() succeeded
[2019-12-13 14:50:26.882 Client.Rpc.Workspace V] > workspace.registerServices() succeeded
[2019-12-13 14:50:26.883 API V] ^onDidChangeIsServiceAvailable(eamodio.gitlens.proxy, true)
[2019-12-13 14:50:26.883 API V] onRequest(eamodio.gitlens.proxy.git)
[2019-12-13 14:50:26.883 API V] onRequest(eamodio.gitlens.proxy.repositories/inFolder)
[2019-12-13 14:50:27.026 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:27.026 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:27.034 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:27.034 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:27.034 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:27.045 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:27.075 Client.Rpc.ServerSharing V] < serverSharing.getSharedServers()
[2019-12-13 14:50:27.084 Client.Rpc.ServerSharing V] > serverSharing.getSharedServers() succeeded
[2019-12-13 14:50:27.084 Client.Rpc.PortForwarding V] < terminal.getRunningTerminals()
[2019-12-13 14:50:27.089 Client.Rpc.PortForwarding V] > terminal.getRunningTerminals() succeeded
[2019-12-13 14:50:31.729 Command:Object I] Decorator: Telemetry Starting
[2019-12-13 14:50:31.729 Command:Object I] Decorator: InviteContactCommand Starting
[2019-12-13 14:50:31.729 Contact.Service V] Request 'inviteLink' serviceId:LivesharePresence
[2019-12-13 14:50:31.729 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:31.938 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:31.939 Command:Object I] Decorator: InviteContactCommand Completed Success (210ms abs=-1401ms pre=-6464ms post=5063)
[2019-12-13 14:50:31.939 Command:Object I] Command []: InviteContact Success (210ms)
[2019-12-13 14:50:31.939 Command:Object I] Decorator: Telemetry Completed Success (210ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:32.105 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:32.105 Contact.Service V] onNotified-> serviceId:LivesharePresence name:messageReceived
[2019-12-13 14:50:32.105 Contact.Manager I] onMessageReceived-> type:inviteStatusChanged body:{"fromContactId":"d21964d1-6560-4bae-b392-b817ffd31059","status":"invited"}
[2019-12-13 14:50:32.105 Contact.Manager I] setInviteStatus-> contactId:<36:<redacted>> status:invited
[2019-12-13 14:50:35.661 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:35.661 Contact.Service V] onNotified-> serviceId:LivesharePresence name:messageReceived
[2019-12-13 14:50:35.661 Contact.Manager I] onMessageReceived-> type:inviteStatusChanged body:{"fromContactId":"d21964d1-6560-4bae-b392-b817ffd31059","status":"joining"}
[2019-12-13 14:50:35.661 Contact.Manager I] setInviteStatus-> contactId:<36:<redacted>> status:joining
[2019-12-13 14:50:35.906 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:35.906 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:36.889 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:36.889 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:54.333 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:54.333 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:54.333 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:54.334 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactChanged
[2019-12-13 14:50:54.334 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:54.334 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:54.813 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:54.813 Contact.Manager I] onMessageReceived-> type:inviteStatusChanged body:{"fromContactId":"d21964d1-6560-4bae-b392-b817ffd31059","status":"none"}
[2019-12-13 14:50:54.813 Contact.Service V] onNotified-> serviceId:LivesharePresence name:messageReceived
[2019-12-13 14:50:54.813 Contact.Manager I] setInviteStatus-> contactId:<36:<redacted>> status:none
[2019-12-13 14:50:54.982 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:54.982 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:55.863 SourceControl V] sendNotification-> sourceControl.sourceControlChanged:
[2019-12-13 14:51:09.960 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:09.960 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:51:09.961 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:09.961 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactChanged
[2019-12-13 14:51:09.962 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:09.962 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:51:12.829 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:12.829 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:51:12.830 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:12.830 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactChanged
[2019-12-13 14:51:12.830 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:12.830 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:51:20.343 SourceControl V] sendNotification-> sourceControl.sourceControlChanged:

Guest vscode log:
[2019-12-13 14:50:02.034 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:02.034 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:07.433 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:07.433 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:07.452 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:07.452 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:19.540 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:19.540 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:19.541 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:19.541 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactChanged
[2019-12-13 14:50:19.542 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:19.542 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:19.544 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:19.544 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:19.544 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:19.545 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:19.545 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:19.544 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactChanged
[2019-12-13 14:50:23.220 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:23.220 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:23.220 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:23.221 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:23.221 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:23.221 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactChanged
[2019-12-13 14:50:25.424 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:25.424 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:27.105 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:27.105 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:27.106 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:27.106 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:31.930 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:31.930 Contact.Service V] onNotified-> serviceId:LivesharePresence name:inviteLinkReceived
[2019-12-13 14:50:31.932 Contact.Manager I] fireInviteStatusChanged-> targetContactId:<36:<redacted>> fromContactId:<36:<redacted>> status:invited
[2019-12-13 14:50:31.932 Contact.Service V] Request 'sendMessageRequest' serviceId:LivesharePresence
[2019-12-13 14:50:31.933 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:32.106 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:35.057 Contact.Manager I] fireInviteStatusChanged-> targetContactId:<36:<redacted>> fromContactId:<36:<redacted>> status:joining
[2019-12-13 14:50:35.057 Contact.Service V] Request 'sendMessageRequest' serviceId:LivesharePresence
[2019-12-13 14:50:35.057 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:35.703 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:35.709 Command:Object I] Decorator: CancellationDecorator Starting
[2019-12-13 14:50:35.711 Command:Object I] Decorator: ErrorNotification Starting
[2019-12-13 14:50:35.711 Command:Object I] Decorator: Telemetry Starting
[2019-12-13 14:50:35.712 Command:Object I] Decorator: TelemetryStatus Starting
[2019-12-13 14:50:35.712 Command:Object I] Decorator: SessionStateTransitions Starting
[2019-12-13 14:50:35.722 Contact.Service V] Request 'publishPresence' serviceId:LivesharePresence
[2019-12-13 14:50:35.723 Command:Object I] SessionContext: State update from "SignedIn" (SignInSuccess) to "JoiningInProgress" (AttemptJoining).
[2019-12-13 14:50:35.723 Command:Object I] Decorator: Authentication Starting
[2019-12-13 14:50:35.726 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:35.726 Command:Object I] Decorator: JoinSessionCommand Starting
[2019-12-13 14:50:35.728 Client.Rpc.Workspace V] < workspace.canUserShareWorkspace()
[2019-12-13 14:50:35.901 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:35.901 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:35.926 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:36.457 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:36.507 Client.Rpc.Workspace V] > workspace.canUserShareWorkspace() succeeded
[2019-12-13 14:50:36.509 Command:Object I] Decorator: CancellationDecorator Starting
[2019-12-13 14:50:36.509 Command:Object I] Decorator: ErrorNotification Starting
[2019-12-13 14:50:36.510 Command:Object I] Decorator: FirstRunTracking Starting
[2019-12-13 14:50:36.510 Command:Object I] HasRunPreviously: Joining collaboration session -> true
[2019-12-13 14:50:36.510 Command:Object I] Decorator: Telemetry Starting
[2019-12-13 14:50:36.510 Command:Object I] Decorator: TelemetryStatus Starting
[2019-12-13 14:50:36.510 Command:Object I] Decorator: Validation Starting
[2019-12-13 14:50:36.510 Command:Object I] Decorator: InfoPage Starting
[2019-12-13 14:50:36.510 Command:Object I] Decorator: Authentication Starting
[2019-12-13 14:50:36.511 Command:Object I] Decorator: JoinPreReloadCommand Starting
[2019-12-13 14:50:36.512 Client.Rpc.Workspace V] < workspace.getWorkspace()
[2019-12-13 14:50:36.510 Command:Object I] Decorator: SessionStateTransitions Starting
[2019-12-13 14:50:36.683 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:36.693 Client.Rpc.Workspace V] > workspace.getWorkspace() succeeded
[2019-12-13 14:50:36.694 Command:Object I] SessionContext: Status update from "SigninCheckCompleted" to "GetWorkspaceComplete".
[2019-12-13 14:50:36.694 Command:Object I] SessionContext: Status update from "SigninCheckCompleted" to "GetWorkspaceComplete".
[2019-12-13 14:50:36.702 Client.Rpc.AgentSession V] < agentSessionService.enableReconnect()
[2019-12-13 14:50:36.705 Client.Rpc.AgentSession V] > agentSessionService.enableReconnect() succeeded
[2019-12-13 14:50:36.706 Command:Object I] Decorator: JoinPreReloadCommand Completed Success (195ms abs=-909ms pre=-1356600ms post=1355691)
[2019-12-13 14:50:36.707 Command:Object I] Decorator: Authentication Completed Success (197ms abs=2ms pre=1ms post=1)
[2019-12-13 14:50:36.707 Command:Object I] Decorator: InfoPage Completed Success (197ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:36.707 Contact.Manager V] ->loadRecentCollaborators:onStateChanged
[2019-12-13 14:50:36.708 Contact.Service V] Request 'contactPresenceDispose' serviceId:LivesharePresence
[2019-12-13 14:50:36.708 Contact.Service V] Request 'publishPresence' serviceId:LivesharePresence
[2019-12-13 14:50:36.708 Command:Object I] SessionContext: State update from "JoiningInProgress" (AttemptJoining) to "SignedIn" (JoiningPendingReload).
[2019-12-13 14:50:36.708 Command:Object I] SessionContext: State update from "JoiningInProgress" (AttemptJoining) to "SignedIn" (JoiningPendingReload).
[2019-12-13 14:50:36.708 Command:Object I] Decorator: SessionStateTransitions Completed Success (198ms abs=1ms pre=0ms post=1)
[2019-12-13 14:50:36.708 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:36.709 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:36.709 Client.Rpc.Auth V] < auth.getCurrentUser()
[2019-12-13 14:50:36.709 Command:Object I] Decorator: Validation Completed Success (199ms abs=1ms pre=0ms post=1)
[2019-12-13 14:50:36.709 Command:Object I] Decorator: TelemetryStatus Completed Success (199ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:36.711 Command:Object I] Command [Joining collaboration session]: Join Success (200ms)
[2019-12-13 14:50:36.711 Command:Object I] Decorator: Telemetry Completed Success (201ms abs=2ms pre=0ms post=2)
[2019-12-13 14:50:36.711 Command:Object I] Decorator: FirstRunTracking Completed Success (201ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:36.711 Command:Object I] Decorator: ErrorNotification Completed Success (202ms abs=1ms pre=1ms post=0)
[2019-12-13 14:50:36.711 Command:Object I] Decorator: CancellationDecorator Completed Success (202ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:36.711 Command:Object I] Decorator: JoinSessionCommand Completed Success (985ms abs=783ms pre=783ms post=0)
[2019-12-13 14:50:36.711 Command:Object I] Decorator: Authentication Completed Success (988ms abs=3ms pre=3ms post=0)
[2019-12-13 14:50:36.711 Command:Object I] Decorator: SessionStateTransitions Completed Success (999ms abs=11ms pre=11ms post=0)
[2019-12-13 14:50:36.711 Command:Object I] Decorator: TelemetryStatus Completed Success (999ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:36.712 Command:Object I] Command [Joining collaboration session]: Join Success (1000ms)
[2019-12-13 14:50:36.712 Command:Object I] Decorator: Telemetry Completed Success (1001ms abs=2ms pre=1ms post=1)
[2019-12-13 14:50:36.712 Command:Object I] Decorator: ErrorNotification Completed Success (1001ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:36.712 Command:Object I] Decorator: CancellationDecorator Completed Success (1003ms abs=2ms pre=2ms post=0)
[2019-12-13 14:50:36.885 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:36.885 Client.Rpc.Auth V] < auth.getCurrentUser()
[2019-12-13 14:50:36.885 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:36.885 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:36.908 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:36.910 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:36.910 Client.Rpc.Auth V] > auth.getCurrentUser() succeeded
[2019-12-13 14:50:36.910 Contact.Manager V] SelfInviteProvider-> status:true
[2019-12-13 14:50:36.910 Contact.Service V] onNotified-> serviceId:selfInvite name:suggestedUsers
[2019-12-13 14:50:36.911 Contact.Manager I] onSuggestedUsersUpdate-> serviceId:selfInvite count:1
[2019-12-13 14:50:36.911 Contact.Manager V] ->updateRecentContacts -> count:0
[2019-12-13 14:50:36.911 Contact.Manager V] <-updateRecentContacts
[2019-12-13 14:50:36.911 Contact.Manager V] ->updateSuggestedUsers
[2019-12-13 14:50:36.911 Contact.Service V] Request 'contactPresenceDispose' serviceId:LivesharePresence
[2019-12-13 14:50:36.911 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:36.913 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:36.914 Contact.Manager V] suggest contacts from provider:selfInvite ids:<36:<redacted>> emails:<<red@acted>>
[2019-12-13 14:50:36.915 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:36.915 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:36.915 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:36.918 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:36.919 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:36.919 Contact.Manager I] <- updateSuggestedUsers count:1
[2019-12-13 14:50:38.987 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:38.987 Client.Rpc.Auth V] > auth.getCurrentUser() succeeded
[2019-12-13 14:50:38.987 Contact.Manager V] loading recent collaborators count:1
[2019-12-13 14:50:38.988 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<9:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:38.988 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:38.988 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:38.988 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:39.135 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:39.136 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:42.843 Client I] Client deactivation requested.
[2019-12-13 14:50:42.844 Contact.Service V] Request 'dispose' serviceId:LivesharePresence

[2019-12-13 14:50:48.507 Client I] Extension, IDE, OS : VSLS/1.0.1377 VSCode/1.41.0 MacOS/18.7.0
[2019-12-13 14:50:48.523 Client I] Passed version check for MacOS: found 18.7.0
[2019-12-13 14:50:50.215 Client.Rpc V] RPCClient.init retryCount:0 currentRetryInterval:0
[2019-12-13 14:50:50.225 Client.Rpc I] Agent connection success - net.pipe://localhost/vscode.cbe9b1a06c47c1c49859c358a5ed5858
[2019-12-13 14:50:50.272 Client.Rpc V] finish initialize agent session with rpcConnectId:a84df0b2-6a84-41e8-9011-f0a2c5e1f578 hostSessionId:1
[2019-12-13 14:50:50.273 Client.Rpc.Auth V] < version.exchangeVersions()
[2019-12-13 14:50:50.278 Client.Rpc.Auth V] > version.exchangeVersions() succeeded
[2019-12-13 14:50:50.278 Client I] Client version: 1.0.1377 (VSLS/2.2), agent version: 1.0.1377.64440 (VSLS/2.2)
[2019-12-13 14:50:50.278 Client.Rpc.Auth V] < version.exchangeSettings()
[2019-12-13 14:50:50.282 Client.Rpc.Auth V] > version.exchangeSettings() succeeded
[2019-12-13 14:50:50.283 Client I] Hub connection is being enabled...
[2019-12-13 14:50:50.284 Hub.Connection V] < hubConnection.setEnabled()
[2019-12-13 14:50:50.293 Hub.Connection V] > hubConnection.setEnabled() succeeded
[2019-12-13 14:50:50.297 Command:Object I] Decorator: Telemetry Starting
[2019-12-13 14:50:50.297 Command:Object I] Decorator: EnableVSCodeAccountAuth Starting
[2019-12-13 14:50:50.297 Command:Object I] Decorator: EnableVSCodeAccountAuth Completed Success (0ms)
[2019-12-13 14:50:50.297 Command:Object I] Command []: EnableVSCodeAccountAuth Success (0ms)
[2019-12-13 14:50:50.297 Command:Object I] Decorator: Telemetry Completed Success (0ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:50.298 Client I] Found workspaceId: 775F8536E4BE878C6A67134B13D1AFFABA82
[2019-12-13 14:50:50.302 Command:Object I] Decorator: CancellationDecorator Starting
[2019-12-13 14:50:50.303 Command:Object I] Decorator: ErrorNotification Starting
[2019-12-13 14:50:50.303 Command:Object I] Decorator: Telemetry Starting
[2019-12-13 14:50:50.303 Command:Object I] Decorator: FirstRunTracking Starting
[2019-12-13 14:50:50.303 Command:Object I] HasRunPreviously: Joining collaboration session -> true
[2019-12-13 14:50:50.303 Command:Object I] Decorator: TelemetryStatus Starting
[2019-12-13 14:50:50.303 Command:Object I] Decorator: Validation Starting
[2019-12-13 14:50:50.304 Command:Object I] Decorator: SessionStateTransitions Starting
[2019-12-13 14:50:50.308 Command:Object I] SessionContext: State update from "Initializing" (undefined) to "JoiningInProgress" (AttemptJoining).
[2019-12-13 14:50:50.308 Command:Object I] Decorator: Progress Starting
[2019-12-13 14:50:50.309 Progress I] Progress notifier opened: 0.
[2019-12-13 14:50:50.312 Command:Object I] Decorator: InfoPage Starting
[2019-12-13 14:50:50.312 Command:Object I] Decorator: Authentication Starting
[2019-12-13 14:50:50.313 Command:Object I] SessionContext: Status update from "undefined" to "SigninCheckStarting".
[2019-12-13 14:50:50.313 Command:Object I] SessionContext: Status update from "SigninCheckStarting" to "AttemptDefaultLoginStarting".
[2019-12-13 14:50:50.316 Command:Object I] SessionContext: Status update from "AttemptDefaultLoginStarting" to "AttemptLoginStarting".
[2019-12-13 14:50:50.316 Client.Rpc.Auth V] < auth.login()
[2019-12-13 14:50:50.369 API I] Initializing Live Share API 0.3.1291 for<EMAIL_ADDRESS>[2019-12-13 14:50:50.398 API I] Initializing Live Share API 0.3.1291 for<EMAIL_ADDRESS>[2019-12-13 14:50:50.402 Client.Rpc.Auth V] > auth.authenticationChanged:
[2019-12-13 14:50:50.403 Hub.Connection V] > hubConnection.hubConnectionStateChanged:
[2019-12-13 14:50:50.477 Contact.Manager I] registerContactProvider-> serviceId:git
[2019-12-13 14:50:50.478 Contact.Service V] _Initialize:git
[2019-12-13 14:50:50.478 Contact.Service V] Request 'initialize' serviceId:git
[2019-12-13 14:50:50.478 Contact.Manager I] registerContactProvider-> serviceId:selfInvite
[2019-12-13 14:50:50.479 Contact.Service V] _Initialize:selfInvite
[2019-12-13 14:50:50.479 Contact.Service V] Request 'initialize' serviceId:selfInvite
[2019-12-13 14:50:50.481 Client.Rpc.SessionContext V] < sessionContext.getServiceNames()
[2019-12-13 14:50:50.487 Client.Rpc.SessionContext V] > sessionContext.getServiceNames() succeeded
[2019-12-13 14:50:50.487 Contact.Manager I] registerContactProvider-> serviceId:LivesharePresence
[2019-12-13 14:50:50.487 Contact.Service V] _Initialize:LivesharePresence
[2019-12-13 14:50:50.487 Contact.Service V] Request 'initialize' serviceId:LivesharePresence
[2019-12-13 14:50:50.487 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:50.492 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:50.549 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:50.558 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:50.558 Contact.Service V] onNotified-> serviceId:LivesharePresence name:signedStateChanged
[2019-12-13 14:50:50.559 Client.Rpc.Auth V] > auth.authenticationChanged:
[2019-12-13 14:50:50.665 Client.Rpc.Auth V] > auth.login() succeeded
[2019-12-13 14:50:50.665 Command:Object I] SessionContext: Status update from "AttemptLoginStarting" to "AttemptLoginComplete".
[2019-12-13 14:50:50.666 Command:Object I] SessionContext: Status update from "AttemptLoginComplete" to "AttemptDefaultLoginComplete".
[2019-12-13 14:50:50.666 Command:Object I] SessionContext: Status update from "AttemptDefaultLoginComplete" to "SigninCheckCompleted".
[2019-12-13 14:50:50.666 Command:Object I] Decorator: GaNotification Starting
[2019-12-13 14:50:50.666 Command:Object I] Decorator: JoinReloadCommand Starting
[2019-12-13 14:50:51.131 Client I] [applying guest workspace settings]: Failed to set the "files.hotExit" setting on the workspace.
[2019-12-13 14:50:52.049 Client.Rpc.Workspace V] < workspace.joinWorkspace()
[2019-12-13 14:50:52.361 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:52.370 Client.Rpc.SessionContext V] > sessionContext.sessionStatusUpdate:
[2019-12-13 14:50:52.371 Command:Object I] SessionContext: Status update from "SigninCheckCompleted" to "AddedUserToWorkspace".
[2019-12-13 14:50:52.373 Client.Rpc.Workspace V] > workspace.progressUpdated:
[2019-12-13 14:50:52.374 Client.Rpc.Workspace V] > workspace.progressUpdated:
[2019-12-13 14:50:52.537 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:52.538 Client.Rpc.SessionContext V] > sessionContext.sessionStatusUpdate:
[2019-12-13 14:50:52.538 Command:Object I] SessionContext: Status update from "AddedUserToWorkspace" to "ReadWorkspaceInfo".
[2019-12-13 14:50:52.538 Client.Rpc.Workspace V] > workspace.progressUpdated:
[2019-12-13 14:50:52.705 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:52.709 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:53.954 Hub.Connection V] > hubConnection.hubConnectionAttempted:
[2019-12-13 14:50:53.956 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:53.956 Contact.Service V] onNotified-> serviceId:LivesharePresence name:capabilitiesUpdate
[2019-12-13 14:50:53.956 Contact.Manager I] onCapabilitiesChanged-> serviceId:LivesharePresence old:{"supportsDispose":false,"supportsPresence":false,"supportsPublishPresence":false,"supportsInviteLink":false,"supportsContactPresenceRequest":false,"supportsAvailableContacts":null,"supportsSelfContact":false,"supportsSearchContacts":null,"supportsMessageRequest":false,"supportedPresenceStatus":["available","doNotDisturb","away"],"supportsSignedStateRequest":true} new:{"supportsDispose":true,"supportsPresence":true,"supportsPublishPresence":true,"supportsInviteLink":true,"supportsContactPresenceRequest":true,"supportsAvailableContacts":null,"supportsSelfContact":true,"supportsSearchContacts":null,"supportsMessageRequest":true,"supportedPresenceStatus":["available","doNotDisturb","away"],"supportsSignedStateRequest":true}
[2019-12-13 14:50:53.956 Contact.Manager V] ->updateRecentContacts -> count:0
[2019-12-13 14:50:53.956 Contact.Manager V] <-updateRecentContacts
[2019-12-13 14:50:53.956 Contact.Manager V] ->updateSuggestedUsers
[2019-12-13 14:50:53.956 Contact.Manager I] <- updateSuggestedUsers count:0
[2019-12-13 14:50:53.957 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:53.957 Contact.Service V] onNotified-> serviceId:LivesharePresence name:signedStateChanged
[2019-12-13 14:50:53.957 Hub.Connection V] > hubConnection.hubConnectionStateChanged:
[2019-12-13 14:50:54.336 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:54.336 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:50:54.336 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:54.336 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactChanged
[2019-12-13 14:50:54.336 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:54.336 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:54.340 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:54.340 Contact.Service V] onNotified-> serviceId:LivesharePresence name:selfContact
[2019-12-13 14:50:54.617 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:54.617 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:54.641 Client.Rpc.Workspace E] > workspace.joinWorkspace() error: undefined
[2019-12-13 14:50:54.642 Contact.Manager I] fireInviteStatusChanged-> targetContactId:<36:<redacted>> fromContactId:<36:<redacted>> status:none
[2019-12-13 14:50:54.642 Contact.Service V] Request 'sendMessageRequest' serviceId:LivesharePresence
[2019-12-13 14:50:54.657 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:54.810 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:54.810 Command:Object I] Decorator: JoinReloadCommand Completed Failed (4144ms abs=4144ms pre=-369ms post=4513)
[2019-12-13 14:50:54.810 Command:Object I] Decorator: GaNotification Completed Failed (4144ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:54.810 Command:Object I] Decorator: Authentication Completed Failed (4498ms abs=354ms pre=354ms post=0)
[2019-12-13 14:50:54.810 Command:Object I] Decorator: InfoPage Completed Failed (4498ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:54.811 Command:Object I] Decorator: Progress Completed Failed (4503ms abs=5ms pre=4ms post=1)
[2019-12-13 14:50:54.812 Command:Object I] SessionContext: State update from "JoiningInProgress" (AttemptJoining) to "SignedIn" (JoiningError).
[2019-12-13 14:50:54.812 Contact.Manager V] ->loadRecentCollaborators:onStateChanged
[2019-12-13 14:50:54.813 Contact.Service V] Request 'publishPresence' serviceId:LivesharePresence
[2019-12-13 14:50:54.813 Command:Object I] Decorator: SessionStateTransitions Completed Failed (4509ms abs=6ms pre=4ms post=2)
[2019-12-13 14:50:54.814 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:54.814 Client.Rpc.Auth V] < auth.getCurrentUser()
[2019-12-13 14:50:54.814 Command:Object I] Decorator: Validation Completed Failed (4511ms abs=2ms pre=1ms post=1)
[2019-12-13 14:50:54.814 Client.Rpc.Auth V] < auth.getCurrentUser()
[2019-12-13 14:50:54.814 Command:Object I] Decorator: TelemetryStatus Completed Failed (4511ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:54.814 Command:Object I] Decorator: FirstRunTracking Completed Failed (4511ms abs=0ms pre=0ms post=0)
[2019-12-13 14:50:55.640 Command:Object E] Command [Joining collaboration session]: Join Failure: Failed to open remote session: Connection timed out. (5337ms)
[2019-12-13 14:50:55.641 Command:Object E] Error: Failed to open remote session: Connection timed out.
	at handleResponse [PATH]/extension-prod.js:100841:48)
	at processMessageQueue [PATH]/extension-prod.js:100668:17)
	at Immediate.<anonymous> [PATH]/extension-prod.js:100652:13)
	at processImmediate [PATH]/timers.js:439:21)
[2019-12-13 14:50:55.641 Command:Object I] Decorator: Telemetry Completed Failed (5338ms abs=827ms pre=0ms post=827)
[2019-12-13 14:50:55.657 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:50:55.657 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:50:55.657 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:55.657 Contact.Manager V] SelfInviteProvider-> status:true
[2019-12-13 14:50:55.657 Contact.Service V] onNotified-> serviceId:selfInvite name:suggestedUsers
[2019-12-13 14:50:55.657 Contact.Manager V] ->updateRecentContacts -> count:0
[2019-12-13 14:50:55.657 Contact.Manager V] <-updateRecentContacts
[2019-12-13 14:50:55.658 Contact.Manager V] suggest contacts from provider:selfInvite ids:<36:<redacted>> emails:<<red@acted>>
[2019-12-13 14:50:55.658 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:55.658 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:55.658 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:55.658 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:50:55.658 Client.Rpc.Auth V] > auth.getCurrentUser() succeeded
[2019-12-13 14:50:55.658 Contact.Manager V] loading recent collaborators count:1
[2019-12-13 14:50:55.659 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<9:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:55.659 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:50:55.659 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:50:55.659 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:50:55.659 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:55.657 Client.Rpc.Auth V] > auth.getCurrentUser() succeeded
[2019-12-13 14:50:55.657 Contact.Manager I] onSuggestedUsersUpdate-> serviceId:selfInvite count:1
[2019-12-13 14:50:55.658 Contact.Manager V] ->updateSuggestedUsers
[2019-12-13 14:50:55.810 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:55.810 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:55.810 Contact.Manager I] <- updateSuggestedUsers count:1
[2019-12-13 14:50:55.814 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:50:55.814 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:50:59.124 Command:Object I] Decorator: ErrorNotification Completed Success (8821ms abs=3483ms pre=0ms post=3483)
[2019-12-13 14:50:59.124 Command:Object I] Decorator: CancellationDecorator Completed Success (8822ms abs=1ms pre=1ms post=0)
[2019-12-13 14:50:59.195 Client I] Client deactivation requested.
[2019-12-13 14:50:59.195 Contact.Service V] Request 'dispose' serviceId:LivesharePresence

[2019-12-13 14:51:05.476 Client.Rpc V] RPCClient.init retryCount:0 currentRetryInterval:0
[2019-12-13 14:51:05.479 Debug.Rpc.Host V] TrackDebugManager created
[2019-12-13 14:51:05.486 Client.Rpc V] Agent connection not completed: Error: connect ENOENT /var/folders/yy/c1gxbg5j7s5cbwzltph5gf5h0000gn/T/CoreFxPipe_vscode.cbe9b1a06c47c1c49859c358a5ed5858; Retrying...
[2019-12-13 14:51:05.488 Client.Rpc V] RPCClient.init retryCount:1 currentRetryInterval:100
[2019-12-13 14:51:05.489 Client.Agent V] Agent lock on:</<redacted>/<redacted>.lock>
[2019-12-13 14:51:05.490 Client.Agent I] starting agent with pipe:vscode.cbe9b1a06c47c1c49859c358a5ed5858
[2019-12-13 14:51:05.490 Client.Agent I] signalR service -> uri:https://signlr-prod.core.vsengsaas.visualstudio.com/api/signalr/signalrhub
[2019-12-13 14:51:06.808 Client.Agent V] Finished agent startup...
[2019-12-13 14:51:06.809 Client.Agent V] Agent unlocked
[2019-12-13 14:51:06.810 Client.Rpc I] Agent connection success - net.pipe://localhost/vscode.cbe9b1a06c47c1c49859c358a5ed5858
[2019-12-13 14:51:08.639 Client.Rpc V] finish initialize agent session with rpcConnectId:null hostSessionId:1
[2019-12-13 14:51:08.640 Client.Rpc.Auth V] < version.exchangeVersions()
[2019-12-13 14:51:08.667 Client.Rpc.Auth V] > version.exchangeVersions() succeeded
[2019-12-13 14:51:08.668 Client I] Client version: 1.0.1377 (VSLS/2.2), agent version: 1.0.1377.64440 (VSLS/2.2)
[2019-12-13 14:51:08.668 Client.Rpc.Auth V] < version.exchangeSettings()
[2019-12-13 14:51:08.704 Client.Rpc.Auth V] > version.exchangeSettings() succeeded
[2019-12-13 14:51:08.705 Client I] Hub connection is being enabled...
[2019-12-13 14:51:08.705 Hub.Connection V] < hubConnection.setEnabled()
[2019-12-13 14:51:08.710 Hub.Connection V] > hubConnection.setEnabled() succeeded
[2019-12-13 14:51:08.713 Command:Object I] Decorator: Telemetry Starting
[2019-12-13 14:51:08.713 Command:Object I] Decorator: EnableVSCodeAccountAuth Starting
[2019-12-13 14:51:08.713 Command:Object I] Decorator: EnableVSCodeAccountAuth Completed Success (0ms)
[2019-12-13 14:51:08.714 Command:Object I] Command []: EnableVSCodeAccountAuth Success (1ms)
[2019-12-13 14:51:08.714 Command:Object I] Decorator: Telemetry Completed Success (1ms abs=1ms pre=0ms post=1)
[2019-12-13 14:51:08.714 Client I] No workspaceId found in workspace settings.
[2019-12-13 14:51:08.736 API I] Initializing Live Share API 0.3.1291 for<EMAIL_ADDRESS>[2019-12-13 14:51:08.743 API I] Initializing Live Share API 0.3.1291 for<EMAIL_ADDRESS>[2019-12-13 14:51:08.744 Client I] No workspace url found in cascade.json file.
[2019-12-13 14:51:08.744 Command:Object I] Decorator: CancellationDecorator Starting
[2019-12-13 14:51:08.744 Command:Object I] Decorator: ErrorNotification Starting
[2019-12-13 14:51:08.745 Command:Object I] Decorator: Telemetry Starting
[2019-12-13 14:51:08.745 Command:Object I] Decorator: TelemetryStatus Starting
[2019-12-13 14:51:08.745 Command:Object I] Decorator: Validation Starting
[2019-12-13 14:51:08.745 Command:Object I] Decorator: SessionStateTransitions Starting
[2019-12-13 14:51:08.746 Command:Object I] SessionContext: State update from "Initializing" (undefined) to "SigningIn" (AttemptSignIn).
[2019-12-13 14:51:08.746 Command:Object I] Decorator: Authentication Starting
[2019-12-13 14:51:08.746 Command:Object I] SessionContext: Status update from "undefined" to "SigninCheckStarting".
[2019-12-13 14:51:08.747 Command:Object I] SessionContext: Status update from "SigninCheckStarting" to "AttemptDefaultLoginStarting".
[2019-12-13 14:51:08.748 Command:Object I] SessionContext: Status update from "AttemptDefaultLoginStarting" to "AttemptLoginStarting".
[2019-12-13 14:51:08.749 Client.Rpc.Auth V] < auth.login()
[2019-12-13 14:51:08.769 Contact.Manager I] registerContactProvider-> serviceId:git
[2019-12-13 14:51:08.769 Contact.Service V] _Initialize:git
[2019-12-13 14:51:08.769 Contact.Service V] Request 'initialize' serviceId:git
[2019-12-13 14:51:08.770 Contact.Manager I] registerContactProvider-> serviceId:selfInvite
[2019-12-13 14:51:08.770 Contact.Service V] _Initialize:selfInvite
[2019-12-13 14:51:08.770 Contact.Service V] Request 'initialize' serviceId:selfInvite
[2019-12-13 14:51:08.771 Client.Rpc.SessionContext V] < sessionContext.getServiceNames()
[2019-12-13 14:51:08.956 Client.Rpc.Auth V] > auth.authenticationChanged:
[2019-12-13 14:51:09.009 Client.Rpc.SessionContext V] > sessionContext.getServiceNames() succeeded
[2019-12-13 14:51:09.009 Contact.Manager I] registerContactProvider-> serviceId:LivesharePresence
[2019-12-13 14:51:09.009 Contact.Service V] _Initialize:LivesharePresence
[2019-12-13 14:51:09.009 Contact.Service V] Request 'initialize' serviceId:LivesharePresence
[2019-12-13 14:51:09.010 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:51:09.064 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:51:09.861 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:51:10.054 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:10.054 Contact.Service V] onNotified-> serviceId:LivesharePresence name:signedStateChanged
[2019-12-13 14:51:10.061 Client.Rpc.Auth V] > auth.authenticationChanged:
[2019-12-13 14:51:10.155 Client.Rpc.Auth V] > auth.login() succeeded
[2019-12-13 14:51:10.156 Command:Object I] SessionContext: Status update from "AttemptLoginStarting" to "AttemptLoginComplete".
[2019-12-13 14:51:10.156 Command:Object I] SessionContext: Status update from "AttemptLoginComplete" to "AttemptDefaultLoginComplete".
[2019-12-13 14:51:10.157 Command:Object I] SessionContext: State update from "SigningIn" (AttemptSignIn) to "SignedIn" (SignInSuccess).
[2019-12-13 14:51:10.157 Contact.Manager V] ->loadRecentCollaborators:onStateChanged
[2019-12-13 14:51:10.157 Command:Object I] SessionContext: Status update from "AttemptDefaultLoginComplete" to "SigninCheckCompleted".
[2019-12-13 14:51:10.157 Client.Rpc.Auth V] < auth.getCurrentUser()
[2019-12-13 14:51:10.157 Command:Object I] Decorator: Authentication Completed Success (1411ms abs=1410ms pre=-33ms post=1443)
[2019-12-13 14:51:10.157 Client.Rpc.Auth V] < auth.getCurrentUser()
[2019-12-13 14:51:10.158 Command:Object I] Decorator: SessionStateTransitions Completed Success (1413ms abs=2ms pre=1ms post=1)
[2019-12-13 14:51:10.158 Command:Object I] Decorator: Validation Completed Success (1413ms abs=0ms pre=0ms post=0)
[2019-12-13 14:51:10.158 Command:Object I] Decorator: TelemetryStatus Completed Success (1413ms abs=0ms pre=0ms post=0)
[2019-12-13 14:51:10.158 Command:Object I] Command [Signing in]: ExplicitSignIn Success (1413ms)
[2019-12-13 14:51:10.158 Command:Object I] Decorator: Telemetry Completed Success (1413ms abs=0ms pre=0ms post=0)
[2019-12-13 14:51:10.158 Command:Object I] Decorator: ErrorNotification Completed Success (1414ms abs=1ms pre=1ms post=0)
[2019-12-13 14:51:10.158 Command:Object I] Decorator: CancellationDecorator Completed Success (1414ms abs=0ms pre=0ms post=0)
[2019-12-13 14:51:10.341 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:51:10.342 Client.Rpc.Auth V] > auth.getCurrentUser() succeeded
[2019-12-13 14:51:10.342 Contact.Manager V] SelfInviteProvider-> status:true
[2019-12-13 14:51:10.342 Contact.Service V] onNotified-> serviceId:selfInvite name:suggestedUsers
[2019-12-13 14:51:10.342 Contact.Manager I] onSuggestedUsersUpdate-> serviceId:selfInvite count:1
[2019-12-13 14:51:10.342 Contact.Manager V] ->updateRecentContacts -> count:0
[2019-12-13 14:51:10.342 Contact.Manager V] <-updateRecentContacts
[2019-12-13 14:51:10.343 Contact.Manager V] ->updateSuggestedUsers
[2019-12-13 14:51:10.343 Contact.Manager V] suggest contacts from provider:selfInvite ids:<36:<redacted>> emails:<<red@acted>>
[2019-12-13 14:51:10.343 Contact.Manager I] <- updateSuggestedUsers count:1
[2019-12-13 14:51:10.906 Client.Rpc V] > telemetry.genericOperation:
[2019-12-13 14:51:10.907 Client.Rpc.Auth V] > auth.getCurrentUser() succeeded
[2019-12-13 14:51:10.907 Contact.Manager V] loading recent collaborators count:1
[2019-12-13 14:51:10.907 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<9:<redacted>> email:<<red@acted>>
[2019-12-13 14:51:12.626 Hub.Connection V] > hubConnection.hubConnectionAttempted:
[2019-12-13 14:51:12.692 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:12.692 Contact.Service V] onNotified-> serviceId:LivesharePresence name:capabilitiesUpdate
[2019-12-13 14:51:12.692 Contact.Manager I] onCapabilitiesChanged-> serviceId:LivesharePresence old:{"supportsDispose":false,"supportsPresence":false,"supportsPublishPresence":false,"supportsInviteLink":false,"supportsContactPresenceRequest":false,"supportsAvailableContacts":null,"supportsSelfContact":false,"supportsSearchContacts":null,"supportsMessageRequest":false,"supportedPresenceStatus":["available","doNotDisturb","away"],"supportsSignedStateRequest":true} new:{"supportsDispose":true,"supportsPresence":true,"supportsPublishPresence":true,"supportsInviteLink":true,"supportsContactPresenceRequest":true,"supportsAvailableContacts":null,"supportsSelfContact":true,"supportsSearchContacts":null,"supportsMessageRequest":true,"supportedPresenceStatus":["available","doNotDisturb","away"],"supportsSignedStateRequest":true}
[2019-12-13 14:51:12.692 Contact.Manager V] ->updateRecentContacts -> count:1
[2019-12-13 14:51:12.692 Contact.Manager V] addRecentContact id:<36:<redacted>> displayName:<9:<redacted>> email:<<red@acted>>
[2019-12-13 14:51:12.693 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:51:12.693 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:51:12.693 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:51:12.693 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:12.693 Contact.Service V] onNotified-> serviceId:LivesharePresence name:signedStateChanged
[2019-12-13 14:51:12.694 Hub.Connection V] > hubConnection.hubConnectionStateChanged:
[2019-12-13 14:51:12.886 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:12.886 Contact.Service V] onNotified-> serviceId:LivesharePresence name:presenceChanged
[2019-12-13 14:51:12.906 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:12.906 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactChanged
[2019-12-13 14:51:12.908 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:12.908 Contact.Service V] onNotified-> serviceId:LivesharePresence name:contactPropertiesChanged
[2019-12-13 14:51:12.924 Client.Rpc.Contact V] > contactService-LivesharePresence.notified:
[2019-12-13 14:51:12.924 Contact.Service V] onNotified-> serviceId:LivesharePresence name:selfContact
[2019-12-13 14:51:12.934 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:51:12.934 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:51:12.935 Contact.Manager V] <-updateRecentContacts
[2019-12-13 14:51:12.935 Contact.Manager V] ->updateSuggestedUsers
[2019-12-13 14:51:12.935 Contact.Manager V] suggest contacts from provider:selfInvite ids:<36:<redacted>> emails:<<red@acted>>
[2019-12-13 14:51:12.935 Contact.Manager V] Request presence on service:LivesharePresence for contact ids:<36:<redacted>>,, emails:<<red@acted>>
[2019-12-13 14:51:12.935 Contact.Service V] Request 'contactPresenceRequest' serviceId:LivesharePresence
[2019-12-13 14:51:12.935 Client.Rpc.Contact V] < contactService-LivesharePresence.request()
[2019-12-13 14:51:13.083 Client.Rpc.Contact V] > contactService-LivesharePresence.request() succeeded
[2019-12-13 14:51:13.083 Contact.Manager V] Presence support found for id:<36:<redacted>> => id:<36:<redacted>> email:<<red@acted>>
[2019-12-13 14:51:13.084 Contact.Manager I] <- updateSuggestedUsers count:1

Guest agent log:

[2019-12-13 14:50:02.032 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:7d6e96e7> connectionId:JdiDSoAdzXzIsa5PM4G8IAd637ca2c1 } properties:status=3
[2019-12-13 14:50:02.033 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:7d6e96e7>
[2019-12-13 14:50:07.431 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:7d6e96e7> connectionId:JdiDSoAdzXzIsa5PM4G8IAd637ca2c1 } properties:liveshareStatus=4
[2019-12-13 14:50:07.432 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:7d6e96e7>
[2019-12-13 14:50:07.451 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:7d6e96e7> connectionId:JdiDSoAdzXzIsa5PM4G8IAd637ca2c1 } properties:status=1
[2019-12-13 14:50:07.451 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:7d6e96e7>
[2019-12-13 14:50:19.539 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:7d6e96e7> connectionId:JdiDSoAdzXzIsa5PM4G8IAd637ca2c1 } properties:status=<null>|liveshareStatus=<null>|name=<null>|avatarUri=<null>|email=<null>
[2019-12-13 14:50:19.539 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:7d6e96e7>
[2019-12-13 14:50:19.541 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:7d6e96e7> connectionId:JdiDSoAdzXzIsa5PM4G8IAd637ca2c1 } properties:status=<null>|liveshareStatus=<null>|name=<null>|avatarUri=<null>|email=<null>
[2019-12-13 14:50:19.541 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:7d6e96e7>
[2019-12-13 14:50:19.546 Agent-PresenceServiceProxy V] ConnectionChanged-> contact:{ Id:<36:7d6e96e7> connectionId:JdiDSoAdzXzIsa5PM4G8IAd637ca2c1 } changeType:Removed
[2019-12-13 14:50:23.218 Agent-PresenceServiceProxy V] ConnectionChanged-> contact:{ Id:<36:7d6e96e7> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 } changeType:Added
[2019-12-13 14:50:23.219 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:7d6e96e7> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 }<EMAIL_ADDRESS>HOST NAME |status=1|avatarUri=https://avatars2.githubusercontent.com/u/ HOST ID ?v=4|liveshareStatus=2
[2019-12-13 14:50:23.219 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:7d6e96e7>
[2019-12-13 14:50:25.423 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:7d6e96e7> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 } properties:liveshareStatus=5
[2019-12-13 14:50:25.423 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:7d6e96e7>
[2019-12-13 14:50:27.104 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:7d6e96e7> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 } properties:liveshareStatus=6
[2019-12-13 14:50:27.104 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:7d6e96e7>
[2019-12-13 14:50:27.105 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:7d6e96e7> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 } properties:status=3
[2019-12-13 14:50:27.105 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:7d6e96e7>
[2019-12-13 14:50:31.922 Agent-PresenceServiceProxy V] MessageReceived-> targetContact:{ Id:<36:982ce712> connectionId:SXBIBY4m6kzgOsTbOF38bw2baf7f311 } fromContact:{ Id:<36:7d6e96e7> connectionId:aErCZxoN6836mKZT0TZ8dw2baf7f311 } messageType:inviteLink body:{}
[2019-12-13 14:50:31.936 Agent V] > #1 {Id=11 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:32.103 Agent V] < #1 {Id=11}
[2019-12-13 14:50:35.060 Agent V] > #1 {Id=12 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:35.703 Agent V] < #1 {Id=12}
[2019-12-13 14:50:35.727 Agent V] > #1 {Id=13 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:35.735 Agent.Rpc.Workspace V] > #1 {Id=14 Name=workspace.canUserShareWorkspace}
[2019-12-13 14:50:35.750 Agent.Http V] < GET <https://prod.liveshare.vsengsaas.visualstudio.com/013e017e>
[2019-12-13 14:50:35.750 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:35.900 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:982ce712> connectionId:SXBIBY4m6kzgOsTbOF38bw2baf7f311 } properties:liveshareStatus=7
[2019-12-13 14:50:35.901 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:982ce712>
[2019-12-13 14:50:35.925 Agent V] < #1 {Id=13}
[2019-12-13 14:50:36.452 Agent.Http V] > GET <https://prod.liveshare.vsengsaas.visualstudio.com/013e017e> (c701ms s<null>ms) => 200 OK
[2019-12-13 14:50:36.506 Agent.Workspace I] Cannot join as host - Workspace is not Persistent.
[2019-12-13 14:50:36.507 Agent.Rpc.Workspace V] < #1 {Id=14}
[2019-12-13 14:50:36.512 Agent.Rpc.Workspace V] > #1 {Id=15 Name=workspace.getWorkspace}
[2019-12-13 14:50:36.517 Agent.Http V] < GET <https://prod.liveshare.vsengsaas.visualstudio.com/013e017e>
[2019-12-13 14:50:36.518 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:36.683 Agent.Http V] > GET <https://prod.liveshare.vsengsaas.visualstudio.com/013e017e> (c164ms s<null>ms) => 200 OK
[2019-12-13 14:50:36.687 Agent.Rpc.Workspace V] < #1 {Id=15}
[2019-12-13 14:50:36.703 Agent V] > #1 {Id=16 Name=agentSessionService.enableReconnect}
[2019-12-13 14:50:36.704 Agent I] EnableReconnectAsync for session #:1 using id:a84df0b2-6a84-41e8-9011-f0a2c5e1f578
[2019-12-13 14:50:36.705 Agent V] < #1 {Id=16}
[2019-12-13 14:50:36.709 Agent V] > #1 {Id=17 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:36.718 ContactService-LiveShare V] Remove subscription for contact Id:<36:7d6e96e7>
[2019-12-13 14:50:36.732 Agent V] > #1 {Id=18 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:36.733 Agent.Rpc.Auth V] > #1 {Id=19 Name=auth.getCurrentUser}
[2019-12-13 14:50:36.739 Agent.Http V] < GET <https://prod.liveshare.vsengsaas.visualstudio.com/7590df5d>
[2019-12-13 14:50:36.740 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:36.884 Agent V] < #1 {Id=17}
[2019-12-13 14:50:36.884 Agent-PresenceServiceProxy V] UpdateProperties-> contact:{ Id:<36:982ce712> connectionId:SXBIBY4m6kzgOsTbOF38bw2baf7f311 } properties:liveshareStatus=4
[2019-12-13 14:50:36.884 ContactService-LiveShare V] OnUpdateProperties -> contact Id:<36:982ce712>
[2019-12-13 14:50:36.885 Agent.Rpc.Auth V] > #1 {Id=20 Name=auth.getCurrentUser}
[2019-12-13 14:50:36.886 Agent.Http V] < GET <https://prod.liveshare.vsengsaas.visualstudio.com/7590df5d>
[2019-12-13 14:50:36.886 Agent.Proxy I] No proxy information found.
[2019-12-13 14:50:36.907 Agent.Http V] > GET <https://prod.liveshare.vsengsaas.visualstudio.com/7590df5d> (c166ms s<null>ms) => 200 OK
[2019-12-13 14:50:36.909 Agent V] < #1 {Id=18}
[2019-12-13 14:50:36.909 Agent.Rpc.Auth V] < #1 {Id=19}
[2019-12-13 14:50:36.912 Agent V] > #1 {Id=21 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:36.913 Agent V] < #1 {Id=21}
[2019-12-13 14:50:36.917 Agent V] > #1 {Id=22 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:36.918 Agent V] < #1 {Id=22}
[2019-12-13 14:50:38.986 Agent.Http V] > GET <https://prod.liveshare.vsengsaas.visualstudio.com/7590df5d> (c2099ms s<null>ms) => 200 OK
[2019-12-13 14:50:38.987 Agent.Rpc.Auth V] < #1 {Id=20}
[2019-12-13 14:50:38.989 Agent V] > #1 {Id=23 Name=contactService-LivesharePresence.request}
[2019-12-13 14:50:38.989 ContactService-LiveShare V] Request subscription for -> id:<36:7d6e96e7>
[2019-12-13 14:50:39.134 ContactService-LiveShare V] Request subscription result for contact Id:<36:7d6e96e7> properties:status=3|liveshareStatus=6|name= HOST NAME |avatarUri=https://avatars2.githubusercontent.com/u/ HOST ID ?v=4|email=<4938194e@gmail.com>|_Id=<36:7d6e96e7>
[2019-12-13 14:50:39.135 Agent V] < #1 {Id=23}