3 Replies Latest reply: Apr 5, 2013 5:50 PM by Srini Chavali-Oracle RSS

    dbua error ora-0604: error occured at recursive SQL level 1

    ChinmayDBA
      Hi,

      I am getting ora-0604: error occured at recursive SQL level 1 whine running DBUA

      OS: Solaris
      DB upgrade from : 11.2.0.1 to 11.2.0.3


      from oralce_server log file of cfgtoollogs: following seems running...
      ================================================

      REM Uncomment the following script invocation if JIT is not enabled for your
      REM platform
      --@@initimnc.sql

      --
      -- Perform in place evolve for the ordcmpf.xsd schema.
      -- Update the schema annotation field.
      -- Since the change to the schema is just replacing the text node of the
      -- annotation field, this can be done multiple times in the case of
      -- upgrade/downgrade/upgrade.
      --
      declare
      2 xmlDiff sys.xmltype;
      3 begin


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


      Please help to resolve this issue.



      Thanks,
      Chinmay
        • 1. Re: dbua error ora-0604: error occured at recursive SQL level 1
          Srini Chavali-Oracle
          Pl post the last 50 lines from the upgrade log file, as well as from the database alert log file

          HTH
          Srini
          • 2. Re: dbua error ora-0604: error occured at recursive SQL level 1
            ChinmayDBA
            UPGRADE AND DATABASE LOG FILES:


            From Trace.log:


            [Thread-130] [ 2013-04-05 09:30:33.179 EDT ] [OsUtilsBase.getBaseFromOrabase:694] orabaseLocation= /oravisr12/oracle
            [Thread-130] [ 2013-04-05 09:30:33.180 EDT ] [SQLEngine.getEnvParams:565] NLS_LANG: American_America.UTF8
            [Thread-130] [ 2013-04-05 09:30:33.181 EDT ] [SQLEngine.initialize:325] Execing SQLPLUS/SVRMGR process...
            [Thread-130] [ 2013-04-05 09:30:33.288 EDT ] [SQLEngine.initialize:362] m_bReaderStarted: false
            [Thread-130] [ 2013-04-05 09:30:33.289 EDT ] [SQLEngine.initialize:366] Starting Reader Thread...
            [Thread-130] [ 2013-04-05 09:30:33.291 EDT ] [SQLEngine.initialize:415] Waiting for m_bReaderStarted to be true
            [Thread-176] [ 2013-04-05 10:48:27.729 EDT ] [SQLEngine.isStepComplete:1452] ..Status of Upgraded Component:RDBMS is: VALID
            [Thread-176] [ 2013-04-05 10:48:27.761 EDT ] [BasicStep.showProgressSubStep:461] *** BasicStep::showProgressSubStep()
            [Thread-176] [ 2013-04-05 10:54:54.900 EDT ] [SQLEngine.isStepComplete:1452] ..Status of Upgraded Component:JAVAVM is: VALID
            [Thread-176] [ 2013-04-05 10:54:54.901 EDT ] [BasicStep.showProgressSubStep:461] *** BasicStep::showProgressSubStep()
            [Thread-176] [ 2013-04-05 10:56:05.900 EDT ] [SQLEngine.isStepComplete:1452] ..Status of Upgraded Component:XML is: VALID
            [Thread-176] [ 2013-04-05 10:56:05.901 EDT ] [BasicStep.showProgressSubStep:461] *** BasicStep::showProgressSubStep()
            [Thread-176] [ 2013-04-05 10:56:08.366 EDT ] [SQLEngine.isStepComplete:1452] ..Status of Upgraded Component:RAC is: INVALID
            [Thread-176] [ 2013-04-05 10:56:08.367 EDT ] [BasicStep.showProgressSubStep:461] *** BasicStep::showProgressSubStep()
            [Thread-176] [ 2013-04-05 10:56:46.389 EDT ] [SQLEngine.isStepComplete:1452] ..Status of Upgraded Component:APS is: VALID
            [Thread-176] [ 2013-04-05 10:56:46.391 EDT ] [BasicStep.showProgressSubStep:461] *** BasicStep::showProgressSubStep()
            [Thread-176] [ 2013-04-05 10:58:34.779 EDT ] [SQLEngine.isStepComplete:1452] ..Status of Upgraded Component:AMD is: VALID
            [Thread-176] [ 2013-04-05 10:58:34.780 EDT ] [BasicStep.showProgressSubStep:461] *** BasicStep::showProgressSubStep()
            [Thread-176] [ 2013-04-05 11:00:55.065 EDT ] [SQLEngine.isStepComplete:1452] ..Status of Upgraded Component:CONTEXT is: VALID
            [Thread-176] [ 2013-04-05 11:00:55.066 EDT ] [BasicStep.showProgressSubStep:461] *** BasicStep::showProgressSubStep()
            [Thread-176] [ 2013-04-05 11:11:06.028 EDT ] [SQLEngine.isStepComplete:1452] ..Status of Upgraded Component:XDB is: VALID
            [Thread-176] [ 2013-04-05 11:11:06.046 EDT ] [BasicStep.showProgressSubStep:461] *** BasicStep::showProgressSubStep()
            [Thread-176] [ 2013-04-05 11:11:54.504 EDT ] [SQLEngine.isStepComplete:1452] ..Status of Upgraded Component:CATJAVA is: VALID
            [Thread-176] [ 2013-04-05 11:11:54.509 EDT ] [BasicStep.showProgressSubStep:461] *** BasicStep::showProgressSubStep()
            [Thread-176] [ 2013-04-05 11:18:15.331 EDT ] [BasicStep.handleNonIgnorableError:430] oracle.sysman.assistants.util.UIMessageHandler@24ec42e0:messageHandler
            [Thread-176] [ 2013-04-05 11:18:15.369 EDT ] [BasicStep.handleNonIgnorableError:431] ORA-00604: error occurred at recursive SQL level 1
            :msg




            Oracle_Server.log:


            264 representations of SH (Short String), LO (Long String), ST (Short Text)
            265 LT (Long Text), PN (Person Name) or UT (Unlimited Text) are decoded
            266 when the Specific Character Set (0008,0005) attribute is missing.
            267 The DICOM standard states that the default character set (ISO-IR 6,
            268 or ASCII) shall be used for decoding when the Specific Character Set
            269 (0008,0005) attribute is not specified. This parameter allows an
            270 application to specify a different character set to use in these
            271 cases.
            272
            273
            274 PARAMETER: BINARY_SKIP_INVALID_ATTR
            275 VALUE: boolean{false(default), true}
            276 DESCRIPTION: The value of this parameter determines whether Oracle
            277 Multimedia DICOM includes or skips invalid attributes (attributes
            278 that do not conform to the DICOM standard) and their values in the
            279 binary output of DICOM content when making a copy of the DICOM
            280 content. The default behavior, which is specified by setting this
            281 parameter to "false", is not to skip these values but to include
            282 them in the resulting output. When an attribute is skipped, its
            283 value is included in the output with length 0.
            284
            285 </xd:content></xd:update-node>
            286 </xd:xdiff>');
            287
            288 xdb.dbms_xmlschema.inPlaceEvolve('http://xmlns.oracle.com/ord/dicom/preference_1_0', xmlDiff, 1);
            289
            290 end;
            291 /
            declare
            *
            ERROR at line 1:
            ORA-00604: error occurred at recursive SQL level 1
            ERROR:



            Database Alert Log:

            Completed: ALTER DATABASE OPEN MIGRATE
            Fri Apr 05 09:30:32 2013
            Starting background process CJQ0
            Fri Apr 05 09:30:33 2013
            CJQ0 started with pid=25, OS id=1548
            Fri Apr 05 09:40:58 2013
            Beginning log switch checkpoint up to RBA [0x3.2.10], SCN: 12805558039366
            Thread 1 advanced to log sequence 3 (LGWR switch)
            Current log# 3 seq# 3 mem# 0: /oravisr12/dbdata/redo1/log03a.dbf
            Current log# 3 seq# 3 mem# 1: /oravisr12/dbdata/redo2/log03b.dbf
            Fri Apr 05 09:41:39 2013
            Completed checkpoint up to RBA [0x3.2.10], SCN: 12805558039366
            Fri Apr 05 09:50:24 2013
            Incremental checkpoint up to RBA [0x3.43026.0], current log tail at RBA [0x3.4a62f.0]
            Fri Apr 05 09:56:11 2013
            Beginning log switch checkpoint up to RBA [0x4.2.10], SCN: 12805558216228
            Thread 1 advanced to log sequence 4 (LGWR switch)
            Current log# 1 seq# 4 mem# 0: /oravisr12/dbdata/redo1/log01a.dbf
            Current log# 1 seq# 4 mem# 1: /oravisr12/dbdata/redo2/log01b.dbf
            Fri Apr 05 09:59:02 2013
            Completed checkpoint up to RBA [0x4.2.10], SCN: 12805558216228
            Fri Apr 05 09:59:30 2013
            WARNING: ASM communication error: op 0 state 0x0 (15055)
            ERROR: direct connection failure with ASM
            WARNING: ASM communication error: op 0 state 0x0 (15055)
            ERROR: direct connection failure with ASM
            Fri Apr 05 10:10:30 2013
            Incremental checkpoint up to RBA [0x4.1d91b.0], current log tail at RBA [0x4.1d91b.0]
            Fri Apr 05 10:21:12 2013
            WARNING: ASM communication error: op 0 state 0x0 (15055)
            ERROR: direct connection failure with ASM
            Fri Apr 05 10:30:36 2013
            Incremental checkpoint up to RBA [0x4.21591.0], current log tail at RBA [0x4.21591.0]
            Fri Apr 05 10:46:34 2013
            XDB installed.
            XDB initialized.
            Fri Apr 05 10:48:09 2013
            SERVER COMPONENT id=CATPROC: timestamp=2013-04-05 10:48:09
            Fri Apr 05 10:48:27 2013
            SERVER COMPONENT id=RDBMS: status=VALID, version=11.2.0.3.0, timestamp=2013-04-05 10:48:27
            Fri Apr 05 10:50:42 2013
            Incremental checkpoint up to RBA [0x4.2b032.0], current log tail at RBA [0x4.432f1.0]
            Fri Apr 05 10:52:58 2013
            Beginning log switch checkpoint up to RBA [0x5.2.10], SCN: 12805558729887
            Thread 1 advanced to log sequence 5 (LGWR switch)
            Current log# 2 seq# 5 mem# 0: /oravisr12/dbdata/redo1/log02a.dbf
            Current log# 2 seq# 5 mem# 1: /oravisr12/dbdata/redo2/log02b.dbf
            Fri Apr 05 10:54:54 2013
            SERVER COMPONENT id=JAVAVM: status=VALID, version=11.2.0.3.0, timestamp=2013-04-05 10:54:54
            Fri Apr 05 10:54:55 2013
            Completed checkpoint up to RBA [0x5.2.10], SCN: 12805558729887
            Fri Apr 05 10:56:05 2013
            SERVER COMPONENT id=XML: status=VALID, version=11.2.0.3.0, timestamp=2013-04-05 10:56:05
            SERVER COMPONENT id=RAC: status=INVALID, version=11.2.0.3.0, timestamp=2013-04-05 10:56:08
            Shared IO Pool defaulting to 192MB. Trying to get it from Buffer Cache for process 1568.
            Fri Apr 05 10:56:46 2013
            SERVER COMPONENT id=APS: status=VALID, version=11.2.0.3.0, timestamp=2013-04-05 10:56:46
            Fri Apr 05 10:58:34 2013
            SERVER COMPONENT id=AMD: status=VALID, version=11.2.0.3.0, timestamp=2013-04-05 10:58:34
            Fri Apr 05 10:59:56 2013
            Beginning log switch checkpoint up to RBA [0x6.2.10], SCN: 12805558850853
            Thread 1 advanced to log sequence 6 (LGWR switch)
            Current log# 3 seq# 6 mem# 0: /oravisr12/dbdata/redo1/log03a.dbf
            Current log# 3 seq# 6 mem# 1: /oravisr12/dbdata/redo2/log03b.dbf
            Fri Apr 05 11:00:55 2013
            SERVER COMPONENT id=CONTEXT: status=VALID, version=11.2.0.3.0, timestamp=2013-04-05 11:00:55
            Fri Apr 05 11:02:53 2013
            Completed checkpoint up to RBA [0x6.2.10], SCN: 12805558850853
            Fri Apr 05 11:10:49 2013
            Incremental checkpoint up to RBA [0x6.322d0.0], current log tail at RBA [0x6.3f46f.0]
            Fri Apr 05 11:11:06 2013
            SERVER COMPONENT id=XDB: status=VALID, version=11.2.0.3.0, timestamp=2013-04-05 11:11:06
            Fri Apr 05 11:11:54 2013
            SERVER COMPONENT id=CATJAVA: status=VALID, version=11.2.0.3.0, timestamp=2013-04-05 11:11:54
            Fri Apr 05 11:14:51 2013
            Beginning log switch checkpoint up to RBA [0x7.2.10], SCN: 12805559043781
            Thread 1 advanced to log sequence 7 (LGWR switch)
            Current log# 1 seq# 7 mem# 0: /oravisr12/dbdata/redo1/log01a.dbf
            Current log# 1 seq# 7 mem# 1: /oravisr12/dbdata/redo2/log01b.dbf
            Fri Apr 05 11:16:57 2013
            Completed checkpoint up to RBA [0x7.2.10], SCN: 12805559043781
            Fri Apr 05 11:30:55 2013
            Incremental checkpoint up to RBA [0x7.340b9.0], current log tail at RBA [0x7.340b9.0]
            • 3. Re: dbua error ora-0604: error occured at recursive SQL level 1
              Srini Chavali-Oracle
              This is most likely a bug - pl open an SR with Support

              HTH
              Srini