Terracotta Discussion Forums

Hi all,

Just a quick question, we have been using terracota server for a while, and all of the sudden the clients can not connect to the terracotta server, both are displaying the following error:

Code:

@@@@ 2049894373 -> OOOLayer-SERVER-ConnectionID(0.f1e695df4de54fd29f91cb127c0a9e56.5bd4b16c-f019-4b36-842b-a38f3efc24c3-136a1920c4b) -> not firing Tx connected event to channel
 
 @@@@ 2049894373 -> OOOLayer-SERVER-ConnectionID(1.f1e695df4de54fd29f91cb127c0a9e56.755a81c3-50fc-4ff4-b67c-f4504187763e-136a1938a92) -> not firing Tx connected event to channel

Have you even come across this message? what does it exactly mean?

Thank you very much

Regards

Daniel

I have found more information in the terracotta client.

Does someone know what it is going on between the server and client?, why can not they get connectity?

Code:

 2012-04-17 11:45:04,259 [AWT-EventQueue-0] INFO org.terracotta.api.org.terracotta.modules.ehcache.store.TerracottaClusteredInstanceFactory - Ehcache Core version 2.5.1 was built on 20120130-1113, at revision 5203, with jdk 1.6.0_27 by cruise@kong.terracotta.lan
 2012-04-17 11:45:08,924 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnection - error reading from channel java.nio.channels.SocketChannel[connected local=/10.243.50.74:50010 remote=/10.243.180.124:9510]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:08,924 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnectionManager - error event on connection com.tc.net.core.TCConnectionImpl@9862796: connected: true, closed: false local=10.243.50.74:50010 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:02 BST 2012] idle=4540ms [473 read, 738 write]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:08,924 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): CLOSE EVENT : com.tc.net.core.TCConnectionImpl@9862796: connected: false, closed: true local=10.243.50.74:50010 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:02 BST 2012] idle=4540ms [473 read, 738 write]. STATUS : ESTABLISHED
 2012-04-17 11:45:08,924 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientConnectionEstablisher$AsyncReconnect - Handling connection request: RestoreConnectionRequest [type=RESTORE_CONNECTION, clientMessageTransport=com.tc.net.protocol.transport.ClientMessageTransport@7a631c70, callback=com.tc.net.protocol.delivery.OOOConnectionWatcher@e03bd6, timeoutMillis=5000, sa=10.243.180.124:9510]
 2012-04-17 11:45:08,924 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): Attaching new connection: com.tc.net.core.TCConnectionImpl@1578131290: connected: true, closed: false local=10.243.50.74:50013 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:08 BST 2012] idle=0ms [0 read, 0 write]
 2012-04-17 11:45:08,939 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.protocol.delivery.SendStateMachine - SENDER-CLIENT-ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd); AckSeq: 2 Acked: 0
 2012-04-17 11:45:13,479 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnection - error reading from channel java.nio.channels.SocketChannel[connected local=/10.243.50.74:50013 remote=/10.243.180.124:9510]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:13,479 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnectionManager - error event on connection com.tc.net.core.TCConnectionImpl@1578131290: connected: true, closed: false local=10.243.50.74:50013 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:08 BST 2012] idle=4352ms [468 read, 844 write]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:13,479 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): CLOSE EVENT : com.tc.net.core.TCConnectionImpl@1578131290: connected: false, closed: true local=10.243.50.74:50013 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:08 BST 2012] idle=4352ms [468 read, 844 write]. STATUS : ESTABLISHED
 2012-04-17 11:45:13,479 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientConnectionEstablisher$AsyncReconnect - Handling connection request: RestoreConnectionRequest [type=RESTORE_CONNECTION, clientMessageTransport=com.tc.net.protocol.transport.ClientMessageTransport@7a631c70, callback=com.tc.net.protocol.delivery.OOOConnectionWatcher@e03bd6, timeoutMillis=5000, sa=10.243.180.124:9510]
 2012-04-17 11:45:13,479 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): Attaching new connection: com.tc.net.core.TCConnectionImpl@1023143764: connected: true, closed: false local=10.243.50.74:50018 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:13 BST 2012] idle=0ms [0 read, 0 write]
 2012-04-17 11:45:13,495 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.protocol.delivery.SendStateMachine - SENDER-CLIENT-ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd); AckSeq: 3 Acked: 2
 2012-04-17 11:45:18,050 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnection - error reading from channel java.nio.channels.SocketChannel[connected local=/10.243.50.74:50018 remote=/10.243.180.124:9510]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:18,050 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnectionManager - error event on connection com.tc.net.core.TCConnectionImpl@1023143764: connected: true, closed: false local=10.243.50.74:50018 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:13 BST 2012] idle=4524ms [454 read, 975 write]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:18,050 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): CLOSE EVENT : com.tc.net.core.TCConnectionImpl@1023143764: connected: false, closed: true local=10.243.50.74:50018 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:13 BST 2012] idle=4524ms [454 read, 975 write]. STATUS : ESTABLISHED
 2012-04-17 11:45:18,050 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientConnectionEstablisher$AsyncReconnect - Handling connection request: RestoreConnectionRequest [type=RESTORE_CONNECTION, clientMessageTransport=com.tc.net.protocol.transport.ClientMessageTransport@7a631c70, callback=com.tc.net.protocol.delivery.OOOConnectionWatcher@e03bd6, timeoutMillis=5000, sa=10.243.180.124:9510]
 2012-04-17 11:45:18,050 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): Attaching new connection: com.tc.net.core.TCConnectionImpl@1002276202: connected: true, closed: false local=10.243.50.74:50019 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:18 BST 2012] idle=0ms [0 read, 0 write]
 2012-04-17 11:45:18,066 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.protocol.delivery.SendStateMachine - SENDER-CLIENT-ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd); AckSeq: 5 Acked: 3
 2012-04-17 11:45:22,605 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnection - error reading from channel java.nio.channels.SocketChannel[connected local=/10.243.50.74:50019 remote=/10.243.180.124:9510]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:22,605 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnectionManager - error event on connection com.tc.net.core.TCConnectionImpl@1002276202: connected: true, closed: false local=10.243.50.74:50019 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:18 BST 2012] idle=4539ms [372 read, 753 write]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:22,605 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): CLOSE EVENT : com.tc.net.core.TCConnectionImpl@1002276202: connected: false, closed: true local=10.243.50.74:50019 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:18 BST 2012] idle=4539ms [372 read, 753 write]. STATUS : ESTABLISHED
 2012-04-17 11:45:22,605 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientConnectionEstablisher$AsyncReconnect - Handling connection request: RestoreConnectionRequest [type=RESTORE_CONNECTION, clientMessageTransport=com.tc.net.protocol.transport.ClientMessageTransport@7a631c70, callback=com.tc.net.protocol.delivery.OOOConnectionWatcher@e03bd6, timeoutMillis=5000, sa=10.243.180.124:9510]
 2012-04-17 11:45:22,605 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): Attaching new connection: com.tc.net.core.TCConnectionImpl@355314512: connected: true, closed: false local=10.243.50.74:50020 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:22 BST 2012] idle=0ms [0 read, 0 write]
 2012-04-17 11:45:22,621 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.protocol.delivery.SendStateMachine - SENDER-CLIENT-ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd); AckSeq: 6 Acked: 5
 2012-04-17 11:45:27,161 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnection - error reading from channel java.nio.channels.SocketChannel[connected local=/10.243.50.74:50020 remote=/10.243.180.124:9510]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:27,161 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnectionManager - error event on connection com.tc.net.core.TCConnectionImpl@355314512: connected: true, closed: false local=10.243.50.74:50020 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:22 BST 2012] idle=4524ms [881 read, 933 write]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:27,161 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): CLOSE EVENT : com.tc.net.core.TCConnectionImpl@355314512: connected: false, closed: true local=10.243.50.74:50020 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:22 BST 2012] idle=4524ms [881 read, 933 write]. STATUS : ESTABLISHED
 2012-04-17 11:45:27,161 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientConnectionEstablisher$AsyncReconnect - Handling connection request: RestoreConnectionRequest [type=RESTORE_CONNECTION, clientMessageTransport=com.tc.net.protocol.transport.ClientMessageTransport@7a631c70, callback=com.tc.net.protocol.delivery.OOOConnectionWatcher@e03bd6, timeoutMillis=5000, sa=10.243.180.124:9510]
 2012-04-17 11:45:27,161 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): Attaching new connection: com.tc.net.core.TCConnectionImpl@511031769: connected: true, closed: false local=10.243.50.74:50021 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:27 BST 2012] idle=0ms [0 read, 0 write]
 2012-04-17 11:45:27,176 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.protocol.delivery.SendStateMachine - SENDER-CLIENT-ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd); AckSeq: 7 Acked: 6
 2012-04-17 11:45:31,700 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnection - error reading from channel java.nio.channels.SocketChannel[connected local=/10.243.50.74:50021 remote=/10.243.180.124:9510]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:31,700 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnectionManager - error event on connection com.tc.net.core.TCConnectionImpl@511031769: connected: true, closed: false local=10.243.50.74:50021 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:27 BST 2012] idle=4524ms [851 read, 1399 write]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:31,700 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] WARN com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): CLOSE EVENT : com.tc.net.core.TCConnectionImpl@511031769: connected: false, closed: true local=10.243.50.74:50021 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:27 BST 2012] idle=4524ms [851 read, 1399 write]. STATUS : ESTABLISHED
 2012-04-17 11:45:31,700 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientConnectionEstablisher$AsyncReconnect - Handling connection request: RestoreConnectionRequest [type=RESTORE_CONNECTION, clientMessageTransport=com.tc.net.protocol.transport.ClientMessageTransport@7a631c70, callback=com.tc.net.protocol.delivery.OOOConnectionWatcher@e03bd6, timeoutMillis=5000, sa=10.243.180.124:9510]
 2012-04-17 11:45:31,700 [ConnectionEstablisher] INFO com.tc.net.protocol.transport.ClientMessageTransport - ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd): Attaching new connection: com.tc.net.core.TCConnectionImpl@625689160: connected: true, closed: false local=10.243.50.74:50022 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:31 BST 2012] idle=0ms [0 read, 0 write]
 2012-04-17 11:45:31,716 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.protocol.delivery.SendStateMachine - SENDER-CLIENT-ConnectionID(0.2380c8f8c1fc4a9d80f6cf2bb9cb8a57.a1f27e13-7c28-43c1-992b-c4aee295f5b5-136bfe79ffd); AckSeq: 9 Acked: 7
 2012-04-17 11:45:36,240 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnection - error reading from channel java.nio.channels.SocketChannel[connected local=/10.243.50.74:50022 remote=/10.243.180.124:9510]: An established connection was aborted by the software in your host machine
 2012-04-17 11:45:36,240 [L1_L2:TCComm Main Selector Thread_R (listen 0.0.0.0:50006)] INFO com.tc.net.core.TCConnectionManager - error event on connection com.tc.net.core.TCConnectionImpl@625689160: connected: true, closed: false local=10.243.50.74:50022 remote=10.243.180.124:9510 connect=[Tue Apr 17 11:45:31 BST 2012] idle=4524ms [213 read, 957 write]: An established connection was aborted by the software in your host machine

Thank you very much.

Daniel

Above message means that when server thinks that clients are connected but actually cleints are disconnected.
In this case server does not allow to clients to connect to server.
You need to restart complete cluster. That means restart your terracotta server and clients.

Cheers!!!!!