8 Replies Latest reply: Apr 17, 2014 8:59 AM by pl_sequel RSS

    ORA-00600: internal error code, arguments: [qmcxeExUseLoc93] with xmltable and large nodes into CLOB column

    pl_sequel

      Hi all,

      Running the following:

       

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

      PL/SQL Release 11.2.0.2.0 - Production

      "CORE    11.2.0.2.0    Production"

      TNS for IBM/AIX RISC System/6000: Version 11.2.0.2.0 - Production

      NLSRTL Version 11.2.0.2.0 - Production

       

      Creating a view from a relational table and XMLTABLE and extracting large text nodes from the xmltable, mapped using COLUMNS. I concatenate the text nodes into one xml element in the xmltable xquery, using simple sequencing:

       

      <CONTENT>
      {$i/summary/text(),$i/know/text(),$i/considerations/text(),$i/look_for/text(),
      $i/nc//text(),$i/level1//text(),$i/level2//text(),$i/ipocc//text()}
      </CONTENT>
      

       

      Running the query in SQLDeveloper and right about when i scroll past the 50th result in the result set or so.... DB connection closes and following error stack appears:

       

      ORA-00600: internal error code, arguments: [qmcxeExUseLoc93], [], [], [], [], [], [], [], [], [], [], []

      00600. 00000 -  "internal error code, arguments: [%s], [%s], [%s], [%s], [%s], [%s], [%s], [%s]"

      *Cause:    This is the generic internal error number for Oracle program

                 exceptions.    This indicates that a process has encountered an

                 exceptional condition.

      *Action:   Report as a bug - the first argument is the internal error number

       

      I went on to Oracle Support site to use the "lookup tool" for these kinds of errors, but it didn't find anything for the argument : "qmcxeExUseLoc93" ... any ideas short of opening a SR with Oracle support? DBA reports no issues with memory or tablespace...trace files report a full stack trace:

       

      ORA-00600: internal error code, arguments: [qmcxeExUseLoc93], [], [], [], [], [], [], [], [], [], [], []

       

      ========= Dump for incident 32114 (ORA 600 [qmcxeExUseLoc93]) ========

       

      *** 2014-04-08 07:31:51.369

      dbkedDefDump(): Starting incident default dumps (flags=0x2, level=3, mask=0x0)

      ----- Current SQL Statement for this session (sql_id=0qn4zxr0stgjh) -----

      SELECT ID, CONTENT FROM ort_active_content_en_vw

       

      ----- Call Stack Trace -----

      calling call entry argument values in hex     

      location type point (? means dubious value)    

      -------------------- -------- -------------------- ----------------------------

      skdstdst()+40 bl 107ca9980 FFFFFFFFFFD99A8 ? 000002004 ?

      000000001 ? 000000003 ?

      000000000 ? 000000002 ?

      000000001 ? 000000000 ?

      ksedst1()+104 call skdstdst() FFFFFFFFFFD89B0 ? 000002004 ?

      110641000 ? 1098C1164 ?

      110641000 ? 000000000 ?

      FFFFFFFFFFD8AE0 ? 700000007 ?

      ksedst()+40 call ksedst1() 3030000000000 ? 002050033 ?

      1098C1158 ? 700000000025C ?

      000000000 ? 000000000 ?

      1098C07B8 ? 000000000 ?

      dbkedDefDump()+2828  call ksedst() FFFFFFFFFFD8B90 ? 000000000 ?

      000000000 ? 000000000 ?

      000000000 ? 000000000 ?

      000000000 ? 300000003 ?

       

       

      …….

       

      --------------------- Binary Stack Dump ---------------------

       

       

      ========== FRAME [1] (skdstdst()+40 -> 107ca9980) ==========

      defined by frame pointers 0xffffffffffd88b0  and 0xffffffffffd8840

      CALL TYPE: bl   ERROR SIGNALED: no COMPONENT: (null)

      Dump of memory from 0xffffffffffd8840 to 0xffffffffffd88b0

      FFFFFFFFFFD8840 0FFFFFFF FFFD88B0 42422220 098C1158 [........BB" ...X]

      FFFFFFFFFFD8850 00000001 08EBE70C 00000001 10641000 [.............d..]

      FFFFFFFFFFD8860 00000000 00000000 0FFFFFFF FFFD89E8 [................]

      FFFFFFFFFFD8870 0FFFFFFF FFFD99A8 00000000 00002004 [.............. .]

      FFFFFFFFFFD8880 00000000 00000001 00000000 00000003 [................]

      FFFFFFFFFFD8890 00000000 00000000 00000000 00000002 [................]

      FFFFFFFFFFD88A0 00000000 00000001 00000000 00000000  [................]

       

      ========== FRAME [2] (ksedst1()+104 -> skdstdst()) ==========

      defined by frame pointers 0xffffffffffd89b0  and 0xffffffffffd88b0

      CALL TYPE: call   ERROR SIGNALED: no COMPONENT: KSE

      Dump of memory from 0xffffffffffd88b0 to 0xffffffffffd89b0

      FFFFFFFFFFD88B0 0FFFFFFF FFFD89B0 42422220 10317A68 [........BB" .1zh]

      FFFFFFFFFFD88C0 00000001 0014963C 00000001 1033F758 [.......<.....3.X]

      FFFFFFFFFFD88D0 09ED0CA9 1873F04F 28422848 73743231 [.....s.O(B(Hst21]

      FFFFFFFFFFD88E0 0FFFFFFF FFFD89B0 00000000 00002004 [.............. .]

      FFFFFFFFFFD88F0 00000001 10641000 00000001 098C1164 [.....d.........d]

      FFFFFFFFFFD8900 00000001 10641000 00000000 00000000 [.....d..........]

       

       

      ……

       

       

      ----- Process State Dump (2)-----

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

      PROCESS STATE

      -------------

      Process global information:

           process: 0x700000012b18de8, call: 0x70000000b880ee0, xact: 0x0, curses: 0x700000012bf11f0, usrses: 0x700000012bf11f0

           in_exception_handler: no

        ----------------------------------------

        SO: 0x700000012b18de8, type: 2, owner: 0x0, flag: INIT/-/-/0x00 if: 0x3 c: 0x3

         proc=0x700000012b18de8, name=process, file=ksu.h LINE:12451 ID:, pg=0

        (process) Oracle pid:42, ser:153, calls cur/top: 0x70000000b880ee0/0x70000000b880ee0

      flags : (0x0) -

      flags2: (0x800),  flags3: (0x0)

                  intr error: 0, call error: 0, sess error: 0, txn error 0

      intr queue: empty

          ksudlp FALSE at location: 0

        (post info) last post received: 0 0 0

      last post received-location: No post

      last process to post me: none

      last post sent: 0 0 26

      last post sent-location: ksa2.h LINE:282 ID:ksasnd

      last process posted by me: 700000012af9058 1 6

          (latch info) wait_event=0 bits=0

          Process Group: DEFAULT, pseudo proc: 0x700000012b4db88

          O/S info: user: tst214, term: UNKNOWN, ospid: 4370650

          OSD pid info: Unix process pid: 4370650, image: oracle@mldb2385

          ----------------------------------------

          SO: 0x700000010430ba0, type: 10, owner: 0x700000012b18de8, flag: INIT/-/-/0x00 if: 0x1 c: 0x1

           proc=0x700000012b18de8, name=FileOpenBlock, file=ksfd.h LINE:6337 ID:, pg=0

          (FOB) flags=2050 fib=700000010de3758 incno=0 pending i/o cnt=0

      fname=/tst214_01/oradata/TST214/temp01.dbf

           fno=201 lblksz=8192 fsiz=36608

          ----------------------------------------

          SO: 0x700000010430578, type: 10, owner: 0x700000012b18de8, flag: INIT/-/-/0x00 if: 0x1 c: 0x1

           proc=0x700000012b18de8, name=FileOpenBlock, file=ksfd.h LINE:6337 ID:, pg=0

          (FOB) flags=2050 fib=700000010de2b40 incno=0 pending i/o cnt=0

      fname=/tst214_03/oradata/TST214/ort_data01.dbf

           fno=6 lblksz=8192 fsiz=25600

          ----------------------------------------

          SO: 0x70000001042ff38, type: 10, owner: 0x700000012b18de8, flag: INIT/-/-/0x00 if: 0x1 c: 0x1

           proc=0x700000012b18de8, name=FileOpenBlock, file=ksfd.h LINE:6337 ID:, pg=0

          (FOB) flags=2050 fib=700000010de1310 incno=0 pending i/o cnt=0

      fname=/tst214_01/oradata/TST214/sysaux01.dbf

           fno=2 lblksz=8192 fsiz=89600

          ----------------------------------------

          SO: 0x70000001042f910, type: 10, owner: 0x700000012b18de8, flag: INIT/-/-/0x00 if: 0x1 c: 0x1

           proc=0x700000012b18de8, name=FileOpenBlock, file=ksfd.h LINE:6337 ID:, pg=0

          (FOB) flags=2050 fib=700000010de0d10 incno=0 pending i/o cnt=0

      fname=/tst214_01/oradata/TST214/system01.dbf

           fno=1 lblksz=8192 fsiz=96000

          ----------------------------------------

          SO: 0x700000012bf11f0, type: 4, owner: 0x700000012b18de8, flag: INIT/-/-/0x00 if: 0x3 c: 0x3

           proc=0x700000012b18de8, name=session, file=ksu.h LINE:12459 ID:, pg=0

          (session) sid: 41 ser: 1263 trans: 0x0, creator: 0x700000012b18de8

      flags: (0x8000045) USR/- flags_idl: (0x1) BSY/-/-/-/-/-

      flags2: (0x40008) -/-

      DID: , short-term DID:

                    txn branch: 0x0

      oct: 3, prv: 0, sql: 0x70000000c59dbd0, psql: 0x70000000b196c38, user: 73/ORT_READ

          ksuxds FALSE at location: 0

          service name: SYS$USERS

          client details:

            O/S info: user: norman.jonker, term: unknown, ospid: 6224

            machine: G4021165 program: SQL Developer

            application name: SQL Developer, hash value=1012150930

          Current Wait Stack:

            Not in wait; last wait ended 2.617517 sec ago

          Wait State:

            fixed_waits=0 flags=0x21 boundary=0x0/-1

          Session Wait History:

              elapsed time of 2.617552 sec since last wait

           0: waited for 'direct path read'

              file number=0x6, first dba=0x2901, block cnt=0x2

              wait_id=2222 seq_num=2223 snap_id=1

              wait times: snap=0.000035 sec, exc=0.000035 sec, total=0.000035 sec

              wait times: max=infinite

              wait counts: calls=0 os=0

              occurred after 0.029003 sec of elapsed time

           1: waited for 'asynch descriptor resize'

              outstanding #aio=0x0, current aio limit=0xc8, new aio limit=0x145

              wait_id=2221 seq_num=2222 snap_id=1

              wait times: snap=0.000003 sec, exc=0.000003 sec, total=0.000003 sec

              wait times: max=307445734561 min 49 sec

              wait counts: calls=0 os=0

              occurred after 0.000710 sec of elapsed time

           2: waited for 'SQL*Net message to client'

              driver id=0x54435000, #bytes=0x1, =0x0

              wait_id=2220 seq_num=2221 snap_id=1

              wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec

              wait times: max=infinite

              wait counts: calls=0 os=0

              occurred after 0.001452 sec of elapsed time

           3: waited for 'SQL*Net message from client'

              driver id=0x54435000, #bytes=0x1, =0x0

              wait_id=2219 seq_num=2220 snap_id=1

              wait times: snap=0.499033 sec, exc=0.499033 sec, total=0.499033 sec

              wait times: max=infinite

              wait counts: calls=0 os=0

              occurred after 0.000034 sec of elapsed time

           4: waited for 'SQL*Net message to client'

              driver id=0x54435000, #bytes=0x1, =0x0

              wait_id=2218 seq_num=2219 snap_id=1

              wait times: snap=0.000003 sec, exc=0.000003 sec, total=0.000003 sec

              wait times: max=infinite

              wait counts: calls=0 os=0

              occurred after 0.000048 sec of elapsed time

           5: waited for 'SQL*Net message from client'

              driver id=0x54435000, #bytes=0x1, =0x0

              wait_id=2217 seq_num=2218 snap_id=1

              wait times: snap=0.061319 sec, exc=0.061319 sec, total=0.061319 sec

              wait times: max=infinite

              wait counts: calls=0 os=0

              occurred after 0.000028 sec of elapsed time

           6: waited for 'SQL*Net message to client'

              driver id=0x54435000, #bytes=0x1, =0x0

              wait_id=2216 seq_num=2217 snap_id=1

              wait times: snap=0.000003 sec, exc=0.000003 sec, total=0.000003 sec

              wait times: max=infinite

              wait counts: calls=0 os=0

              occurred after 0.000048 sec of elapsed time

           7: waited for 'SQL*Net message from client'

              driver id=0x54435000, #bytes=0x1, =0x0

              wait_id=2215 seq_num=2216 snap_id=1

              wait times: snap=0.063435 sec, exc=0.063435 sec, total=0.063435 sec

              wait times: max=infinite

              wait counts: calls=0 os=0

              occurred after 0.000045 sec of elapsed time

           8: waited for 'SQL*Net message to client'

              driver id=0x54435000, #bytes=0x1, =0x0

              wait_id=2214 seq_num=2215 snap_id=1

              wait times: snap=0.000003 sec, exc=0.000003 sec, total=0.000003 sec

              wait times: max=infinite

              wait counts: calls=0 os=0

              occurred after 0.000070 sec of elapsed time

           9: waited for 'SQL*Net message from client'

              driver id=0x54435000, #bytes=0x1, =0x0

              wait_id=2213 seq_num=2214 snap_id=1

              wait times: snap=0.060780 sec, exc=0.060780 sec, total=0.060780 sec

              wait times: max=infinite

              wait counts: calls=0 os=0

              occurred after 0.000030 sec of elapsed time

          Sampled Session History of session 41 serial 1263

      ---------------------------------------------------

          The sampled session history is constructed by sampling

          the target session every 1 second. The sampling process

          captures at each sample if the session is in a non-idle wait,

          an idle wait, or not in a wait. If the session is in a

          non-idle wait then one interval is shown for all the samples

          the session was in the same non-idle wait. If the

          session is in an idle wait or not in a wait for

          consecutive samples then one interval is shown for all

          the consecutive samples. Though we display these consecutive

          samples  in a single interval the session may NOT be continuously

          idle or not in a wait (the sampling process does not know).

       

          The history is displayed in reverse chronological order.

       

          sample interval: 1 sec, max history 120 sec

          ---------------------------------------------------

            [3 samples, 07:31:51 - 07:31:53]

              not in wait at each sample

            [18 samples, 07:31:33 - 07:31:50]

              idle wait at each sample

            [2 samples, 07:31:31 - 07:31:32]

              not in wait at each sample

            [42 samples, 07:30:49 - 07:31:30]

              idle wait at each sample

            [4 samples, 07:30:45 - 07:30:48]

              not in wait at each sample

            [7 samples, 07:30:38 - 07:30:44]

              idle wait at each sample

            [1 sample, 07:30:37]

              waited for 'SQL*Net break/reset to client', seq_num: 1149

                p1: 'driver id'=0x54435000

                p2: 'break?'=0x0

                p3: ''=0x0

      time_waited: 0.013444 sec (sample interval: 0 sec)

            [14 samples, 07:30:23 - 07:30:36]

              idle wait at each sample

            [4 samples, 07:30:19 - 07:30:22]

              not in wait at each sample

            [26 samples, 07:29:53 - 07:30:18]

              idle wait at each sample

      ---------------------------------------------------

          Sampled Session History Summary:

            longest_non_idle_wait: 'SQL*Net break/reset to client'

            [1 sample, 07:30:37 ]

      time_waited: 0.013444 sec (sample interval: 0 sec)

      ---------------------------------------------------

          temporary object counter: 2

      ----------------------------------------

            Virtual Thread:

            kgskvt: 700000011f06658, sess: 700000012bf11f0 sid: 41 ser: 1263

            vc: 0, proc: 700000012b18de8, id: 41

            consumer group cur: OTHER_GROUPS (upd? 0), mapped: DEFAULT_CONSUMER_GROUP, orig:

            vt_state: 0x100, vt_flags: 0xA030, blkrun: 0, numa: 1

            inwait: 0

            location where insched last set: kgskthrrun

            location where insched last cleared: kgskthrrun2

            location where inwait last set: NULL

            location where inwait last cleared: kgskbindfast

            is_assigned: 1, in_sched: 0 (0)

            qcls: 0, qlink: FALSE

            vt_active: 0 (pending: 1)

            vt_pq_active: 0, dop: 0

            used quanta: 0 (cg: 0) usec, num penalty: 0

            cpu start time: 0

            idle time: 0, active time: 0 (cg: 0)

            cpu yields: 0 (cg: 0), waits: 0 (cg: 0), wait time: 0 (cg: 0) usec

            io waits: 0 (cg: 0), wait time: 0 (cg: 0) usec

            ASL queued time outs: 0, time: 0 (cur 0, cg 0)

            PQQ queued time outs: 0, time: 0 (cur 0, cg 0)

            Queue timeout violation: 0

            calls aborted: 0, num est exec limit hit: 0

            undo current: 0k max: 0k

            I/O credits acquired:small=0 large=0

            I/O credits waiting for:small=0 large=0

            KTU Session Commit Cache Dump for IDLs:

            xid: 0x0009.008.00002025 scn: 0x0000.00cd4e90       flg = 0x1

            xid: 0x0006.018.00001f5a scn: 0x0000.00cd4e90       flg = 0x1

            xid: 0x0009.015.00002024 scn: 0x0000.00cd4e90       flg = 0x1

            xid: 0x0005.004.00001f30 scn: 0x0000.00cd4e90       flg = 0x1

            xid: 0x0006.021.000011ed scn: 0x0000.00cd4e90       flg = 0x1

            KTU Session Commit Cache Dump for Non-IDLs:

            ----------------------------------------

            KKS-UOL used : 0 locks(used=19, free=3)

            KGX Atomic Operation Log 70000000b30a840

             Mutex 0(0, 0) idn 0 oper NONE

             Cursor Parent uid 41 efd 4 whr 7 slp 0

             oper=NONE pt1=0 pt2=0 pt3=0

             pt4=0 u41=0 stt=0

            KGX Atomic Operation Log 70000000b30a890

             Mutex 0(0, 0) idn 0 oper NONE

             hash table uid 41 efd 4 whr 6 slp 0

             oper=NONE pt1=70000000d1485e8 pt2=70000000d148678 pt3=0

             pt4=0 u41=0 stt=0

            KGX Atomic Operation Log 70000000b30a8e0

             Mutex 0(0, 0) idn 0 oper NONE

             FSO mutex uid 41 efd 0 whr 0 slp 0

            KGX Atomic Operation Log 70000000b30a930

             Mutex 0(0, 0) idn 0 oper NONE

             FSO mutex uid 41 efd 0 whr 0 slp 0

      ----------------------------------------

            KGL-UOL SO Cache(total=182, free=107)

            KGX Atomic Operation Log 70000000b045538

             Mutex 0(0, 0) idn 0 oper NONE

             Library Cache uid 41 efd 5 whr 85 slp 0

             oper=0 pt1=70000000b227350 pt2=70000000c5df180 pt3=0

             pt4=0 pt5=0 ub4=0

            KGX Atomic Operation Log 70000000b045590

             Mutex 70000000c5b11d8(0, 0) idn 69fd5d60 oper NONE

             Library Cache uid 41 efd 4 whr 77 slp 0

             oper=0 pt1=70000000934fb60 pt2=0 pt3=0

             pt4=0 pt5=0 ub4=0

            KGX Atomic Operation Log 70000000b0455e8

             Mutex 0(0, 0) idn 0 oper NONE

             Library Cache uid 41 efd 4 whr 79 slp 0

             oper=0 pt1=70000000b225950 pt2=70000000c382cb8 pt3=0

             pt4=0 pt5=0 ub4=0

            KGX Atomic Operation Log 70000000b045640

             Mutex 70000000b225a80(0, 0) idn ae8e84f6 oper NONE

             Library Cache uid 41 efd 4 whr 70 slp 0

             oper=0 pt1=70000000b225950 pt2=0 pt3=0

             pt4=0 pt5=0 ub4=4

            KGX Atomic Operation Log 70000000b045698

             Mutex 70000000b225a80(0, 0) idn ae8e84f6 oper NONE

             Library Cache uid 41 efd 4 whr 70 slp 0

             oper=0 pt1=70000000b225950 pt2=0 pt3=0

             pt4=0 pt5=0 ub4=0

            KGX Atomic Operation Log 70000000b0456f0

             Mutex 0(0, 0) idn 0 oper NONE

             Library Cache uid 41 efd 0 whr 0 slp 0

             oper=0 pt1=0 pt2=0 pt3=0

             pt4=0 pt5=0 ub4=0

            KGX Atomic Operation Log 70000000b045748

             Mutex 0(0, 0) idn 0 oper NONE

             Library Cache uid 41 efd 0 whr 0 slp 0

             oper=0 pt1=0 pt2=0 pt3=0

             pt4=0 pt5=0 ub4=0

            KGL SO cache

           SO=70000000c5df180 Link=70000000c5df1f0[70000000b115e38,70000000b045520]

            flg=8000 use=700000012bf11f0 ses=700000012bf11f0

            SO=70000000b115dc8 Link=70000000b115e38[70000000c5df380,70000000c5df1f0]

            flg=8000 use=700000012bf11f0 ses=700000012bf11f0

            SO=70000000c5df310 Link=70000000c5df380[70000000c7abab8,70000000b115e38]

            flg=8000 use=700000012bf11f0 ses=700000012bf11f0