[DERBY-6831] org.apache.derby.client.am.DisconnectException: A communications error has been detected: Read timed out.

• Type: Bug
• Status: Open
• Priority: Major
• Resolution: Unresolved
• Affects Version/s: 10.5.3.0
• Component/s: Network Client
• Environment: Windows 2003
• Urgency: Normal
• Bug behavior facts: Crash
• Assignee: Unassigned
• Reporter: Ashlesha
• Created: 04/Sep/15 06:36
• Updated: 14/Oct/15 21:16

Description

We are using derby server 10.5.3.0 version with our product where we are consistently getting below connection timed out error :
Caused by: java.sql.SQLException: A communications error has been detected: Read timed out.
at org.apache.derby.client.am.SQLExceptionFactory.getSQLException(Unknown Source)
at org.apache.derby.client.am.SqlException.getSQLException(Unknown Source)
at org.apache.derby.client.am.PreparedStatement.executeQuery(Unknown Source)
at com.ibm.ac.si.iuregistry.IUTypeDatabaseImpl.orderInstanceIDsByTimeStamp(IUTypeDatabaseImpl.java:7359)
at com.ibm.ac.si.iuregistry.IUTypeDatabaseImpl.listUndoOrder(IUTypeDatabaseImpl.java:10124)
... 18 more
Caused by: org.apache.derby.client.am.DisconnectException: A communications error has been detected: Read timed out.
at org.apache.derby.client.net.NetAgent.throwCommunicationsFailure(Unknown Source)
at org.apache.derby.client.net.Reply.fill(Unknown Source)
at org.apache.derby.client.net.Reply.ensureALayerDataInBuffer(Unknown Source)
at org.apache.derby.client.net.Reply.readDssHeader(Unknown Source)
at org.apache.derby.client.net.Reply.startSameIdChainParse(Unknown Source)
at org.apache.derby.client.net.NetStatementReply.readOpenQuery(Unknown Source)
at org.apache.derby.client.net.StatementReply.readOpenQuery(Unknown Source)
at org.apache.derby.client.net.NetStatement.readOpenQuery_(Unknown Source)
at org.apache.derby.client.am.Statement.readOpenQuery(Unknown Source)
at org.apache.derby.client.am.PreparedStatement.flowExecute(Unknown Source)
at org.apache.derby.client.am.PreparedStatement.executeQueryX(Unknown Source)
... 21 more
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.read(SocketInputStream.java:155)
... 31 more

The derby logs doesn't show any error message but for the last prepared statement query it waits for sometime (around 5 mins) and then our program comes out i.e it ends with connection timed out error .

Derby log statements :

2015-09-03 09:40:01.308 GMT Thread[DRDAConnThread_8,5,derby.daemons] (XID = 6580), (SESSIONID = 3), (DATABASE = IURegistryDB), (DRDAID = NF000001.G418-1011056675071531060

{4}), Begin compiling prepared statement: SELECT IUI.INSTANCE_ID FROM IU_INSTANCE IUI WHERE IUI.INSTANCE_ID IN (?,?) ORDER BY IUI.CREATED_TIME DESC :End prepared statement
2015-09-03 09:40:01.308 GMT Thread[DRDAConnThread_8,5,derby.daemons] (XID = 6580), (SESSIONID = 3), (DATABASE = IURegistryDB), (DRDAID = NF000001.G418-1011056675071531060{4}

), End compiling prepared statement: SELECT IUI.INSTANCE_ID FROM IU_INSTANCE IUI WHERE IUI.INSTANCE_ID IN (?,?) ORDER BY IUI.CREATED_TIME DESC :End prepared statement
2015-09-03 09:40:01.324 GMT Thread[DRDAConnThread_8,5,derby.daemons] (XID = 6580), (SESSIONID = 3), (DATABASE = IURegistryDB), (DRDAID = NF000001.G418-1011056675071531060

{4}

), Executing prepared statement: SELECT IUI.INSTANCE_ID FROM IU_INSTANCE IUI WHERE IUI.INSTANCE_ID IN (?,?) ORDER BY IUI.CREATED_TIME DESC :End prepared statement with 2 parameters begin parameter #1: NULL :end parameter begin parameter #2: f26326912b681e5b:77389106:14f73d7488b:-778a :end parameter
2015-09-03 09:42:03.105 GMT Thread[DRDAConnThread_9,5,derby.daemons] (XID = 424), (SESSIONID = 3), (DATABASE = TouchPointRegistryDB), (DRDAID = NF000001.G534-868067386902462499

{5}), Rolling back
2015-09-03 09:42:03.105 GMT Thread[DRDAConnThread_9,5,derby.daemons] (XID = 424), (SESSIONID = 3), (DATABASE = TouchPointRegistryDB), (DRDAID = NF000001.G534-868067386902462499{5}

), Rolling back
2015-09-03 09:48:54.777 GMT Thread[DRDAConnThread_7,5,derby.daemons] (XID = 382), (SESSIONID = 1), (DATABASE = TouchPointRegistryDB), (DRDAID = NF000001.H380-508905316620002396

{3}), Committing
2015-09-03 09:48:54.777 GMT Thread[DRDAConnThread_5,5,derby.daemons] (XID = 6545), (SESSIONID = 1), (DATABASE = IURegistryDB), (DRDAID = NF000001.H37F-592784859679789228{2}), Committing
2015-09-03 09:48:54.777 GMT Thread[DRDAConnThread_7,5,derby.daemons] (XID = 382), (SESSIONID = 1), (DATABASE = TouchPointRegistryDB), (DRDAID = NF000001.H380-508905316620002396{3}

), Rolling back
2015-09-03 09:48:54.777 GMT Thread[DRDAConnThread_7,5,derby.daemons] (XID = 382), (SESSIONID = 1), (DATABASE = TouchPointRegistryDB), (DRDAID = NF000001.H380-508905316620002396

{3}

), Rolling back
2015-09-03 09:48:54.777 GMT Thread[DRDAConnThread_5,5,derby.daemons] (XID = 6545), (SESSIONID = 1), (DATABASE = IURegistryDB), (DRDAID = NF000001.H37F-592784859679789228

{2}), Rolling back
2015-09-03 09:48:54.777 GMT Thread[DRDAConnThread_5,5,derby.daemons] (XID = 6545), (SESSIONID = 1), (DATABASE = IURegistryDB), (DRDAID = NF000001.H37F-592784859679789228{2}

), Rolling back

Not sure why derby connection is timed out. Could you please go through the derby log and help us to understand the cause of failure?