Controller is randomly re-adopting everything due to `javax.net.ssl.SSLException: Socket closed`
Controller is randomly re-adopting everything due to `javax.net.ssl.SSLException: Socket closed`
Several times a controller (software based, in docker) is losing all the devices and re-adopting them. Because of this, all the clients lose connection and internet access...
Omada mobile app sends such notifications during this:
Controller logs have only WAN-down:
Docker container has more helpful logs of the controller app itself:
2021-06-21 00:27:58 [Thread-28] [INFO]-[SourceFile:146] - Close connection to service server.
2021-06-21 00:27:58 [Thread-39] [INFO]-[SourceFile:921] - Thread 'heartBeatThread' is stopped
2021-06-21 00:27:58 [Thread-38] [INFO]-[SourceFile:792] - expiredRequestCleanThread is interrupted.
2021-06-21 00:27:58 [Thread-38] [INFO]-[SourceFile:796] - Thread 'expiredRequestCleanThread' is stopped
2021-06-21 00:27:58 [Thread-37] [INFO]-[SourceFile:837] - javax.net.ssl.SSLException: Socket closed
javax.net.ssl.SSLException: Socket closed
at sun.security.ssl.Alert.createSSLException(Alert.java:127) ~[?:1.8.0_292]
at sun.security.ssl.TransportContext.fatal(TransportContext.java:324) ~[?:1.8.0_292]
at sun.security.ssl.TransportContext.fatal(TransportContext.java:267) ~[?:1.8.0_292]
at sun.security.ssl.TransportContext.fatal(TransportContext.java:262) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1554) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl.access$400(SSLSocketImpl.java:73) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:964) ~[?:1.8.0_292]
at com.tplink.eap.cloudsdk.client.p.a(SourceFile:120) ~[cloudsdk-1.0.5.jar:?]
at com.tplink.eap.cloudsdk.client.s.run(SourceFile:830) [cloudsdk-1.0.5.jar:?]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_292]
Caused by: java.net.SocketException: Socket closed
at java.net.SocketInputStream.socketRead0(Native Method) ~[?:1.8.0_292]
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) ~[?:1.8.0_292]
at java.net.SocketInputStream.read(SocketInputStream.java:171) ~[?:1.8.0_292]
at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:457) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1332) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl.access$300(SSLSocketImpl.java:73) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:948) ~[?:1.8.0_292]
... 3 more
2021-06-21 00:27:58 [Thread-37] [INFO]-[SourceFile:839] - Thread 'recvThread' is stopped
2021-06-21 00:27:58 [Thread-28] [INFO]-[SourceFile:127] - Connect service server automatically, ConnectionType is PERSISTENT_CONNECTION.
2021-06-21 00:27:58 [Thread-28] [INFO]-[SourceFile:366] - The result of connection is false.
2021-06-21 00:27:58 [Thread-28] [INFO]-[SourceFile:146] - Close connection to service server.
2021-06-21 00:27:58 [Thread-28] [INFO]-[SourceFile:110] - Connect SEF server automatically.
2021-06-21 00:29:03 [Thread-28] [INFO]-[SourceFile:194] - The result of connection is true, service host is n-devs-smb.tplinkcloud.com, port is 443.
2021-06-21 00:29:03 [Thread-28] [INFO]-[SourceFile:114] - Close connection to SEF server.
2021-06-21 00:29:03 [Thread-1702] [INFO]-[SourceFile:403] - javax.net.ssl.SSLException: Socket closed
javax.net.ssl.SSLException: Socket closed
at sun.security.ssl.Alert.createSSLException(Alert.java:127) ~[?:1.8.0_292]
at sun.security.ssl.TransportContext.fatal(TransportContext.java:324) ~[?:1.8.0_292]
at sun.security.ssl.TransportContext.fatal(TransportContext.java:267) ~[?:1.8.0_292]
at sun.security.ssl.TransportContext.fatal(TransportContext.java:262) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1554) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl.access$400(SSLSocketImpl.java:73) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:964) ~[?:1.8.0_292]
at com.tplink.eap.cloudsdk.client.p.a(SourceFile:120) ~[cloudsdk-1.0.5.jar:?]
at com.tplink.eap.cloudsdk.client.o.run(SourceFile:397) [cloudsdk-1.0.5.jar:?]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_292]
Caused by: java.net.SocketException: Socket closed
at java.net.SocketInputStream.socketRead0(Native Method) ~[?:1.8.0_292]
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) ~[?:1.8.0_292]
at java.net.SocketInputStream.read(SocketInputStream.java:171) ~[?:1.8.0_292]
at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:457) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1332) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl.access$300(SSLSocketImpl.java:73) ~[?:1.8.0_292]
at sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:948) ~[?:1.8.0_292]
... 3 more
2021-06-21 00:29:03 [Thread-1702] [INFO]-[SourceFile:407] - ssl recv thread is stopped.
2021-06-21 00:29:03 [Thread-28] [INFO]-[SourceFile:127] - Connect service server automatically, ConnectionType is PERSISTENT_CONNECTION.
2021-06-21 00:29:23 [Thread-28] [INFO]-[SourceFile:320] - Exception occurs when connecting service server:java.net.UnknownHostException: n-devs-smb.tplinkcloud.com: Temporary failure in name resolution
2021-06-21 00:29:23 [pool-32-thread-1] [WARN]-[SourceFile:577] - check device bind status error: -7119, Operation failed. Network connection error.
2021-06-21 00:30:48 [Thread-28] [INFO]-[SourceFile:366] - The result of connection is true.
2021-06-21 04:47:48 [scheduled-pool-8] [INFO]-[SourceFile:61] - Roaming status unstable, roaming interval changed to 20s
2021-06-21 04:57:48 [scheduled-pool-4] [INFO]-[SourceFile:54] - Roaming status stable, roaming interval changed to 8h
2021-06-21 08:36:11 [topology-task-0] [INFO]-[SourceFile:221] - topology of switch 00-5F-67-00-00-00 changed
2021-06-21 08:39:41 [topology-task-0] [INFO]-[SourceFile:221] - topology of switch 00-5F-67-00-00-00 changed
Apparently, my switch hasn't been moved\re-plugged\etc (referring to the last log line regarding topology of switch changed).
- Copy Link
- Subscribe
- Bookmark
- Report Inappropriate Content
Helped for me! Thank you, and the team.
Now restarting the controller (docker container on Linux) doesn't lay the whole WAN down.
I think, it was it happened even after R605 v1.1.1 firmware update (few days prior to controller v4.4.4)
- Copy Link
- Report Inappropriate Content
Information
Helpful: 0
Views: 4587
Replies: 11
Voters 0
No one has voted for it yet.