From oracle-l-bounce@freelists.org Fri Sep 9 12:54:45 2005 Return-Path: Received: from air891.startdedicated.com (root@localhost) by orafaq.com (8.12.10/8.12.10) with ESMTP id j89HsjDT018007 for ; Fri, 9 Sep 2005 12:54:45 -0500 X-ClientAddr: 206.53.239.180 Received: from turing.freelists.org (freelists-180.iquest.net [206.53.239.180]) by air891.startdedicated.com (8.12.10/8.12.10) with ESMTP id j89HsWIP017947 for ; Fri, 9 Sep 2005 12:54:33 -0500 Received: from localhost (localhost [127.0.0.1]) by turing.freelists.org (Avenir Technologies Mail Multiplex) with ESMTP id BC3ED1E9C12; Fri, 9 Sep 2005 12:54:25 -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 12700-08; Fri, 9 Sep 2005 12:54:25 -0500 (EST) Received: from turing (localhost [127.0.0.1]) by turing.freelists.org (Avenir Technologies Mail Multiplex) with ESMTP id 3551A1E9931; Fri, 9 Sep 2005 12:54:25 -0500 (EST) DomainKey-Signature: a=rsa-sha1; q=dns; c=nofws; s=beta; d=gmail.com; h=received:message-id:from:to:references:subject:date:mime-version:content-type:x-priority:x-msmail-priority:x-mailer:x-mimeole; b=CE3U0OLXbVqCmgSziiQiBn4HjzsaMZmu9j5lqmM2VpNtC6iGZW3W/1c47RypoPf0CD4iebSEyDOQf/tnutYunVZdn3ATKCBheqiKmcxGkF4tmttxeXeysKIMamFwipbs3CJaYuyZWm4KQRkgk1hsFtqjHGesTeta/u36jUxDxhE= Message-ID: <009c01c5b567$4140ec80$b000a8c0@IBME1D11967173> From: "Radoulov, Dimitre" To: , References: Subject: Re: What do you make of this? Date: Fri, 9 Sep 2005 19:52:27 +0200 MIME-Version: 1.0 Content-Type: multipart/alternative; boundary="----=_NextPart_000_0097_01C5B578.018D7D40" X-MimeOLE: Produced By Microsoft MimeOLE V6.00.2900.2670 X-archive-position: 25237 X-ecartis-version: Ecartis v1.0.0 Sender: oracle-l-bounce@freelists.org Errors-To: oracle-l-bounce@freelists.org X-original-sender: cichomitiko@gmail.com Precedence: normal Reply-To: cichomitiko@gmail.com X-list: oracle-l X-Virus-Scanned: by amavisd-new-20030616-p9 (Debian) at avenirtech.net X-mailscan-MailScanner-Information: Please contact the ISP for more information X-mailscan-MailScanner: Found to be clean X-MailScanner-From: oracle-l-bounce@freelists.org X-Spam-Level: X-Spam-Checker-Version: SpamAssassin 2.63 (2004-01-11) on air891.startdedicated.com X-Spam-Status: No, hits=-4.3 required=5.0 tests=AWL,BAYES_00,HTML_50_60, HTML_MESSAGE autolearn=no version=2.63 ------=_NextPart_000_0097_01C5B578.018D7D40 Content-Type: text/plain; charset="iso-8859-1" Content-Transfer-Encoding: quoted-printable Could you please send the entire raw trace file. Regards, Dimitre Radoulov ----- Original Message -----=20 From: Paul Baumgartel=20 To: oracle-l@freelists.org=20 Sent: Friday, September 09, 2005 6:18 PM Subject: What do you make of this? Hi, all. Oracle10g (10.1.0.4.0) on Windows 2000. I have a set of traces that are puzzling. I had the traces done in = controlled conditions, with a single user on the database; CPU usage on = the DB server was less than 25%. This statement is a good example of = what I'm trying to figure out. There's a high ratio of elapsed to CPU = on the parse, but not much in the way of wait information--except the = SQL*Net message from client. The client is a COM+ application server. = No recent changes that I know of on that machine, nor on the DB server = itself. I have inspected the raw trace file as well and don't see = anything to suggest that the tkprof timing summaries are incorrect. II'm looking for ideas on how to go about diagnosing the SQL*Net waits = (assuming they're accounting for the different between CPU and elapsed) = or finding where the rest of the elapsed time comes from. Thanks in = advance for any suggestions! UPDATE VALIDKEYS SET LASTACCESS =3D :V00001=20 WHERE VALIDKEYS_PK =3D :V00002 call count cpu elapsed disk query current = rows ------- ------ -------- ---------- ---------- ---------- ---------- = ---------- Parse 198 0.46 2.72 0 0 0 = 0 Execute 99 0.12 0.49 0 297 396 = 99 Fetch 0 0.00 0.00 0 0 0 = 0 ------- ------ -------- ---------- ---------- ---------- ---------- = ---------- total 297 0.59 3.22 0 297 396 = 99 Misses in library cache during parse: 0 Optimizer mode: ALL_ROWS Parsing user id: 12492 =20 Elapsed times include waiting on following events: Event waited on Times Max. Wait Total = Waited ---------------------------------------- Waited ---------- = ------------ SQL*Net message to client 381 0.00 = 0.00 SQL*Net message from client 381 0.01 = 0.51 log file sync 1 0.00 = 0.00 = *************************************************************************= ******* --=20 Paul Baumgartel paul.baumgartel@aya.yale.edu ------=_NextPart_000_0097_01C5B578.018D7D40 Content-Type: text/html; charset="iso-8859-1" Content-Transfer-Encoding: quoted-printable
Could you please send the entire = raw trace=20 file.
 
 
 
Regards,
Dimitre = Radoulov
 
 
 
 
----- Original Message -----
From:=20 Paul Baumgartel
Sent: Friday, September 09, = 2005 6:18=20 PM
Subject: What do you make of = this?

Hi, all.  Oracle10g (10.1.0.4.0) on Windows = 2000.

I=20 have a set of traces that are puzzling.  I had the traces done in = controlled conditions, with a single user on the database; CPU usage = on the DB=20 server was less than 25%.  This statement is a good example of = what I'm=20 trying to figure out.  There's a high ratio of elapsed to CPU on = the=20 parse, but not much in the way of wait information--except the SQL*Net = message=20 from client.  The client is a COM+ application server.  No = recent=20 changes that I know of on that machine, nor on the DB server = itself.  I=20 have inspected the raw trace file as well and don't see anything to = suggest=20 that the tkprof timing summaries are incorrect.

II'm looking = for ideas=20 on how to go about diagnosing the SQL*Net waits (assuming they're = accounting=20 for the different between CPU and elapsed) or finding where the rest = of the=20 elapsed time comes from.  Thanks in advance for any=20 suggestions!

UPDATE=20 VALIDKEYS SET LASTACCESS =3D :V00001 WHERE VALIDKEYS_PK =3D=20 :V00002
call    =20 count       cpu   =20 elapsed       = disk     =20 query    = current       =20 rows
------- ------  = --------=20 ---------- ---------- ---------- ----------  ----------
Parse     =20 198      = 0.46      =20 2.72         =20 0         =20 0         =20 0           = 0Execute    =20 99      = 0.12      =20 0.49         =20 0       =20 297       =20 396          99
Fetch       =20 0      = 0.00      =20 0.00         =20 0         =20 0         =20 0           = 0------- ------  = --------=20 ---------- ---------- ---------- ----------  ----------
total     =20 297      = 0.59      =20 3.22         =20 0       =20 297       =20 396          99
Misses in library cache = during=20 parse: 0
Optimizer mode: = ALL_ROWSParsing user id: = 12492 =20
Elapsed times include = waiting on=20 following events:
  Event waited=20 = on            = ;            =     =20 Times   Max. Wait  Total Waited =20 ----------------------------------------   Waited =20 ----------  ------------  SQL*Net message to = = client           &= nbsp;        =20 381       =20 0.00          = 0.00  SQL*Net message = from=20 = client           &= nbsp;      =20 381       =20 0.01          = 0.51  log file=20 = sync           &nb= sp;           &nbs= p;          =20 1       =20 0.00          = 0.00**********************************************************= **********************
--
Paul Baumgartel
paul.baumgartel@aya.yale.edu=

------=_NextPart_000_0097_01C5B578.018D7D40-- -- http://www.freelists.org/webpage/oracle-l