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)