CPU wait in trace files?

Hello world

I have a doubt: cpu events (for example "logical reading of cache bytes") enter trace files when tracing with level 12?

Thanks in advance

Laughing out loud

This isn't a wait event "what I expect."

It is a ' what I'm working on ' while the processor.

Source is statistics of session.

Session/state event tells you what you're doing.

Session statistics will tell you why you are doing something.

Or, actually, "logical reading of cache bytes" are more a summary measure. Other session statistics will contribute to this and you say why you read this for hiding, for example if it is a complies then the different categories of uniform get reasons, etc.

Tags: Database

Similar Questions

  • Interpreting the Trace file.

    Hi, I use 10.2.0.4.0 oracle version.

    I have some info to trace file as below, for one of the query. So how should I interpret the trace file? What is the problem in the application and scope of the improvements in the query? Please note that I withdrew the request and plans of the trace file, I've posted only the sections of waiting.
    call     count       cpu    elapsed       disk      query    current        rows
------- ------  -------- ---------- ---------- ---------- ----------  ----------
Parse        1      0.14       0.13          0          0          1           0
Execute      1      6.63     162.12      33540      72921        383           0
Fetch    17272    178.89    1933.95     274835    3147603         20      259063
------- ------  -------- ---------- ---------- ---------- ----------  ----------
total    17274    185.66    2096.21     308375    3220524        404      259063

Misses in library cache during parse: 1
Optimizer mode: CHOOSE
Parsing user id: 36  

Elapsed times include waiting on following events:
  Event waited on                             Times   Max. Wait  Total Waited
  ----------------------------------------   Waited  ----------  ------------
  control file sequential read                    4        0.00          0.00
  db file sequential read                    302812        0.62       1913.89
  latch: cache buffers chains                     3        0.04          0.04
  direct path write temp                        501        0.01          0.30
  SQL*Net message to client                   17272        0.00          0.04
  db file scattered read                        120        0.02          0.63
  direct path read temp                         608        0.14          1.71
  SQL*Net message from client                 17272       44.81      31865.74
  SQL*Net more data to client                    15        0.00          0.00
  latch: object queue header operation            1        0.00          0.00
  latch: library cache                            3        0.03          0.04
  latch: library cache pin                        1        0.00          0.00
  latch: cache buffer handles                     1        0.00          0.00



********************************************************************************

OVERALL TOTALS FOR ALL NON-RECURSIVE STATEMENTS

call     count       cpu    elapsed       disk      query    current        rows
------- ------  -------- ---------- ---------- ---------- ----------  ----------
Parse        1      0.14       0.13          0          0          1           0
Execute      1      6.63     162.12      33540      72921        383           0
Fetch    17272    178.89    1933.95     274835    3147603         20      259063
------- ------  -------- ---------- ---------- ---------- ----------  ----------
total    17274    185.66    2096.21     308375    3220524        404      259063

Misses in library cache during parse: 1

Elapsed times include waiting on following events:
  Event waited on                             Times   Max. Wait  Total Waited
  ----------------------------------------   Waited  ----------  ------------
  SQL*Net message to client                   17275        0.00          0.04
  SQL*Net message from client                 17274       75.57      31941.39
  SQL*Net more data from client                   2        0.00          0.01
  db file sequential read                    302812        0.62       1913.89
  control file sequential read                    4        0.00          0.00
  latch: cache buffers chains                     3        0.04          0.04
  direct path write temp                        501        0.01          0.30
  db file scattered read                        120        0.02          0.63
  direct path read temp                         608        0.14          1.71
  SQL*Net more data to client                    15        0.00          0.00
  latch: object queue header operation            1        0.00          0.00
  latch: library cache                            3        0.03          0.04
  latch: library cache pin                        1        0.00          0.00
  latch: cache buffer handles                     1        0.00          0.00


OVERALL TOTALS FOR ALL RECURSIVE STATEMENTS

call     count       cpu    elapsed       disk      query    current        rows
------- ------  -------- ---------- ---------- ---------- ----------  ----------
Parse       11      0.02       0.01          0          0          0           0
Execute    348      0.20       0.17          0          0          1           0
Fetch      367      0.06       0.37         59       1187          0        3806
------- ------  -------- ---------- ---------- ---------- ----------  ----------
total      726      0.28       0.56         59       1187          1        3806

Misses in library cache during parse: 11
Misses in library cache during execute: 10

Elapsed times include waiting on following events:
  Event waited on                             Times   Max. Wait  Total Waited
  ----------------------------------------   Waited  ----------  ------------
  db file sequential read                        59        0.01          0.32

    1  user  SQL statements in session.
  348  internal SQL statements in session.
  349  SQL statements in session.
********************************************************************************

    But rewrite the SQL code will probably be irrelevant.

    If most of the numbers in the file plan and execution of trace is correct, then it is not more time is lost somewhere in the network that you send to 260 000 lines back and forward with a size of 15 lines extraction (the default in sql * more)?

    You must increase the size of mining significantly.

    And you can watch some of the writings of Charles Hooper on the monitoring network:
    http://hoopercharles.WordPress.com/category/network-monitoring/

  • Trace file has many references obj # but this oppose is nowhere in SQL statements

    Hi all

    Oracle 11.2.0.3 x 64 on x 64 Linux.

    No CARS.

    I have a trace file (captured with lie = true, wait = true) where inside, I have a large number of obj # references for the object that is not in the above trace file nowhere.

    Example of part of the trace file:

    ...

    PARSING IN CURSOR #22 len = dep 52 = uid 0 = 46 oct = cover 3 = 46 tim = hv 43179827168145 = ad 1014772292 = "c00000023baba230" sqlid = "amqq2ncy7sck4."

    Select TNC in PF_TRX where (CTN =: b0 and ROWNUM = 1)

    END OF STMT

    ANALYSIS #22:c = 0, e = 17, p = 0, cr = 0, cu = 0, set = 0, r = 0, dep = 0, og = 1, plh is 2442708035, tim = 43179827168144

    LINKS FOR #22:

    Link #0

    oacdty = 01 mxl = 32 (25) = 00 mxlc bad = 00 = 00 = 00 pre scl

    oacflg = 00 fl2 = 1000000 frm = 01 csi = 32 off siz = 32 = 0

    kxsbbbfp = 9fffffffbf5faba0 = 32 avl bln = 10 flg = 05

    value = "0606015172".

    EXEC #22:c = 0, e = 94, p = 0, cr = 0, cu = 0, set = 0, r = 0, dep = 0, og = 1, plh is 2442708035, tim = 43179827168314

    WAITING #22: nam ='SQL * Net message to client' ela = 1 driver id = 1413697536 #bytes = 1 p3 = 0 obj #= 775422 = 44216143020385 tim

    FETCH #22:c = 0, e = 13, p = 0, cr = 2, cu = 0, set = 0, r = 1, dep = 0, og = 1, plh is 2442708035, tim = 43179827168378

    "STAT id #22 = 1 cnt = 1 pid = 0 obj = 0 op = pos = 1' STOPKEY COUNTY (cr = 2 pr = 0 pw = time 0 = 0 US)"

    STAT #22 = 2 cnt = 1 pid = 1 pos = 1 obj id = 8236 op ='INDEX RANGE SCAN PF_TRX_1IX (cr = 2 pr = 0 pw = time 0 = 0 US cost = 1 size = 11 card = 1)'

    WAITING #22: nam ='SQL * Net client message' ela = 135 driver id = 1413697536 #bytes = 1 p3 = 0 obj #= 775422 tim = 44216143020630

    ...

    obj #= 775422 is an index into a schema user wealth of paintings, consideration of related things.

    But the picture, who own index which is never mentioned in the trace file all (DDL, DML, or Select statement).

    TKPROF does not display as well without the presence of this table.

    How to interpret these topics, as registrations in the event of mine is the biggest event of waiting.

    THX,

    Damir

    «SQL * Net message to client "is in fact a wait on the BONE (layers TCPIP).»  Oracle is not clear when the package reaches the customer, he knows only that she has presented the package to the TCPIP services provided by the operating system on the database server.

    Hemant K Collette

  • Disable the trace file in ebs 12.1.3

    Hi all

    EBS 12.1.3 (OUL5x64) the 11.2.0.3 database

    I have a lot of files to trace as below and the database had been reset to TRACE_ENABLED = FALSE and bounced.

    but still a lot of trace files to the location of the dump.

    Since this sql

    Select trace_type, primary_id, QUALIFIER_ID1, wait, links to DBA_ENABLED_TRACES;

    I couldn't see anything on.

    Please indicate how to disable * _APPS.tr*

    Thanks in advance.

    Kind regards

    _trace_files_publicBooleanFAKE
    log_archive_traceinteger0
    sec_protocol_error_trace_actionstringTRACE
    SQL_TraceBooleanFAKE
    trace_enabledBooleanFAKE

    TEST_ora_18943_APPS.TRC

    TEST_ora_18943_APPS. TRM

    TEST_ora_18945_APPS.TRC

    TEST_ora_18945_APPS. TRM

    TEST_ora_18982_APPS.TRC

    TEST_ora_18982_APPS. TRM

    TEST_ora_18984_APPS.TRC

    TEST_ora_18984_APPS. TRM

    TEST_ora_18986_APPS.TRC

    TEST_ora_18986_APPS. TRM

    TEST_ora_15062_APPS. TRM

    TEST_ora_18925_APPS. TRM

    TEST_ora_18925_APPS.TRC

    TEST_ora_10509_APPS. TRM

    TEST_ora_10524_APPS. TRM

    TEST_ora_18929_APPS. TRM

    TEST_ora_10509_APPS.TRC

    TEST_ora_10768_APPS. TRM

    TEST_ora_18988_APPS.TRC

    TEST_ora_18929_APPS.TRC

    TEST_ora_14764_APPS.TRC

    TEST_ora_10524_APPS.TRC

    TEST_ora_14764_APPS. TRM

    TEST_ora_18988_APPS. TRM

    TEST_ora_15062_APPS.TRC

    TEST_lgwr_31711.TRC

    Run the query (query to get activated Trace/Log/Debug profile options (Doc ID 559618.1)) to determine if debugging is turned on - I suspect debugging generates these trace files.

    Also, please confirm that the trace is not enabled for any simultaneous program (simultaneous > program > define)-how can Trace and Debug be turned on for a competitor request? (Doc ID 759389.1)

    Thank you

    Hussein

  • How simple extraction do more consistent results in trace file,

    Hello Experts,

    In my trace file, one thing I don't understand is clearly why a single extraction contains more than 1 get consistent results? Can someone just explain this please? As you can see only the first and the last extraction get over a uniform result (cr = 5, cr = 2).

    Is this possible? Why is that?

    My last question is to make recursive calls is the value of cr (consisten GETS) increase? If so, what fetch is done of this? first? second? third?

    =====================

    PARSING IN CURSOR #2 len = 23 dep = uid 0 = 85 oct = cover 3 = 85 tim = hv 2197698804 = ad 4069246757 = "7ff5f323410" sqlid = "f34thrbt8rjt5."

    Select * from employees

    END OF STMT

    ANALYSIS # 2: c = 0, e = 80, p = 0, cr = 0, set cu = 0, = 0, r = 0, dep = 0, og = 1, plh is 1445457117, tim = 2197698803

    # 2 EXEC: c = 0, e = 37, p = 0, cr = 0, set cu = 0, = 0, r = 0, dep = 0, og = 1, plh is 1445457117, tim = 2197698938

    WAITING #2: nam ='SQL * Net message to client' ela = driver id 6 = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197699018

    FETCH # 2: c = 0, e = 92, p = 0,cr = 5, cu = 0, = 0, r = 1, dep = 0, og = 1, plh is 1445457117, tim = 2197699158

    WAITING #2: nam ='SQL * Net client message' ela = 464 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197699670

    WAITING #2: nam ='SQL * Net message to client' ela = 2 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197699751

    FETCH # 2: c = 0, e = 92, p = 0, cr = 1, cu = 0, = 0, r = 15, dep = 0, og = 1, plh is 1445457117, tim = 2197699827

    WAITING #2: nam ='SQL * Net client message' ela = driver id 10342 = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197710219

    WAITING #2: nam ='SQL * Net message to client' ela = 4 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197710375

    FETCH # 2: c = 0, e = 82, p = 0, cr = 1, cu = 0, = 0, r = 15, dep = 0, og = 1, plh is 1445457117, tim = 2197710435

    WAITING #2: nam ='SQL * Net client message' ela = 6642 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197717124

    WAITING #2: nam ='SQL * Net message to client' ela = 3 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197717210

    FETCH # 2: c = 0, e = 59, p = 0, cr = 1, cu = 0, = 0, r = 15, dep = 0, og = 1, plh is 1445457117, tim = 2197717249

    WAITING #2: nam ='SQL * Net client message' ela = 5954 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197723240

    WAITING #2: nam ='SQL * Net message to client' ela = 2 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197723350

    FETCH # 2: c = 0, e = 53, p = 0, cr = 1, cu = 0, = 0, r = 15, dep = 0, og = 1, plh is 1445457117, tim = 2197723389

    WAITING #2: nam ='SQL * Net client message' ela = 5356 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197728775

    WAITING #2: nam ='SQL * Net message to client' ela = 1 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197728843

    FETCH # 2: c = 0, e = 54, p = 0, cr = 1, cu = 0, = 0, r = 15, dep = 0, og = 1, plh is 1445457117, tim = 2197728882

    WAITING #2: nam ='SQL * Net client message' ela = 5827 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197734740

    WAITING #2: nam ='SQL * Net message to client' ela = 3 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197734899

    FETCH # 2: c = 0, e = 83, p = 0, cr = 1, cu = 0, = 0, r = 15, dep = 0, og = 1, plh is 1445457117, tim = 2197734956

    WAITING #2: nam ='SQL * Net client message' ela = 6038 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197741053

    WAITING #2: nam ='SQL * Net message to client' ela = 2 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197741166

    FETCH # 2: c = 0, e = 117, p = 0, cr = 2, set cu = 0, = 0, r = 15, dep = 0, og = 1, plh is 1445457117, tim = 2197741264

    WAITING #2: nam ='SQL * Net client message' ela = 4365 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197745665

    WAITING #2: nam ='SQL * Net message to client' ela = 1 driver id = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2197745714

    FETCH # 2: c = 0, e = 37, p = 0,cr = 2, cu = 0, = 0, r = 1, dep = 0, og = 1, plh is 1445457117, tim = 2197745738

    "STAT #2 = 1 cnt = 107 pid = 0 pos = 1 obj id = 73948 op ='TABLE ACCESS FULL EMPLOYEES (cr = 15 pr = 0 pw = time 0 = 0 cost = US size 3 = 7383 card = 107)"

    2014-01-04 23:34:20.251

    WAITING #2: nam ='SQL * Net client message' driver ela = id 4200655 = 1111838976 #bytes = 1 p3 = 0 obj #=-1 tim = 2201946471

    ISSUE # 2: c = 0, e = 32, dep = 0, type = 0, tim = 2201946695

    =====================

    Thanks in advance.

    Franck,

    Looks like you think of the freelist management.

    Under the SAMS, the cr number gets reported in the first extraction could be much higher - probably up to 16 - and it still is missing a number of visits due to the segment header block and the blocks of space management. Unfortunately I don't know of a mechanism to track the visit of each block in 11g (which I think is using the OP).

    Concerning

    Jonathan Lewis

  • Phase of extraction in a trace file

    Oracle version: 11.2.0.3.0 Enterprise Edition
    Operating system - IBM/AIX RISC System/6000

    I'm trying to generate a trace file from a piece of code executed by the java server. What I asked the java developer to do is to place this block immediately after a connection is established:
    BEGIN
  EXECUTE IMMEDIATE 'ALTER SESSION SET TRACEFILE_IDENTIFIER = ''M1''';
  dbms_monitor.session_trace_enable(waits => FALSE, binds => TRUE);
END;
    And at the end of the block of logic java code:
    BEGIN
  dbms_monitor.session_trace_disable;
END;
    I want to know is how many lines the java Server recovers after executing a particular select statement, because they complain about getting less account lines select statement waits.
    For example, if I run the same query sql in the sqlplus session, then we'll, I extract say 1000 rows.
    When the same query is executed on the side of java, the lines of recoveries are less in number, let's say 500.
    And because I doubt it, I wanted to trace to see what is actually executed and how.
    The extract of the trace file I see exactly the same query that I myself run in a sqplus session.
    There is no precise control over the udnerlying tables in the query.

    And my question is, how to interpret the phase EXTRACTION of the slider (for the select statement)?
    For example, if I see a FETCH for this cursor, this means that the java server retrieves a single row?
    If I see 100 extractions, this means that they recovered 100 lines of the cursor?

    Here's a short excerpt from the trace file (please don't crucify me for obvious denormalized tables design, and the request it is not invented by me):
    PARSING IN CURSOR #4573587152 len=667 dep=0 uid=737 oct=3 lid=737 tim=17685516462413 hv=954980718 ad='70000006d3e4940' sqlid='69pm96nwfrqbf'
select /* ordered */ o.id, nvl(o.par_id, -1) as par_id, o.NAME_GER, o.NAME_ENG, o.NAME_ESP, o.NAME_ITL,o.NAME_FRA, decode(lo.lflag, 'Y', 'L', 'N') as leaf_or_node, lo.distance + 1 as "LEVEL",  to_char(o.beg_date, 'DD.MM.YYYY HH24:MI:SS'),  o.mais_id, l.path, nvl(o.non_selectable, 'N')  from   st_prod o, lprod_new l, lprod lo where  o.end_date = to_date('31.12.3999', 'DD.MM.YYYY') and   (lo.id, lo.beg_date) in (select id, beg_date from st_prod where par_id is null and end_date = to_date('31.12.3999', 'DD.MM.YYYY')) and   lo.lid = o.id and lo.lid_beg_date = o.beg_date and   l.st_prod_id = o.id and l.st_prod_beg_date = o.beg_date order by lo.distance, o.name_ger
END OF STMT
PARSE #4573587152:c=31,e=152,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=2027551050,tim=17685516462412
EXEC #4573587152:c=80,e=375,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=2027551050,tim=17685516462936

*** 2013-03-11 11:28:09.122
FETCH #4573587152:c=519446,e=892645,p=0,cr=113446,cu=0,mis=0,r=10,dep=0,og=1,plh=2027551050,tim=17685517355715
FETCH #4573587152:c=37,e=59,p=0,cr=0,cu=0,mis=0,r=10,dep=0,og=1,plh=2027551050,tim=17685517359109
FETCH #4573587152:c=39,e=63,p=0,cr=0,cu=0,mis=0,r=10,dep=0,og=1,plh=2027551050,tim=17685517361128
FETCH #4573587152:c=29,e=46,p=0,cr=0,cu=0,mis=0,r=10,dep=0,og=1,plh=2027551050,tim=17685517362849
FETCH #4573587152:c=31,e=48,p=0,cr=0,cu=0,mis=0,r=10,dep=0,og=1,plh=2027551050,tim=17685517364621
<162 more FETCH-es here>
<STAT phase>
CLOSE #4573587152:c=533,e=849,dep=0,type=1,tim=17685517671878
    It is possible based on the trace file (if I have to change something in the way of tracing) to determine the number of rows retrieved? 
    FETCH #4573587152:c=519446,e=892645,p=0,cr=113446,cu=0,mis=0,r=10,dep=0,og=1,plh=2027551050,tim=17685517355715
FETCH #4573587152:c=37,e=59,p=0,cr=0,cu=0,mis=0,r=10,dep=0,og=1,plh=2027551050,tim=17685517359109
FETCH #4573587152:c=39,e=63,p=0,cr=0,cu=0,mis=0,r=10,dep=0,og=1,plh=2027551050,tim=17685517361128
FETCH #4573587152:c=29,e=46,p=0,cr=0,cu=0,mis=0,r=10,dep=0,og=1,plh=2027551050,tim=17685517362849
FETCH #4573587152:c=31,e=48,p=0,cr=0,cu=0,mis=0,r=10,dep=0,og=1,plh=2027551050,tim=17685517364621

    Each FETCH call returns r = number of lines.

    Run TKPROF trace file for a readable activity summary.

    How to interpret the phase EXTRACTION of the slider (for the select statement)?

    The java code uses a default fetchsize to 10 (lines per extraction).

  • Blocker withdraws the deadlock trace file (self)

    Hello

    Recently, I had a problem on a 10.2.0.4 database to single instance where blockages are produced. The following test case reproduced the problem (I create three tables parent, a child table with foreign keys indexed to all parents three tables and a procedure that performs an insert in the child table in a standalone transaction):
    create table parent_1(id number primary key);

create table parent_2(id number primary key);

create table parent_3(id number primary key);
 
create table child( id_c number primary key,
                   id_p1 number,
                   id_p2 number,
                   id_p3 number,
                   constraint fk_id_p1 foreign key (id_p1) references parent_1(id),
                   constraint fk_id_p2 foreign key (id_p2) references parent_2(id),
                   constraint fk_id_p3 foreign key (id_p3) references parent_3(id)
                   );
 
create index i_id_p1 on child(id_p1);

create index i_id_p2 on child(id_p2);

create index i_id_p3 on child(id_p3);

create or replace procedure insert_into_child as
pragma autonomous_transaction;
begin
  insert into child(id_c, id_p1, id_p2, id_p3) values(1,1,1,1);
  commit;
end;
/
 
insert into parent_1 values(1);

insert into parent_2 values(1);

commit;
    And now the action that causes the deadlock:
    SQL> insert into parent_3 values(1);

1 row created.

SQL> exec insert_into_child;
BEGIN insert_into_child; END;

*
ERROR at line 1:
ORA-00060: deadlock detected while waiting for resource
ORA-06512: at "SCOTT.INSERT_INTO_CHILD", line 4
ORA-06512: at line 1
    My question is: How can I determine which integration into the CHILD table waiting for? He could wait on a combination of these, PARENT_3, PARENT_2, PARENT_1, or even on the CHILD if I tried to insert a primary key that is duplicated in the CHILD. Since we have the full test case, we know that he was waiting on PARENT_3 (or better said, he expected to perform a commit / rollback of the transaction 'parent'), but is it possible to determine that only from the deadlock trace file? I ask that because to identify the problem, I had to perform redo log mining, tracing pl/sql with DBMS_TRACE and manual debugging on a clone of the production database that has been restored to a SNA just before blocking is product. So, I had to do a lot of work to get to the trainer table and if this information is already in the deadlock trace file, it would have saved me a lot of time.

    Here is the deadlock trace file. In section "DML LOCK", I assumed that the child table (tab = 227042) holds a 3 way locks (SX), all other tables of three parents have a mode 2 locks (SS), but from this excerpt, I see that parent_3 (tab = 227040) blocks the children insert:
    Deadlock graph:
                       ---------Blocker(s)--------  ---------Waiter(s)---------
Resource Name          process session holds waits  process session holds waits
TX-00070029-00749150        23     476     X             23     476           S
session 476: DID 0001-0017-00000003     session 476: DID 0001-0017-00000003
Rows waited on:
Session 476: obj - rowid = 000376E2 - AAA3biAAEAAA4BwAAA
  (dictionary objn - 227042, file - 4, block - 229488, slot - 0)
Information on the OTHER waiting sessions:
End of information on OTHER waiting sessions.
Current SQL statement for this session:
INSERT INTO CHILD(ID_C, ID_P1, ID_P2, ID_P3) VALUES(1,1,1,1)
----- PL/SQL Call Stack -----
  object      line  object
  handle    number  name
3989eef50         4  procedure SCOTT.INSERT_INTO_CHILD
391f3d870         1  anonymous block
.
.
.
.
        SO: 397691978, type: 36, owner: 39686af98, flag: INIT/-/-/0x00
        DML LOCK: tab=227042 flg=11 chi=0
                  his[0]: mod=3 spn=35288
        (enqueue) TM-000376E2-00000000  DID: 0001-0017-00000003
        lv: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  res_flag: 0x6
        res: 0x398341fe8, mode: SX, lock_flag: 0x0
        own: 0x3980df420, sess: 0x3980df420, proc: 0x39859c660, prv: 0x398341ff8
        ----------------------------------------
        SO: 397691878, type: 36, owner: 39686af98, flag: INIT/-/-/0x00
        DML LOCK: tab=227040 flg=11 chi=0
                  his[0]: mod=2 spn=35288
        (enqueue) TM-000376E0-00000000  DID: 0001-0017-00000003
        lv: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  res_flag: 0x6
        res: 0x3983386e8, mode: SS, lock_flag: 0x0
        own: 0x3980df420, sess: 0x3980df420, proc: 0x39859c660, prv: 0x3983386f8
        ----------------------------------------
        SO: 397691778, type: 36, owner: 39686af98, flag: INIT/-/-/0x00
        DML LOCK: tab=227038 flg=11 chi=0
                  his[0]: mod=2 spn=35288
        (enqueue) TM-000376DE-00000000  DID: 0001-0017-00000003
        lv: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  res_flag: 0x6
        res: 0x398340f58, mode: SS, lock_flag: 0x0
        own: 0x3980df420, sess: 0x3980df420, proc: 0x39859c660, prv: 0x398340f68
        ----------------------------------------
        SO: 397691678, type: 36, owner: 39686af98, flag: INIT/-/-/0x00
        DML LOCK: tab=227036 flg=11 chi=0
                  his[0]: mod=2 spn=35288
        (enqueue) TM-000376DC-00000000  DID: 0001-0017-00000003
        lv: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  res_flag: 0x6
        res: 0x39833f358, mode: SS, lock_flag: 0x0
        own: 0x3980df420, sess: 0x3980df420, proc: 0x39859c660, prv: 0x39833f368
      ----------------------------------------
    Thanks in advance for your comments,
    Swear

    user633661 wrote:

    My question is: How can I determine which integration into the CHILD table waiting for? He could wait on a combination of these, PARENT_3, PARENT_2, PARENT_1, or even on the CHILD if I tried to insert a primary key that is duplicated in the CHILD. Since we have the full test case, we know that he was waiting on PARENT_3 (or better said, he expected to perform a commit / rollback of the transaction 'parent'), but is it possible to determine that only from the deadlock trace file?

    There is no way to get the answer from the deadlock trace.

    At this stage and with your example, the waiting session waits for a lock of the TX (transaction) - this means that he has no idea (and uninteresting) in the involved actual data, that it is simply waiting for a location of transaction undo segment header table clear.

    An easy way to demonstrate, it is as follows:-


    create the parent and child tables with the activated FK constraint
    Session 1 - set a save point, then insert line into parent but do not commit
    Session 2 - insert a load line in the child - the session will pass a waiting for TX lock on the parent transaction
    Session 1-restoration to the point of backup

    Because restoration is a save point, session 1 always held a TX lock in exclusive mode, even if it will take is more all lock TM (table).
    Session 2 will still wait for session 1 to commit or rollback - even if the parent required row does not exist, even in a State that is not validated.

    Concerning
    Jonathan Lewis
    http://jonathanlewis.WordPress.com
    Author: core Oracle

  • Interpretation of Trace files with the following statement: swap_info

    Could someone tell me where I can find a way of interpreting the following entry in a trace file:

    loadavg: 59,19 53.52 42.27
    Exchange of information: free_mem = 1779.71 rsv M = 13442,54 M
    Alloc = DISP 12032,48 M = swap_free 12821.84 = 14231,91 M
    F S UID PID PPID C PRI OR ADDR SZ WCHAN STIME TTY TIME CMD
    0 S 4162 1 3 40 20 oracle? 606691? 14:01:49? 0:39 ora_j001_db.

    I need assistance with the headings for the swap_info and columns below?

    Thank you

    John

    loadavg: 59,19 53.52 42.27

    If you have 4 or 8 processors, these are numbers very high!
    OTOH, if you have 64 processors, you have still a few idle CPU cycles.

    Exchange of information: free_mem = 1779.71 rsv M = 13442,54 M
    Alloc = DISP 12032,48 M = swap_free 12821.84 = 14231,91 M

    These figures depends on how swap is allocated your OS.

    Without a context (i.e. without understanding the specifics of the OS and the memory management configured) interpretation of the figures of swap and memory is meaningless.

    Hemant K Collette

  • LMS huge trace file created

    Hi all

    2 CARS db in window Server 2003 nodes. The version of db is 10.2.0.3. My question is why we get huge lms trace files in the bdump directory and they become bigger and bigger. In the trace file, we have something like the following. Thanks a lot for your help, Shirley

    2009-12-29 10:11:01.926
    KJM_HISTORY: OP (12) of STALL RCVR context 0 elapsed 651716247 US
    KJM HIST LMS3:
    12:651716247 7:1 6:0 10:1 17:1 16:0 15:1 12:15475015 7:1 6:1
    10:0 17:2 16:1 15:291 12:651692189 7:1 6:1 10:0 17:1 16:1
    12:12345971 15:1 7:0 6:1 10:0 17:1 16:0 15:1 12:12020 7:0
    6:1 10:0 17:1 16:1 15:0 12:11977 7:1 6:0 10:0 17:1
    16:1 15:0 12:12054 7:1 6:0 10:0 17:1 16:1 15:0 12:12016
    7:1 6:0 10:0 17:1 16:1 12:12017 7:1 6:0 10:0 15:0
    17:1 16:1 15:0 12:11692
    ----------------------------------------
    SO: 000000012A3B11D8, type: 4, owner: 000000012A0041B8, flag: INIT /-/-/ 0x00
    (session) sid: 543 trans: 0000000000000000, creator: 000000012A0041B8, flag: (51) USR /-BSY /-/ - /-/ - / -.
    DID: 0000-0000-00000000, DID short term: 0000-0000-00000000
    TXN branch: 0000000000000000
    Oct: 0, prv: 0, sql: 0000000000000000, psql: 0000000000000000, users: 0/SYS
    last wait "gcs remote message" blocking sess = 0 x 0000000000000000 seq = 10 wait_time = 651716241 seconds since then started to wait = 300
    waittime = 18, survey = 0, event = 0
    Dumping history of waiting for Session
    for "BSC remote messages" number = 1 wait_time = 651716241
    waittime = 18, survey = 0, event = 0
    for "BSC remote messages" number = 1 wait_time = 15475008
    waittime = 18, survey = 0, event = 0
    for "BSC remote messages" number = 1 wait_time = 651692179
    waittime = 18, survey = 0, event = 0
    for "BSC remote messages" number = 1 wait_time = 12345963
    waittime = 18, survey = 0, event = 0
    for "BSC remote messages" number = 1 wait_time = 12017
    waittime = 18, survey = 0, event = 0
    for "BSC remote messages" number = 1 wait_time = 11974
    waittime = 18, survey = 0, event = 0
    for "BSC remote messages" number = 1 wait_time = 12052
    waittime = 18, survey = 0, event = 0
    for "BSC remote messages" number = 1 wait_time = 12013
    waittime = 18, survey = 0, event = 0
    for "BSC remote messages" number = 1 wait_time = 12014
    waittime = 18, survey = 0, event = 0
    for "BSC remote messages" number = 1 wait_time = 11688
    waittime = 18, survey = 0, event = 0
    the temporary object counter: 0
    ----------------------------------------
    UOL used: 0 locks (used = 0, free = 0)
    KGX atomic operation Log 000007FFE6FF5600
    Mutex 0000000000000000 (0, 0) oper idn 0 NONE
    Library Cache 543 DTS uid 0 w/h 0 slp 0
    KGX atomic operation Log 000007FFE6FF5648
    Mutex 0000000000000000 (0, 0) oper idn 0 NONE
    Library Cache 543 DTS uid 0 w/h 0 slp 0
    KGX atomic operation Log 000007FFE6FF5690
    Mutex 0000000000000000 (0, 0) oper idn 0 NONE
    Library Cache 543 DTS uid 0 w/h 0 slp 0

    check metalink note:
    excessive LMS and lmd of sizes of trace files generated on windows rac - 437101.1

    HTH
    -André

  • The server trace file sharing

    Hi all

    Our production database is 10.2.0.3 with 2 RAC nodes in the window MS 2003 servers. I wonder what kind of information that Oracle captures in the trace of the shared server file (file name looks like instance_s001_3333.trc). When I open some of these files I still see a single query always there along with messages like:

    WAITING #8: nam = 'gc cr block 2 ways' ela = 1222 p1 = p2 65 = 49677 p3 = 1 obj #= tim 69689 = 4137263779
    WAITING #8: nam = 'gc cr block 2 ways' ela = 593 p1 = p2 65 = 50863 p3 = 1 obj #= tim 69689 = 4137265531
    WAITING #8: nam = 'gc cr block 2 ways' ela = 592 65 p1 = p2 = 50879 p3 = 1 obj #= tim 69689 = 4137266700

    If we need to grant this request to make it disappear in the shared server trace files? What are the conditions to trigger Oracle put this request in the trace file?

    Thank you very much in advance for your support!

    Shirley

    I did a quick test with 10.2.0.1 EE on Linux with default shared server configuration. I have run your trace statements, and I see:
    -1 small file trace with the identifier of the EMT in USER_DUMP_DEST
    -1 large track .trc file s000 in BACKGROUND_DUMP_DEST.

    And it looks like the outcome according to trcsess doc. http://download.Oracle.com/docs/CD/B19306_01/server.102/b14211/SQLTrace.htm#PFGRF01050

    >
    However, in a shared server configuration a user session is served by different processes from time to time. The trace on the user session is dispersed across the different stack belonging to different processes.
    >

    Most traced statements are in the trace file to the shared server because it's the shared server that runs most of the SQL statements.

  • Large number of trace files generated

    Many of the following trace files are current generted throughout the day, sometimes 4/5 per minute

    There is nothing in the alerts log

    Any ideas?

    Thanks in advance

    ________________________________________________________________

    E:\oracle\admin\nauti1\udump\nauti1_ora_5552.TRC dump file
    Kills Nov 18 17:36:11 2008
    ORACLE V10.2.0.4.0 - Production vsnsta = 0
    vsnsql = 14 vsnxtr = 3
    Oracle Database 10g Enterprise Edition Release 10.2.0.4.0 - Production
    Windows Server 2003 V5.2 Service Pack 2 Version
    CPU: type 4-586, 4 physical cores
    Process affinity: 0x00000000
    Memory (success/Total): Ph: 2045 M / 3839 M, Ph + FCP: 3718 M / 5724 M, GOES: 649 M / 2047 M
    Instance name: nauti1

    Redo thread mounted by this instance: 1

    Oracle process number: 32

    Windows thread ID: 5552, image: ORACLE. EXE (SHAD)


    ACTION NAME :() 2008-11-18 17:36:11.432
    MODULE NAME: (Nautilus.Exe) 2008-11-18 17:36:11.432
    SERVICE NAME: (nauti1) 2008-11-18 17:36:11.432
    SESSION ID: (130.42066) 2008-11-18 17:36:11.432
    KGX cleaning...
    KGX atomic operation Log 342CD2A4
    Mutex 452CC5F8 (130, 0) idn 0 oper EXAM
    Cursor Parent uid 130 DTS 17 w/h 26 slp 0
    Oper = DEFAULT pt1 = 00000000 00000000 00000000 = pt2 = pt3
    PT4 = 00000000 u41 TWU 0 = 0 =
    KGX cleaning...
    KGX atomic operation Log 342CD2A4
    Mutex 452CC5F8 (130, 0) idn 0 oper EXAM
    Cursor Parent uid 130 DTS 17 w/h 26 slp 0
    Oper = DEFAULT pt1 = 48265D6C 48265E68 = 48265D3C pt2 = pt3
    PT4 = 00000000 u41 TWU 0 = 0 =
    E:\oracle\admin\nauti1\udump\nauti1_ora_5552.TRC dump file
    Sat 22 Nov 12:52:32 2008
    ORACLE V10.2.0.4.0 - Production vsnsta = 0
    vsnsql = 14 vsnxtr = 3
    Oracle Database 10g Enterprise Edition Release 10.2.0.4.0 - Production
    Windows Server 2003 V5.2 Service Pack 2 Version
    CPU: type 4-586, 4 physical cores
    Process affinity: 0x00000000
    Memory (success/Total): Ph: 2070 M / 3839 M, Ph + FCP: 3896 M / 5724 M, GOES: 673 M / 2047 M
    Instance name: nauti1

    Redo thread mounted by this instance: 1

    Oracle process number: 29

    Windows thread ID: 5552, image: ORACLE. EXE (SHAD)

    See metalink Bug 6638558 bug description

  • CoreTelephony error in trace file as a file for coretelephony tracing operation has failed, perhaps you need more disk space. Details "error opening the file/tmp/ct.shutdown, err = operation not permitted

    I got this error:

    "CoreTelephony Trace file error
    A file for CoreTelephony tracing operation failed, you might run out of disk space. Details "error opening the file/tmp/ct.shutdown, err = operation not permitted"

    I tried to solve it by searching for CoreTelephony errors. Could not resolve yet.
    Software does not, especially of photoshop...

    Any ideas?

    Same thing here, iMac with OS X 10.11.6. All started a couple days ago. Have not found any valid solution online yet, I tried rebooting in recovery mode and check disk, but it seems that everything is ok with the drives and permissions.

  • CoreTelephony trace file error pops up suddenly

    I'm on iMac (retina 4K, 21.5 inches, end of 2015)

    Processor: Intel Core i5 to 3.1 GHz

    Memory: 8 GB 1867 MHz DDR3

    OS X El Capitan (10.11.3)

    When I turned on my computer this morning a message box is displayed:

    "CoreTelephony Trace file error"

    A file for CoreTelephony tracing operation failed, you might run out of disk space. Details "error opening file /tm/ct.shutdown,err = operation not permitted"

    Then I just ignored it but when I open Xcode and run my the simulator program is unable to start. I do not change Xcode or something. He thinks that he has been affected by this error.

    Please help me. Thanks in advance

    I get the same error message to unexpectedly without good reason

    My specs:

    MacBook Air (13 inch, early 2015)

    Intel Core i7 2.2 GHz

    8 GB 1600 MHz DDR3

    Intel HD Graphics 6000 1536 MB

    I tried a google search and didn't find any solution that seems to work

    I don't know if it's my antivirus Sophos or another application to the original of this

    I even installed the latest update and have the latest version 10.11.6 (15G 31)

    Any help please?

  • Trace file error CoreTelephony - more (El Capitan 10.11.3) disk space

    Hello. My Mac (Macbook Air 2013) started crashing, most often when I have 3 + tabs open in Chrome, or when I play Football Manager and have Chrome open, as if it's getting overworked. Normally I could easily do this, and I've done it since I got my Mac. But suddenly he crashes, freezes and shuts down, and when I reboot I get this message before you start: it is said: trace file error Coretelephony a file for coretelephony tracing operation failed, you might run out of disk space.

    I have 56 GB / 120 GB left, it makes no sense.

    Someone had the same problem, or maybe a solution? Thank you.

    These must be run as administrator. If you have only one user account, you are the administrator.

    Please launch the Console application in one of the following ways:

    ☞ Enter the first letters of his name in a Spotlight search. Select from the results (it should be at the top).

    ☞ In the Finder, select go utilities ▹ of menu bar or press the combination of keys shift-command-U. The application is in the folder that opens.

    ☞ Open LaunchPad and start typing the name.

    In the Console window, select

    DIAGNOSIS AND diagnostic USE information reports ▹ System

    (not diagnose them and use Messages) in the list of logs on the left. If you don't see this list, select

    List of newspapers seen ▹ display

    in the menu bar.

    There is a disclosure triangle to the left of the list item. If the triangle is pointing to the right, click it so that it points downwards. You will see a list of reports. A report of panic has a name that begins with "Kernel" and ends with ".panic." Select the most recent. The content of the report is displayed at right. Allows you to copy and paste to validate all of the content, text, not a screenshot.

    If you don't see any report, but you know, there was a panic, you have chosen diagnostic and using the list of Log Messages. INFORMATION on the USE of DIAGNOSTIC AND choose instead.

    In the interest of privacy, I suggest that, before posting, you change the UUID ' anonymous, ' a long string of letters, numbers and dashes in the header of the report, if it is present (it cannot be). "

    Please do not post other types of diagnostic report.

    I know that the report is long, perhaps several hundred lines. Please report all this anyway.

    When you post the report, an error message may appear on the web page: "you have included content in your post that is not allowed", or "the message contains invalid characters." It's a bug in the forum software. Thanks for posting the text on Pastebin, then post here a link to the page you created.

    If you have an account on Pastebin, please do not select private in exposure menu to paste on the page, because no one else that you will be able to see it.

  • eRecovery wait while the file is loaded in the last 36 hours

    Hello

    I have an Aspire 5735-4774 I do a default erecovery because Windows Vista does not start after switching on. I did using the Alt F10 procedure and do not have the recovery disk. It has been in the Please wait while the file load in the last 36 hours. Is this normal? Thanks for your replies

    Windows Vista has been not loading so had to do a factory reset erecovery twice. I had to keep on hitting Alt F10
    So many times to work since I don't have any drive recovery.

Maybe you are looking for

  • plan to iCloud for multiple accounts

    I think of purchasing a plan from iCloud for my family. Between my wife and me, we have nearby a 1 terrabyte of photos.  I tried to use a personal cloud at home, but it's quickly become cumbersome the advantage, however, is that both of us can access

  • Satellite A30. How can I save BIOS?

    Hello. I'll update the BIOS on my laptop, and how I can save BIOS witch is already setuped on my computer? Thank you. PS Sorry for my English. I'm of the Russia.

  • Key signing does not allow a specific key

    I record a song in EB major, and I would like to be able to use loops / the score editor. When I try to select major MIB in the drop-down list signature, logical key selects Ab minor. I've tested this in several projects and have revived the logic, a

  • black screen on PC sleep/resume

    I have a problem with my WPF .NET application using measurement studio. The application consists of a tabcontroller with 5 tabpages which two involve only WPF graphics. My problem is that the app works fine until I put the PC sleep how the tappages w

  • New ink cartridges show empty

    Hello HP Photosmart C6380 All in one Despite the purchase of authentic HP ink cartridges from a reputable supplier, the ink is not recognized or shows empty as they do now. Eitherwayi unable to print. I bought several and still get the same message.