Picked up _JAVA_OPTIONS: "-Djava.net.preferIPv4Stack=true" >>> __________ ________________ >>> / _/ ___/ |/ / _/_ __/ __/ >>> _/ // (7 7 // / / / / _/ >>> /___/\___/_/|_/___/ /_/ /___/ >>> >>> ver. 2.3.0#20171028-sha1:8add7fd5 >>> 2017 Copyright(C) Apache Software Foundation >>> >>> Ignite documentation: http://ignite.apache.org 2018-05-10 17:17:33 INFO IgniteKernal:475 - Config URL: n/a 2018-05-10 17:17:33 INFO IgniteKernal:475 - Daemon mode: off 2018-05-10 17:17:33 INFO IgniteKernal:475 - OS: Windows Server 2016 10.0 amd64 2018-05-10 17:17:33 INFO IgniteKernal:475 - OS user: bizruntime 2018-05-10 17:17:33 INFO IgniteKernal:475 - PID: 14124 2018-05-10 17:17:33 INFO IgniteKernal:475 - Language runtime: Java Platform API Specification ver. 1.8 2018-05-10 17:17:33 INFO IgniteKernal:475 - VM information: Java(TM) SE Runtime Environment 1.8.0_131-b11 Oracle Corporation Java HotSpot(TM) 64-Bit Server VM 25.131-b11 2018-05-10 17:17:33 INFO IgniteKernal:475 - VM total memory: 1.6GB 2018-05-10 17:17:33 INFO IgniteKernal:475 - Remote Management [restart: off, REST: on, JMX (remote: off)] 2018-05-10 17:17:33 INFO IgniteKernal:475 - IGNITE_HOME=null 2018-05-10 17:17:33 INFO IgniteKernal:475 - VM arguments: [-Djava.net.preferIPv4Stack=true] 2018-05-10 17:17:33 INFO IgniteKernal:475 - System cache's DataRegion size is configured to 40 MB. Use DataStorageConfiguration.systemCacheMemorySize property to change the setting. 2018-05-10 17:17:33 INFO IgniteKernal:475 - Configured caches [in 'sysMemPlc' dataRegion: ['ignite-sys-cache']] 2018-05-10 17:17:33 INFO IgnitePluginProcessor:475 - Configured plugins: 2018-05-10 17:17:33 INFO IgnitePluginProcessor:475 - ^-- None 2018-05-10 17:17:33 INFO IgnitePluginProcessor:475 - 2018-05-10 17:17:33 WARN GridDiagnostic:480 - This operating system has been tested less rigorously: Windows Server 2016 10.0 amd64. Our team will appreciate the feedback if you experience any problems running ignite in this environment. 2018-05-10 17:17:33 WARN GridDiagnostic:480 - Initial heap size is 112MB (should be no less than 512MB, use -Xms512m -Xmx512m). 2018-05-10 17:17:33 INFO TcpCommunicationSpi:475 - Successfully bound communication NIO server to TCP port [port=47100, locHost=/10.0.0.4, selectorsCnt=4, selectorSpins=0, pairedConn=false] 2018-05-10 17:17:33 WARN TcpCommunicationSpi:480 - Message queue limit is set to 0 which may lead to potential OOMEs when running cache operations in FULL_ASYNC or PRIMARY_SYNC modes due to message queues growth on sender and receiver sides. 2018-05-10 17:17:33 WARN NoopCheckpointSpi:480 - Checkpoints are disabled (to enable configure any GridCheckpointSpi implementation) 2018-05-10 17:17:33 WARN GridCollisionManager:480 - Collision resolution is disabled (all jobs will be activated upon arrival). 2018-05-10 17:17:33 INFO IgniteKernal:475 - Security status [authentication=off, tls/ssl=off] 2018-05-10 17:17:33 INFO TcpDiscoverySpi:475 - Successfully bound to TCP port [port=47500, localHost=node000000.3npsisijdwge1p1rq0zy1olyvh.dx.internal.cloudapp.net/10.0.0.4, locNodeId=23933936-99b7-4a6d-b1cf-b9a8ce637516] 2018-05-10 17:17:33 INFO PdsFoldersResolver:475 - Successfully locked persistence storage folder [C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42] 2018-05-10 17:17:33 INFO PdsFoldersResolver:475 - Consistent ID used for local node is [a34f89ca-3f52-426d-b4e8-8b24b7e1fb42] according to persistence data storage folders 2018-05-10 17:17:33 INFO CacheObjectBinaryProcessorImpl:475 - Resolved directory for serialized binary metadata: C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\binary_meta\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42 2018-05-10 17:17:34 INFO FilePageStoreManager:475 - Resolved page store work directory: C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42 2018-05-10 17:17:34 INFO FileWriteAheadLogManager:475 - Resolved write ahead log work directory: C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\wal\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42 2018-05-10 17:17:34 INFO FileWriteAheadLogManager:475 - Resolved write ahead log archive directory: C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\wal\archive\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42 2018-05-10 17:17:34 INFO FileWriteAheadLogManager:475 - Started write-ahead log manager [mode=DEFAULT] 2018-05-10 17:17:34 WARN IgniteH2Indexing:480 - Serialization of Java objects in H2 was enabled. 2018-05-10 17:17:34 INFO ClientListenerProcessor:475 - Client connector processor has started on TCP port 10800 2018-05-10 17:17:34 INFO GridTcpRestProtocol:475 - Command protocol successfully started [name=TCP binary, host=0.0.0.0/0.0.0.0, port=11211] 2018-05-10 17:17:34 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54353] 2018-05-10 17:17:39 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54396] 2018-05-10 17:17:49 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54499] 2018-05-10 17:17:44 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54440] 2018-05-10 17:17:43 INFO IgniteKernal:475 - Non-loopback local IPs: 10.0.0.4, 192.168.80.1, 192.168.80.2 2018-05-10 17:18:15 INFO IgniteKernal:475 - Enabled local MACs: 000D3A3800E3, 00155D1C8100, 00155D76FABA, 00155DE6CC2C, 00155DE6CC5D 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54354] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54354] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54395] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54395] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54439] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54439] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54498] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54498] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54354, rmtPort=54354] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54439, rmtPort=54439] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54542] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54542] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54619] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54619] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54677] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54677] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54726] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54726] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54787] 2018-05-10 17:18:15 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54439, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54726, rmtPort=54726] 2018-05-10 17:18:15 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54726, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54726, rmtPort=54726 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54498, rmtPort=54498] 2018-05-10 17:18:15 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54498, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54498, rmtPort=54498 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54395, rmtPort=54395] 2018-05-10 17:18:15 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54395, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54395, rmtPort=54395 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54619, rmtPort=54619] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54542, rmtPort=54542] 2018-05-10 17:18:15 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54542, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54542, rmtPort=54542 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54677, rmtPort=54677] 2018-05-10 17:18:15 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54677, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54677, rmtPort=54677 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54439, rmtPort=54439 2018-05-10 17:18:15 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54354, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54787] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54354, rmtPort=54354 2018-05-10 17:18:15 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54619, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54619, rmtPort=54619 2018-05-10 17:18:15 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54787, rmtPort=54787] 2018-05-10 17:18:19 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54787, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:19 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54787, rmtPort=54787 2018-05-10 17:18:19 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54853] 2018-05-10 17:18:19 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54853] 2018-05-10 17:18:19 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54853, rmtPort=54853] 2018-05-10 17:18:24 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54903] 2018-05-10 17:18:24 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54853, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:24 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54903] 2018-05-10 17:18:24 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54853, rmtPort=54853 2018-05-10 17:18:24 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54903, rmtPort=54903] 2018-05-10 17:18:29 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54903, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:34 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54903, rmtPort=54903 2018-05-10 17:18:29 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=54954] 2018-05-10 17:18:34 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=54954] 2018-05-10 17:18:34 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:54954, rmtPort=54954] 2018-05-10 17:18:34 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:54954, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:34 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55016] 2018-05-10 17:18:34 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:54954, rmtPort=54954 2018-05-10 17:18:34 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55016] 2018-05-10 17:18:34 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55016, rmtPort=55016] 2018-05-10 17:18:39 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55058] 2018-05-10 17:18:39 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55058] 2018-05-10 17:18:39 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55016, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:39 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55016, rmtPort=55016 2018-05-10 17:18:39 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55058, rmtPort=55058] 2018-05-10 17:18:44 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55058, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:44 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55111] 2018-05-10 17:18:44 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55058, rmtPort=55058 2018-05-10 17:18:44 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55111] 2018-05-10 17:18:44 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55111, rmtPort=55111] 2018-05-10 17:18:49 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55111, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:49 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55111, rmtPort=55111 2018-05-10 17:18:49 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55174] 2018-05-10 17:18:49 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55174] 2018-05-10 17:18:49 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55174, rmtPort=55174] 2018-05-10 17:18:54 ERROR TcpDiscoverySpi:495 - Failed to initialize connection (this can happen due to short time network problems and can be ignored if does not affect node discovery) [sock=Socket[addr=/168.63.129.16,port=55174,localport=47500]] java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.BufferedInputStream.fill(Unknown Source) at java.io.BufferedInputStream.read1(Unknown Source) at java.io.BufferedInputStream.read(Unknown Source) at org.apache.ignite.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:5734) at org.apache.ignite.spi.IgniteSpiThread.run(IgniteSpiThread.java:62) 2018-05-10 17:18:54 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55174, rmtPort=55174 2018-05-10 17:18:54 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55220] 2018-05-10 17:18:54 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55220] 2018-05-10 17:18:54 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55220, rmtPort=55220] 2018-05-10 17:18:59 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55220, locAddr=/10.0.0.4:47500] 2018-05-10 17:18:59 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55220, rmtPort=55220 2018-05-10 17:18:59 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55276] 2018-05-10 17:18:59 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55276] 2018-05-10 17:18:59 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55276, rmtPort=55276] 2018-05-10 17:19:04 ERROR TcpDiscoverySpi:495 - Failed to initialize connection (this can happen due to short time network problems and can be ignored if does not affect node discovery) [sock=Socket[addr=/168.63.129.16,port=55276,localport=47500]] java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.BufferedInputStream.fill(Unknown Source) at java.io.BufferedInputStream.read1(Unknown Source) at java.io.BufferedInputStream.read(Unknown Source) at org.apache.ignite.spi.discovery.tcp.ServerImpl$SocketReader.body(ServerImpl.java:5734) at org.apache.ignite.spi.IgniteSpiThread.run(IgniteSpiThread.java:62) 2018-05-10 17:19:04 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55276, rmtPort=55276 2018-05-10 17:19:04 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55332] 2018-05-10 17:19:04 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55332] 2018-05-10 17:19:04 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55332, rmtPort=55332] 2018-05-10 17:19:05 WARN IgniteKernal:480 - Nodes started on local machine require more than 80% of physical RAM what can lead to significant slowdown due to swapping (please decrease JVM heap size, data region size or checkpoint buffer size) [required=3484MB, available=7167MB] 2018-05-10 17:19:05 INFO IgniteKernal:475 - Performance suggestions for grid (fix if possible) 2018-05-10 17:19:05 INFO IgniteKernal:475 - To disable, set -DIGNITE_PERFORMANCE_SUGGESTIONS_DISABLED=true 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54727] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54955] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55173] 2018-05-10 17:19:05 INFO IgniteKernal:475 - ^-- Enable G1 Garbage Collector (add '-XX:+UseG1GC' to JVM options) 2018-05-10 17:19:05 INFO IgniteKernal:475 - ^-- Specify JVM heap max size (add '-Xmx[g|G|m|M|k|K]' to JVM options) 2018-05-10 17:19:05 INFO IgniteKernal:475 - ^-- Set max direct memory size if getting 'OOME: Direct buffer memory' (add '-XX:MaxDirectMemorySize=[g|G|m|M|k|K]' to JVM options) 2018-05-10 17:19:05 INFO IgniteKernal:475 - ^-- Disable processing of calls to System.gc() (add '-XX:+DisableExplicitGC' to JVM options) 2018-05-10 17:19:05 INFO IgniteKernal:475 - Refer to this page for more performance suggestions: https://apacheignite.readme.io/docs/jvm-and-system-tuning 2018-05-10 17:19:05 INFO IgniteKernal:475 - 2018-05-10 17:19:05 INFO IgniteKernal:475 - To start Console Management & Monitoring run ignitevisorcmd.{sh|bat} 2018-05-10 17:19:05 INFO IgniteKernal:475 - 2018-05-10 17:19:05 INFO IgniteKernal:475 - >>> +----------------------------------------------------------------------+ >>> Ignite ver. 2.3.0#20171028-sha1:8add7fd5b501b40658096cdde48af9e948aa8150 >>> +----------------------------------------------------------------------+ >>> OS name: Windows Server 2016 10.0 amd64 >>> CPU(s): 2 >>> Heap: 1.6GB >>> VM name: 14124@node000000 >>> Local node [ID=23933936-99B7-4A6D-B1CF-B9A8CE637516, order=1, clientMode=false] >>> Local node addresses: [node000000.3npsisijdwge1p1rq0zy1olyvh.dx.internal.cloudapp.net/10.0.0.4] >>> Local ports: TCP:10800 TCP:11211 TCP:47100 TCP:47500 2018-05-10 17:19:05 INFO GridDiscoveryManager:475 - Topology snapshot [ver=1, servers=1, clients=0, CPUs=2, heap=1.6GB] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54676] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54902] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55110] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55333] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54543] 2018-05-10 17:19:05 INFO GridClusterStateProcessor:475 - Started state transition: true 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54618] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54852] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55059] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55275] 2018-05-10 17:19:05 INFO time:475 - Started exchange init [topVer=AffinityTopologyVersion [topVer=1, minorTopVer=1], crd=true, evt=DISCOVERY_CUSTOM_EVT, evtNode=23933936-99b7-4a6d-b1cf-b9a8ce637516, customEvt=ChangeGlobalStateMessage [id=d9cce0b4361-6c5df0c1-b5b5-4f92-a868-0c157133d7f6, reqId=c017ec77-3c4c-4f40-9b1a-be5326cabd5a, initiatingNodeId=23933936-99b7-4a6d-b1cf-b9a8ce637516, activate=true], allowMerge=false] 2018-05-10 17:19:05 INFO GridDhtPartitionsExchangeFuture:475 - Start activation process [nodeId=23933936-99b7-4a6d-b1cf-b9a8ce637516, client=false, topVer=AffinityTopologyVersion [topVer=1, minorTopVer=1]] 2018-05-10 17:19:05 INFO FilePageStoreManager:475 - Resolved page store work directory: C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42 2018-05-10 17:19:05 INFO FileWriteAheadLogManager:475 - Resolved write ahead log work directory: C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\wal\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42 2018-05-10 17:19:05 INFO FileWriteAheadLogManager:475 - Resolved write ahead log archive directory: C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\wal\archive\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42 2018-05-10 17:19:05 INFO FileWriteAheadLogManager:475 - Started write-ahead log manager [mode=DEFAULT] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:54786] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55017] 2018-05-10 17:19:05 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55219] 2018-05-10 17:19:05 INFO PageMemoryImpl:475 - Started page memory [memoryAllocated=1.4 GiB, pages=357476, tableSize=20.8 MiB, checkpointBuffer=358.4 MiB] 2018-05-10 17:19:05 INFO GridCacheDatabaseSharedManager:475 - Read checkpoint status [startMarker=C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42\cp\1525970716793-02cd6e24-cbdf-4e0e-83c5-601d98dd991d-START.bin, endMarker=C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42\cp\1525970716793-02cd6e24-cbdf-4e0e-83c5-601d98dd991d-END.bin] 2018-05-10 17:19:05 INFO GridCacheDatabaseSharedManager:475 - Checking memory state [lastValidPos=FileWALPointer [idx=0, fileOffset=276825, len=20281, forceFlush=false], lastMarked=FileWALPointer [idx=0, fileOffset=276825, len=20281, forceFlush=false], lastCheckpointId=02cd6e24-cbdf-4e0e-83c5-601d98dd991d] 2018-05-10 17:19:05 INFO GridCacheDatabaseSharedManager:475 - Found last checkpoint marker [cpId=02cd6e24-cbdf-4e0e-83c5-601d98dd991d, pos=FileWALPointer [idx=0, fileOffset=276825, len=20281, forceFlush=false]] 2018-05-10 17:19:05 INFO FileWriteAheadLogManager:475 - Resuming logging to WAL segment [file=C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\wal\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42\0000000000000000.wal, offset=297106, ver=1] 2018-05-10 17:19:06 INFO GridCacheProcessor:475 - Started cache [name=ignite-sys-cache, memoryPolicyName=sysMemPlc, mode=REPLICATED, atomicity=TRANSACTIONAL] 2018-05-10 17:19:06 INFO GridCacheProcessor:475 - Started cache [name=test, memoryPolicyName=default, mode=PARTITIONED, atomicity=ATOMIC] 2018-05-10 17:19:06 INFO GridDhtPartitionsExchangeFuture:475 - Successfully activated caches [nodeId=23933936-99b7-4a6d-b1cf-b9a8ce637516, client=false, topVer=AffinityTopologyVersion [topVer=1, minorTopVer=1]] 2018-05-10 17:19:06 INFO GridDhtPartitionsExchangeFuture:475 - Finished waiting for partition release future [topVer=AffinityTopologyVersion [topVer=1, minorTopVer=1], waitTime=0ms, futInfo=NA] 2018-05-10 17:19:06 INFO GridCacheDatabaseSharedManager:475 - Read checkpoint status [startMarker=C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42\cp\1525970716793-02cd6e24-cbdf-4e0e-83c5-601d98dd991d-START.bin, endMarker=C:\Users\BIZRUN~1\AppData\Local\Temp\2\ignite\work\db\node00-a34f89ca-3f52-426d-b4e8-8b24b7e1fb42\cp\1525970716793-02cd6e24-cbdf-4e0e-83c5-601d98dd991d-END.bin] 2018-05-10 17:19:06 INFO GridCacheDatabaseSharedManager:475 - Applying lost cache updates since last checkpoint record [lastMarked=FileWALPointer [idx=0, fileOffset=276825, len=20281, forceFlush=false], lastCheckpointId=02cd6e24-cbdf-4e0e-83c5-601d98dd991d] 2018-05-10 17:19:06 INFO GridCacheDatabaseSharedManager:475 - Finished applying WAL changes [updatesApplied=0, time=75ms] 2018-05-10 17:19:06 INFO GridCacheDatabaseSharedManager:475 - Finished indexes rebuilding for cache: [name=ignite-sys-cache, grpName=null 2018-05-10 17:19:06 INFO GridDhtPartitionsExchangeFuture:475 - finishExchangeOnCoordinator [topVer=AffinityTopologyVersion [topVer=1, minorTopVer=1], resVer=AffinityTopologyVersion [topVer=1, minorTopVer=1]] 2018-05-10 17:19:06 INFO GridClusterStateProcessor:475 - Received state change finish message: true 2018-05-10 17:19:06 INFO GridDhtPartitionsExchangeFuture:475 - Finish exchange future [startVer=AffinityTopologyVersion [topVer=1, minorTopVer=1], resVer=AffinityTopologyVersion [topVer=1, minorTopVer=1], err=null] 2018-05-10 17:19:06 INFO time:475 - Finished exchange init [topVer=AffinityTopologyVersion [topVer=1, minorTopVer=1], crd=true] 2018-05-10 17:19:06 INFO GridCachePartitionExchangeManager:475 - Skipping rebalancing (nothing scheduled) [top=AffinityTopologyVersion [topVer=1, minorTopVer=1], evt=DISCOVERY_CUSTOM_EVT, node=23933936-99b7-4a6d-b1cf-b9a8ce637516] 2018-05-10 17:19:06 INFO GridClusterStateProcessor:475 - Successfully performed final activation steps [nodeId=23933936-99b7-4a6d-b1cf-b9a8ce637516, client=false, topVer=AffinityTopologyVersion [topVer=1, minorTopVer=1]] 2018-05-10 17:19:06 INFO SpringBootStarter:118 - After server started..... 2018-05-10 17:19:09 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55332, locAddr=/10.0.0.4:47500] 2018-05-10 17:19:10 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55332, rmtPort=55332 2018-05-10 17:19:09 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55379] 2018-05-10 17:19:09 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55378] 2018-05-10 17:19:10 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55378] 2018-05-10 17:19:10 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55378, rmtPort=55378] 2018-05-10 17:19:10 ERROR TcpCommunicationSpi:495 - Failed to process selector key [ses=GridSelectorNioSessionImpl [worker=DirectNioClientWorker [super=AbstractNioClientWorker [idx=2, bytesRcvd=0, bytesSent=18, bytesRcvd0=0, bytesSent0=0, select=true, super=GridWorker [name=grid-nio-worker-tcp-comm-2, igniteInstanceName=null, finished=false, hashCode=692178478, interrupted=false, runner=grid-nio-worker-tcp-comm-2-#27]]], writeBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], readBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], inRecovery=null, outRecovery=null, super=GridNioSessionImpl [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55333, createTime=1525972745492, closeTime=0, bytesSent=18, bytesRcvd=0, bytesSent0=0, bytesRcvd0=0, sndSchedTime=1525972745492, lastSndTime=1525972745492, lastRcvTime=1525972745492, readsPaused=false, filterChain=FilterChain[filters=[GridNioCodecFilter [parser=o.a.i.i.util.nio.GridDirectParser@101749d9, directMode=true], GridConnectionBytesVerifyFilter], accepted=true]]] java.io.IOException: An existing connection was forcibly closed by the remote host at sun.nio.ch.SocketDispatcher.read0(Native Method) at sun.nio.ch.SocketDispatcher.read(Unknown Source) at sun.nio.ch.IOUtil.readIntoNativeBuffer(Unknown Source) at sun.nio.ch.IOUtil.read(Unknown Source) at sun.nio.ch.SocketChannelImpl.read(Unknown Source) at org.apache.ignite.internal.util.nio.GridNioServer$DirectNioClientWorker.processRead(GridNioServer.java:1233) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.processSelectedKeysOptimized(GridNioServer.java:2272) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2048) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1717) at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:110) at java.lang.Thread.run(Unknown Source) 2018-05-10 17:19:11 WARN TcpCommunicationSpi:480 - Closing NIO session because of unhandled exception [cls=class o.a.i.i.util.nio.GridNioException, msg=An existing connection was forcibly closed by the remote host] 2018-05-10 17:19:14 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55378, locAddr=/10.0.0.4:47500] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55378, rmtPort=55378 2018-05-10 17:19:24 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55549] [17:19:15] New version is available at ignite.apache.org: 2.4.0 2018-05-10 17:19:19 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55496] 2018-05-10 17:19:15 ERROR TcpCommunicationSpi:495 - Failed to process selector key [ses=GridSelectorNioSessionImpl [worker=DirectNioClientWorker [super=AbstractNioClientWorker [idx=3, bytesRcvd=0, bytesSent=18, bytesRcvd0=0, bytesSent0=0, select=true, super=GridWorker [name=grid-nio-worker-tcp-comm-3, igniteInstanceName=null, finished=false, hashCode=2006476843, interrupted=false, runner=grid-nio-worker-tcp-comm-3-#28]]], writeBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], readBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], inRecovery=null, outRecovery=null, super=GridNioSessionImpl [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55379, createTime=1525972749960, closeTime=0, bytesSent=18, bytesRcvd=0, bytesSent0=0, bytesRcvd0=0, sndSchedTime=1525972749960, lastSndTime=1525972750902, lastRcvTime=1525972749960, readsPaused=false, filterChain=FilterChain[filters=[GridNioCodecFilter [parser=o.a.i.i.util.nio.GridDirectParser@101749d9, directMode=true], GridConnectionBytesVerifyFilter], accepted=true]]] java.io.IOException: An existing connection was forcibly closed by the remote host at sun.nio.ch.SocketDispatcher.read0(Native Method) at sun.nio.ch.SocketDispatcher.read(Unknown Source) at sun.nio.ch.IOUtil.readIntoNativeBuffer(Unknown Source) at sun.nio.ch.IOUtil.read(Unknown Source) at sun.nio.ch.SocketChannelImpl.read(Unknown Source) at org.apache.ignite.internal.util.nio.GridNioServer$DirectNioClientWorker.processRead(GridNioServer.java:1233) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.processSelectedKeysOptimized(GridNioServer.java:2272) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2048) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1717) at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:110) at java.lang.Thread.run(Unknown Source) 2018-05-10 17:19:35 WARN TcpCommunicationSpi:480 - Closing NIO session because of unhandled exception [cls=class o.a.i.i.util.nio.GridNioException, msg=An existing connection was forcibly closed by the remote host] 2018-05-10 17:19:14 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55426] 2018-05-10 17:19:14 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55427] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55427] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55495] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55495] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55548] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55548] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55598] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55598] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55651] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55651] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55548, rmtPort=55548] 2018-05-10 17:19:35 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55548, locAddr=/10.0.0.4:47500] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55548, rmtPort=55548 2018-05-10 17:19:35 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55650] 2018-05-10 17:19:35 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55599] 2018-05-10 17:19:35 WARN GridUpdateNotifier:480 - New version is available at ignite.apache.org: 2.4.0 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55495, rmtPort=55495] 2018-05-10 17:19:35 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55495, locAddr=/10.0.0.4:47500] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55495, rmtPort=55495 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55598, rmtPort=55598] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55427, rmtPort=55427] 2018-05-10 17:19:35 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55427, locAddr=/10.0.0.4:47500] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55427, rmtPort=55427 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55651, rmtPort=55651] 2018-05-10 17:19:35 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55598, locAddr=/10.0.0.4:47500] 2018-05-10 17:19:35 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55598, rmtPort=55598 2018-05-10 17:19:39 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55651, locAddr=/10.0.0.4:47500] 2018-05-10 17:19:39 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55651, rmtPort=55651 2018-05-10 17:19:39 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55696] 2018-05-10 17:19:39 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55696] 2018-05-10 17:19:39 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55695] 2018-05-10 17:19:39 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55696, rmtPort=55696] 2018-05-10 17:19:40 ERROR TcpCommunicationSpi:495 - Failed to process selector key [ses=GridSelectorNioSessionImpl [worker=DirectNioClientWorker [super=AbstractNioClientWorker [idx=0, bytesRcvd=0, bytesSent=18, bytesRcvd0=0, bytesSent0=18, select=true, super=GridWorker [name=grid-nio-worker-tcp-comm-0, igniteInstanceName=null, finished=false, hashCode=1249121287, interrupted=false, runner=grid-nio-worker-tcp-comm-0-#25]]], writeBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], readBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], inRecovery=null, outRecovery=null, super=GridNioSessionImpl [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55650, createTime=1525972775382, closeTime=0, bytesSent=18, bytesRcvd=0, bytesSent0=18, bytesRcvd0=0, sndSchedTime=1525972775382, lastSndTime=1525972775404, lastRcvTime=1525972775382, readsPaused=false, filterChain=FilterChain[filters=[GridNioCodecFilter [parser=o.a.i.i.util.nio.GridDirectParser@101749d9, directMode=true], GridConnectionBytesVerifyFilter], accepted=true]]] java.io.IOException: An existing connection was forcibly closed by the remote host at sun.nio.ch.SocketDispatcher.read0(Native Method) at sun.nio.ch.SocketDispatcher.read(Unknown Source) at sun.nio.ch.IOUtil.readIntoNativeBuffer(Unknown Source) at sun.nio.ch.IOUtil.read(Unknown Source) at sun.nio.ch.SocketChannelImpl.read(Unknown Source) at org.apache.ignite.internal.util.nio.GridNioServer$DirectNioClientWorker.processRead(GridNioServer.java:1233) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.processSelectedKeysOptimized(GridNioServer.java:2272) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2048) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1717) at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:110) at java.lang.Thread.run(Unknown Source) 2018-05-10 17:19:40 WARN TcpCommunicationSpi:480 - Closing NIO session because of unhandled exception [cls=class o.a.i.i.util.nio.GridNioException, msg=An existing connection was forcibly closed by the remote host] 2018-05-10 17:19:44 WARN TcpDiscoverySpi:480 - Failed to read magic header (too few bytes received) [rmtAddr=/168.63.129.16:55696, locAddr=/10.0.0.4:47500] 2018-05-10 17:19:45 INFO TcpDiscoverySpi:475 - Finished serving remote node connection [rmtAddr=/168.63.129.16:55696, rmtPort=55696 2018-05-10 17:19:44 INFO TcpCommunicationSpi:475 - Accepted incoming communication connection [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55749] 2018-05-10 17:19:44 INFO TcpDiscoverySpi:475 - TCP discovery accepted incoming connection [rmtAddr=/168.63.129.16, rmtPort=55748] 2018-05-10 17:19:45 INFO TcpDiscoverySpi:475 - TCP discovery spawning a new thread for connection [rmtAddr=/168.63.129.16, rmtPort=55748] 2018-05-10 17:19:45 INFO TcpDiscoverySpi:475 - Started serving remote node connection [rmtAddr=/168.63.129.16:55748, rmtPort=55748] 2018-05-10 17:19:45 ERROR TcpCommunicationSpi:495 - Failed to process selector key [ses=GridSelectorNioSessionImpl [worker=DirectNioClientWorker [super=AbstractNioClientWorker [idx=1, bytesRcvd=0, bytesSent=18, bytesRcvd0=0, bytesSent0=0, select=true, super=GridWorker [name=grid-nio-worker-tcp-comm-1, igniteInstanceName=null, finished=false, hashCode=20014811, interrupted=false, runner=grid-nio-worker-tcp-comm-1-#26]]], writeBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], readBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], inRecovery=null, outRecovery=null, super=GridNioSessionImpl [locAddr=/10.0.0.4:47100, rmtAddr=/168.63.129.16:55695, createTime=1525972779978, closeTime=0, bytesSent=18, bytesRcvd=0, bytesSent0=0, bytesRcvd0=0, sndSchedTime=1525972779978, lastSndTime=1525972779989, lastRcvTime=1525972779978, readsPaused=false, filterChain=FilterChain[filters=[GridNioCodecFilter [parser=o.a.i.i.util.nio.GridDirectParser@101749d9, directMode=true], GridConnectionBytesVerifyFilter], accepted=true]]] java.io.IOException: An existing connection was forcibly closed by the remote host at sun.nio.ch.SocketDispatcher.read0(Native Method) at sun.nio.ch.SocketDispatcher.read(Unknown Source) at sun.nio.ch.IOUtil.readIntoNativeBuffer(Unknown Source) at sun.nio.ch.IOUtil.read(Unknown Source) at sun.nio.ch.SocketChannelImpl.read(Unknown Source) at org.apache.ignite.internal.util.nio.GridNioServer$DirectNioClientWorker.processRead(GridNioServer.java:1233) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.processSelectedKeysOptimized(GridNioServer.java:2272) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2048) at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1717) at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:110) at java.lang.Thread.run(Unknown Source) 2018-05-10 17:19:45 WARN TcpCommunicationSpi:480 - Closing NIO session because of unhandled exception [cls=class o.a.i.i.util.nio.GridNioException, msg=An existing connection was forcibly closed by the remote host]