When connecting through from .NET (via the .NET OpenClient, v11.3.0) through to a 10.2B AIA (and then an 11.3 AppServer, running in State-free mode), we are experiencing some intermittent errors. The exception that we get is:
[[[
Communication layer message: Invalid state for read: Current state = STATE_ALLOCATED (7249) . (7175)
at Progress.Open4GL.Proxy.ProObject.runProcedure(String requestID, String procName, ParameterSet params_Renamed, MetaSchema schema)
at Progress.Open4GL.Proxy.ProObject.runProcedure(String procName, ParameterSet params_Renamed, MetaSchema schema)
]]]
This only seems to happen on the second request that we send through to the appserver. For example:
Note that it isn't always the second request - it can take 3 or 4 requests sometimes before the failure happens.
If we close the application between (2) and (3), everything works fine.
We are creating a single connection through to the AppServer at (2), and reusing that connection for the valid request in (3). This is the same model we use for the connections through to our main Application AppServer, and we do not have the same issues.
There is no proxy between our client and the AppServer/AIA.
From the AIA logs, we are seeing the following pattern for a valid request:
[[[
[16/11/11@16:50:46.212+1000] P-001588 T-ajp-bio-8009-exec-5599 1 AIA ----------- Unauthorized admin access from 192.168.30.55
[16/11/11@16:50:51.176+1000] P-001588 T-ajp-bio-8009-exec-5597 2 AIA AiaMgmt SC-403274 Negotiated ASK Version= 0.0 Capabilities= denyServerASK,denyClientASK
]]]
For an invalid request:
[[[
[16/11/11@16:50:57.570+1000] P-001588 T-ajp-bio-8009-exec-5600 1 AIA ----------- Aia ERROR : invalid connection handle
[16/11/11@16:50:58.789+1000] P-001588 T-ajp-bio-8009-exec-5597 2 AIA AiaMgmt SC-403275 Negotiated ASK Version= 0.0 Capabilities= denyServerASK,denyClientASK
[16/11/11@16:51:00.298+1000] P-001588 T-ajp-bio-8009-exec-5597 1 AIA ----------- Unauthorized admin access from 192.168.30.55
]]]
I've also enabled logging in the .NET OpenClient, and the below is a snipped from a failed request:
[[[
[16/11/11@17:26:16.975+1000] P-009936 T-1 4 O4GL _O4GL WRITEDATALAST IOException: System.IO.IOException: 9990
[16/11/11@17:26:16.975+1000] P-009936 T-1 4 O4GL _O4GL at Progress.UBroker.Client.HttpClientMsgOutputStream.writeMsg(ubMsg msg)
[16/11/11@17:26:16.975+1000] P-009936 T-1 4 O4GL _O4GL at Progress.UBroker.Client.BrokerSystem.writeMsg(ubAppServerMsg msg, Boolean flush)
[16/11/11@17:26:16.975+1000] P-009936 T-1 4 O4GL _O4GL at Progress.UBroker.Client.BrokerSystem.writeLastPacket(Int32 reason, Byte[] msgbuf, Int32 len)
[16/11/11@17:26:16.975+1000] P-009936 T-1 1 O4GL Trace WRITELAST: IOException: System.IO.IOException: 9990
[16/11/11@17:26:16.975+1000] P-009936 T-1 1 O4GL Trace at Progress.UBroker.Client.HttpClientMsgOutputStream.writeMsg(ubMsg msg)
[16/11/11@17:26:16.975+1000] P-009936 T-1 1 O4GL Trace at Progress.UBroker.Client.BrokerSystem.writeMsg(ubAppServerMsg msg, Boolean flush)
[16/11/11@17:26:16.975+1000] P-009936 T-1 1 O4GL Trace at Progress.UBroker.Client.BrokerSystem.writeLastPacket(Int32 reason, Byte[] msgbuf, Int32 len)
[16/11/11@17:26:16.977+1000] P-009936 T-1 1 O4GL Trace Progress.Open4GL.Broker.BrokerException: General Error: prepareToReceive() error
[16/11/11@17:26:16.977+1000] P-009936 T-1 1 O4GL Trace at Progress.UBroker.Client.BrokerSystem.throwBrokerException(String exceptionMsg)
[16/11/11@17:26:16.977+1000] P-009936 T-1 1 O4GL Trace at Progress.UBroker.Client.BrokerSystem.prepareToReceive(Int32 reason)
[16/11/11@17:26:16.977+1000] P-009936 T-1 1 O4GL Trace at Progress.Open4GL.DynamicAPI.SrcOutputStream.Close()
]]]
Has anyone come across this issue before? Any ideas about where it might be occurring?
Daniel, please open a support case with Tech Support for this.