From oracle-l-bounce@freelists.org Tue Mar 23 10:58:14 2004 Return-Path: Received: from air189.startdedicated.com (root@localhost) by orafaq.com (8.11.6/8.11.6) with ESMTP id i2NGwEN11597 for ; Tue, 23 Mar 2004 10:58:14 -0600 X-ClientAddr: 206.53.239.180 Received: from turing.freelists.org (freelists-180.iquest.net [206.53.239.180]) by air189.startdedicated.com (8.11.6/8.11.6) with ESMTP id i2NGwAo11448 for ; Tue, 23 Mar 2004 10:58:11 -0600 Received: from localhost (localhost [127.0.0.1]) by turing.freelists.org (Avenir Technologies Mail Multiplex) with ESMTP id D3771393520; Tue, 23 Mar 2004 11:52:48 -0500 (EST) Received: from turing.freelists.org ([127.0.0.1]) by localhost (turing [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id 02542-100; Tue, 23 Mar 2004 11:52:48 -0500 (EST) Received: from turing (localhost [127.0.0.1]) by turing.freelists.org (Avenir Technologies Mail Multiplex) with ESMTP id BAFE0392CE2; Tue, 23 Mar 2004 11:45:05 -0500 (EST) Received: with ECARTIS (v1.0.0; list oracle-l); Tue, 23 Mar 2004 11:43:30 -0500 (EST) X-Original-To: oracle-l@freelists.org Delivered-To: oracle-l@freelists.org Received: from localhost (localhost [127.0.0.1]) by turing.freelists.org (Avenir Technologies Mail Multiplex) with ESMTP id C1CB2393343 for ; Tue, 23 Mar 2004 11:06:15 -0500 (EST) Received: from turing.freelists.org ([127.0.0.1]) by localhost (turing [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id 24916-29 for ; Tue, 23 Mar 2004 11:06:15 -0500 (EST) Received: from aphrodite.espn.com (aphrodite.espn.com [204.149.192.11]) by turing.freelists.org (Avenir Technologies Mail Multiplex) with ESMTP id AB534392EBB for ; Tue, 23 Mar 2004 10:47:44 -0500 (EST) content-class: urn:content-classes:message MIME-Version: 1.0 Content-Type: multipart/alternative; boundary="----_=_NextPart_001_01C410EF.2F46662C" X-MimeOLE: Produced By Microsoft Exchange V6.0.6487.1 Subject: TKPROF question Date: Tue, 23 Mar 2004 10:54:54 -0500 Message-ID: X-MS-Has-Attach: X-MS-TNEF-Correlator: Thread-Topic: TKPROF question Thread-Index: AcQQ7yXC1VsIiOvlSCGT3w0SW1ZbXg== From: "Jamadagni, Rajendra" To: "Oracle List (E-mail)" X-OriginalArrivalTime: 23 Mar 2004 15:54:51.0569 (UTC) FILETIME=[2D412A10:01C410EF] X-Virus-Scanned: by amavisd-new at freelists.org X-archive-position: 1553 X-ecartis-version: Ecartis v1.0.0 Sender: oracle-l-bounce@freelists.org Errors-To: oracle-l-bounce@freelists.org X-original-sender: Rajendra.Jamadagni@espn.com Precedence: normal Reply-To: oracle-l@freelists.org X-list: oracle-l X-Virus-Scanned: by amavisd-new at freelists.org ------_=_NextPart_001_01C410EF.2F46662C Content-Type: text/plain; charset="iso-8859-1" Content-Transfer-Encoding: quoted-printable call count cpu elapsed disk query current = rows ------- ------ -------- ---------- ---------- ---------- ---------- = ---------- Parse 1 0.01 0.01 0 5 0 = 0 Execute 6 213.15 949.43 393775 10256309 20885 = 10094 Fetch 0 0.00 0.00 0 0 0 = 0 ------- ------ -------- ---------- ---------- ---------- ---------- = ---------- total 7 213.16 949.45 393775 10256314 20885 = 10094 Misses in library cache during parse: 1 Optimizer goal: CHOOSE Parsing user id: 47 (recursive depth: 1) Elapsed times include waiting on following events: Event waited on Times Max. Wait Total = Waited ---------------------------------------- Waited ---------- = ------------ global cache cr request 191074 3288.24 = 3352.21 db file sequential read 392778 0.16 = 717.89 global cache s to x 88 0.00 = 0.02 direct path write 11 0.00 = 0.01 direct path read 128 0.00 = 0.03 db file scattered read 45 0.07 = 0.38 global cache open s 3 0.00 = 0.00 global cache open x 45 0.00 = 0.01 *************************************************************************= ******* Okay ... so this is 9204 db, and I am trying to correctly interpret the = MAX.WAIT column value for 'global cache cr request' ... I assume that = max wait means in all waits this was the max value for given wait event. = right? If so, Did I really have wait of 3288.24 (seconds, centi seconds, = milliseconds???)=20 If so, I can't see that in the trace file, I checked all the 'ela=3D' = values for wait event g_c_cr_r .... where the heck this value 3288.24 = came from? And how do I find it? Truse me, the trace file is > 5G. Raj -------------------------------------------------------------------------= ------- Rajendra dot Jamadagni at nospamespn dot com All Views expressed in this email are strictly personal. select standard_disclaimer from company_requirements; QOTD: Any clod can have facts, having an opinion is an art ! ------_=_NextPart_001_01C410EF.2F46662C Content-Type: text/html; charset="iso-8859-1" Content-Transfer-Encoding: quoted-printable TKPROF question

<some big bad MERGE statement = here>

call     = count       cpu    = elapsed       = disk      query    = current        rows
------- ------  -------- = ---------- ---------- ---------- ----------  ----------
Parse        = 1      0.01       = 0.01          = 0          = 5          = 0           0
Execute      6    = 213.15     949.43     = 393775   10256309      = 20885       10094
Fetch        = 0      0.00       = 0.00          = 0          = 0          = 0           0
------- ------  -------- = ---------- ---------- ---------- ----------  ----------
total        7    = 213.16     949.45     = 393775   10256314      = 20885       10094

Misses in library cache during = parse: 1
Optimizer goal: CHOOSE
Parsing user id: = 47     (recursive depth: 1)

Elapsed times include waiting on = following events:
  Event waited = on            = ;            =      Times   Max. Wait  Total = Waited
  = ----------------------------------------   Waited  = ----------  ------------
  global cache cr = request           =          = 191074     = 3288.24       3352.21
  db file sequential = read           &nb= sp;        = 392778        = 0.16        717.89
  global cache s to = x            =             &= nbsp;   88        = 0.00          0.02
  direct path = write           &n= bsp;           &nb= sp;      = 11        = 0.00          0.01
  direct path = read           &nb= sp;           &nbs= p;      = 128        = 0.00          0.03
  db file scattered = read           &nb= sp;           &nbs= p; 45        = 0.07          0.38
  global cache open = s            =             &= nbsp;    3        = 0.00          0.00
  global cache open = x            =             &= nbsp;   45        = 0.00          0.01
********************************************************************= ************


Okay ... so this is 9204 db, and = I am trying to correctly interpret the MAX.WAIT column value for 'global = cache cr request' ... I assume that max wait means in all waits this was = the max value for given wait event. right? If so, Did I really have wait = of 3288.24 (seconds, centi seconds, milliseconds???)

If so, I can't see that in the = trace file, I checked all the 'ela=3D' values for wait event g_c_cr_r = .... where the heck this value 3288.24 came from? And how do I find it? = Truse me, the trace file is > 5G.

Raj
--------------------------------------------------------------------= ------------
Rajendra dot Jamadagni at = nospamespn dot com
All Views expressed in this = email are strictly personal.
select standard_disclaimer from = company_requirements;
QOTD: Any clod can have facts, = having an opinion is an art !

------_=_NextPart_001_01C410EF.2F46662C-- ---------------------------------------------------------------- Please see the official ORACLE-L FAQ: http://www.orafaq.com ---------------------------------------------------------------- To unsubscribe send email to: oracle-l-request@freelists.org put 'unsubscribe' in the subject line. -- Archives are at http://www.freelists.org/archives/oracle-l/ FAQ is at http://www.freelists.org/help/fom-serve/cache/1.html -----------------------------------------------------------------