Alert Log Message during Backup Process

[kburrows]

Hi all,

I run an offline backup once a week. I got a few srange things going on here. Seems the backup had to issue an abort and would not shut immediate. Then the files did not get copied to the backup location and I got a dump from pmon. Went through all the logs and cannot see what went wrong here. I believe this has happened once before and only when the shutdown immedite fails and I have to go to a shutdown abort. Any Ideas, Thanks Kathy

AIX 5.2
Oracle 9.2.0.3 (stayed at .3 for 3rd party software req's)

Alert.log
Mon Nov 7 00:05:05 2005
alter database backup controlfile to trace
Completed: alter database backup controlfile to trace
Mon Nov 7 00:05:05 2005
alter database backup controlfile to '/oracle/backup/weekly/prd003/cntrl_Nov0705.ctl'
Completed: alter database backup controlfile to '/oracle/back
Shutting down instance: further logons disabled
Mon Nov 7 00:05:10 2005
Shutting down instance: further logons disabled
Mon Nov 7 00:05:10 2005
Shutting down instance (immediate)
Mon Nov 7 00:05:10 2005
Shutting down instance (immediate)
License high water mark = 30
Mon Nov 7 00:05:10 2005
License high water mark = 30
Mon Nov 7 00:05:14 2005
ALTER DATABASE CLOSE NORMAL
ORA-1089 signalled during: ALTER DATABASE CLOSE NORMAL...
Mon Nov 7 00:10:12 2005
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Shutting down instance (abort)
License high water mark = 30
Instance terminated by USER, pid = 545020
Instance terminated by USER, pid = 757828
Instance terminated by USER, pid = 778266
Instance terminated by USER, pid = 467068
Instance terminated by USER, pid = 311456
Instance terminated by USER, pid = 487548
Instance terminated by USER, pid = 503922
Instance terminated by USER, pid = 815166
Instance terminated by USER, pid = 471110
Instance terminated by USER, pid = 499718
Instance terminated by USER, pid = 594072
Instance terminated by USER, pid = 528424
Instance terminated by USER, pid = 860346
Instance terminated by USER, pid = 540866
Instance terminated by USER, pid = 708672
Mon Nov 7 01:25:27 2005
Starting ORACLE instance (normal)
Disable cache advisory with old cache parameters
LICENSE_MAX_SESSION = 0
LICENSE_SESSIONS_WARNING = 0
SCN scheme 3
Using log_archive_dest parameter default value
LICENSE_MAX_USERS = 0
SYS auditing is disabled
Starting up ORACLE RDBMS Version: 9.2.0.3.0.
System parameters with non-default values:
processes = 1030
timed_statistics = TRUE
shared_pool_size = 67108864
shared_pool_reserved_size= 2750000
large_pool_size = 16777216
enqueue_resources = 2000
nls_date_format = DD-MM-YYYY
control_files = /u01/oradata/ORACLE/prd003/cont
l/ORACLE/prd003/control03.ctl
db_block_buffers = 29000
db_block_size = 8192
compatible = 9.2.0
log_buffer = 512000
log_checkpoint_interval = 10000
db_file_multiblock_read_count= 8
log_checkpoints_to_alert = TRUE
dml_locks = 2000
transactions = 300
rollback_segments = r01, r02, r03, r04, LARGE_R01
max_enabled_roles = 90
remote_os_authent = TRUE
db_domain = world
global_names = FALSE
background_dump_dest = /oracle/admin/prd003/bdump
user_dump_dest = /oracle/admin/prd003/udump
max_dump_file_size = 10240
core_dump_dest = /oracle/admin/prd003/cdump
sort_area_size = 1048576
sort_area_retained_size = 32000
db_name = prd003
open_cursors = 800
os_authent_prefix = ops$
optimizer_mode = CHOOSE
query_rewrite_enabled = TRUE
query_rewrite_integrity = enforced
pga_aggregate_target = 25165824
PMON started with pid=2
DBW0 started with pid=3
LGWR started with pid=4
CKPT started with pid=5
SMON started with pid=6
RECO started with pid=7
Mon Nov 7 01:25:29 2005
ALTER DATABASE MOUNT
Mon Nov 7 01:25:33 2005
Successful mount of redo thread 1, with mount id 3897004873.
Mon Nov 7 01:25:33 2005
Database mounted in Exclusive Mode.
Completed: ALTER DATABASE MOUNT
Mon Nov 7 01:25:34 2005
ALTER DATABASE OPEN
Mon Nov 7 01:25:34 2005
Beginning crash recovery of 1 threads
Mon Nov 7 01:25:34 2005
Started first pass scan
Mon Nov 7 01:25:34 2005
Completed first pass scan
0 redo blocks read, 0 data blocks need recovery
Mon Nov 7 01:25:34 2005
Started recovery at
Thread 1: logseq 778, block 147840, scn 0.18613736
Recovery of Online Redo Log: Thread 1 Group 1 Seq 778 Reading mem 0
Mem# 0 errs 0: /u07/oradata/ORACLE/prd003/redo01.log
Mon Nov 7 01:25:34 2005
Mon Nov 7 01:25:34 2005
Completed redo application
Mon Nov 7 01:25:34 2005
Ended recovery at
Thread 1: logseq 778, block 147840, scn 0.18633737
0 data blocks read, 0 data blocks written, 0 redo blocks read
Crash recovery completed successfully
Mon Nov 7 01:25:34 2005
Thread 1 advanced to log sequence 779
Thread 1 opened at log sequence 779
Current log# 2 seq# 779 mem# 0: /u07/oradata/ORACLE/prd003/redo02.log
Successful open of redo thread 1.
Mon Nov 7 01:25:34 2005
MTTR advisory is disabled because FAST_START_MTTR_TARGET is not set
Mon Nov 7 01:25:34 2005
SMON: enabling cache recovery
SMON: enabling tx recovery
Mon Nov 7 01:25:35 2005
Database Characterset is US7ASCII
replication_dependency_tracking turned off (no async multimaster replication found)
Completed: ALTER DATABASE OPEN

Dump.log

*** SESSION ID:(7.3) 2005-11-07 01:25:34.488
Thread checkpoint rba:0x00030a.0000cf55.0010 scn:0x0000.0118f02e
Cache low rba is 0xffffffff.ffffffff.ffff
Use incremental checkpoint on-disk rba
Thread 1 recovery from rba:0x00030a.00024180.0000 scn:0x0000.011c05e8
----- Recovery Hash Table Statistics ---------
Hash table buckets = 32768
Longest hash chain = 0
Average hash chain = 0/0 = 0.0
Max compares per lookup = 0
Avg compares per lookup = 0/0 = 0.0
----------------------------------------------
*** 2005-11-07 01:25:34.586
KCRA: start recovery claims for 0 data blocks
*** 2005-11-07 01:25:34.586
KCRA: buffers claimed = 0/0, eliminated = 0
----- Recovery Hash Table Statistics ---------
Hash table buckets = 32768
Longest hash chain = 0
Average hash chain = 0/0 = 0.0
Max compares per lookup = 0
Avg compares per lookup = 0/0 = 0.0
----------------------------------------------

[tamilselvan]

Always issue "alter system checkpoint" before you shutdown the database with any option and switch logfile.

I think there might be active transactions going on when you issue "shutdown immediate" command.
Try this method:

SQL> alter system checkpoint ;

System altered.

SQL> alter system switch logfile;

System altered.

SQL> shutdown transactional;
Database closed.
Database dismounted.
ORACLE instance shut down.


Tamil