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
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.
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.
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)
Thanks oliii. Next time it happens I will dig through those logs!