ORA-03137: TTC protocol internal error: [12333]分析及處理

guocun09發表於2018-04-17

DB: Oracle 11.2.0.1 

OS: Redhat Linux 5.3 64bit


異常:

陽春三月的週末,正準備出門踏青,突然接到使用者的電話說一個程式使用中報錯ORA-03113,但其它功能程式可正常使用。


檢查DB日誌 alert.log:

Sat Apr 14 10:43:04 2018

Errors in file /u01/product/diag/rdbms/orcl/orcl/trace/orcl_ora_2189.trc  (incident=386284):

ORA-03137: TTC 協議內部錯誤: [12333] [12] [48] [48] [] [] [] []

Sat Apr 14 10:43:05 2018

Sweep [inc][386284]: completed

Sat Apr 14 10:43:09 2018

Errors in file /u01/product/diag/rdbms/orcl/orcl/trace/orcl_ora_2244.trc  (incident=386371):

ORA-03137: TTC 協議內部錯誤: [12333] [12] [48] [48] [] [] [] []

Sat Apr 14 10:44:05 2018

Sweep [inc][386371]: completed


使用oerr檢視報錯說明,哇,錯誤解決要聯絡Oracle Support Services。。。

$oerr ora 3137

03137, 00000, "TTC protocol internal error : [%s] [%s] [%s] [%s] [%s] [%s] [%s] [%s]"

// *Cause:  TTC protocol internal error.

// *Action: Contact Oracle Support Services.


還是先自己看看吧,進一步檢視報錯生產的trc檔案:

Trace file /u01/product/diag/rdbms/orcl/orcl/trace/orcl_ora_2189.trc

Oracle Database 11g Enterprise Edition Release 11.2.0.1.0 - 64bit Production

With the Partitioning, Oracle Label Security, OLAP, Data Mining,

Oracle Database Vault and Real Application Testing options

ORACLE_HOME = /u01/product/oracle

System name:  Linux

Node name:     SFCDB

Release:  2.6.18-128.el5

Version:   #1 SMP Wed Dec 17 11:41:38 EST 2008

Machine: x86_64

Instance name: orcl

Redo thread mounted by this instance: 1

Oracle process number: 194

Unix process pid: 2189, image: oracle@SFCDB


*** 2018-04-14 10:43:04.436

*** SESSION ID:(197.17986) 2018-04-14 10:43:04.436

*** CLIENT ID:() 2018-04-14 10:43:04.436

*** SERVICE NAME:(orcl) 2018-04-14 10:43:04.436

*** MODULE NAME:(DFMS.exe) 2018-04-14 10:43:04.436

*** ACTION NAME:() 2018-04-14 10:43:04.436


--- PROTOCOL VIOLATION DETECTED ---

----- Dump Cursor sql_id=9pmx42bttx2st xsc=0x2b48f67503b8 cur=0x2b48f6381c50 -----


LibraryHandle:  Address=2bd38090 Hash=f39e8b19 LockMode=N PinMode=0 LoadLockMode=0 Status=VALD

  ObjectName:  Name= Select serial_number from R_SN_LASER_CARVING_PRINT_T  WHERE MO_NUMBER=:MO AND STATE_FLAG='0' ORDER BY SERIAL_NUMBER ASC


    FullHashValue=12484201093a22129acfa412f39e8b19 Namespace=SQL AREA(00) Type=CURSOR(00) Identifier=4087253785 OwnerIdn=91

  Statistics:  InvalidationCount=17 ExecutionCount=42034 LoadCount=57 ActiveLocks=1 TotalLockCount=831 TotalPinCount=1

  Counters:  BrokenCount=1 RevocablePointer=1 KeepDependency=4 KeepHandle=4 BucketInUse=824 HandleInUse=824

  Concurrency:  DependencyMutex=2bd38140(0, 0, 0, 0) Mutex=2bd381b8(197, 6821, 0, 6)

  Flags=RON/PIN/TIM/PN0/DBN/[10012841]

  WaitersLists: 

    Lock=2bd38120[2bd38120,2bd38120]

    Pin=2bd38130[2bd38100,2bd38100]

  Timestamp:  Current=03-30-2018 15:42:57

  LibraryObject:  Address=282e40b0 HeapMask=0000-0001-0001 Flags=EXS[0000] Flags2=[0000] PublicFlags=[0000]

    ChildTable:  size='16'

      Child:  id='0' Table=282e4f60 Reference=282e4a00 Handle=2bd37e90

      Child:  id='1' Table=282e4f60 Reference=282e4d28 Handle=8755c338

      Child:  id='2' Table=282e4f60 Reference=28f1ef18 Handle=628e66f0

      Child:  id='3' Table=282e4f60 Reference=382cf368 Handle=87e39fa0

    Children: 

      Child:  childNum='0'

        LibraryHandle:  Address=2bd37e90 Hash=0 LockMode=0 PinMode=0 LoadLockMode=0 Status=VALD

          Name:  Namespace=SQL AREA(00) Type=CURSOR(00)

          Statistics:  InvalidationCount=0 ExecutionCount=100 LoadCount=19 ActiveLocks=0 TotalLockCount=388 TotalPinCount=15647

          Counters:  BrokenCount=1 RevocablePointer=1 KeepDependency=0 KeepHandle=0 BucketInUse=0 HandleInUse=0

          Concurrency:  DependencyMutex=2bd37f40(0, 0, 0, 0) Mutex=2bd381b8(197, 6821, 0, 6)

          Flags=RON/PIN/PN0/EXP/[10012111]

          WaitersLists: 

            Lock=2bd37f20[2bd37f20,2bd37f20]

            Pin=2bd37f30[2bd37f00,2bd37f00]

          LibraryObject:  Address=386860b0 HeapMask=0000-0001-0001 Flags=EXS[0000] Flags2=[0000] PublicFlags=[0000]

          NamespaceDump: 

            Child Cursor:  Heap0=0x138686198 Heap6=0x12bd37e90 Heap0 Load Time=04-14-2018 08:57:09 Heap6 Load Time=04-14-2018 08:57:09

      Child:  childNum='1'

        LibraryHandle:  Address=8755c338 Hash=0 LockMode=0 PinMode=0 LoadLockMode=0 Status=VALD

          Name:  Namespace=SQL AREA(00) Type=CURSOR(00)

          Statistics:  InvalidationCount=0 ExecutionCount=72 LoadCount=15 ActiveLocks=0 TotalLockCount=495 TotalPinCount=22196

          Counters:  BrokenCount=1 RevocablePointer=1 KeepDependency=0 KeepHandle=0 BucketInUse=0 HandleInUse=0

          Concurrency:  DependencyMutex=8755c3e8(0, 0, 0, 0) Mutex=2bd381b8(197, 6821, 0, 6)

          Flags=RON/PIN/PN0/EXP/[10012111]

          WaitersLists: 

            Lock=8755c3c8[8755c3c8,8755c3c8]

            Pin=8755c3d8[8755c3a8,8755c3a8]

          LibraryObject:  Address=564f97c0 HeapMask=0000-0001-0001 Flags=EXS[0000] Flags2=[0000] PublicFlags=[0000]

          NamespaceDump: 

            Child Cursor:  Heap0=0x1564f98a8 Heap6=0x1227353c0 Heap0 Load Time=04-14-2018 09:46:29 Heap6 Load Time=04-14-2018 10:02:56

      Child:  childNum='2'

        LibraryHandle:  Address=628e66f0 Hash=0 LockMode=0 PinMode=0 LoadLockMode=0 Status=VALD

          Name:  Namespace=SQL AREA(00) Type=CURSOR(00)

          Statistics:  InvalidationCount=0 ExecutionCount=1810 LoadCount=3 ActiveLocks=0 TotalLockCount=123 TotalPinCount=3063

          Counters:  BrokenCount=1 RevocablePointer=1 KeepDependency=0 KeepHandle=0 BucketInUse=0 HandleInUse=0

          Concurrency:  DependencyMutex=628e67a0(0, 0, 0, 0) Mutex=2bd381b8(197, 6821, 0, 6)

          Flags=RON/PIN/PN0/EXP/[10012111]

          WaitersLists: 

            Lock=628e6780[628e6780,628e6780]

            Pin=628e6790[628e6760,628e6760]

          LibraryObject:  Address=56bc87c0 HeapMask=0000-0001-0001 Flags=EXS/RIV[0200] Flags2=[0000] PublicFlags=[0000]

          NamespaceDump: 

            Child Cursor:  Heap0=0x156bc88a8 Heap6=0x1273bb830 Heap0 Load Time=04-13-2018 16:50:25 Heap6 Load Time=04-13-2018 16:50:25

      Child:  childNum='3'

        LibraryHandle:  Address=87e39fa0 Hash=0 LockMode=N PinMode=S LoadLockMode=0 Status=VALD

          Name:  Namespace=SQL AREA(00) Type=CURSOR(00)

          Statistics:  InvalidationCount=17 ExecutionCount=1 LoadCount=19 ActiveLocks=1 TotalLockCount=44 TotalPinCount=1379

          Counters:  BrokenCount=18 RevocablePointer=18 KeepDependency=0 KeepHandle=0 BucketInUse=0 HandleInUse=0

          Concurrency:  DependencyMutex=87e3a050(0, 0, 0, 0) Mutex=2bd381b8(197, 6821, 0, 6)

          Flags=RON/PIN/PN0/EXP/[10012111]

          WaitersLists: 

            Lock=87e3a030[87e3a030,87e3a030]

            Pin=87e3a040[87e3a010,87e3a010]

          LibraryObject:  Address=28c070b0 HeapMask=0000-0001-0001 Flags=EXS[0000] Flags2=[0000] PublicFlags=[0000]

            DataBlocks: 

              Block:  #='0' name=CCUR^f39e8b19 pins=0 Change=NONE  

                Heap=62727f48 Pointer=28c07198 Extent=28c07030 Flags=I/-/P/A/-/-

                FreedLocation=0 Alloc=6.078125 Size=7.898438 LoadTime=5587806980

              Block:  #='6' name=SQLA^f39e8b19 pins=0 Change=NONE  

                Heap=382cf208 Pointer=3d58d7e0 Extent=3d58cb80 Flags=I/-/P/A/-/E

                FreedLocation=0 Alloc=11.820312 Size=15.820312 LoadTime=0

          NamespaceDump: 

            Child Cursor:  Heap0=0x128c07198 Heap6=0x13d58d7e0 Heap0 Load Time=04-14-2018 10:43:04 Heap6 Load Time=04-14-2018 10:43:04

  NamespaceDump: 

    Parent Cursor:  sql_id=9pmx42bttx2st parent=0x1282e4198 maxchild=4 plk=y ppn=n

      Current Cursor Sharing Diagnostics Nodes: 

        Child Node: 2  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 1  ID=40 reason=Bind mismatch(33) size=2x4

          init ranges in first pass: 1

          selectivity: 0

      Aged Out Cursor Sharing Diagnostic Nodes: 

        Child Node: 3  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 0  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:  

        Child Node: 1  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 0  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:  

        Child Node: 2  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:  

        Child Node: 0  ID=34 reason=Rolling Invalidate Window Exceeded(3) size=2x4

          invalidation window(kglobitm): 1523507948

          ksugctm(): 1523508173

        Child Node: 1  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 1  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:  

        Child Node: 0  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 0  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:  

        Child Node: 1  ID=34 reason=Rolling Invalidate Window Exceeded(3) size=2x4

          invalidation window(kglobitm): 1523245310

          ksugctm(): 1523246431

        Child Node: 0  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:  

        Child Node: 1  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 0  ID=34 reason=Rolling Invalidate Window Exceeded(3) size=2x4

          invalidation window(kglobitm): 1523163964

          ksugctm(): 1523163994

        Child Node: 0  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 2  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:  

        Child Node: 1  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:  

        Child Node: 0  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 0  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 1  ID=34 reason=Rolling Invalidate Window Exceeded(3) size=2x4

          invalidation window(kglobitm): 1523055454

          ksugctm(): 1523061100

        Child Node: 0  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:  

        Child Node: 1  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 0  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 1  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 0  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:  

        Child Node: 1  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 0  ID=34 reason=Rolling Invalidate Window Exceeded(3) size=2x4

          invalidation window(kglobitm): 1522536383

          ksugctm(): 1522539662

        Child Node: 1  ID=34 reason=Rolling Invalidate Window Exceeded(3) size=2x4

          invalidation window(kglobitm): 1522500785

          ksugctm(): 1522501236

        Child Node: 0  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:  

        Child Node: 1  ID=34 reason=Rolling Invalidate Window Exceeded(2) size=0x0

          already processed:  

        Child Node: 0  ID=40 reason=Bind mismatch(25) size=0x0

          extended cursor sharing:      kkscs=0x1282e46a0 nxt=0x1282e4a98 flg=11 cld=0 hd=0x12bd37e90 par=0x1282e4198

   Mutex 0x1282e46a0(0, 0) idn 3000000000

   ct=39 hsh=0 unp=(nil) unn=0 hvl=28f1efd8 nhv=0 ses=(nil)

   hep=0x1282e4730 flg=80 ld=0 ob=(nil) ptr=(nil) fex=(nil)

   kkscs=0x1282e4a98 nxt=0x128f1ec88 flg=14 cld=1 hd=0x18755c338 par=0x1282e4198

   Mutex 0x1282e4a98(0, 0) idn 0

   ct=27 hsh=0 unp=(nil) unn=0 hvl=282e4f40 nhv=1 ses=0x185997c88

   hsv[0]=0

   hep=0x1282e4b28 flg=80 ld=1 ob=0x1564f97c0 ptr=0x1227353c0 fex=0x122734760

   kkscs=0x128f1ec88 nxt=0x1382cf0d8 flg=18 cld=2 hd=0x1628e66f0 par=0x1282e4198

   Mutex 0x128f1ec88(0, 0) idn 100000000

   ct=24 hsh=0 unp=(nil) unn=0 hvl=282e4ff8 nhv=0 ses=(nil)

   hep=0x128f1ed18 flg=80 ld=1 ob=0x156bc87c0 ptr=0x1273bb830 fex=0x1273babd0

   kkscs=0x1382cf0d8 nxt=(nil) flg=18 cld=3 hd=0x187e39fa0 par=0x1282e4198

   Mutex 0x1382cf0d8(0, 0) idn 100000000

   ct=39 hsh=0 unp=(nil) unn=0 hvl=28f1eff8 nhv=0 ses=(nil)

   hep=0x1382cf168 flg=80 ld=1 ob=0x128c070b0 ptr=0x13d58d7e0 fex=0x13d58cb80

cursor instantiation=0x2b48f67503b8 used=1523673783 exec_id=16819249 exec=1

 child#3(0x187e39fa0) pcs=0x1382cf0d8

  clk=0x18ac132b8 ci=0x128c07198 pn=0x18a2c3760 ctx=0x13d58d7e0

 kgsccflg=0 llk[0x2b48f67503c0,0x2b48f67503c0] idx=0

 xscflg=c0110676 fl2=5d000008 fl3=42222008 fl4=180

 sharing failure(s)=800000040000

----- Bind Info (kkscoacd) -----

 Bind#0

  oacdty=01 mxl=128(72) mxlc=00 mal=00 scl=00 pre=00

  oacflg=03 fl2=1000010 frm=01 csi=873 siz=128 off=0

  kxsbbbfp=2b48f6768b68  bln=128  avl=00  flg=05

 Frames pfr 0x2b48f6768f90 siz=5448 efr 0x2b48f6768ed0 siz=5432

 kxscphp=0x2b48f6390290 siz=984 inu=376 nps=328

 kxscbhp=0x2b48f67397e8 siz=984 inu=272 nps=152

 kxscwhp=0x2b48f67398d8 siz=4056 inu=1000 nps=608

Starting SQL statement dump

SQL Information

user_id=91 user_name=MPROGRAM module=DFMS.exe action=

sql_id=9pmx42bttx2st plan_hash_value=-307866389 problem_type=4

----- Current SQL Statement for this session (sql_id=9pmx42bttx2st) -----

 Select serial_number from R_SN_LASER_CARVING_PRINT_T  WHERE MO_NUMBER=:MO AND STATE_FLAG='0' ORDER BY SERIAL_NUMBER ASC

sql_text_length=121

sql= Select serial_number from R_SN_LASER_CARVING_PRINT_T  WHERE MO_NUMBER=:MO AND STATE_FLAG='0' ORDER BY SERIAL_NUMBER ASC

Compilation Environment Dump

............

====================== END SQL Statement Dump ======================

ttcdrvdmplocation: msg-12 ln-1004 reporting 12333

Dumping 'Buffer dump info:' addr=0x146a3b60 size=267 bytes

Dump of memory from 0x146a3b60 to 0x146a3c6c

0146A3B60 01636911 00000001 00000006 69645E03  [.ic..........^di]

0146A3B70 00000080 01000000 00000168 00000C01  [........h.......]

0146A3B80 00010000 00000000 00000000 01000000  [................]

0146A3B90 00000001 00000000 00000101 40FE0000  [...............@]

0146A3BA0 6C655320 20746365 69726573 6E5F6C61  [ Select serial_n]

0146A3BB0 65626D75 72662072 52206D6F 5F4E535F  [umber from R_SN_]

0146A3BC0 4553414C 41435F52 4E495652 52505F47  [LASER_CARVING_PR]

0146A3BD0 5F544E49 57202054 45524548 5F4F4D20  [INT_T  WHERE MO_]

0146A3BE0 4D554E38 3D524542 204F4D3A 20444E41  [8NUMBER=:MO AND ]

0146A3BF0 54415453 4C465F45 273D4741 4F202730  [STATE_FLAG='0' O]

0146A3C00 52454452 20594220 49524553 4E5F4C41  [RDER BY SERIAL_N]

0146A3C10 45424D55 53412052 00010043 00000000  [UMBER ASC.......]

0146A3C20 00000000 00000000 00000000 00000000  [................]

0146A3C30 00000000 00010000 00000000 00000000  [................]

0146A3C40 00000000 00000000 03010000 00480000  [..............H.]

0146A3C50 00000000 00100000 00000000 00000000  [................]

0146A3C60 00010369 07000000 3030300C           [i........000]   

Dumping 'Buffer dump info:' addr=0x146a3c6b size=10 bytes

Dump of memory from 0x146a3c68 to 0x146a3c78

0146A3C60                   3030300C 30393436          [.0006490]

0146A3C70 34303431 49565231                    [14041RVI]       

Dumping 'Buffer dump info:' addr=0x146a4380 size=198 bytes

Dump of memory from 0x146a4380 to 0x146a4448

0146A4380 42011710 22121248 A412093A 8B199ACF  [...BH..":.......]

0146A4390 7678F39E 2C0B0E04 00001E05 00000100  [..xv...,........]

0146A43A0 80015100 001E0000 00000000 00000000  [.Q..............]

0146A43B0 00000000 00000000 1E010369 01000000  [........i.......]

0146A43C0 00000D0D 45530D00 4C414952 4D554E5F  [......SERIAL_NUM]

0146A43D0 00524542 00000000 07000000 07000000  [BER.............]

0146A43E0 0E047678 08052C0B 12B00006 0AE7368C  [xv...,.......6..]

0146A43F0 00050000 00000000 00000000 00000000  [................]

0146A4400 00000000 01040000 00000000 00000000  [................]

0146A4410 00000000 1B000500 00000300 00000008  [................]

0146A4420 00000000 00000000 00000000 00000000  [................]

0146A4430 00006400 00000001 00000000 00000000  [.d..............]

0146A4440 00000000 2D410000                    [......A-]       

hstflg:  0x40202d91

hstcflg: 0x00000000

hstpro:  6

hstccs:  (DESCRIPTION=(ADDRESS=(PROTOCOL=TCP)(HOST=192.168.0.100)(PORT=1526))(CONNECT_DATA=(SERVER=DEDICATED)(SERVICE_NAME=orcl)(CID=(PROGRAM=D:\lasercarving_NEW\DFMS.exe)(HOST=HG-PC)(USER=hg))))

--- dump of hsttti ---

00A9C7A90 00 04 01 0C 0E 09 0B 0F 04 0A 03 03 01 00 00 0A  [................]

00A9C7AA0 00 00 00 00 00 00 00 01 01 01 01 01 01 01 01 01  [................]

00A9C7AB0 06 06 00 00 00 00 00 07 03 03 00 00 00 00 00 00  [................]

00A9C7AC0 00 00 00 00 00 00 00 00 00 00 13 00 00 00 00 00  [................]

00A9C7AD0 00 00 00 00 0C 00 00 00 00 00 14 00 00 00 00 00  [................]

00A9C7AE0 00 00 00 00 00 00 00 00 00 00 00 0D 00 00 11 11  [................]

00A9C7AF0 04 09 00 00 00 00 05 00 22 00 12 00 13 13 15 15  [........".......]

00A9C7B00 17 17 17 17 21 03 00 00 03 13 13 13 00 00 00 00  [....!...........]

00A9C7B10 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  [................]

00A9C7B20 00 00 04 2E 00 00 00 00 0F 0F 0F 1F 20 00 00 00  [............ ...]

00A9C7B30 00 00 00 00 00 00 00 00 00 00 00 00 0F 00 00 00  [................]

00A9C7B40 00 00 01 01 01 01 01 01 2A 2A 2A 2A 2A 2B 2B 00  [........*****++.]

00A9C7B50 00 00 00 23 23 23 00 00 00 00 00 00 00 00 00 00  [...###..........]

00A9C7B60 22 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  ["...............]

00A9C7B70 00 00 00 00 00 00 00 35 2A 00 00 00 00 00 00 00  [.......5*.......]

00A9C7B80 00 13 00 00 00 00 00 00 00 00 00 00 00 00 00 36  [...............6]

00A9C7B90 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  [................]

00A9C7BA0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  [................]

00A9C7BB0 00 00 03 01 03 01 01 00 00 00 03 03 03 01 03 03  [................]

00A9C7BC0 03 03 02 01 03 03 03 03 03 03 00 03 03 03 03 01  [................]

00A9C7BD0 03 03 03 03 00 00 00 03 03 03 00 03 00 03 03 03  [................]

00A9C7BE0 03 03 01 03 03 03 03 01 00 03 01 00 03 01 00 00  [................]

00A9C7BF0 00 00 03 03 00 00 00 03 00 00 00 03 00 00 00 00  [................]

00A9C7C00 00 00 00 00 00 00 00 00 00 00 00 00 03 03 03 03  [................]

00A9C7C10 03 03 03 03 03 03 03 01 00 03 03 01 01 03 03 03  [................]

00A9C7C20 03 03 00 00 03 03 03 03 00 00 00 00 00 03 03 03  [................]

00A9C7C30 03 03 03 03 03 03 03 03 03 03 03 03 00 03 03 03  [................]

00A9C7C40 03 03 00 00 00 00 00 00 00 00 00 00 00 00 00 00  [................]

00A9C7C50 00 03 03 00 00 00 03 03 03 03 03 03 03 03 03 03  [................]

00A9C7C60 00 00 03 03 03 03 03 03 03 03 03 03 03 03 03 03  [................]

00A9C7C70 03 03 03 03 03 03 03 00 00 00 03 03 03 03 03 03  [................]

00A9C7C80 00 00 03 00 03 03 00 00 00 00 00 00 00 00 00 00  [................]

00A9C7C90 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  [................]

00A9C7CA0 00 00 00 00 1A 00 00 00 00 00 00 00 00 00 00 00  [................]

00A9C7CB0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  [................]

00A9C7CC0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00  [................]

00A9C7CD0 00 00 00 00 00 00 00 00 00 00                    [..........]     

ttclxlccst:     873

ttclxrccst:     873

ttclxlncst:     2000

ttclxrncst:     2000

ttclxccl2nr:    1

ttclxccn2lr:    1

ttclxncl2nr:    1

ttclxncn2lr:    1

ttclxrccminbpc: 1

ttclxrccmaxbpc: 4

ttclxrncminbpc: 2

ttclxrncmaxbpc: 2

ttclxflags:     0x23

prev funcode: 0x5e


*** 2018-04-14 10:43:04.494

DDE: Problem Key 'ORA 3137 [12333]' was flood controlled (0x2) (incident: 386284)

ORA-03137: TTC : [12333] [12] [48] [48] [] [] [] []


初步分析
trc 檔案中紅色部分,基本說明繫結變數的SQL:因為Rolling Invalidate Window Exceeded(2) & Bind mismatch(33)最終導致 sharing failure(s)=800000040000
常見Rolling Invalidate Window Exceeded原因: 
常見Bind mismatch原因:

臨時處理:

初步認為此塊問題是SQL在 Library cache中生成cursor失敗相關,嘗試重新整理share pool:alter system flush shared_pool  (也可以使用dbms_sahred_pool.purge清理cursor)
重新整理share pool後程式可以正常使用

長期處理:

檢視官方 OERR: ORA-3137 "TTC protocol internal error : [n] []" Master Note (文件 ID 1388487.1)
引起ORA-3137報錯的原因BUG很多種,根本解決還需要升級DB版本(部分問題只需要升級DB JDBC版本)

ID 1388487.1內容:
Support Notes for ORA-3137

Client / server message exchanges use an Oracle specific protocol (TTC). ORA-3137 is reported if something unexpected is seen that does not conform to the expected protocol. The arguments in the error indicate what sort of issue was seen. Specifically "arg1" may be a number or a string indicating the problem seen, and arg2 onwards may give additional information about the error. The error can be raised by the client or server side of a connection.

ORA-3137 was introduced in 11g and replaces some earlier ORA-600 errors. 
eg: A problem in 11g which reports ORA-3137 may have reported an ORA-600 with a similar first argument in 10g and earlier releases. 
eg: An issue causing ORA-3137 [12333] in 11g may have reported as ORA-600 [12333] in 10g.

Some common example arguments are:
  • [12333]
    This is one of the most common forms of the error. It indicates that we are about to read an new function request from the message but the function code seen is not a valid code. This can typically happen if the current message position has gotten out of sync for some reason.
  • [1010]
    Similar to 12333 this typically implies that the message asked to execute some invalid operation.
  • [12209]
    The message asked to map a cursor but the cursor number requested to be mapped is not valid.

Troubleshooting

As the error is typically related to some problem in the message exchange then for investigation purposes it is usually helpful to know:
  • Client version and product (eg: OCI, JDBC Thin, Pro, JDBC OCI etc..)
  • Server version
  • Details of patches applied at either end
  • Client and server traces and incident traces produced
  • Details of the logic / SQL of the code executing in the session around the failure time
  • If a problem is reproducible then it can be helpful to have NET trace from the client and server side for the problem dialogue as often an error depends on some sequence of events earlier in the message exchange. See Note:219968.1 for details of NET trace options.

When reporting ORA-3137 to Oracle collect evidence as described in the following document:

Note:1668523.1 - Checklist of Evidence to Supply for ORA-3137 Issues

Search Links for ORA-03137

The links below can be used to locate ORA-3137 in the documentation, and to search for documents that give more information about the error.

Database Bugs Related to ORA-03137

This section lists bugs that have been linked to error "ORA-3137" . Check any bug description carefully as this error can need a client side fix, a server side fix, or both depending on the issue.


You can restrict the list below to issues likely to affect one of the following versions by clicking the relevant button: 
              

The list below shows bugs affecting any version.

There are 19 bugs listed.
NB Prob Bug Fixed Description
III 20903906 12.1.0.2.DBBP11, 12.2.0.1 ORA-3137 "TTC protocol internal error" [kpoal8-3] from 8i client to 12c server
IIII 18841764 12.2.0.1 Network related error like ORA-12592 or ORA-3137 or ORA-3106 may be signaled
IIII 18263924 12.2.0.1 ORA-3137 (varying arguments) / ORA-1460 (usually with ORA-1002) on the Database When Using Multii-Threaded OCI Application
II 16444583 12.1.0.2, 12.2.0.1 ORA-3137 [1010] from FGA on HS
III 16184271 12.1.0.2, 12.2.0.1 ORA-3113 / ORA-3137 when using "with function" and bind variables
IIII 14489591 11.2.0.3.11, 11.2.0.3.BP24, 11.2.0.4, 12.1.0.1 ORA-3137 [3149] on server due to bad bind attempt in client
III 14473913 11.2.0.2.8, 11.2.0.2.BP18, 11.2.0.3.BP11, 11.2.0.4, 12.1.0.1 ORA-3137 [12333] / ORA-600 [knclprstr:str] with fix 12337012 present at Streams target when using TIMESTAMP data
II 11059133 11.2.0.3, 12.1.0.1 ORA-3137:[12333] or ORA-3106 when long binds are used in updates that affect no rows
III 10075392 11.2.0.3, 12.1.0.1 ORA-3137 [12333] or bind variable interpreted as NULL using SQL with binds
IIII 9703463 11.1.0.7.8, 11.2.0.1.BP12, 11.2.0.2, 12.1.0.1 ORA-3137 [12333] or ORA-600 [kpobav-1] When Using Bind Peeking - superceded
II 9571659 11.2.0.3, 12.1.0.1 ORA-3137 [12333] using DRCP
III 9445675 11.2.0.2, 12.1.0.1 "No more data" / ORA-3137 using end to end metrics with JDBC Thin
IIII 9373370 11.2.0.2.8, 11.2.0.2.BP18, 11.2.0.3, 12.1.0.1 The wrong cursor may be executed by JDBC thin following a query timeout / ORA-3137 [12333]
IIII 9243912 11.2.0.2, 12.1.0.1 Additional diagnostics for ORA-3137 [12333] / OERI:12333
III 8643311 11.2.0.2, 12.1.0.1 ORA-3137 / ORA-600 [12333] from INSERT .. RETURNING / Procedure with OUT parameter
IIII 8625762 11.1.0.7.3, 11.2.0.1 ORA-3137 [12333] due to bind data not read from wire
II 6900681 11.2.0.1 Unexpected errors (ORA-3137 [12209]) after incorrect OCIStmtRelease following OCISessionRelease with DRCP
II 6737706 10.2.0.5, 11.2.0.1 OERI [12333] / ORA-3137 possible from multi table insert SQL with long binds
II 6667800 10.2.0.5, 11.1.0.7 OERI:12333 / client hang using OCILob() in multithreaded OCI client
  • '*' indicates that an alert exists for that issue.
  • '+' indicates a particularly notable issue / bug.
  • See Note:1944526.1 for details of other symbols used


注:本文以實際案例整理而成,因為筆者水平有限,文中如有不準確之處請包涵,如轉載請註明原文出處

來自 “ ITPUB部落格 ” ,連結:http://blog.itpub.net/25583515/viewspace-2153010/,如需轉載,請註明出處,否則將追究法律責任。

相關文章