2016-04-04 3 views
0

Ich habe gerade angefangen, an Oracle BI zu arbeiten, habe ich ein Szenario, in dem ein Dashboard, wenn geladen wird dauert eine ganze Weile. Zuerst dachte ich, dass der Grund für diese langsame Leistung Datenbankabfragen waren. Aber wenn die Protokolle angezeigt wurden, zeigen Abfragen, die bei der Ausführung auf der Datenbank (MDS) kaum eine Sekunde gedauert haben, in Weblogics 6 Sekunden als Ausführungszeit an.Abfrage dauert mehr Zeit bei der Ausführung in OBIEE Weblogic als auf der Datenbank

Unten ist das Protokoll einer einfachen Abfrage, die dauert fast 6s auf Weblogics und dauert 897 Millisekunden, wenn auf Datenbank ausgeführt.

[2016-04-04T05:11:58.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-0] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:9:3] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] ############################################## [[ 
-------------------- SQL Request, logical request hash: 
44507272 
SET VARIABLE QUERY_SRC_CD='ValuePrompt';SELECT "Project"."Project Type#1" saw_0 FROM "PLM PPM - Project Summary" ORDER BY saw_0 
FETCH FIRST 65001 ROWS ONLY 


]] 
[2016-04-04T05:11:58.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-23] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:9:3] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- General Query Info: [[ 
Repository: Star, Subject Area: PLMA, Presentation: PLM PPM - Project Summary 

]] 
[2016-04-04T05:11:58.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-2] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:9:3] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- Logical Request (before navigation): [[ 

RqList distinct 
    Dim - Project.Project Type as c1 GB 
OrderBy: c1 asc 

]] 
[2016-04-04T05:11:58.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-48] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:9:5] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- The logical query block fail to hits or seed the cache in subrequest level due to [[ 
only one subrequest 


]] 
[2016-04-04T05:11:58.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-51] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:9:5] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- The logical plan contains non-cacheable node, plan [[ 
RqList <<82402988>> distinct 
    D1.c1 as c1 GB 
Child Nodes (RqJoinSpec): <<82403017>> 
    RqJoinNode <<82403016>> [] 
     (
      RqList <<82402995>> 
       Dim_PPM_PROJECT_D.PRJ_USRDEF_LIST0 as c1 GB 
      Child Nodes (RqJoinSpec): <<82403009>> 
       RqJoinNode <<82403008>> [] 
        PPM_PROJECT_D AS Dim_PPM_PROJECT_D 
      DetailFilter: Dim_PPM_PROJECT_D.DELETE_FLG = 0 
     ) as D1 
OrderBy: c1 asc 
node cache seed not inserted due to multiple plan not cacheable 

]] 
[2016-04-04T05:11:58.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-50] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:9:5] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- The logical query seed the plan cache [[ 
plan 
RqList <<82403049>> [for database 3023:57:PLMA,57] distinct /* FETCH FIRST 65001 ROWS ONLY */ 
    Dim_PPM_PROJECT_D.PRJ_USRDEF_LIST0 as c1 GB [for database 3023:57,57] 
Child Nodes (RqJoinSpec): <<82403053>> [for database 3023:57:PLMA,57] 
    RqJoinNode <<82403054>> [] 
     PPM_PROJECT_D AS Dim_PPM_PROJECT_D 
DetailFilter: Dim_PPM_PROJECT_D.DELETE_FLG = 0 [for database 3023:57] 
OrderBy: c1 asc [for database 3023:57,57] 

]] 
[2016-04-04T05:11:58.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-16] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:9:5] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- Execution plan: [[ 

RqList <<82402995>> [for database 3023:57:PLMA,57] distinct /* FETCH FIRST 65001 ROWS ONLY */ 
    Dim_PPM_PROJECT_D.PRJ_USRDEF_LIST0 as c1 GB [for database 3023:57,57] 
Child Nodes (RqJoinSpec): <<82403009>> [for database 3023:57:PLMA,57] 
    RqJoinNode <<82403008>> [] 
     PPM_PROJECT_D AS Dim_PPM_PROJECT_D 
DetailFilter: Dim_PPM_PROJECT_D.DELETE_FLG = 0 [for database 3023:57] 
OrderBy: c1 asc [for database 3023:57,57] 

]] 
[2016-04-04T05:11:58.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-18] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:9:5] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- Sending query to database named PLMA (id: <<82402995>>), connection pool named Connection Pool, logical request hash 44507272, physical request hash f82d8b03: [[ 

select D1.c1 as c1 from (select distinct T286689.PRJ_USRDEF_LIST0 as c1 
from 
    PPM_PROJECT_D T286689 /* Dim_PPM_PROJECT_D */ 
where (T286689.DELETE_FLG = 0) 
order by c1) D1 where rownum <= 65001 

]] 
[2016-04-04T05:12:04.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-34] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:11] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- Query Status: Successful Completion [[ 

]] 
[2016-04-04T05:12:04.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-26] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:11] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- Rows 5, bytes 20060 retrieved from database query id: <<82402995>> [[ 

]] 
[2016-04-04T05:12:04.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-28] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:11] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- Physical query response time 5 (seconds), id <<82402995>> [[ 

]] 
[2016-04-04T05:12:04.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-29] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:11] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- Physical Query Summary Stats: Number of physical queries 1, Cumulative time 5, DB-connect time 0 (seconds) [[ 

]] 
[2016-04-04T05:12:04.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-24] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:11] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- Rows returned to Client 5 [[ 

]] 
[2016-04-04T05:12:04.000-07:00] [OracleBIServerComponent] [TRACE:4] [USER-33] [] [ecid: 77bb740f357d872f:52e5f112:1522a219085:-8000-00000000004961ca,0:1:11] [tid: dbf7c700] [requestid: 157d0024] [sessionid: 157d0000] [username: weblogic] -------------------- Logical Query Summary Stats: Elapsed time 6, Response time 5, Compilation time 0 (seconds) [[ 

]] 

Kann mir jemand sagen, wie ich die Ursache dieses Problems finden kann.

+0

Dieser Satz im Protokoll 'Physische Abfrage Antwortzeit 5 (Sekunden)' lässt mich denken, dass es die physische Abfrage in der Datenbank ist ... oder zumindest die Zeit, um eine Verbindung zur Datenbank herzustellen. Melden Sie sich bei der Anmeldung als derselbe Benutzer an, unter dem OBIEE ausgeführt wird? Sind Sie in derselben db-Partition, wenn Sie mehrere haben? Müssen Sie die Anzahl der Verbindungen im Verbindungspool erhöhen? –

Antwort

1

Zuerst die Grundlagen, wie messen Sie die Zeit, die die Abfrage dauert? Verwenden Sie einen Chronometer (oder einen Firebug und dergleichen) und messen Sie die Zeit, die zum Anzeigen der Ergebnisse seit dem Öffnen der Dashboard-Seite benötigt wird? Oder haben Sie User Tracking aktiv und die Ausführungszeit ist die Datenbankzeit in der Usage Tracking-Tabelle? Wenn es die erste Option ist, empfehle ich, Usage Tracking zu aktivieren und zu überprüfen, wo die Ausführungszeit ausgegeben wird. Vielleicht gibt es eine Verzögerung zwischen OBIEE Server und Datenbank oder zwischen dem Server und Analytics ... Zum besseren Verständnis der Leistung von OBIEE empfehle ich die Reihe von Blog-Posts von Robin Moffat auf Rittman Mead Blog. Es ist eine Menge Zeug zu verdauen, und es gibt keine einfache Antwort auf Ihre Frage, aber wenn Sie mit obiee arbeiten, lernen Sie besser die Komponenten und wie jeder einzelne funktioniert. Grüße

+0

Und zu einem Punkt hinzufügen, haben Sie nicht erwähnt, wenn beim Testen direkt in der Datenbank, wenn Sie Cache in Betracht ziehen ... –

+0

Sicher Sache Ana werde ich weiter in den Blogs graben, die Sie vorgeschlagen haben. @Walter_Ritzel wenn durch cache meinst du, dass, wenn ich die Abfrage kompiliere (unter Verwendung von F9 in Toad), die ersten 500 Datensätze zurückgibt oder die Abfrage (unter Verwendung von F5 in Toad) ausführt, die alle Datensätze zurückgibt. Dann führe ich die Abfrage aus und sie wird in Millisekunden ausgeführt, wie in der Frage erwähnt. :) –

Verwandte Themen