Skip to Main Content

ODP.NET

Announcement

For appeals, questions and feedback about Oracle Forums, please email oracle-forums-moderators_us@oracle.com. Technical questions should be asked in the appropriate category. Thank you!

Exception : OracleTuningAgent::TuningFunction()

1399438Feb 19 2014 — edited Feb 20 2014

Dear all,

In the ODP.net traces files, we have couple of exceptions that we don't explain. (We are using  ODP.net - 4.112.3.0 / Oracle Database 11g Release 11.2.0.3.0).

TIME:2014/02/18-19:25:24:576 TID: f0c  (ERROR) OracleTuningAgent::TuningFunction(): Error : System.Threading.ThreadAbortException: Thread was being aborted.

   at System.Threading.Thread.SleepInternal(Int32 millisecondsTimeout)

   at System.Threading.Thread.Sleep(Int32 millisecondsTimeout)

   at Oracle.DataAccess.Client.OracleTuningAgent.DoScan()

   at Oracle.DataAccess.Client.OracleTuningAgent.TuningFunction()

Next this exception, we'are thinking that the opened pool is currenlty flushing, and release every opened thread !

TIME:2014/02/18-19:25:24:623 TID: ef4  (EXIT)  OpsConRelRef(): RefCount=0 RetCode=0 Line=3507 (0)=0

TIME:2014/02/18-19:25:24:623 TID: ef4  (EXIT)  OpsConClose(): RetCode=0 Line=1304 (110e3590)=286143888

TIME:2014/02/18-19:25:24:623 TID: ef4  (ENTRY) OpsConFreeValCtx()

TIME:2014/02/18-19:25:24:623 TID: ef4  (EXIT)  OpsConFreeValCtx(): RetCode=0 Line=1329

TIME:2014/02/18-19:25:24:623 TID: ef4  (EXIT)  OpsConDispose(): RetCode=0 Line=1382

TIME:2014/02/18-19:25:24:623 TID: ef4  (ENTRY) OpsConDispose(): (110e3540)=286143808

TIME:2014/02/18-19:25:24:623 TID: ef4  (ENTRY) OpsConClose(): (110e3540)=286143808

TIME:2014/02/18-19:25:24:623 TID: ef4  (ENTRY) OpsConRelRef(): (110e3540)=286143808

TIME:2014/02/18-19:25:24:623 TID: ef4  (ENTRY) OpsErrFreeCtx()

TIME:2014/02/18-19:25:24:623 TID: ef4  (EXIT)  OpsErrFreeCtx(): RetCode=0 Line=224

...

And a new pool is opened (1 minute later)

TIME:2014/02/18-19:26:25:155 TID: 888  (VERSION)  (4.112.3.0)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (DllPath : C:\applications\oracle\product\11.2.0\client_1\bin)

TIME:2014/02/18-19:26:25:155 TID: 888  (CONFIG)   (TraceFileName : D:\...\odpnet_wcf.trc)

TIME:2014/02/18-19:26:25:155 TID: 888  (CONFIG)   (TraceLevel : 127)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (TraceOption : 0)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (CheckConStatus : 1)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (UdtCacheSize : 4096)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (StatementCacheSize : 0)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (DynamicEnlist : 0)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (FetchSize : 131072)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (OCI_EVENTS : 0)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (StatementCacheWithUdts : 1)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (MetadataPooling : 1)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (DBNotificationPort : -1)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (ThreadPoolMaxSize : -1)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (DBNotificationRegInterval : 0)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (DemandOraclePermission : 0)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (SelfTuning : True)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (MaxStatementCacheSize : 100)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (AppEdition : )

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (MetaDataXml : )

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (RevertBatchUpdateErrorHandling : 0)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (FetchArrayPooling : 1)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (PerformanceCounters : None)

TIME:2014/02/18-19:26:25:155 TID: 888  (REGISTRY) (PSPE : 1)

TIME:2014/02/18-19:26:25:171 TID: 888  (REGISTRY) (ThreadPoolMaxSize : -1 [Original: 200; Set: -1; Post-Set: 200])

TIME:2014/02/18-19:26:25:171 TID: 888  (ENTRY) OracleConnection::OracleConnection(2)

TIME:2014/02/18-19:26:25:186 TID: 888  (EXIT)  OracleConnection::OracleConnection(2)

TIME:2014/02/18-19:26:25:202 TID: 888  (ENTRY) OracleConnection::Open()

TIME:2014/02/18-19:26:25:202 TID: 888  (ENTRY) OpsConAllocValCtx()

TIME:2014/02/18-19:26:25:202 TID: 888  (EXIT)  OpsConAllocValCtx(): RetCode=0 Line=400

TIME:2014/02/18-19:26:25:218 TID: 888  (ENTRY) OpsConOpen()

TIME:2014/02/18-19:26:25:218 TID: 888  (HA)    OpsConOpen(): OciEvents=0 Line=1784

TIME:2014/02/18-19:26:25:249 TID: 888  (ENTRY) OpsConAddRef(): (10fc5b68)=284973928

TIME:2014/02/18-19:26:25:249 TID: 888  (EXIT)  OpsConAddRef(): RefCount=1 RetCode=0 Line=3331 (10fc5b68)=284973928

TIME:2014/02/18-19:26:25:249 TID: 888  (ENTRY) OpsErrAllocCtx(): (10fc5b68)=284973928

TIME:2014/02/18-19:26:25:249 TID: 888  (EXIT)  OpsErrAllocCtx(): RetCode=0 Line=197 (10fc5b68)=284973928

TIME:2014/02/18-19:26:25:249 TID: 888  (EXIT)  OpsConOpen(): RetCode=0 Line=2364 (10fc5b68)=284973928

TIME:2014/02/18-19:26:25:265 TID: 888  (ENTRY) OpsGetOptimumUsableMemoryInfo()

TIME:2014/02/18-19:26:25:265 TID: 888  (EXIT)  OpsGetOptimumUsableMemoryInfo(): returnValue=0 Line=1033

TIME:2014/02/18-19:26:25:265 TID: 888  (ENTRY) OracleTuningAgent::Register()

TIME:2014/02/18-19:26:25:265 TID: 888  (TUNING) OracleTuningAgent::Register(): Registered pool "Data Source=###;Persist Security Info=True;User ID=###;Min Pool Size=20;Max Pool Size=100;Pooling=True;" with pool Id 32819219

TIME:2014/02/18-19:26:25:265 TID: 888  (TUNING) OracleTuningAgent::Register(): Tuning thread started.

TIME:2014/02/18-19:26:25:265 TID: 888  (EXIT) OracleTuningAgent::Register()

TIME:2014/02/18-19:26:25:265 TID: 888  (ENTRY) OpsConAllocValCtx()

TIME:2014/02/18-19:26:25:265 TID: 888  (EXIT)  OpsConAllocValCtx(): RetCode=0 Line=400

TIME:2014/02/18-19:26:25:265 TID: 888  (POOL)  New connection pool created for: "Data Source=###;Persist Security Info=True;User ID=###;Min Pool Size=20;Max Pool Size=100;Pooling=True;" (id: 32819219)

TIME:2014/02/18-19:26:25:265 TID: 888  (POOL)  Total number of connection pools: 1

TIME:2014/02/18-19:26:25:280 TID: 888  (POOL)  Total number of connections for pool (id: 32819219) : 1

TIME:2014/02/18-19:26:25:280 TID: 888  (EXIT)  OracleConnection::Open()

TIME:2014/02/18-19:26:25:280 TID: 888  (ENTRY) OracleConnection::BeginTransaction()

TIME:2014/02/18-19:26:25:280 TID: 888  (ENTRY) OpsConAddRef(): (10fc5b68)=284973928

...

Does it mean that the webservices can't reach the database for a minute ?

Is there any logical explanation for this behaviour ?

I've also parsed metalink support looking for similar exceptions/bug, here is the result of our research : Doc ID 1151489.1

Any feedback could be great & helpfull.

Thanks in advance.

Comments
Locked Post
New comments cannot be posted to this locked post.
Post Details
Locked on Mar 20 2014
Added on Feb 19 2014
1 comment
774 views