Ksenia Rybakova created IGNITE-4322: ---------------------------------------
Summary: .NET: ClientReconnectExample is not clear enough Key: IGNITE-4322 URL: https://issues.apache.org/jira/browse/IGNITE-4322 Project: Ignite Issue Type: Bug Components: platforms Affects Versions: 1.8 Reporter: Ksenia Rybakova ClientReconnectExample has the following output {noformat} >>> Client reconnect example started. [13:19:53] __________ ________________ [13:19:53] / _/ ___/ |/ / _/_ __/ __/ [13:19:53] _/ // (7 7 // / / / / _/ [13:19:53] /___/\___/_/|_/___/ /_/ /___/ [13:19:53] [13:19:53] ver. 1.8.0-QASK2501#20161125-sha1:ccbe6007 [13:19:53] 2016 Copyright(C) Apache Software Foundation [13:19:53] [13:19:53] Ignite documentation: http://ignite.apache.org [13:19:53] [13:19:53] Quiet mode. [13:19:53] ^-- Logging to file 'C:\WORK\Releases\apache-ignite-fabric-1.8.0-QASK2501-bin\work\log\ignite-a18708d8.0.log' [13:19:53] ^-- To see **FULL** console log here add -DIGNITE_QUIET=false or "-v" to ignite.{sh|bat} [13:19:53] [13:19:53] OS: Windows 10 10.0 amd64 [13:19:53] VM information: Java(TM) SE Runtime Environment 1.8.0_91-b14 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.91-b14 [13:19:53] Initial heap size is 256MB (should be no less than 512MB, use -Xms512m -Xmx512m). [13:19:53] Configured plugins: [13:19:53] ^-- None [13:19:53] [13:19:58] Security status [authentication=off, tls/ssl=off] [13:19:59] Performance suggestions for grid 'serverNode' (fix if possible) [13:19:59] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true [13:19:59] ^-- Disable grid events (remove 'includeEventTypes' from configuration) [13:19:59] ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC) [13:19:59] [13:19:59] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat} [13:19:59] [13:19:59] Ignite node started OK (id=a18708d8, grid=serverNode) [13:19:59] Topology snapshot [ver=1, servers=1, clients=0, CPUs=4, heap=3.5GB] >>> Server node started. [13:20:00] __________ ________________ [13:20:00] / _/ ___/ |/ / _/_ __/ __/ [13:20:00] _/ // (7 7 // / / / / _/ [13:20:00] /___/\___/_/|_/___/ /_/ /___/ [13:20:00] [13:20:00] ver. 1.8.0-QASK2501#20161125-sha1:ccbe6007 [13:20:00] 2016 Copyright(C) Apache Software Foundation [13:20:00] [13:20:00] Ignite documentation: http://ignite.apache.org [13:20:00] [13:20:00] Quiet mode. [13:20:00] ^-- Logging to file 'C:\WORK\Releases\apache-ignite-fabric-1.8.0-QASK2501-bin\work\log\ignite-a18708d8.0.log' [13:20:00] ^-- To see **FULL** console log here add -DIGNITE_QUIET=false or "-v" to ignite.{sh|bat} [13:20:00] [13:20:00] OS: Windows 10 10.0 amd64 [13:20:00] VM information: Java(TM) SE Runtime Environment 1.8.0_91-b14 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.91-b14 [13:20:00] Initial heap size is 256MB (should be no less than 512MB, use -Xms512m -Xmx512m). [13:20:00] Configured plugins: [13:20:00] ^-- None [13:20:00] [13:20:00] Security status [authentication=off, tls/ssl=off] [13:20:02] Topology snapshot [ver=2, servers=1, clients=1, CPUs=4, heap=3.5GB] [13:20:02] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat} [13:20:02] [13:20:02] Ignite node started OK (id=2f5922f5) [13:20:02] Topology snapshot [ver=2, servers=1, clients=1, CPUs=4, heap=3.5GB] >>> Client node connected to the cluster. >>> Put value with key:0 >>> Put value with key:1 >>> Put value with key:2 [13:20:04,824][SEVERE][tcp-client-disco-sock-writer-#5%null%][TcpDiscoverySpi] Failed to send message: TcpDiscoveryClientPingRequest [nodeToPing=a18708d8-e489-4b47-a8e9-e16efc6b814e, super=TcpDiscoveryAbstractMessage [sndNodeId=null, id=a9fa27aa851-2f5922f5-d38b-4b4f-80cf-feec8bf52013, verifierNodeId=null, topVer=0, pendingIdx=0, failedNodes=null, isClient=false]] java.net.SocketException: Socket is closed at java.net.Socket.getOutputStream(Socket.java:943) at org.apache.ignite.spi.discovery.tcp.TcpDiscoverySpi.writeToSocket(TcpDiscoverySpi.java:1354) at org.apache.ignite.spi.discovery.tcp.ClientImpl$SocketWriter.body(ClientImpl.java:1088) at org.apache.ignite.spi.IgniteSpiThread.run(IgniteSpiThread.java:62) [13:20:04] Ignite node stopped OK [name=serverNode, uptime=00:00:04:899] >>> Put value with key:3 [13:20:09] New version is available at ignite.apache.org: 1.7.0 >>> Restarting server node... [13:20:09] __________ ________________ [13:20:09] / _/ ___/ |/ / _/_ __/ __/ [13:20:09] _/ // (7 7 // / / / / _/ [13:20:09] /___/\___/_/|_/___/ /_/ /___/ [13:20:09] [13:20:09] ver. 1.8.0-QASK2501#20161125-sha1:ccbe6007 [13:20:09] 2016 Copyright(C) Apache Software Foundation [13:20:09] [13:20:09] Ignite documentation: http://ignite.apache.org [13:20:09] [13:20:09] Quiet mode. [13:20:09] ^-- Logging to file 'C:\WORK\Releases\apache-ignite-fabric-1.8.0-QASK2501-bin\work\log\ignite-a18708d8.0.log' [13:20:09] ^-- To see **FULL** console log here add -DIGNITE_QUIET=false or "-v" to ignite.{sh|bat} [13:20:09] [13:20:09] OS: Windows 10 10.0 amd64 [13:20:09] VM information: Java(TM) SE Runtime Environment 1.8.0_91-b14 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.91-b14 [13:20:09] Initial heap size is 256MB (should be no less than 512MB, use -Xms512m -Xmx512m). [13:20:09] Configured plugins: [13:20:09] ^-- None [13:20:09] [13:20:09] Security status [authentication=off, tls/ssl=off] [13:20:10] Performance suggestions for grid 'serverNode' (fix if possible) [13:20:10] To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true [13:20:10] ^-- Disable grid events (remove 'includeEventTypes' from configuration) [13:20:10] ^-- Disable fully synchronous writes (set 'writeSynchronizationMode' to PRIMARY_SYNC or FULL_ASYNC) [13:20:10] [13:20:10] To start Console Management & Monitoring run ignitevisorcmd.{sh|bat} [13:20:10] [13:20:10] Ignite node started OK (id=791211e8, grid=serverNode) [13:20:10] Topology snapshot [ver=1, servers=1, clients=0, CPUs=4, heap=3.5GB] [13:20:12] New version is available at ignite.apache.org: 1.7.0 >>> Client disconnected from the cluster. [13:20:20] Topology snapshot [ver=2, servers=1, clients=1, CPUs=4, heap=3.5GB] [13:20:20] Client node was reconnected after it was already considered failed by the server topology (this could happen after all servers restarted or due to a long network outage between the client and servers). All continuous queries and remote event listeners created by this client will be unsubscribed, consider listening to EVT_CLIENT_NODE_RECONNECTED event to restore them. [13:20:20] New version is available at ignite.apache.org: 1.7.0 >>> Client reconnected to the cluster. [13:20:20] Topology snapshot [ver=2, servers=1, clients=1, CPUs=4, heap=3.5GB] >>> Put value with key:5 >>> Put value with key:6 >>> Put value with key:7 >>> Put value with key:8 >>> Put value with key:9 >>> Example finished, press any key to exit ... [13:20:23] Ignite node stopped OK [name=serverNode, uptime=00:00:12:607] [13:20:24,312][SEVERE][tcp-client-disco-sock-writer-#5%null%][TcpDiscoverySpi] Failed to send message: TcpDiscoveryClientPingRequest [nodeToPing=791211e8-9f45-4a19-8d8b-79950a421f38, super=TcpDiscoveryAbstractMessage [sndNodeId=null, id=7ffa27aa851-844ade6c-017c-4384-a2a6-cdbf149268dd, verifierNodeId=null, topVer=0, pendingIdx=0, failedNodes=null, isClient=false]] java.net.SocketException: Socket is closed at java.net.Socket.getOutputStream(Socket.java:943) at org.apache.ignite.spi.discovery.tcp.TcpDiscoverySpi.writeToSocket(TcpDiscoverySpi.java:1354) at org.apache.ignite.spi.discovery.tcp.ClientImpl$SocketWriter.body(ClientImpl.java:1088) at org.apache.ignite.spi.IgniteSpiThread.run(IgniteSpiThread.java:62) {noformat} The following is confusing: 1) When server node stops we get SocketException but still put key 3 successfully. 2) ">>> Client disconnected from the cluster." message appears after server restart (should be before). Possibly need to increase this timeout: {noformat} // Wait for client to detect the disconnect. Thread.Sleep(5000); {noformat} 3) SocketException happens when example finished already. {noformat} [13:20:24,312][SEVERE][tcp-client-disco-sock-writer-#5%null%][TcpDiscoverySpi] Failed to send message: TcpDiscoveryClientPingRequest [nodeToPing=791211e8-9f45-4a19-8d8b-79950a421f38, super=TcpDiscoveryAbstractMessage [sndNodeId=null, id=7ffa27aa851-844ade6c-017c-4384-a2a6-cdbf149268dd, verifierNodeId=null, topVer=0, pendingIdx=0, failedNodes=null, isClient=false]] java.net.SocketException: Socket is closed at java.net.Socket.getOutputStream(Socket.java:943) at org.apache.ignite.spi.discovery.tcp.TcpDiscoverySpi.writeToSocket(TcpDiscoverySpi.java:1354) at org.apache.ignite.spi.discovery.tcp.ClientImpl$SocketWriter.body(ClientImpl.java:1088) at org.apache.ignite.spi.IgniteSpiThread.run(IgniteSpiThread.java:62) {noformat} -- This message was sent by Atlassian JIRA (v6.3.4#6332)