Monday, January 10, 2011

Snapshot standby example in 11gR2 (Part 1)


SQL> startup mount
ORACLE instance started.

Total System Global Area 636100608 bytes
Fixed Size 1346048 bytes
Variable Size 406849024 bytes
Database Buffers 222298112 bytes
Redo Buffers 5607424 bytes
Database mounted.
SQL> recover managed standby database using current logfile disconnect;
Media recovery complete.
SQL> alter database convert to snapshot standby;
alter database convert to snapshot standby
*
ERROR at line 1:
ORA-38784: Cannot create restore point 'SNAPSHOT_STANDBY_REQUIRED_01/10/2011
11:07:53'.
ORA-01153: an incompatible media recovery is active


SQL> recover managed standby database cancel;
Media recovery complete.
SQL> alter database convert to snapshot standby;

Database altered.

SQL> select open_mode from v$database;

OPEN_MODE
--------------------
MOUNTED

SQL> alter database open;

Database altered.

SQL> select open_mode from v$database;

OPEN_MODE
--------------------
READ WRITE



From alert_drorcl.log:

2011-01-10 11:07:53.939000 +08:00
alter database convert to snapshot standby
ORA-38784 signalled during: alter database convert to snapshot standby...
2011-01-10 11:08:06.648000 +08:00
ALTER DATABASE RECOVER managed standby database cancel
MRP0: Background Media Recovery cancelled with status 16037
Errors in file /u01/app/oracle/diag/rdbms/drorcl/drorcl/trace/drorcl_mrp0_10173.trc:
ORA-16037: user requested cancel of managed recovery operation
Managed Standby Recovery not using Real Time Apply
Recovery interrupted!
Recovered data files to a consistent state at change 335626
MRP0: Background Media Recovery process shutdown (drorcl)
2011-01-10 11:08:07.929000 +08:00
Managed Standby Recovery Canceled (drorcl)
Completed: ALTER DATABASE RECOVER managed standby database cancel
2011-01-10 11:08:20.495000 +08:00
alter database convert to snapshot standby
Starting background process RVWR
RVWR started with pid=27, OS id=10206
Allocated 3981204 bytes in shared pool for flashback generation buffer
Created guaranteed restore point SNAPSHOT_STANDBY_REQUIRED_01/10/2011 11:08:20
krsv_proc_kill: Killing 3 processes (all RFS)
2011-01-10 11:08:23.595000 +08:00
Begin: Standby Redo Logfile archival
End: Standby Redo Logfile archival
RESETLOGS after incomplete recovery UNTIL CHANGE 335626
Resetting resetlogs activation ID 4067055344 (0xf26a56f0)
Online log /u01/app/oracle/oradata/drorcl/redo01.log: Thread 1 Group 1 was previously cleared
Online log /u01/app/oracle/oradata/drorcl/redo02.log: Thread 1 Group 2 was previously cleared
Online log /u01/app/oracle/oradata/drorcl/redo03.log: Thread 1 Group 3 was previously cleared
Standby became primary SCN: 335624
Setting recovery target incarnation to 2
CONVERT TO SNAPSHOT STANDBY: Complete - Database mounted as snapshot standby
Completed: alter database convert to snapshot standby
selec2011-01-10 11:09:36.857000 +08:00
alter database open
Assigning activation ID 4067086165 (0xf26acf55)
Thread 1 opened at log sequence 1
Current log# 1 seq# 1 mem# 0: /u01/app/oracle/oradata/drorcl/redo01.log
Successful open of redo thread 1
MTTR advisory is disabled because FAST_START_MTTR_TARGET is not set
ARC1: Becoming the 'no SRL' ARCH
SMON: enabling cache recovery
NSA2 started with pid=24, OS id=10241
[10149] Successfully onlined Undo Tablespace 2.
Undo initialization finished serial:0 start:6705224 end:6705404 diff:180 (1 seconds)
Dictionary check beginning
Dictionary check complete
Verifying file header compatibility for 11g tablespace encryption..
Verifying 11g file header compatibility for tablespace encryption completed
SMON: enabling tx recovery
Database Characterset is WE8MSWIN1252
No Resource Manager plan active
replication_dependency_tracking turned off (no async multimaster replication found)
2011-01-10 11:09:38.401000 +08:00
Starting background process QMNC
QMNC started with pid=25, OS id=10243
LOGSTDBY: Validating controlfile with logical metadata
LOGSTDBY: Validation complete
Completed: alter database open
2011-01-10 11:09:41.012000 +08:00
Thread 1 advanced to log sequence 2 (LGWR switch)
Current log# 2 seq# 2 mem# 0: /u01/app/oracle/oradata/drorcl/redo02.log
Archived Log entry 53 added for thread 1 sequence 1 ID 0xf26acf55 dest 1:
2011-01-10 11:09:42.379000 +08:00
db_recovery_file_dest_size of 4977 MB is 0.65% 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.
2011-01-10 11:11:47.018000 +08:00
RFS[3]: Assigned to RFS process 10339
RFS[3]: Selected log 6 for thread 1 sequence 63 dbid -227949715 branch 739987437
RFS[3]: Opened log for thread 1 sequence 66 dbid -227949715 branch 739987437
Archived Log entry 54 added for thread 1 sequence 66 rlc 739987437 ID 0xf26a56f0 dest 2:
Archived Log entry 55 added for thread 1 sequence 63 ID 0xf26a56f0 dest 1:
RFS[3]: Opened log for thread 1 sequence 67 dbid -227949715 branch 739987437
Archived Log entry 56 added for thread 1 sequence 67 rlc 739987437 ID 0xf26a56f0 dest 2:
RFS[4]: Assigned to RFS process 10341
RFS[4]: Opened log for thread 1 sequence 65 dbid -227949715 branch 739987437
Archived Log entry 57 added for thread 1 sequence 65 rlc 739987437 ID 0xf26a56f0 dest 2:
RFS[5]: Assigned to RFS process 10343
RFS[5]: Opened log for thread 1 sequence 64 dbid -227949715 branch 739987437
Archived Log entry 58 added for thread 1 sequence 64 rlc 739987437 ID 0xf26a56f0 dest 2:
2011-01-10 11:11:50.482000 +08:00
RFS[4]: Selected log 5 for thread 1 sequence 68 dbid -227949715 branch 739987437
Archived Log entry 59 added for thread 1 sequence 68 ID 0xf26a56f0 dest 1:
RFS[6]: Assigned to RFS process 10345
RFS[6]: Selected log 5 for thread 1 sequence 69 dbid -227949715 branch 739987437