Database startup takes 10 minutes extra
(Doc ID 2659344.1)
Last updated on SEPTEMBER 27, 2023
Applies to:
Oracle Database - Enterprise Edition - Version 12.1.0.2 and laterInformation in this document applies to any platform.
Symptoms
srvctl stop and start of database instance taking 11/12 minutes.
[oracle@xxxxxx1 dbs]$ date;srvctl start service -database mydb -service mydb_svc;date
Tue Feb 11 22:02:45 EST 2020
Tue Feb 11 22:12:48 EST 2020
You have new mail in /var/spool/mail/oracle
[oracle@xxxxxx1 dbs]$
Some time it Fails to start
[oracle@xxxxxx1 ~]$ srvctl start service -db mydb
PRCD-1084 : Failed to start service test_svc
PRCR-1079 : Failed to start resource ora.mydb.test_svc.svc
CRS-2800: Cannot start resource 'ora.mydb.db' as it is already in the INTERMEDIATE state on server 'xxxxxx1'
020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} no-op RG op monitor
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.ACFS1.dg 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.ASMNET1LSNR_ASM.lsnr 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.DATAC1.dg 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.RECOC1.dg 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.asm 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.asmnet1.asmnetwork 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} entered RGI::setWithPhaseCompleted, succeeded=1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Received member ops completed notification
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} No progress message for partial/placeholder op:RGI: ora.asmgroup 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Resource::ocrWriter flushing : ora.asmgroup
2020-02-11 22:29:33.321 : CRSPE:1790924544: [ INFO] {1:7322:29534} Marking complete for ppath : 0x7ff358687b60, RI = RGI: ora.asmgroup 4 1
2020-02-11 22:29:33.321 : CRSPE:1790924544: [ INFO] {1:7322:29534} no-op RG op monitor
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.ACFS1.dg 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.ASMNET1LSNR_ASM.lsnr 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.DATAC1.dg 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.RECOC1.dg 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.asm 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Deleting RI-path from op-history:ora.asmnet1.asmnetwork 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Completing op, hasMadeAStep=false, , pException=null : START of [ora.mydb.db 2 1] on [mydbnodevm01] : Op:0x7ff358619840, Cmd:0x7ff3586be960, SeqId:484
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} entered RGI::setWithPhaseCompleted, succeeded=1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Received member ops completed notification
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} No progress message for partial/placeholder op:RGI: ora.asmgroup 3 1
2020-02-11 22:29:33.320 : CRSPE:1790924544: [ INFO] {1:7322:29534} Resource::ocrWriter flushing : ora.asmgroup
2020-02-11 22:29:33.321 : CRSPE:1790924544: [ INFO] {1:7322:29534} Marking complete for ppath : 0x7ff358687b60, RI = RGI: ora.asmgroup 4 1
2020-02-11 22:29:33.321 : CRSPE:1790924544: [ INFO] {1:7322:29534} no-op RG op monitor
Changes
Cause
To view full details, sign in with your My Oracle Support account. |
|
Don't have a My Oracle Support account? Click to get started! |