oliii
Contributor
Contributor

RDS Application - Random session disconnects

Hello

We have randomly session disconnect on RDS Applications.Its "just" a disconnect and not a logoff of the session, so the user can reconnect and he get the same session back.

Environment setup:

- Windows Server 2012 R2 (RDS)

- Horizon View 6.1.1

- Horizon Client 3.5

- vSphere 6.0

In the logs i found the follow:

2015-10-07T17:12:49.814+02:00 INFO  (0BC0-0FD8) <DesktopControlJMS> [Audit] DISCONNECTED:Server:cn=1ea307cd-6d36-4155-8830-600bfde96ecf,ou=servers,dc=vdi,dc=vmware,dc=int;Pool:cn=busit_prod_rds,ou=server groups,dc=vdi,dc=vmware,dc=int;DNS:CPVRDSBIT01.busit.ch;IP:10.20.15.81;IP6:null;USER:BUSIT\michel.bigall;USERDN:cn=s-1-5-21-4257691896-3969396083-3416808920-1130,cn=foreignsecurityprincipals,dc=vdi,dc=vmware,dc=int;BROKERUSERSID:S-1-5-21-4257691896-3969396083-3416808920-1130;

2015-10-07T17:12:49.815+02:00 INFO  (0BC0-0FD8) <DesktopControlJMS> [DesktopTracker] User BUSIT\michel.bigall disconnected from machine cpvrdsbit01.busit.ch for desktop busit_prod_rds - session allocated at 28. September 2015 13:58:38 MESZ, connected for 102 mins 32 secs

2015-10-07T17:12:49.815+02:00 DEBUG (0BC0-0FD8) <DesktopControlJMS> [DesktopTracker] Agent session counts for cn=1ea307cd-6d36-4155-8830-600bfde96ecf,ou=servers,dc=vdi,dc=vmware,dc=int : sessionCount=32, pendingSessionCount=0, connectedSessionCount=9, disconnectedSessionCount=23

2015-10-07T17:12:49.815+02:00 DEBUG (0BC0-14D8) <ThreadedActionBase-1> [DesktopTracker] (ce3f57d5-0903-492d-b096-284bda24bfc1) onEvent: DISCONNECTED - UserName:michel.bigall;DomainName:BUSIT;UserDn:cn=s-1-5-21-4257691896-3969396083-3416808920-1130,cn=foreignsecurityprincipals,dc=vdi,dc=vmware,dc=int;UserSid:S-1-5-21-4257691896-3969396083-3416808920-1130;GroupSids:null;BrokerUserSid:S-1-5-21-4257691896-3969396083-3416808920-1130;ConnectionId:F2C1_***_0E3E;Protocol:PCOIP;ClientName:EB840SSDU04;ClientAddress:10.41.1.117;ServerDn:cn=1ea307cd-6d36-4155-8830-600bfde96ecf,ou=servers,dc=vdi,dc=vmware,dc=int;ServerPoolDn:cn=busit_prod_rds,ou=server groups,dc=vdi,dc=vmware,dc=int;ServerAddress:cpvrdsbit01.busit.ch/-/10.20.15.81;ManagedObjectId:null;Id:ce3f57d5-0903-492d-b096-284bda24bfc1;State:Disconnected;SessionGuid:6074-***-ba35;PreviousSessionGuid:null;LoggedInAsDomain:BUSIT;LoggedInAsUser:michel.bigall;SessionType:APPLICATION;RemotableContent:false

2015-10-07T17:12:49.815+02:00 DEBUG (0BC0-14D8) <ThreadedActionBase-1> [MachineInformation] (ce3f57d5-0903-492d-b096-284bda24bfc1) Created new session entry based on event data. Merged local session 17/6074-***-ba35.

2015-10-07T17:12:49.860+02:00 WARN  (0D58-1F9C) <Tunnel#70> [ca] (F2C1_***_0E3E) Tunnel error: Connection reset

2015-10-07T17:12:49.860+02:00 DEBUG (0D58-1F9C) <Tunnel#70> [ca] (F2C1_***_0E3E) Tunnel error: java.net.SocketException: Connection reset com.vmware.vdi.ob.tunnelservice.ca.run(SourceFile:926)

java.net.SocketException: Connection reset

  at java.net.SocketInputStream.read(SocketInputStream.java:196)

  at java.net.SocketInputStream.read(SocketInputStream.java:122)

  at sun.security.ssl.InputRecord.readFully(InputRecord.java:442)

  at sun.security.ssl.InputRecord.read(InputRecord.java:480)

  at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:934)

  at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:891)

  at sun.security.ssl.AppInputStream.read(AppInputStream.java:102)

  at sun.security.ssl.AppInputStream.read(AppInputStream.java:69)

  at simple.http.PollerInputStream.read(PollerInputStream.java:122)

  at java.io.FilterInputStream.read(FilterInputStream.java:83)

  at simple.http.ChunkedInputStream.doLength(ChunkedInputStream.java:347)

  at simple.http.ChunkedInputStream.nextChunk(ChunkedInputStream.java:280)

  at simple.http.ChunkedInputStream.parseRead(ChunkedInputStream.java:251)

  at simple.http.ChunkedInputStream.readBytes(ChunkedInputStream.java:182)

  at simple.http.MonitoredInputStream.read(MonitoredInputStream.java:115)

  at java.io.DataInputStream.read(DataInputStream.java:149)

  at java.io.BufferedInputStream.fill(BufferedInputStream.java:235)

  at java.io.BufferedInputStream.read(BufferedInputStream.java:254)

  at com.vmware.vdi.ob.tunnelservice.av.b(SourceFile:1048)

  at com.vmware.vdi.ob.tunnelservice.ca.a(SourceFile:1402)

  at com.vmware.vdi.ob.tunnelservice.ca.run(SourceFile:793)

  at java.lang.Thread.run(Thread.java:745)

2015-10-07T17:12:49.860+02:00 DEBUG (0D58-1F9C) <Tunnel#70> [bx] (F2C1_***_0E3E) Tunnel reported as disconnected

2015-10-07T17:12:49.860+02:00 DEBUG (0D58-1F9C) <Tunnel#70> [az] (F2C1_***_0E3E) User michel.bigall disconnected from the Secure Gateway Server - session ID: F2C1_***_0E3E

2015-10-07T17:12:49.860+02:00 DEBUG (0D58-1F9C) <Tunnel#70> [ca] (F2C1_***_0E3E) lostConnection done

2015-10-07T17:12:49.860+02:00 DEBUG (0D58-1F9C) <Tunnel#70> [ca] (F2C1_***_0E3E) Tunnel waiting for a reconnection...

Hope someone can help me...

Thanks

0 Kudos
4 Replies
grossag
VMware Employee
VMware Employee

Do the client tunnel logs show anything at that time?  Assuming it's a Windows client, the log would be in %APPDATA%\..\Local\VMware\VDM\logs\vmware-horizon-viewclient-<etc>.log.

0 Kudos
loganhsmith2012
Contributor
Contributor

We're having the same issue - random, almost daily disconnects of RDSH applications.  We are using the same servers of RDS host, View, and the Horizon client as listed by oliii, but vCenter 5.5.  We are not using a security server for these connections, but they sit behind an F5 load balancer.  Removing the F5 from the mix doesn't resolve the issue.  We haven't made any progress in two month working a ticket with VMware.  No issues with Horizon View users, just RDSH.  We are also having an issue where thinprint stops handing print jobs from the RDSH session to the local workstation, which may or may not happen right after a disconnect.  Frustrating.

0 Kudos
oliii
Contributor
Contributor

Hello loganhsmith

I also open a support case, VMware told me that we have Packet loss on the RDSH Host. We could still not find out where they come from.

You can find the entry's in the PCoIP Log of the RDSH Host. (C:\ProgramData\VMware\VDM\logs)

In my case it looks like that:

Line 920: 2015-10-09T14:06:05.645+02:00> LVL:1 RC: 0           VGMAC :Stat frms: R=000000/000000/004158  T=000000/005483/002003 (A/I/O) Loss=2.70%/0.00% (R/T)

Line 879: 2015-10-09T08:56:07.131+02:00> LVL:1 RC: 0           VGMAC :Stat frms: R=000000/000000/003030  T=000000/001802/001531 (A/I/O) Loss=0.14%/0.00% (R/T)
Line 899: 2015-10-09T08:57:07.270+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/003872  T=000000/002121/002034 (A/I/O) Loss=0.00%/0.00% (R/T)
Line 919: 2015-10-09T08:58:07.455+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/003946  T=000000/002125/002108 (A/I/O) Loss=0.00%/0.00% (R/T)
Line 971: 2015-10-09T08:59:07.607+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/004276  T=000000/002220/002309 (A/I/O) Loss=0.00%/0.00% (R/T)
Line 991: 2015-10-09T09:00:07.756+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/004740  T=000000/002317/002521 (A/I/O) Loss=0.00%/0.00% (R/T)
Line 1011: 2015-10-09T09:01:07.824+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/005286  T=000000/002614/002801 (A/I/O) Loss=0.18%/0.00% (R/T)
Line 1031: 2015-10-09T09:02:07.933+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/005660  T=000000/002726/003010 (A/I/O) Loss=0.00%/0.00% (R/T)
Line 1051: 2015-10-09T09:03:08.017+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/006421  T=000000/002965/003380 (A/I/O) Loss=0.13%/0.00% (R/T)
Line 1071: 2015-10-09T09:04:08.119+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/006802  T=000000/003068/003618 (A/I/O) Loss=0.26%/0.00% (R/T)


Line 1194: 2015-10-09T09:10:08.971+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/008189  T=000000/003345/004522 (A/I/O) Loss=0.00%/0.41% (R/T)
Line 1214: 2015-10-09T09:11:09.107+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/008999  T=000000/003638/004988 (A/I/O) Loss=0.00%/0.00% (R/T)
Line 1234: 2015-10-09T09:12:09.254+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/009407  T=000000/003794/005225 (A/I/O) Loss=0.00%/0.00% (R/T)
Line 1254: 2015-10-09T09:13:09.278+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/009803  T=000000/003913/005470 (A/I/O) Loss=0.50%/0.00% (R/T)
Line 1274: 2015-10-09T09:14:09.442+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/010133  T=000000/004008/005663 (A/I/O) Loss=0.60%/0.00% (R/T)
Line 1194: 2015-10-09T09:10:08.971+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/008189  T=000000/003345/004522 (A/I/O) Loss=0.00%/0.41% (R/T)

Line 1214: 2015-10-09T09:11:09.107+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/008999  T=000000/003638/004988 (A/I/O) Loss=0.00%/0.00% (R/T)
Line 1234: 2015-10-09T09:12:09.254+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/009407  T=000000/003794/005225 (A/I/O) Loss=0.00%/0.00% (R/T)
Line 1254: 2015-10-09T09:13:09.278+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/009803  T=000000/003913/005470 (A/I/O) Loss=0.50%/0.00% (R/T)
Line 1274: 2015-10-09T09:14:09.442+02:00> LVL:1 RC:   0 VGMAC :Stat frms: R=000000/000000/010133  T=000000/004008/005663 (A/I/O) Loss=0.60%/0.00% (R/T)

0 Kudos
loganhsmith2012
Contributor
Contributor

Thanks oliii.  Next time it happens I will dig through those logs!

0 Kudos