1. ホーム
  2. 質問と答え
  3. データベースよりもOBIEE Weblogicで実行されたクエリの方が時間がかかります

データベースよりもOBIEE Weblogicで実行されたクエリの方が時間がかかります

2016-04-04 10 views
0

Oracle BIの作業を開始したばかりですが、ダッシュボードの読み込みにはかなりの時間がかかります。最初は、このようなパフォーマンスの遅れの原因がデータベースクエリであると思っていました。しかし、ログを見ると、データベース（MDS）上で実行されたときにほとんど時間がかからなかったクエリは、Weblogicsの実行時間として6秒間表示されます。データベースよりもOBIEE Weblogicで実行されたクエリの方が時間がかかります

以下は、Weblogicsで実行するのに約6秒かかり、データベースで実行したときに897ミリ秒かかる単純なクエリのログです。

[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) [[ 

]]

この問題の根本的な原因をどのように見つけることができますか？

出典

2016-04-04 Burhan Khalid Butt

+0

この文は、「物理的なクエリの応答時間5（秒）」というログで、データベース内の物理的なクエリ...または少なくともデータベースに接続する時間と考えることができます。ログインすると、OBIEEと同じユーザーとしてログインしていますか？あなたは複数のDBパーティションを持っていますか？接続プール内の接続数を増やす必要がありますか？ –

答えて

1

まず最初に、クエリの実行時間をどのように測定していますか？クロノメーター（火かき棒など）を使用し、ダッシュボードページを開いてから結果を表示するのにかかる時間を測定していますか？ または、ユーザ追跡が有効で、実行時間がUsage Trackingテーブルのデータベース時間になっていますか？ 最初のオプションの場合は、使用状況トラッキングを有効にして、実行時間がどこに費やされているかを確認することをお勧めします。たぶん、OBIEEサーバーとデータベースの間、またはサーバーとアナリティクスの間に遅れがあるかもしれません... OBIEEのパフォーマンスをよりよく理解するために、Rittman MeadのブログでRobin Moffatの一連のブログ記事をお勧めします。ダイジェストにはたくさんのものがありますが、あなたの質問には簡単な答えはありませんが、あなたがオベイを使って作業している場合は、コンポーネントとそれぞれの動作をよりよく知ることができます。 よろしくお願いします。

出典

2016-04-05 21:32:17

+0

ポイントに追加するには、キャッシュを検討している場合は、データベースで直接テストするかどうかは言及していませんでした。 –

+0

確かにアナは、あなたが提案したブログをさらに詳しく調べます。 @Walter_Ritzelキャッシュであれば、最初の500レコードを返したり、すべてのレコードを返すクエリ（F5キーを使用）を実行するクエリ（FadキーでF9を使用）をコンパイルしている場合に意味します。それから私はクエリを実行していると、質問に記載されているミリ秒単位で実行されます。 :) –

関連する問題

 関連する問題