Hi There,
We have a database running oracle 11.1.0.7 on Solaris 11, that is taking 10 mins to startup.. The database is very small in size (just under 3gb) that hosts the b2b software schemas.
We shutdown the database with "shutdown immediate" and then started it through "startup" and we noticed that its taking a while. We had a look at the alert log and it doesn't show much:
MMNL started with pid=62, OS id=25708
Mon Aug 24 11:59:44 2015
DISM started, OS id=25721
Mon Aug 24 12:09:03 2015
Here is a snippet for the the full startup log taken out of the instance alert log:
oracle@b2bbd02:/home/oracle> tail -f /u01/app/oracle/product/11.1.0/db_1/log/diag/rdbms/b2bd/b2bd/trace/alert_b2bd.log
Mon Aug 24 11:58:35 2015
Starting ORACLE instance (normal)
LICENSE_MAX_SESSION = 0
LICENSE_SESSIONS_WARNING = 0
Picked latch-free SCN scheme 3
Mon Aug 24 11:58:47 2015
Using LOG_ARCHIVE_DEST_10 parameter default value as USE_DB_RECOVERY_FILE_DEST
Autotune of undo retention is turned on.
IMODE=BR
ILAT =73
LICENSE_MAX_USERS = 0
SYS auditing is disabled
Mon Aug 24 11:59:15 2015
Starting up ORACLE RDBMS Version: 11.1.0.7.0.
Using parameter settings in server-side pfile /u01/app/oracle/product/11.1.0/db_1/dbs/initb2bd.ora
System parameters with non-default values:
processes = 600
sessions = 665
spfile = "/u01/app/oracle/admin/b2bd/spfileb2bd.ora"
memory_target = 4G
control_files = "/oradata/b2bd/control01.ctl"
control_files = "/oradata/b2bd/control02.ctl"
control_files = "/oralog/b2bd/control03.ctl"
db_block_size = 8192
compatible = "11.1.0"
db_file_multiblock_read_count= 16
db_recovery_file_dest = "/orabkup/flash_recovery_area"
db_recovery_file_dest_size= 10G
undo_management = "AUTO"
undo_tablespace = "UNDOTBS1"
remote_login_passwordfile= "EXCLUSIVE"
db_domain = "reg.gov.ab.ca"
job_queue_processes = 40
parallel_max_servers = 10
audit_file_dest = "/u01/app/oracle/admin/b2bd/adump"
db_name = "b2bd"
open_cursors = 1000
pga_aggregate_target = 256M
aq_tm_processes = 2
Mon Aug 24 11:59:16 2015
PMON started with pid=2, OS id=25070
Mon Aug 24 11:59:18 2015
VKTM started with pid=6, OS id=25086
VKTM running at (100ms) precision
Mon Aug 24 11:59:18 2015
DIAG started with pid=10, OS id=25135
Mon Aug 24 11:59:19 2015
DBRM started with pid=14, OS id=25153
Mon Aug 24 11:59:20 2015
PSP0 started with pid=18, OS id=25160
Mon Aug 24 11:59:21 2015
DIA0 started with pid=22, OS id=25171
Mon Aug 24 11:59:22 2015
MMAN started with pid=26, OS id=25211
Mon Aug 24 11:59:23 2015
DBW0 started with pid=30, OS id=25219
Mon Aug 24 11:59:25 2015
DBW1 started with pid=3, OS id=25228
Mon Aug 24 11:59:27 2015
DBW2 started with pid=4, OS id=25261
Mon Aug 24 11:59:28 2015
DBW3 started with pid=5, OS id=25280
Mon Aug 24 11:59:29 2015
DBW4 started with pid=34, OS id=25323
Mon Aug 24 11:59:31 2015
DBW5 started with pid=7, OS id=25358
Mon Aug 24 11:59:32 2015
DBW6 started with pid=8, OS id=25387
Mon Aug 24 11:59:33 2015
DBW7 started with pid=9, OS id=25406
Mon Aug 24 11:59:35 2015
DBW8 started with pid=38, OS id=25462
Mon Aug 24 11:59:36 2015
DBW9 started with pid=11, OS id=25545
Mon Aug 24 11:59:38 2015
DBWa started with pid=12, OS id=25554
Mon Aug 24 11:59:39 2015
DBWb started with pid=13, OS id=25561
Mon Aug 24 11:59:39 2015
DBWc started with pid=42, OS id=25567
Mon Aug 24 11:59:39 2015
DBWd started with pid=15, OS id=25570
Mon Aug 24 11:59:39 2015
DBWe started with pid=16, OS id=25574
Mon Aug 24 11:59:39 2015
DBWf started with pid=17, OS id=25586
Mon Aug 24 11:59:40 2015
LGWR started with pid=19, OS id=25598
Mon Aug 24 11:59:42 2015
CKPT started with pid=46, OS id=25608
Mon Aug 24 11:59:42 2015
SMON started with pid=50, OS id=25638
Mon Aug 24 11:59:43 2015
RECO started with pid=54, OS id=25663
Mon Aug 24 11:59:43 2015
MMON started with pid=58, OS id=25691
Mon Aug 24 11:59:44 2015
MMNL started with pid=62, OS id=25708
Mon Aug 24 11:59:44 2015
DISM started, OS id=25721
Mon Aug 24 12:09:03 2015
ORACLE_BASE not set in environment. It is recommended
that ORACLE_BASE be set in the environment
Mon Aug 24 12:09:03 2015
ALTER DATABASE MOUNT
Setting recovery target incarnation to 1
Mon Aug 24 12:09:07 2015
Successful mount of redo thread 1, with mount id 462930143
Database mounted in Exclusive Mode
Lost write protection disabled
Completed: ALTER DATABASE MOUNT
Mon Aug 24 12:09:08 2015
ALTER DATABASE OPEN
LGWR: STARTING ARCH PROCESSES
Mon Aug 24 12:09:08 2015
ARC0 started with pid=66, OS id=11683
Mon Aug 24 12:09:08 2015
ARC1 started with pid=27, OS id=11686
Mon Aug 24 12:09:09 2015
ARC2 started with pid=20, OS id=11688
ARC0: Archival started
Mon Aug 24 12:09:09 2015
ARC3 started with pid=21, OS id=11690
ARC1: Archival started
ARC2: Archival started
ARC3: Archival started
LGWR: STARTING ARCH PROCESSES COMPLETE
Thread 1 opened at log sequence 7869
Current log# 4 seq# 7869 mem# 0: /oralog/b2bd/redo01a.log
Current log# 4 seq# 7869 mem# 1: /oralog/b2bd/redo01b.log
Successful open of redo thread 1
MTTR advisory is disabled because FAST_START_MTTR_TARGET is not set
ARC3: Becoming the 'no FAL' ARCH
ARC3: Becoming the 'no SRL' ARCH
ARC0: Becoming the heartbeat ARCH
Mon Aug 24 12:09:09 2015
SMON: enabling cache recovery
Successfully onlined Undo Tablespace 2.
Verifying file header compatibility for 11g tablespace encryption..
Verifying 11g file header compatibility for tablespace encryption completed
SMON: enabling tx recovery
Database Characterset is AL32UTF8
Opening with internal Resource Manager plan : on 4 X 32 NUMA system
Starting background process FBDA
Mon Aug 24 12:09:11 2015
FBDA started with pid=31, OS id=11711
replication_dependency_tracking turned off (no async multimaster replication found)
Starting background process QMNC
Mon Aug 24 12:09:11 2015
QMNC started with pid=70, OS id=11726
Completed: ALTER DATABASE OPEN
Mon Aug 24 12:09:14 2015
db_recovery_file_dest_size of 10240 MB is 60.90% used. This is a
user-specified limit on the amount of space that will be used by this
database for recovery-related files, and does not reflect the amount of
space available in the underlying filesystem or ASM diskgroup.
Mon Aug 24 12:14:19 2015
Starting background process SMCO
Mon Aug 24 12:14:20 2015
SMCO started with pid=78, OS id=20505
Memory_target/memory_max_target is set to 4gb.
Any idea to figure out what's going on please?
Thanks