Hello, I'm developing a .NET service that includes the Oracle.ManagedDataAccess.dll fetched from nuget (it's not the latest version available):
[assembly: AssemblyInformationalVersion("4.121.2.20150926 ODAC RELEASE 4")]
[assembly: AssemblyProduct("Oracle Data Provider for .NET, Managed Driver")]
[assembly: AssemblyVersion("4.121.2.0")]
[assembly: PoweredBy("Powered by SmartAssembly 6.8.0.121")]
In order to execute a procedure a connection is opened, a command is created and a OracleDataAdapter.Fill is used to fill a DataTable with the result.
When a command takes too long to execute or the transmission of the response from the database to the client takes too long I can cancel the command.
In order to cancel the command first the OracleCommand.Cancel() is invoked, then the OracleConnection.Close() is invoked and at last the OracleDataAdapter.Dispose() is called..
Everything works as expected except when I call a particular database procedure that is blocked reading from a queue table.
In this scenario the call to OracleDataAdapter.Fill never returns or throw's an exception. I have activated the oracle client tracing by placing the following at app.config.
<configSections>
<section name="oracle.manageddataaccess.client" type="OracleInternal.Common.ODPMSectionHandler,Oracle.ManagedDataAccess" />
</configSections>
<oracle.manageddataaccess.client>
<version number="*">
<settings>
<setting name="TraceFileLocation" value="c:\temp\" />
<setting name="TraceLevel" value="7" />
<setting name="TraceOption" value="0" />
</settings>
</version>
</oracle.manageddataaccess.client>
The result of the operation described above is registered in the log below:
After cancelling the command, closing the connection and disposing the OracleDataAdapter, there is a ORA-02055 followed by a ORA-01013. Between these two errors the following exception was registered:
2017-01-26 17:36:05.977023 TID:21 (PRI) (TTC) (ERR) TTCError.ReadErrorMessage() (txnid=n/a) System.NullReferenceException: Object reference not set to an instance of an object.
at OracleInternal.TTC.TTCError.ReadErrorMessage()
After the ORA-01013 lots of the same System.NullReferenceException were registered in something that seems a tight loop.
2017-01-26 17:36:05.977023 TID:21 (PRI) (TTC) (ERR) TTCError.ReadErrorMessage() (txnid=n/a) System.NullReferenceException: Object reference not set to an instance of an object.
at OracleInternal.TTC.TTCError.ReadErrorMessage()
Can someone help me with this issue please?
Thanks.
Full log:
2017-01-26 17:35:31.830211 TID:21 (PRI) (CP) Pool`3.Get() (oper=get:reg1) (aff=n/a) (inst=TESTDB) (affmatch=n/a) (sessid=133:28481) (F;F;T;TESTDB;N) (pmid=54030073)
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) (CP) Pool`3.Get()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) (CP) OraclePool.Get()
2017-01-26 17:35:31.830211 TID:21 (PRI) (CP) PoolManager`3.Get() (oper=pm:get:rro1) (aff=n/a) (inst=TESTDB) (affmatch=n/a) (sessid=133:28481) (F;F;T;TESTDB;N) (pmid=54030073)
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) (CP) PoolManager`3.Get() (aff=n/a) (inst=TESTDB) (affmatch=n/a) (sessid=133:28481) (T;F;F;TESTDB;N) (pmid=54030073) PM.Get(aff=;force=F) returning (bMatchFound=F)
2017-01-26 17:35:31.830211 TID:21 (NET) (SND) 00 0A 00 00 06 00 00 00 |........|
2017-01-26 17:35:31.830211 TID:21 (NET) (SND) 00 00 |.. |
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) (CP) OraclePoolManager.Get() (aff=n/a) (inst=TESTDB) (affmatch=n/a) (sessid=133:28481) (T;F;F;TESTDB;N) (pmid=54030073)
2017-01-26 17:35:31.830211 TID:21 (PRI) (TUN) OracleTuningAgent::Register(): Registered pool User Id=JOHN;Password=;DATA SOURCE=(DESCRIPTION=(ADDRESS_LIST=(ADDRESS=(PROTOCOL=TCP)(HOST=###.###.###.##)(PORT=1524)))(CONNECT_DATA=(SERVICE_NAME=TESTDB))); Connection Timeout=120;
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) (CP) OracleConnectionDispenser`3.Get() (aff=n/a) (inst=TESTDB) (affmatch=n/a) (sessid=133:28481) (T;F;F;TESTDB;N) (pmid=54030073)
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleConnection.CheckForWarnings()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleConnection.CheckForWarnings()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleConnection.OnStateChange()
2017-01-26 17:35:31.830211 TID:21 (PUB) (ENT) OracleConnection.GetSessionInfo()
2017-01-26 17:35:31.830211 TID:21 (PUB) (EXT) OracleConnection.GetSessionInfo()
2017-01-26 17:35:31.830211 TID:21 (PUB) (ENT) OracleGlobalization.Dispose()
2017-01-26 17:35:31.830211 TID:21 (PUB) (EXT) OracleGlobalization.Dispose()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleConnection.OnStateChange()
2017-01-26 17:35:31.830211 TID:21 (PRI) (CP) OracleConnection.Open() (GET) (ENDSID=133:28481)
2017-01-26 17:35:31.830211 TID:21 (PUB) (EXT) OracleConnection.Open() (conid=49976148) (state=Open) (sessid=133) (implid=18989437) (pooling=T) (aff=n/a) (inst=TESTDB) (affmatch=n/a) (sessid=133:28481) (T;F;F;TESTDB;N) (pmid=54030073) (DISP) (inst=TESTDB;used=1;idle=0;tot=1)
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleCommand.DoPreExecuteProcessing()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleCommand.BuildCommandText()
2017-01-26 17:35:31.830211 TID:21 (PUB) (ENT) ConfigBaseClass.GetStoredProcInfo() (REFCURSOR) GetRefCursorInfo(MY$PCK$TEST.GET_SOME_DATA)
2017-01-26 17:35:31.830211 TID:21 (PUB) (EXT) ConfigBaseClass.GetStoredProcInfo() (REFCURSOR) GetRefCursorInfo(MY$PCK$TEST.GET_SOME_DATA) : no match
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleCommand.GetReturnValueParam()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleCommand.GetReturnValueParam()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleCommand.BuildCommandText()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleCommand.DoPreExecuteProcessing()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (ENT) OracleCommandImpl.ExecuteReader()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (ENT) OracleCommandImpl.ValidateStatementCacheSize()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (EXT) OracleCommandImpl.ValidateStatementCacheSize()
2017-01-26 17:35:31.830211 TID:21 (PUB) (SQL) Begin :ret := MY$PCK$TEST.GET_SOME_DATA(:v1, :v2, :v3, :v4, :v5, :v6, :v7, :v8, :v9); End;
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (ENT) OracleCommandImpl.ProcessParameters()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (ENT) OracleCommandImpl.InitializeParamInfo()
2017-01-26 17:35:31.830211 TID:21 (PUB) (ENT) OracleParameterCollection.GetEnumerator()
2017-01-26 17:35:31.830211 TID:21 (PUB) (EXT) OracleParameterCollection.GetEnumerator()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.PreBind()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.PreBind()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.PreBind()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.830211 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.PreBind()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.PreBind()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.830211 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.830211 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.PreBind()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.PreBind()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.PreBind()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.PreBind()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.PreBind()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.PreBind()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.PreBind()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.PreBind()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.845911 TID:21 (PRI) (EXT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.PreBind()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.PreBind()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.IsProviderSpecificNullValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.GetBindingSize_Char()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.SetCharDataInBytes()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.PreBind()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.PreBind()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.PreBind()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.PreBind()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.SetNullIndicators()
2017-01-26 17:35:31.861459 TID:21 (PRI) (ENT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.PreBind_Char()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (ENT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (EXT) OracleParameterImpl.GetParameterMetaData()
2017-01-26 17:35:31.861459 TID:21 (PRI) (EXT) OracleParameter.PreBind()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (EXT) OracleCommandImpl.InitializeParamInfo()
2017-01-26 17:35:31.861459 TID:21 (PUB) (PRM) Parameters Count = 10
2017-01-26 17:35:31.861459 TID:21 (PUB) (ENT) OracleParameterCollection.GetEnumerator()
2017-01-26 17:35:31.861459 TID:21 (PUB) (EXT) OracleParameterCollection.GetEnumerator()
2017-01-26 17:35:31.861459 TID:21 (PUB) (PRM) (Name/Position = O_RETURN) (OracleDbType = Varchar2) (Direction = ReturnValue) (Size (In Bytes) = 4000) (Array Bind Count = 0)
2017-01-26 17:35:31.861459 TID:21 (PUB) (PRM) (Name/Position = I_USER_ID) (OracleDbType = Varchar2) (Direction = Input) (Size (In Bytes) = 5) (Array Bind Count = 0)
2017-01-26 17:35:31.861459 TID:21 (PUB) (PRM) (Name/Position = I_LANGUAGE_ID) (OracleDbType = Varchar2) (Direction = Input) (Size (In Bytes) = 3) (Array Bind Count = 0)
2017-01-26 17:35:31.861459 TID:21 (PUB) (PRM) (Name/Position = I_PARAM_1) (OracleDbType = Varchar2) (Direction = Input) (Size (In Bytes) = 5) (Array Bind Count = 0)
2017-01-26 17:35:31.861459 TID:21 (PUB) (PRM) (Name/Position = I_PARAM_2) (OracleDbType = Varchar2) (Direction = Input) (Size (In Bytes) = 4) (Array Bind Count = 0)
2017-01-26 17:35:31.861459 TID:21 (PUB) (PRM) (Name/Position = I_PARAM_3) (OracleDbType = Varchar2) (Direction = Input) (Size (In Bytes) = 3) (Array Bind Count = 0)
2017-01-26 17:35:31.861459 TID:21 (PUB) (PRM) (Name/Position = I_PARAM_4) (OracleDbType = Varchar2) (Direction = Input) (Size (In Bytes) = 1) (Array Bind Count = 0)
2017-01-26 17:35:31.861459 TID:21 (PUB) (PRM) (Name/Position = I_PARAM_5) (OracleDbType = Varchar2) (Direction = Input) (Size (In Bytes) = 1) (Array Bind Count = 0)
2017-01-26 17:35:31.861459 TID:21 (PUB) (PRM) (Name/Position = I_PARAM_6) (OracleDbType = Varchar2) (Direction = Input) (Size (In Bytes) = 1) (Array Bind Count = 0)
2017-01-26 17:35:31.861459 TID:21 (PUB) (PRM) (Name/Position = O_ERROR_MESSAGE) (OracleDbType = Varchar2) (Direction = Output) (Size (In Bytes) = 4000) (Array Bind Count = 0)
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (EXT) OracleCommandImpl.ProcessParameters()
2017-01-26 17:35:31.861459 TID:21 (PUB) (ENT) OracleCommandImpl.CheckForReturningClause() Cmd Text (StoredProcedure): Begin :ret := MY$PCK$TEST.GET_SOME_DATA(:v1, :v2, :v3, :v4, :v5, :v6, :v7, :v8, :v9); End;
Has Returning Clause: False
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (ENT) OracleConnectionImpl.AddAllPiggyBackRequests()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCEndToEndMetrics.Write()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCEndToEndMetrics.Write()
2017-01-26 17:35:31.861459 TID:21 (PRI) (SVC) (EXT) OracleConnectionImpl.AddAllPiggyBackRequests()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.SendExecuteRequest()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.GetExecuteOptions()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.GetExecuteOptions()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.WriteOall8Message()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.WritePisdef()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.WritePisdef()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.WritePisdefData()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalBindMetaData()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCColumnMetaData.WriteMessage()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalBindMetaData()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalBindValues()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalParameterValue()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.MarshalBindValues()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.WritePisdefData()
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 7F 00 00 06 00 00 00 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 00 00 |.. |
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 11 87 00 00 00 02 01 11 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 00 00 00 01 00 00 00 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 00 00 01 00 00 00 00 00 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 03 5E 00 03 04 05 29 00 |.^....).|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 01 62 01 01 0D 00 00 |..b.....|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 00 00 01 01 01 01 0A 00 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 00 00 00 00 00 00 00 00 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 00 00 00 00 00 42 65 |......Be|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 67 69 6E 20 3A 72 65 74 |gin :ret|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 20 3A 3D 20 53 42 4F 24 | := MY$|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 4D 43 53 24 42 43 50 49 |PCK$|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 2E 53 42 4F 4D 43 53 42 |TEST.GET|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 5F 53 45 54 5F 50 46 43 |_SOME_|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 5F 52 45 53 50 28 3A 76 |DATA(:v|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 31 2C 20 3A 76 32 2C 20 |1, :v2, |
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 3A 76 33 2C 20 3A 76 34 |:v3, :v4|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 2C 20 3A 76 35 2C 20 3A |, :v5,
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 76 36 2C 20 3A 76 37 2C |v6, :v7,|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 20 3A 76 38 2C 20 3A 76 | :v8, :v|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 39 29 3B 20 45 6E 64 3B |9); End;|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 01 01 01 00 00 00 00 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 00 00 00 02 80 00 00 00 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 00 01 03 00 00 02 0F A0 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 00 01 10 00 00 01 B2 01 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 02 0F A0 01 03 00 00 01 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 05 00 01 10 00 00 01 B2 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 01 05 01 03 00 00 01 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 03 00 01 10 00 00 01 B2 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 01 03 01 03 00 00 01 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 05 00 01 10 00 00 01 B2 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 01 05 01 03 00 00 01 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 04 00 01 10 00 00 01 B2 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 01 04 01 03 00 00 01 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 03 00 01 10 00 00 01 B2 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 01 03 01 03 00 00 01 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 00 01 10 00 00 01 B2 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 01 01 01 03 00 00 01 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 00 01 10 00 00 01 B2 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 01 01 01 03 00 00 01 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 00 01 10 00 00 01 B2 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 00 01 03 00 00 02 0F |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) A0 00 01 10 00 00 01 B2 |........|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 01 02 0F A0 07 00 05 41 |.......U|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 33 33 31 33 03 50 52 54 |1111.PRT|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 05 31 30 32 37 50 04 58 |.60778.I|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 4C 49 53 03 57 41 52 01 |UYT.DFG.|
2017-01-26 17:35:31.861459 TID:21 (NET) (SND) 53 01 53 00 00 |X.Z.. |
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.WriteOall8Message()
2017-01-26 17:35:31.861459 TID:21 (PRI) (TTC) (EXT) TTCExecuteSql.SendExecuteRequest()
2017-01-26 17:35:31.877683 TID:21 (PRI) (TTC) (ENT) TTCExecuteSql.ReceiveExecuteResponse()
2017-01-26 17:35:31.877683 TID:21 (PRI) (BUF) (COBP.GET) (poolid:10) (key:8192) (bufid:132) (count:7) (OraBufReader.GetDataFromNetwork)
2017-01-26 17:35:50.946819 TID:27 (PRI) (TUN) OracleTuningAgent::DoScan(): No pools identified for optimization. Stay in SCAN state.
2017-01-26 17:35:55.962109 TID:36 (PRI) (ENT) (CP) PoolManager`3.RLBGravitateThreadFunc()
2017-01-26 17:35:55.962109 TID:36 (PRI) (CP) PoolManager`3.RLBGravitateThreadFunc() 5 connections to be unpopulated by the regulator thread
2017-01-26 17:35:55.962109 TID:36 (PRI) (EXT) (CP) PoolManager`3.RLBGravitateThreadFunc()
2017-01-26 17:35:55.962109 TID:36 (PRI) (ENT) (CP) PoolManager`3.UnPopulatePool()
2017-01-26 17:35:55.962109 TID:36 (PRI) (ENT) (CP) OraclePoolManager.Close()
2017-01-26 17:35:55.962109 TID:36 (PRI) (ENT) (CP) PoolManager`3.Close() (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=407:42899) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:55.962109 TID:36 (PRI) (ENT) (CP) OraclePoolManager.RemoveCheckedInPR()
2017-01-26 17:35:55.962109 TID:36 (PRI) (ENT) (CP) PoolManager`3.RemoveCheckedInPR()
2017-01-26 17:35:55.962109 TID:36 (PRI) (EXT) (CP) PoolManager`3.RemoveCheckedInPR() (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=407:42899) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:55.962109 TID:36 (PRI) (EXT) (CP) OraclePoolManager.RemoveCheckedInPR()
2017-01-26 17:35:55.962109 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.DisConnect() (oper=kill) (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=407:42899) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:55.962109 TID:36 (PRI) (CP) OracleConnectionImpl.DisConnect() (DISCON) (ENDSID=407:42899)
2017-01-26 17:35:55.962109 TID:36 (PRI) (MTS) OracleConnectionImpl.DisConnect() Start to disconnect Conn ID = 407
2017-01-26 17:35:55.962109 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.Logoff()
2017-01-26 17:35:55.962109 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.AddAllPiggyBackRequests()
2017-01-26 17:35:55.962109 TID:36 (PRI) (TTC) (ENT) TTCClose.Write()
2017-01-26 17:35:55.962109 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:55.962109 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:55.962109 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (EXT) TTCClose.Write()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (ENT) TTCEndToEndMetrics.Write()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (EXT) TTCEndToEndMetrics.Write()
2017-01-26 17:35:55.977700 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.AddAllPiggyBackRequests()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (ENT) TTCSimpleOperations.WriteMessage()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (EXT) TTCSimpleOperations.WriteMessage()
2017-01-26 17:35:55.977700 TID:36 (NET) (SND) 00 2F 00 00 06 00 00 00 |./......|
2017-01-26 17:35:55.977700 TID:36 (NET) (SND) 00 00 |.. |
2017-01-26 17:35:55.977700 TID:36 (NET) (SND) 11 69 00 01 01 02 01 0C |.i......|
2017-01-26 17:35:55.977700 TID:36 (NET) (SND) 01 0C 11 87 00 00 00 02 |........|
2017-01-26 17:35:55.977700 TID:36 (NET) (SND) 01 11 01 00 00 00 01 00 |........|
2017-01-26 17:35:55.977700 TID:36 (NET) (SND) 00 00 00 00 01 00 00 00 |........|
2017-01-26 17:35:55.977700 TID:36 (NET) (SND) 00 00 03 09 00 |..... |
2017-01-26 17:35:55.977700 TID:36 (PRI) (TTC) (ENT) TTCSimpleOperations.ReadResponse()
2017-01-26 17:35:55.977700 TID:36 (PRI) (BUF) (COBP.GET) (poolid:1) (key:8192) (bufid:136) (count:7) (OraBufReader.GetDataFromNetwork)
2017-01-26 17:35:56.008991 TID:36 (NET) (REC) New receive packet. Header:
2017-01-26 17:35:56.008991 TID:36 (NET) (REC) 00 0E 00 00 06 00 00 00 |........|
2017-01-26 17:35:56.008991 TID:36 (NET) (REC) 00 00 |.. |
2017-01-26 17:35:56.008991 TID:36 (NET) (REC) 09 01 01 00 |.... |
2017-01-26 17:35:56.008991 TID:36 (PRI) (TTC) (EXT) TTCSimpleOperations.ReadResponse()
2017-01-26 17:35:56.008991 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.Logoff()
2017-01-26 17:35:56.008991 TID:36 (PRI) (MTS) OracleConnectionImpl.DisConnect() End to disconnect Conn ID = 407
2017-01-26 17:35:56.008991 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:1) (key:8192) (bufid:0) (count:0) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.008991 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:8) (count:1) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.008991 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:1) (key:8192) (bufid:8) (count:6) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.008991 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:87) (count:2) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.024569 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:1) (key:8192) (bufid:87) (count:5) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.024569 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:7) (count:3) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.024569 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:1) (key:8192) (bufid:7) (count:4) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.024569 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:6) (count:4) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.024569 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:1) (key:8192) (bufid:6) (count:3) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.024569 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:5) (count:5) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.024569 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:1) (key:8192) (bufid:5) (count:2) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.024569 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:4) (count:6) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.024569 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:1) (key:8192) (bufid:4) (count:1) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.024569 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:3) (count:7) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.024569 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.ClearState()
2017-01-26 17:35:56.024569 TID:36 (PRI) (MTS) OracleConnectionImpl.ClearState() Start Setting m_bConnected to false for Conn ID = 407
2017-01-26 17:35:56.024569 TID:36 (PRI) (MTS) OracleConnectionImpl.ClearState() End Setting m_bConnected to false for Conn ID = 407
2017-01-26 17:35:56.024569 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.ClearState()
2017-01-26 17:35:56.024569 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.DisConnect() (oper=kill) (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=-1:-1) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.024569 TID:36 (PRI) (EXT) (CP) PoolManager`3.Close() (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=-1:-1) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832) (pmListPR.count=6)
2017-01-26 17:35:56.024569 TID:36 (PRI) (EXT) (CP) OraclePoolManager.Close()
2017-01-26 17:35:56.024569 TID:36 (PRI) (ENT) (CP) OraclePoolManager.Close()
2017-01-26 17:35:56.024569 TID:36 (PRI) (ENT) (CP) PoolManager`3.Close() (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=332:25181) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.024569 TID:36 (PRI) (ENT) (CP) OraclePoolManager.RemoveCheckedInPR()
2017-01-26 17:35:56.024569 TID:36 (PRI) (ENT) (CP) PoolManager`3.RemoveCheckedInPR()
2017-01-26 17:35:56.024569 TID:36 (PRI) (EXT) (CP) PoolManager`3.RemoveCheckedInPR() (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=332:25181) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.024569 TID:36 (PRI) (EXT) (CP) OraclePoolManager.RemoveCheckedInPR()
2017-01-26 17:35:56.024569 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.DisConnect() (oper=kill) (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=332:25181) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.024569 TID:36 (PRI) (CP) OracleConnectionImpl.DisConnect() (DISCON) (ENDSID=332:25181)
2017-01-26 17:35:56.024569 TID:36 (PRI) (MTS) OracleConnectionImpl.DisConnect() Start to disconnect Conn ID = 332
2017-01-26 17:35:56.024569 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.Logoff()
2017-01-26 17:35:56.024569 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.AddAllPiggyBackRequests()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (ENT) TTCClose.Write()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (EXT) TTCClose.Write()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (ENT) TTCEndToEndMetrics.Write()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (EXT) TTCEndToEndMetrics.Write()
2017-01-26 17:35:56.024569 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.AddAllPiggyBackRequests()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (ENT) TTCSimpleOperations.WriteMessage()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (EXT) TTCSimpleOperations.WriteMessage()
2017-01-26 17:35:56.024569 TID:36 (NET) (SND) 00 2F 00 00 06 00 00 00 |./......|
2017-01-26 17:35:56.024569 TID:36 (NET) (SND) 00 00 |.. |
2017-01-26 17:35:56.024569 TID:36 (NET) (SND) 11 69 00 01 01 02 01 09 |.i......|
2017-01-26 17:35:56.024569 TID:36 (NET) (SND) 01 09 11 87 00 00 00 02 |........|
2017-01-26 17:35:56.024569 TID:36 (NET) (SND) 01 11 01 00 00 00 01 00 |........|
2017-01-26 17:35:56.024569 TID:36 (NET) (SND) 00 00 00 00 01 00 00 00 |........|
2017-01-26 17:35:56.024569 TID:36 (NET) (SND) 00 00 03 09 00 |..... |
2017-01-26 17:35:56.024569 TID:36 (PRI) (TTC) (ENT) TTCSimpleOperations.ReadResponse()
2017-01-26 17:35:56.040219 TID:36 (NET) (REC) New receive packet. Header:
2017-01-26 17:35:56.040219 TID:36 (NET) (REC) 00 0E 00 00 06 00 00 00 |........|
2017-01-26 17:35:56.040219 TID:36 (NET) (REC) 00 00 |.. |
2017-01-26 17:35:56.040219 TID:36 (NET) (REC) 09 01 01 00 |.... |
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (EXT) TTCSimpleOperations.ReadResponse()
2017-01-26 17:35:56.040219 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.Logoff()
2017-01-26 17:35:56.040219 TID:36 (PRI) (MTS) OracleConnectionImpl.DisConnect() End to disconnect Conn ID = 332
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:3) (key:8192) (bufid:0) (count:0) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:33) (count:8) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:3) (key:8192) (bufid:33) (count:8) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:35) (count:9) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:3) (key:8192) (bufid:35) (count:7) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:36) (count:10) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:3) (key:8192) (bufid:36) (count:6) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:32) (count:11) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:3) (key:8192) (bufid:32) (count:5) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:31) (count:12) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:3) (key:8192) (bufid:31) (count:4) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:30) (count:13) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:3) (key:8192) (bufid:30) (count:3) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:29) (count:14) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:3) (key:8192) (bufid:29) (count:2) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:28) (count:15) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:3) (key:8192) (bufid:28) (count:1) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:27) (count:16) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.040219 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.ClearState()
2017-01-26 17:35:56.040219 TID:36 (PRI) (MTS) OracleConnectionImpl.ClearState() Start Setting m_bConnected to false for Conn ID = 332
2017-01-26 17:35:56.040219 TID:36 (PRI) (MTS) OracleConnectionImpl.ClearState() End Setting m_bConnected to false for Conn ID = 332
2017-01-26 17:35:56.040219 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.ClearState()
2017-01-26 17:35:56.040219 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.DisConnect() (oper=kill) (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=-1:-1) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.040219 TID:36 (PRI) (EXT) (CP) PoolManager`3.Close() (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=-1:-1) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832) (pmListPR.count=5)
2017-01-26 17:35:56.040219 TID:36 (PRI) (EXT) (CP) OraclePoolManager.Close()
2017-01-26 17:35:56.040219 TID:36 (PRI) (ENT) (CP) OraclePoolManager.Close()
2017-01-26 17:35:56.040219 TID:36 (PRI) (ENT) (CP) PoolManager`3.Close() (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=93:16525) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.040219 TID:36 (PRI) (ENT) (CP) OraclePoolManager.RemoveCheckedInPR()
2017-01-26 17:35:56.040219 TID:36 (PRI) (ENT) (CP) PoolManager`3.RemoveCheckedInPR()
2017-01-26 17:35:56.040219 TID:36 (PRI) (EXT) (CP) PoolManager`3.RemoveCheckedInPR() (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=93:16525) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.040219 TID:36 (PRI) (EXT) (CP) OraclePoolManager.RemoveCheckedInPR()
2017-01-26 17:35:56.040219 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.DisConnect() (oper=kill) (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=93:16525) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.040219 TID:36 (PRI) (CP) OracleConnectionImpl.DisConnect() (DISCON) (ENDSID=93:16525)
2017-01-26 17:35:56.040219 TID:36 (PRI) (MTS) OracleConnectionImpl.DisConnect() Start to disconnect Conn ID = 93
2017-01-26 17:35:56.040219 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.Logoff()
2017-01-26 17:35:56.040219 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.AddAllPiggyBackRequests()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (ENT) TTCClose.Write()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (EXT) TTCClose.Write()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (ENT) TTCEndToEndMetrics.Write()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (EXT) TTCEndToEndMetrics.Write()
2017-01-26 17:35:56.040219 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.AddAllPiggyBackRequests()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (ENT) TTCSimpleOperations.WriteMessage()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (EXT) TTCSimpleOperations.WriteMessage()
2017-01-26 17:35:56.040219 TID:36 (NET) (SND) 00 2F 00 00 06 00 00 00 |./......|
2017-01-26 17:35:56.040219 TID:36 (NET) (SND) 00 00 |.. |
2017-01-26 17:35:56.040219 TID:36 (NET) (SND) 11 69 00 01 01 02 01 09 |.i......|
2017-01-26 17:35:56.040219 TID:36 (NET) (SND) 01 09 11 87 00 00 00 02 |........|
2017-01-26 17:35:56.040219 TID:36 (NET) (SND) 01 11 01 00 00 00 01 00 |........|
2017-01-26 17:35:56.040219 TID:36 (NET) (SND) 00 00 00 00 01 00 00 00 |........|
2017-01-26 17:35:56.040219 TID:36 (NET) (SND) 00 00 03 09 00 |..... |
2017-01-26 17:35:56.040219 TID:36 (PRI) (TTC) (ENT) TTCSimpleOperations.ReadResponse()
2017-01-26 17:35:56.040219 TID:36 (PRI) (BUF) (COBP.GET) (poolid:4) (key:8192) (bufid:143) (count:9) (OraBufReader.GetDataFromNetwork)
2017-01-26 17:35:56.055842 TID:36 (NET) (REC) New receive packet. Header:
2017-01-26 17:35:56.055842 TID:36 (NET) (REC) 00 0E 00 00 06 00 00 00 |........|
2017-01-26 17:35:56.055842 TID:36 (NET) (REC) 00 00 |.. |
2017-01-26 17:35:56.055842 TID:36 (NET) (REC) 09 01 01 00 |.... |
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (EXT) TTCSimpleOperations.ReadResponse()
2017-01-26 17:35:56.055842 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.Logoff()
2017-01-26 17:35:56.055842 TID:36 (PRI) (MTS) OracleConnectionImpl.DisConnect() End to disconnect Conn ID = 93
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:4) (key:8192) (bufid:0) (count:0) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:115) (count:17) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:4) (key:8192) (bufid:115) (count:8) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:90) (count:18) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:4) (key:8192) (bufid:90) (count:7) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:142) (count:19) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:4) (key:8192) (bufid:142) (count:6) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:44) (count:20) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:4) (key:8192) (bufid:44) (count:5) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:43) (count:21) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:4) (key:8192) (bufid:43) (count:4) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:42) (count:22) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:4) (key:8192) (bufid:42) (count:3) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:41) (count:23) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:4) (key:8192) (bufid:41) (count:2) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:40) (count:24) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:4) (key:8192) (bufid:40) (count:1) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:39) (count:25) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.055842 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.ClearState()
2017-01-26 17:35:56.055842 TID:36 (PRI) (MTS) OracleConnectionImpl.ClearState() Start Setting m_bConnected to false for Conn ID = 93
2017-01-26 17:35:56.055842 TID:36 (PRI) (MTS) OracleConnectionImpl.ClearState() End Setting m_bConnected to false for Conn ID = 93
2017-01-26 17:35:56.055842 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.ClearState()
2017-01-26 17:35:56.055842 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.DisConnect() (oper=kill) (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=-1:-1) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.055842 TID:36 (PRI) (EXT) (CP) PoolManager`3.Close() (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=-1:-1) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832) (pmListPR.count=4)
2017-01-26 17:35:56.055842 TID:36 (PRI) (EXT) (CP) OraclePoolManager.Close()
2017-01-26 17:35:56.055842 TID:36 (PRI) (ENT) (CP) OraclePoolManager.Close()
2017-01-26 17:35:56.055842 TID:36 (PRI) (ENT) (CP) PoolManager`3.Close() (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=253:10261) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.055842 TID:36 (PRI) (ENT) (CP) OraclePoolManager.RemoveCheckedInPR()
2017-01-26 17:35:56.055842 TID:36 (PRI) (ENT) (CP) PoolManager`3.RemoveCheckedInPR()
2017-01-26 17:35:56.055842 TID:36 (PRI) (EXT) (CP) PoolManager`3.RemoveCheckedInPR() (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=253:10261) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.055842 TID:36 (PRI) (EXT) (CP) OraclePoolManager.RemoveCheckedInPR()
2017-01-26 17:35:56.055842 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.DisConnect() (oper=kill) (aff=n/a) (inst=OTHER_TEST_INSTANCE) (affmatch=n/a) (sessid=253:10261) (F;F;T;OTHER_TEST_INSTANCE;N) (pmid=16019832)
2017-01-26 17:35:56.055842 TID:36 (PRI) (CP) OracleConnectionImpl.DisConnect() (DISCON) (ENDSID=253:10261)
2017-01-26 17:35:56.055842 TID:36 (PRI) (MTS) OracleConnectionImpl.DisConnect() Start to disconnect Conn ID = 253
2017-01-26 17:35:56.055842 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.Logoff()
2017-01-26 17:35:56.055842 TID:36 (PRI) (SVC) (ENT) OracleConnectionImpl.AddAllPiggyBackRequests()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (ENT) TTCClose.Write()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (EXT) TTCClose.Write()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (ENT) TTCEndToEndMetrics.Write()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (EXT) TTCEndToEndMetrics.Write()
2017-01-26 17:35:56.055842 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.AddAllPiggyBackRequests()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (ENT) TTCSimpleOperations.WriteMessage()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (ENT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (ENT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (EXT) TTCMessage.WriteTTCCode()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (EXT) TTCFunction.WriteFunctionHeader()
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (EXT) TTCSimpleOperations.WriteMessage()
2017-01-26 17:35:56.055842 TID:36 (NET) (SND) 00 2F 00 00 06 00 00 00 |./......|
2017-01-26 17:35:56.055842 TID:36 (NET) (SND) 00 00 |.. |
2017-01-26 17:35:56.055842 TID:36 (NET) (SND) 11 69 00 01 01 02 01 09 |.i......|
2017-01-26 17:35:56.055842 TID:36 (NET) (SND) 01 09 11 87 00 00 00 02 |........|
2017-01-26 17:35:56.055842 TID:36 (NET) (SND) 01 11 01 00 00 00 01 00 |........|
2017-01-26 17:35:56.055842 TID:36 (NET) (SND) 00 00 00 00 01 00 00 00 |........|
2017-01-26 17:35:56.055842 TID:36 (NET) (SND) 00 00 03 09 00 |..... |
2017-01-26 17:35:56.055842 TID:36 (PRI) (TTC) (ENT) TTCSimpleOperations.ReadResponse()
2017-01-26 17:35:56.055842 TID:36 (PRI) (BUF) (COBP.GET) (poolid:5) (key:8192) (bufid:92) (count:7) (OraBufReader.GetDataFromNetwork)
2017-01-26 17:35:56.071461 TID:36 (NET) (REC) New receive packet. Header:
2017-01-26 17:35:56.071461 TID:36 (NET) (REC) 00 0E 00 00 06 00 00 00 |........|
2017-01-26 17:35:56.071461 TID:36 (NET) (REC) 00 00 |.. |
2017-01-26 17:35:56.071461 TID:36 (NET) (REC) 09 01 01 00 |.... |
2017-01-26 17:35:56.071461 TID:36 (PRI) (TTC) (EXT) TTCSimpleOperations.ReadResponse()
2017-01-26 17:35:56.071461 TID:36 (PRI) (SVC) (EXT) OracleConnectionImpl.Logoff()
2017-01-26 17:35:56.071461 TID:36 (PRI) (MTS) OracleConnectionImpl.DisConnect() End to disconnect Conn ID = 253
2017-01-26 17:35:56.071461 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:5) (key:8192) (bufid:0) (count:0) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.071461 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:91) (count:26) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.071461 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:5) (key:8192) (bufid:91) (count:6) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.071461 TID:36 (PRI) (BUF) (OBP.PUT) (poolid:16478929) (key:8192) (bufid:54) (count:27) (ConOraBufPool.ReturnAll)
2017-01-26 17:35:56.071461 TID:36 (PRI) (BUF) (COBP.RETURNALL) (poolid:5) (key:8192) (bufid:54) (count:5) (OracleConnectionImpl.DisConnect)
2017-01-26 17:35:56.071461 TID:36 (PRI) (BUF) (OBP.PUT) (po