Wolf-Dieter Fink schrieb:
I suppose you leave the test without load for 30mins, right?
Yes, there is no load on the client and therefore there are no requests send to JBoss. There are no additional clients in my test environment, so there is also no server load during the 30mins.
Wolf-Dieter Fink schrieb:
do you see any messages in the log if you set 'org.jboss.ejb' to TRACE at the client
Sorry, I cannot provide a client log at the moment
Wolf-Dieter Fink schrieb:
do you see any messages in the log if you set [...] org.jboss.remote (remoting) and org.xnio to TRACE at server side?
This is writte to the server log:
07:47:33,835 TRACE [org.xnio.channels.framed] (Remoting "testserver" read-1) Did not read a length
07:47:33,836 TRACE [org.xnio.nio.selector] (Remoting "testserver" read-1) Beginning select on sun.nio.ch.WindowsSelectorImpl@5a424a96
08:52:01,337 TRACE [org.xnio.nio.selector] (Remoting "testserver" read-1) Selected on sun.nio.ch.WindowsSelectorImpl@5a424a96
08:52:01,338 TRACE [org.xnio.nio.selector] (Remoting "testserver" read-1) Selected key sun.nio.ch.SelectionKeyImpl@1ba62063 for java.nio.channels.SocketChannel[connected local=testserver/192.168.11.62:4447 remote=/192.168.10.146:51059]
08:52:01,338 TRACE [org.xnio.listener] (Remoting "testserver" read-1) Invoking listener Read listener for org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956> on channel TCP socket channel (NIO) <2599e956>
08:52:01,339 TRACE [org.xnio.listener] (Remoting "testserver" read-1) Invoking listener org.jboss.remoting3.remote.RemoteReadListener@4abf8d4e on channel org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>
08:52:01,339 TRACE [org.xnio.channels.framed] (Remoting "testserver" read-1) Copying message from java.nio.HeapByteBuffer[pos=4 lim=158 cap=8196] into java.nio.HeapByteBuffer[pos=0 lim=8192 cap=8192]
08:52:01,341 TRACE [org.xnio.channels.framed] (Remoting "testserver" read-1) Did not read a length
08:52:01,343 TRACE [org.xnio.nio.selector] (Remoting "testserver" read-1) Beginning select on sun.nio.ch.WindowsSelectorImpl@5a424a96
08:52:01,343 TRACE [org.xnio.channels.framed] (Remoting "testserver" task-2) Accepting java.nio.HeapByteBuffer[pos=0 lim=11 cap=8192] into java.nio.HeapByteBuffer[pos=0 lim=8196 cap=8196]
08:52:01,343 TRACE [org.xnio.channels.framed] (Remoting "testserver" task-2) Accepted a message into java.nio.HeapByteBuffer[pos=15 lim=8196 cap=8196]
08:52:01,344 TRACE [org.xnio.channels.framed] (Remoting "testserver" task-2) Fully flushed org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>
08:52:01,359 TRACE [org.xnio.safe-close] (Remoting "testserver" task-2) Closing resource org.jboss.remoting3.remote.InboundMessage$3@6dfb9261
08:52:01,402 INFO [stdout] (EJB default - 4) Hibernate: select [...]
08:52:01,420 TRACE [org.xnio.channels.framed] (EJB default - 4) Accepting java.nio.HeapByteBuffer[pos=0 lim=1639 cap=8192] into java.nio.HeapByteBuffer[pos=0 lim=8196 cap=8196]
08:52:01,420 TRACE [org.xnio.channels.framed] (EJB default - 4) Accepted a message into java.nio.HeapByteBuffer[pos=1643 lim=8196 cap=8196]
08:52:01,421 TRACE [org.xnio.channels.framed] (EJB default - 4) Fully flushed org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>
08:52:01,421 TRACE [org.xnio.channels.framed] (EJB default - 4) Fully flushed org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>
08:52:01,421 TRACE [org.xnio.channels.framed] (EJB default - 4) Accepting java.nio.HeapByteBuffer[pos=0 lim=8 cap=8192] into java.nio.HeapByteBuffer[pos=0 lim=8196 cap=8196]
08:52:01,422 TRACE [org.xnio.channels.framed] (EJB default - 4) Accepted a message into java.nio.HeapByteBuffer[pos=12 lim=8196 cap=8196]
08:52:01,422 TRACE [org.xnio.channels.framed] (EJB default - 4) Fully flushed org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>
08:52:01,422 TRACE [org.xnio.channels.framed] (EJB default - 4) Fully flushed org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>
08:52:22,852 TRACE [org.xnio.nio.selector] (Remoting "testserver" read-1) Selected on sun.nio.ch.WindowsSelectorImpl@5a424a96
08:52:22,852 TRACE [org.xnio.nio.selector] (Remoting "testserver" read-1) Selected key sun.nio.ch.SelectionKeyImpl@1ba62063 for java.nio.channels.SocketChannel[connected local=testserver/192.168.11.62:4447 remote=/192.168.10.146:51059]
08:52:22,852 TRACE [org.xnio.listener] (Remoting "testserver" read-1) Invoking listener Read listener for org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956> on channel TCP socket channel (NIO) <2599e956>
08:52:22,853 TRACE [org.xnio.listener] (Remoting "testserver" read-1) Invoking listener org.jboss.remoting3.remote.RemoteReadListener@4abf8d4e on channel org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>
08:52:22,854 ERROR [org.jboss.remoting.remote.connection] (Remoting "testserver" read-1) JBREM000200: Remote connection failed: java.io.IOException: Eine vorhandene Verbindung wurde vom Remotehost geschlossen
08:52:22,854 TRACE [org.xnio.safe-close] (Remoting "testserver" read-1) Closing resource org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>
08:52:22,854 TRACE [org.xnio.channels.framed] (Remoting "testserver" read-1) Fully flushed org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>
08:52:22,855 TRACE [org.xnio.nio.tcp.channel] (Remoting "testserver" read-1) Closing TCP socket channel (NIO) <2599e956>
08:52:22,855 TRACE [org.xnio.nio] (Remoting "testserver" read-1) Cancelling key sun.nio.ch.SelectionKeyImpl@1ba62063 of java.nio.channels.SocketChannel[closed] (same thread)
08:52:22,855 TRACE [org.xnio.nio.selector] (Remoting "testserver" write-1) Selected on sun.nio.ch.WindowsSelectorImpl@235b7c7f
08:52:22,855 TRACE [org.xnio.nio] (Remoting "testserver" read-1) Cancelling key sun.nio.ch.SelectionKeyImpl@379851c3 of java.nio.channels.SocketChannel[closed] (other thread)
08:52:22,856 TRACE [org.xnio.nio.selector] (Remoting "testserver" write-1) Beginning select on sun.nio.ch.WindowsSelectorImpl@235b7c7f (with timeout)
08:52:22,856 TRACE [org.xnio.listener] (Remoting "testserver" read-1) Invoking listener Close listener for org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956> on channel TCP socket channel (NIO) <2599e956>
08:52:22,856 TRACE [org.xnio.nio.selector] (Remoting "testserver" write-1) Selected on sun.nio.ch.WindowsSelectorImpl@235b7c7f
08:52:22,856 TRACE [org.xnio.safe-close] (Remoting "testserver" read-1) Closing resource org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>
08:52:22,857 TRACE [org.xnio.nio.selector] (Remoting "testserver" write-1) Beginning select on sun.nio.ch.WindowsSelectorImpl@235b7c7f (with timeout)
08:52:22,857 TRACE [org.xnio.safe-close] (Remoting "testserver" read-1) Closing resource TCP socket channel (NIO) <2599e956>
08:52:22,857 TRACE [org.xnio.listener] (Remoting "testserver" read-1) Invoking listener org.jboss.remoting3.remote.RemoteReadListener$1@4f2dd7fe on channel org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>
08:52:22,858 TRACE [org.xnio.nio.selector] (Remoting "testserver" read-1) Beginning select on sun.nio.ch.WindowsSelectorImpl@5a424a96
08:52:22,858 TRACE [org.xnio.safe-close] (Remoting "testserver" task-3) Closing resource org.xnio.channels.FramedMessageChannel around TCP socket channel (NIO) <2599e956>