Reading and Understanding Systemstate Dumps (Doc ID 423153.1)
In this Document
|
Purpose |
|
Scope |
|
Details |
|
How to use this document |
|
What is a Systemstate ? |
|
How to Navigate through a systemstate |
|
Common wait scenarios and corresponding Entries |
|
Enqueues |
|
Rowcache locks |
|
Library Cache Pins (10G - Mutexes) |
|
Library Cache Lock |
|
Latch free |
APPLIES TO:
Oracle Database - Enterprise Edition - Version 10.2.0.1 and later
Oracle Database - Personal Edition - Version 10.2.0.1 and later
Oracle Database - Standard Edition - Version 10.2.0.1 and later
Oracle Database Exadata Cloud Machine - Version N/A and later
Oracle Cloud Infrastructure - Database Service - Version N/A and later
Information in this document applies to any platform.
PURPOSE
To be able to read a systemstate, or navigate through a systemstate in order to identify what sessions are doing and ,
in the case of a waiting session, which session(s) hold the resource it requires
SCOPE
This document is intended for DBAs.
DETAILS
How to use this document
Each wait scenario will be given, along with key points in the systemstate which can be used to match to the corresponding entry
in your own systemstates. It will then give you examples of matching holders (ie what you need to find in the rest of the systemstate
to be able to identify who is blocking)
What is a Systemstate ?
A systemstate is made up of the processstate of each process in the instance found at the time the systemstate was called for. Each
processtate is made up of SO (State Objects) which hold details of the state of current objects owned by each PROCESS.
How to Navigate through a systemstate
What you need to do is start by determining what most session are waiting for (or in the case of a session you know
is blocked, the PROCESS number of the process). So - you will now have either a PROCESS XX or a , for example, 'latch free'
which you need to begin with. What you then need to do is navigate (through vi or a windows editor) and find either
PROCESS XX or the first example of 'latch free'. If you are using PROCESS XX then you now need to find what the process
is waiting for. You will now have :-
PROCESS XX waits for YYYYYYY
What you then need to do is find, by using this guide, the SO for the resource the session is waiting for and then find (by
searching back from that point) the PROCESS XX of that session. You now have:-
PROCESS XX waits for YYYYYYY
PROCESS YY holds YYYYYYY
You then begin again, finding the resource it is waiting
for (if any) and that resources holder. Eventually you will come to a process which is on the CPU (last waited) or you will
have navigated back to a PROCESS you already know about. In the case of the process which is on the CPU you will then need
to get an errorstack to determine why it is blocking. In the case of a 'deadlock' you will now have a dependency tree of the form:-
PROCESS XX waits for YYYYYYY
PROCESS YY holds YYYYYYY and waits for ZZZZZZZZ
PROCESS ZZ holds ZZZZZZZ ... etc etc
Common wait scenarios and corresponding Entries
This sections looks at how to investigate various common scenarios and how to follow the trail to the holder based upon each starting wait.
Enqueues
These are waits for locks held upon a particular object. In the example below, the process is waiting for a TX enqueue as indicated by the "waiting for 'enq: TX - row lock contention'" message:
PROCESS 41 ... waiting for 'enq: TX - row lock contention' blocking sess=0x39b3a5c90 seq=152 wait_time=0 seconds since wait started=796 name|mode=54580006, usn * 54580006 is ASCII and can be split up as follows to reveal the meaning: * ASCII 54 (T) + ASCII 58 (T) => (TX) + Mode 0006 (X) ...
To find more details on the enqueue, do a search for the string 'req:' (searching DOWN) within the process. In this case we find a section with a "req:X" request:
SO: 39ad80d60, type: 5, owner: 393cb85e0, flag: INIT/-/-/0x00 (enqueue) TX-00020009-0001FA04 DID: 0001-0029-00000090 lv: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 flag: 0x6 res: 39aef20c8, req: X, prv: 39aef20e8, own: 39b383aa8, sess: 39b383aa8, proc: 39b7384f0
"req:" in this case refers the "request" for the TX lock that is being waited for by the 'enq: TX - row lock contention' wait. The request is for an eXclusive TX lock.
This section also reveals the enqueue name as a string: (
TX-00020009-0001FA04) that can be used to search for the HOLDER (the holder of the resource is shown with the string "mode:" with the mode that the lock is being held in by the holder, in this case eXclusive) :
(enqueue) TX-00020009-0001FA04 DID: 0001-002E-00000014 lv: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 flag: 0x6 res: 39aef20c8, mode: X, prv: 39aef20d8, own: 39b3a5c90, sess: 39b3a5c90, proc: 39b73ac78
We can see we hold the enqueue (mode: X) in a incompatible mode to the req: X request...
Rowcache locks
A Row cache waits are waits against the Row Cache (or Dictionary Cache). Processes will show "waiting for 'row cache lock'" :
PROCESS 19: ... waiting for 'row cache lock' blocking sess=0x0 seq=2174 wait_time=0 cache id=7, mode=0, request=3 * "mode=0" Indicates that the lock is not currently held -------------------------------------------------------------------------------- * "request=3" Indicates that we are requesting the lock in Shared (mode 3) SO: 7000000c6de7678, type: 48, owner: 7000000a6c97cf8, flag: INIT/-/-/0x00 row cache enqueue: count=1 session=7000000a660b8b0 object=7000000eedc13a0, request=S * Request for the lock on object=7000000eedc13a0 savepoint=2148 * "request=S" is for the lock in Shared(S) row cache parent object: address=7000000eedc13a0 cid=7(dc_users) * dc_users is the cache type with Cache ID (cid) 7 hash=2a057ebe typ=9 transaction=7000000c42297a0 flags=00000002 own=7000000eedc1480[7000000c6de8518,7000000c6de8518] wat=7000000eedc1490[7000000c6de7568,7000000c6deed98] mode=X * The holder holds the lock in eXclusive (mode=X) status=VALID/-/-/-/-/-/-/-/- request=N release=TRUE flags=0
This process is waiting for 'row cache lock'. The waiter is waiting for "
object=7000000eedc13a0" and it is requesting a Share mode lock "
request=S".
To find the HOLDER, search for object but use the mode: string to indicate a holder.
SO: 7000000c6de84e8, type: 48, owner: 7000000c42297a0, flag: INIT/-/-/0x00 row cache enqueue: count=1 session=7000000a6702710 object=7000000eedc13a0, mode=X * The matching object=7000000eedc13a0 is held with mode=Xsavepoint=109 row cache parent object: address=7000000eedc13a0 cid=7(dc_users) hash=2a057ebe typ=9 transaction=7000000c42297a0 flags=00000002 own=7000000eedc1480[7000000c6de8518,7000000c6de8518] wat=7000000eedc1490[7000000c6de7568,7000000c6df1b08] mode=X status=VALID/-/-/-/-/-/-/-/- request=N release=TRUE flags=0 instance lock id=QH 00000440 00000000 set=0, complete=FALSE set=1, complete=FALSE set=2, complete=FALSE data=
In this case the "mode:" of the holder is eXclusive (i.e.
object=7000000eedc13a0, mode=X). Search back up to the top of this process to find which process is holding the resource.
Library Cache Pins (10G - Mutexes)
Waits for library cache pins are of the form" waiting for 'cursor: pin S wait on X'"
PROCESS 16: waiting for 'cursor: pin S wait on X' blocking sess=0x0 seq=58849 wait_time=0 seconds since wait started=0 idn=535d1a6c, value=c1600000000, where|sleeps=5003f2428
To find more details use the idn=XXXXXX to search down in the systemstate (ie
idn=535d1a6c)
KGX Atomic Operation Log 7000002e5b9d160 Mutex 7000002b8e92268(3094, 0) idn 535d1a6c oper GET_SHRD * SID 3094 holds the Mutex (3094,0) and Cursor Pin uid 2489 efd 0 whr 5 slp 58733 * Request is for Shared (GET_SHRD) mode opr=2 pso=70000028c47def0 flg=0 pcs=7000002b8e92268 nxt=0 flg=34 cld=3 hd=70000030d6c6eb0 par=7000002eefe64d0 ct=31 hsh=0 unp=0 unn=0 hvl=b825a4d0 nhv=1 ses=700000309b42600 hep=7000002b8e922e8 flg=80 ld=1 ob=7000002de49f8a0 ptr=70000022cf39db8 fex=70000022cf390c8
To find the HOLDER, search for idn XXXXXXX oper until you find one which is held (ie not GET_XXX)( ie
idn 535d1a6c oper):-
KGX Atomic Operation Log 7000002cd934270 Mutex 7000002b8e92268(3094, 0) idn 535d1a6c oper EXCL * SID 3094 holds Mutex in Exclusive (EXCL) Cursor Pin uid 3094 efd 0 whr 7 slp 0 opr=3 pso=7000002a71c4180 flg=0 pcs=7000002b8e92268 nxt=0 flg=34 cld=3 hd=70000030d6c6eb0 par=7000002eefe64d0 ct=31 hsh=0 unp=0 unn=0 hvl=b825a4d0 nhv=1 ses=700000309b42600 hep=7000002b8e922e8 flg=80 ld=1 ob=7000002de49f8a0 ptr=70000022cf39db8 fex=70000022cf390c8
4 - Library Cache Pins (Pre 10G - non mutex)
PROCESS 20: waiting for 'library cache pin' blocking sess=0x0 seq=575 wait_time=0 handle address=c00000006c0f8490, pin address=c0000000689b19a8, 10*mode+namespace=14
To find more details use the handle=XXXXXX to search down in the systemstate (ie
handle=c00000006c0f8490) and you will see a 'request' line
SO: c0000000689b19a8, type: 34, owner: c00000006cf85e80, flag: INIT/-/-/0x00 LIBRARY OBJECT PIN: pin=c0000000689b19a8 handle=c00000006c0f8490 request=S lock=c00000006d00e218 * Request=3 for indicates a request for a Shared (S) mode lock user=c00000005eeafeb0 session=c00000005eeafeb0 count=0 mask=0000 savepoint=17 flags=[00]
To find the HOLDER, search for 'handle=XXXXXXXXX mode' oper until you find one which is held in an incompatible mode( ie
handle=c00000006c0f8490 mode):-
SO: c00000006b1f4780, type: 34, owner: c0000000699758e8, flag: INIT/-/-/0x00 LIBRARY OBJECT PIN: pin=c00000006b1f4780 handle=c00000006c0f8490 mode=X lock=c00000006b6c40a0 * mode=X - lock held in eXclusive (X) user=c00000005edf0f48 session=c00000005edf0f48 count=1 mask=0001 savepoint=49 flags=[00]
Library Cache Lock
PROCESS 35: waiting for 'library cache lock' blocking sess=0x0 seq=35844 wait_time=0 seconds since wait started=14615 handle address=70000030de975a8, lock address=70000026947e190, 100*mode+namespace=12d
To find more details use the handle address in the form handle=address to search down in the systemstate (ie
handle=70000030de975a8)
SO: 70000026947e190, type: 53, owner: 700000308d726f0, flag: INIT/-/-/0x00 LIBRARY OBJECT LOCK: lock=70000026947e190 handle=70000030de975a8 request=X * Exclusive (X) Requested call pin=0 session pin=0 hpc=0000 hlc=0000 htl=70000026947e210[7000002b333ffe8,7000002b333ffe8] htb=7000002b333ffe8 ssga=7000002b333f2a0 user=700000307a7ca68 session=700000307a7ca68 count=0 flags=[0000] savepoint=0x23e411 LIBRARY OBJECT HANDLE: handle=70000030de975a8 mtx=70000030de976d8(0) cdp=0 name=<USER_NAME>.<OBJECT_NAME> * This is the object we are trying to lock
To find the HOLDER, search for 'handle=XXXXXXXXXX mode=' until you find one which is held (but not in NULL)( ie
handle=70000030de975a8 mode=):-
SO: 700000288b03ae0, type: 53, owner: 7000002cc697468, flag: INIT/-/-/0x00 LIBRARY OBJECT LOCK: lock=700000288b03ae0 handle=70000030de975a8 mode=S * Hold in Shared (S) call pin=0 session pin=0 hpc=0000 hlc=0000 htl=700000288b03b60[7000002a179a1a8,7000002b3800878] htb=7000002b3800878 ssga=7000002b37ffb30 user=70000030fafab00 session=70000030fafab00 count=1 flags=[0000] savepoint=0x417 LIBRARY OBJECT HANDLE: handle=70000030de975a8 mtx=70000030de976d8(0) cdp=0 name=<USER_NAME>.<OBJECT_NAME> * This confirms the object
Latch free
PROCESS 8: waiting for 'latch free' blocking sess=0x0 seq=4577 wait_time=0 address=99ff60018, number=9d, tries=0 * 9d is the latch# (in HEX = 157) from v$latchname
Towards the top of the PROCESS dump you will see the exact latch we are waiting for and even who holds it:
waiting for 99ff60018 Child library cache level=5 child#=3 Location from where latch is held: kglic: child Context saved from call: 26 state=busy possible holder pid = 127 ospid=23086 * PROCESS 127 (ospid:23086) holds it wtr=99ff60018, next waiter 9993858b8
So - PROCESS 127 holds it. If we now go to PROCESS 127 we will see :-
holding 99ff60018 Child library cache level=5 child#=3 Location from where latch is held: kglic: child Context saved from call: 26 state=busy
Other useful information
If you wish to find what object a handle refers to then use the handle=XXXXXXXXXX until you come across the LIBRARY OBJECT HANDLE. ie
handle=c00000006c0f8490:-
LIBRARY OBJECT HANDLE: handle=c00000006c0f8490 name=SELECT USER FROM DUAL * This is the name of the handle hash=cd1ceca0 timestamp=03-23-2007 09:00:00 namespace=CRSR flags=RON/TIM/PN0/SML/[12010000] * "CRSR" indicates that it is type CURSOR
來自 “ ITPUB部落格 ” ,連結:http://blog.itpub.net/29487349/viewspace-2892915/,如需轉載,請註明出處,否則將追究法律責任。
相關文章
- Oracle 最新PSU (Doc ID 2118136.2)Oracle
- expdp/impdp變慢 (Doc ID 2469587.1)
- RMAN -- Frequently Asked Question (FAQ) (Doc ID 469777.1)
- Oracle 下載最新psu (Doc ID 2118136.2)Oracle
- PRCT-1011 : Failed to run "oifcfg" (Doc ID 1380183.1)AI
- How to Collect Diagnostics for Database Hanging Issues (Doc ID 452358.1)Database
- [轉帖]Release Schedule of Current Database Releases (Doc ID 742060.1)Database
- 【CURSOR】How to Monitor and tune Open and Cached Cursors (Doc ID 1430255.1)
- Physical Standby Switchover_status Showing Not Allowed. (Doc ID 1392763.1)
- oracle11g: Scheduler Maintenance Tasks or Autotasks (Doc ID 756734.1)OracleAINaN
- 【DBA】Relinking Oracle Home 常見問題 (Doc ID 2048232.1)Oracle
- How To Replicate Data from Oracle to Postgres Using GoldenGate (Doc ID 1544137.1OracleGo
- 11g-Reduce Transportable Tablespace Downtime using XTTS (Doc ID 1389592.1)TTS
- Can GoldenGate Replicate An Oracle Table That Contains Only CLOB Column(s)? (Doc ID 971833.1)GoOracleAI
- 12c DG新特性 - Active Data Guard Far Sync (Doc ID 2179719.1)
- 【GRID】Grid Infrastructure 啟動的五大問題 (Doc ID 1526147.1)ASTStruct
- 【DATAGUARD】Data Guard 12C 新特性:Far Sync Standby (Doc ID 2179719.1)
- Understanding Recursion
- ORA-04063: package body SYS.DBMS_REGISTRY_SYS has errors (Doc ID 2165402.1)PackageError
- Understanding LSTM Networks
- Understanding JSON SchemaJSON
- diff output understanding
- 【ASM】ORA-27504 ORA-27300 ORA-27303 while starting ASM (Doc ID 2281441.1)ASMWhile
- 【RAC】Oracle 12c以及以上版本的diagsnap是什麼? (Doc ID 2469643.1)Oracle
- Understanding System Statistics(zt)
- Understanding Linux CPU statsLinux
- Understanding Buffer Overflow Bugs
- Redis Reading NotesRedis
- sqlserver docSQLServer
- 【譯】Understanding NodeJS Event LoopNodeJSOOP
- Understanding HBase and BigTable 譯文
- Understanding React `setState` 翻譯React
- BraTs literature reading(Lee)
- Python中dumps, loads dump, load用法詳解Python
- 5-Overview-Understanding Kubernetes ObjectsViewObject
- Understanding the linux kernel Chapter 6 Timing MeasurementsLinuxAPTREM
- [Javascript] Understanding JavaScript Proxies with Symbol.toPrimitiveJavaScriptSymbolMIT
- Understanding ODIS Component Protection for VW/Audi Vehicles