/* || Oracle 11g DataGuard Listing 6 || || Illustrates how to: || - Activate ... || in an Oracle Database 11g primary and standby Data Guard environment. || || Author: Jim Czuprynski || || Usage Notes: || These examples are provided to demonstrate various Oracle 11gR1 Data Guard || features, and they should be carefully proofread before being executed || against any existing Oracle database to avoid potential damage! */ /* || Listing 6.1: || Performing a manual failover */ >> Standby database alert log entries:
Tue Aug 25 19:00:07 2009 ALTER DATABASE RECOVER MANAGED STANDBY DATABASE FINISH FORCE Terminal Recovery: Stopping real time apply Tue Aug 25 19:00:07 2009 MRP0: Background Media Recovery cancelled with status 16037 Errors in file /u01/app/oracle/diag/rdbms/orcl_stdby1/orcl_stdby1/trace/orcl_stdby1_mrp0_5709.trc: ORA-16037: user requested cancel of managed recovery operation Managed Standby Recovery not using Real Time Apply Shutting down recovery slaves due to error 16037 Recovery interrupted! Recovered data files to a consistent state at change 5050217 Waiting for MRP0 pid 5709 to terminate Errors in file /u01/app/oracle/diag/rdbms/orcl_stdby1/orcl_stdby1/trace/orcl_stdby1_mrp0_5709.trc: ORA-16037: user requested cancel of managed recovery operation MRP0: Background Media Recovery process shutdown (orcl_stdby1) Terminal Recovery: Stopped real time apply Attempt to do a Terminal Recovery (orcl_stdby1) Media Recovery Start: Managed Standby Recovery (orcl_stdby1) Fast Parallel Media Recovery enabled Managed Standby Recovery not using Real Time Apply Terminal Recovery timestamp is '08/25/2009 19:00:08' Terminal Recovery: applying standby redo logs. Terminal Recovery: thread 1 seq# 24 redo required Terminal Recovery: Recovery of Online Redo Log: Thread 1 Group 5 Seq 24 Reading mem 0 Mem# 0: /u01/app/oracle/oradata/stdby/srl02.log Identified End-Of-Redo for thread 1 sequence 24 Incomplete recovery applied all redo ever generated. Recovery completed through change 5050219 time 08/25/2009 18:54:09 Media Recovery Complete (orcl_stdby1) Terminal Recovery: successful completion Resetting standby activation ID 1221597403 (0x48d018db) Completed: ALTER DATABASE RECOVER MANAGED STANDBY DATABASE FINISH FORCE ALTER DATABASE COMMIT TO SWITCHOVER TO PRIMARY WAIT WITH SESSION SHUTDOWN ALTER DATABASE SWITCHOVER TO PRIMARY (orcl_stdby1) Maximum wait for role transition is 15 minutes. Backup controlfile written to trace file /u01/app/oracle/diag/rdbms/orcl_stdby1/orcl_stdby1/trace/orcl_stdby1_rsm0_5707.trc Standby terminal recovery start SCN: 5050217 RESETLOGS after complete recovery through change 5050219 Online log /u01/app/oracle/oradata/stdby/redo01.log: Thread 1 Group 1 was previously cleared Online log /u01/app/oracle/oradata/stdby/redo02.log: Thread 1 Group 2 was previously cleared Online log /u01/app/oracle/oradata/stdby/redo03.log: Thread 1 Group 3 was previously cleared Standby became primary SCN: 5050216 Tue Aug 25 19:00:09 2009 Setting recovery target incarnation to 7 Converting standby mount to primary mount. Switchover: Complete - Database mounted as primary (orcl_stdby1) Completed: ALTER DATABASE COMMIT TO SWITCHOVER TO PRIMARY WAIT WITH SESSION SHUTDOWN Tue Aug 25 19:00:09 2009 ARC2: STARTING ARCH PROCESSES ARC4: Archival started ARC2: STARTING ARCH PROCESSES COMPLETE Tue Aug 25 19:00:09 2009 ARC4 started with pid=26, OS id=5755 ALTER DATABASE OPEN Tue Aug 25 19:00:09 2009 Assigning activation ID 1223627885 (0x48ef146d) Thread 1 opened at log sequence 1 Current log# 1 seq# 1 mem# 0: /u01/app/oracle/oradata/stdby/redo01.log Successful open of redo thread 1 MTTR advisory is disabled because FAST_START_MTTR_TARGET is not set Tue Aug 25 19:00:09 2009 ARC3: Becoming the 'no SRL' ARCH Tue Aug 25 19:00:09 2009 SMON: enabling cache recovery Successfully onlined Undo Tablespace 2. 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 AL32UTF8 Opening with internal Resource Manager plan Starting background process FBDA Starting background process SMCO Tue Aug 25 19:00:12 2009 SMCO started with pid=30, OS id=5759 Tue Aug 25 19:00:12 2009 FBDA started with pid=29, OS id=5757 replication_dependency_tracking turned off (no async multimaster replication found) Starting background process QMNC Tue Aug 25 19:00:13 2009 QMNC started with pid=31, OS id=5761 LOGSTDBY: Validating controlfile with logical metadata LOGSTDBY: Validation complete Tue Aug 25 19:00:25 2009 Deleted Oracle managed file /u01/app/oracle/flash_recovery_area/ORCL_STDBY1/onlinelog/o1_mf_7_579cs7y0_.log Tue Aug 25 19:00:43 2009 Completed: ALTER DATABASE OPEN Tue Aug 25 19:00:43 2009 Shutting down archive processes ALTER SYSTEM SET log_archive_dest_1='location="/u01/app/oracle/flash_recovery_area/STDBY/"','valid_for=(ALL_LOGFILES,ALL_ROLES)' SCOPE=BOTH SID='orcl_stdby1'; ALTER SYSTEM SET log_archive_dest_state_1='ENABLE' SCOPE=BOTH SID='orcl_stdby1'; ALTER SYSTEM SET log_archive_trace=0 SCOPE=BOTH SID='orcl_stdby1'; ALTER SYSTEM SET log_archive_format='log_%s_%t_%r.arc' SCOPE=SPFILE SID='orcl_stdby1'; ALTER SYSTEM SET standby_file_management='AUTO' SCOPE=BOTH SID='*'; ALTER SYSTEM SET archive_lag_target=0 SCOPE=BOTH SID='*'; ARCH shutting down ARC4: Archival stopped ALTER SYSTEM SET log_archive_max_processes=4 SCOPE=BOTH SID='*'; ALTER SYSTEM SET log_archive_min_succeed_dest=1 SCOPE=BOTH SID='*'; ALTER SYSTEM SET log_file_name_convert='/u01/app/oracle/oradata/orcl/','/u01/app/oracle/oradata/stdby/' SCOPE=SPFILE; Tue Aug 25 19:00:48 2009 Failover succeeded. Primary database is now orcl_stdby1.
/* || Listing 6.2: || Reinstating a failed primary database */ >> Primary database alert log entries:
Tue Aug 25 19:08:48 2009 Starting ORACLE instance (normal) LICENSE_MAX_SESSION = 0 LICENSE_SESSIONS_WARNING = 0 Picked latch-free SCN scheme 2 Autotune of undo retention is turned on. IMODE=BR ILAT =18 LICENSE_MAX_USERS = 0 SYS auditing is disabled Starting up ORACLE RDBMS Version: 11.1.0.6.0. Using parameter settings in server-side spfile /u01/app/oracle/product/11.1.0/db_1/dbs/spfileorcl_primary.ora System parameters with non-default values: processes = 150 sga_target = 400M control_files = "/u01/app/oracle/oradata/orcl/control01.ctl" control_files = "/u01/app/oracle/oradata/orcl/control02.ctl" control_files = "/u01/app/oracle/oradata/orcl/control03.ctl" db_block_size = 8192 compatible = "11.1.0.0.0" log_archive_config = "DG_CONFIG=(orcl_primary,orcl_stdby1)" log_archive_dest_1 = "location="/u01/app/oracle/flash_recovery_area/ORCL/"" log_archive_dest_1 = "valid_for=(ALL_LOGFILES,ALL_ROLES)" log_archive_dest_2 = "service="orcl_stdby1"" log_archive_dest_2 = " LGWR ASYNC NOAFFIRM delay=0 OPTIONAL compression=DISABLE max_failure=0 max_connections=1 reopen=300 db_unique_name="orcl_stdby1" net_timeout=30 valid_for=(online_logfile,primary_role)" log_archive_dest_state_1 = "ENABLE" log_archive_dest_state_2 = "ENABLE" log_archive_max_processes= 4 log_archive_min_succeed_dest= 1 log_archive_trace = 0 log_archive_format = "log_%s_%t_%r.arc" fal_client = "orcl_primary" fal_server = "orcl_stdby1" archive_lag_target = 0 db_recovery_file_dest = "/u01/app/oracle/flash_recovery_area" db_recovery_file_dest_size= 8G standby_file_management = "AUTO" undo_tablespace = "UNDOTBS1" sec_case_sensitive_logon = FALSE remote_login_passwordfile= "EXCLUSIVE" db_domain = "" dispatchers = "(PROTOCOL=TCP) (SERVICE=orclXDB)" audit_file_dest = "/u01/app/oracle/admin/orcl/adump" audit_trail = "DB" db_name = "orcl" db_unique_name = "orcl_primary" open_cursors = 300 pga_aggregate_target = 150M dg_broker_start = TRUE dg_broker_config_file1 = "/u01/app/oracle/product/11.1.0/db_1/dbs/dr1orcl_primary.dat" dg_broker_config_file2 = "/u01/app/oracle/product/11.1.0/db_1/dbs/dr2orcl_primary.dat" diagnostic_dest = "/u01/app/oracle" Tue Aug 25 19:08:51 2009 PMON started with pid=2, OS id=12286 Tue Aug 25 19:08:51 2009 VKTM started with pid=3, OS id=12299 at elevated priority VKTM running at (20)ms precision Tue Aug 25 19:08:51 2009 DIAG started with pid=4, OS id=12303 Tue Aug 25 19:08:51 2009 DBRM started with pid=5, OS id=12305 Tue Aug 25 19:08:51 2009 DSKM started with pid=7, OS id=12309 Tue Aug 25 19:08:51 2009 PSP0 started with pid=6, OS id=12307 Tue Aug 25 19:08:51 2009 DIA0 started with pid=8, OS id=12311 Tue Aug 25 19:08:51 2009 MMAN started with pid=7, OS id=12313 Tue Aug 25 19:08:51 2009 DBW0 started with pid=9, OS id=12315 Tue Aug 25 19:08:52 2009 CKPT started with pid=11, OS id=12319 Tue Aug 25 19:08:52 2009 SMON started with pid=12, OS id=12321 Tue Aug 25 19:08:52 2009 LGWR started with pid=10, OS id=12317 Tue Aug 25 19:08:52 2009 RECO started with pid=13, OS id=12323 Tue Aug 25 19:08:52 2009 MMON started with pid=14, OS id=12325 starting up 1 dispatcher(s) for network address '(ADDRESS=(PARTIAL=YES)(PROTOCOL=TCP))'... Tue Aug 25 19:08:52 2009 MMNL started with pid=15, OS id=12327 starting up 1 shared server(s) ... ORACLE_BASE from environment = /u01/app/oracle Tue Aug 25 19:08:53 2009 DMON started with pid=18, OS id=12333 Tue Aug 25 19:08:53 2009 ALTER DATABASE MOUNT Setting recovery target incarnation to 3 Successful mount of redo thread 1, with mount id 1223620167 Allocated 3981204 bytes in shared pool for flashback generation buffer Starting background process RVWR Tue Aug 25 19:09:00 2009 RVWR started with pid=20, OS id=12340 Database mounted in Exclusive Mode Lost write protection disabled Completed: ALTER DATABASE MOUNT Starting Data Guard Broker (DMON) Tue Aug 25 19:09:01 2009 NSV1 started with pid=21, OS id=12343 Tue Aug 25 19:09:05 2009 INSV started with pid=22, OS id=12345 Tue Aug 25 19:09:08 2009 ALTER SYSTEM SET log_archive_dest_state_2='RESET' SCOPE=BOTH; Tue Aug 25 19:09:32 2009 Using STANDBY_ARCHIVE_DEST parameter default value as /u01/app/oracle/flash_recovery_area/ORCL/ Redo Shipping Client Connected as PUBLIC -- Connected User is Valid RFS[1]: Assigned to RFS process 12448 RFS[1]: Database mount ID mismatch [0x48ef146d:0x48eef647] (1223627885:1223620167) RFS[1]: Not using real application clusters Errors in file /u01/app/oracle/diag/rdbms/orcl_primary/orcl_primary/trace/orcl_primary_rfs_12448.trc: ORA-16009: remote archive log destination must be a STANDBY database Tue Aug 25 19:09:50 2009 NSV1 started with pid=23, OS id=12456 Tue Aug 25 19:09:55 2009 RSM0 started with pid=24, OS id=12561 FLASHBACK DATABASE TO SCN 5050216 Flashback Restore Start Flashback Restore Complete Flashback Media Recovery Start Fast Parallel Media Recovery NOT enabled Flashback Media Recovery Log /u01/app/oracle/flash_recovery_area/ORCL/log_18_1_693763762.arc Flashback Media Recovery Log /u01/app/oracle/flash_recovery_area/ORCL/log_19_1_693763762.arc Flashback Media Recovery Log /u01/app/oracle/flash_recovery_area/ORCL/log_20_1_693763762.arc Flashback Media Recovery Log /u01/app/oracle/flash_recovery_area/ORCL/log_21_1_693763762.arc Recovery of Online Redo Log: Thread 1 Group 1 Seq 22 Reading mem 0 Mem# 0: /u01/app/oracle/oradata/orcl/redo01.log Recovery of Online Redo Log: Thread 1 Group 2 Seq 23 Reading mem 0 Mem# 0: /u01/app/oracle/oradata/orcl/redo02.log Recovery of Online Redo Log: Thread 1 Group 3 Seq 24 Reading mem 0 Mem# 0: /u01/app/oracle/oradata/orcl/redo03.log Incomplete Recovery applied until change 5050217 time 08/25/2009 18:54:06 Flashback Media Recovery Complete Completed: FLASHBACK DATABASE TO SCN 5050216 alter database convert to physical standby ALTER DATABASE CONVERT TO PHYSICAL STANDBY (orcl_primary) Tue Aug 25 19:10:05 2009 Clearing standby activation ID 1221597403 (0x48d018db) The primary database controlfile was created using the 'MAXLOGFILES 16' clause. There is space for up to 13 standby redo logfiles Use the following SQL commands on the standby database to create standby redo logfiles that match the primary database: ALTER DATABASE ADD STANDBY LOGFILE 'srl1.f' SIZE 52428800; ALTER DATABASE ADD STANDBY LOGFILE 'srl2.f' SIZE 52428800; ALTER DATABASE ADD STANDBY LOGFILE 'srl3.f' SIZE 52428800; ALTER DATABASE ADD STANDBY LOGFILE 'srl4.f' SIZE 52428800; Completed: alter database convert to physical standby Tue Aug 25 19:10:05 2009 Shutting down instance: further logons disabled Stopping background process MMNL Stopping background process MMON Shutting down instance (immediate) License high water mark = 2 Waiting for shared server 'S000' to die All dispatchers and shared servers shutdown alter database CLOSE NORMAL ORA-1109 signalled during: alter database CLOSE NORMAL... alter database DISMOUNT Completed: alter database DISMOUNT ARCH: Archival disabled due to shutdown: 1089 Shutting down archive processes Archiving is disabled Archive process shutdown avoided: 0 active Shutting down Data Guard Broker processes Tue Aug 25 19:10:12 2009 Completed: Data Guard Broker shutdown Tue Aug 25 19:10:13 2009 Stopping background process VKTM: ARCH: Archival disabled due to shutdown: 1089 Shutting down archive processes Archiving is disabled Archive process shutdown avoided: 0 active Tue Aug 25 19:10:15 2009 Instance shutdown complete Tue Aug 25 19:10:38 2009 Starting ORACLE instance (normal) LICENSE_MAX_SESSION = 0 LICENSE_SESSIONS_WARNING = 0 Picked latch-free SCN scheme 2 Autotune of undo retention is turned on. IMODE=BR ILAT =18 LICENSE_MAX_USERS = 0 SYS auditing is disabled Starting up ORACLE RDBMS Version: 11.1.0.6.0. Using parameter settings in server-side spfile /u01/app/oracle/product/11.1.0/db_1/dbs/spfileorcl_primary.ora System parameters with non-default values: processes = 150 sga_target = 400M control_files = "/u01/app/oracle/oradata/orcl/control01.ctl" control_files = "/u01/app/oracle/oradata/orcl/control02.ctl" control_files = "/u01/app/oracle/oradata/orcl/control03.ctl" db_block_size = 8192 compatible = "11.1.0.0.0" log_archive_config = "DG_CONFIG=(orcl_primary,orcl_stdby1)" log_archive_dest_1 = "location="/u01/app/oracle/flash_recovery_area/ORCL/"" log_archive_dest_1 = "valid_for=(ALL_LOGFILES,ALL_ROLES)" log_archive_dest_2 = "service="orcl_stdby1"" log_archive_dest_2 = " LGWR ASYNC NOAFFIRM delay=0 OPTIONAL compression=DISABLE max_failure=0 max_connections=1 reopen=300 db_unique_name="orcl_stdby1" net_timeout=30 valid_for=(online_logfile,primary_role)" log_archive_dest_state_1 = "ENABLE" log_archive_dest_state_2 = "RESET" log_archive_max_processes= 4 log_archive_min_succeed_dest= 1 log_archive_trace = 0 log_archive_format = "log_%s_%t_%r.arc" fal_client = "orcl_primary" fal_server = "orcl_stdby1" archive_lag_target = 0 db_recovery_file_dest = "/u01/app/oracle/flash_recovery_area" db_recovery_file_dest_size= 8G standby_file_management = "AUTO" undo_tablespace = "UNDOTBS1" sec_case_sensitive_logon = FALSE remote_login_passwordfile= "EXCLUSIVE" db_domain = "" dispatchers = "(PROTOCOL=TCP) (SERVICE=orclXDB)" audit_file_dest = "/u01/app/oracle/admin/orcl/adump" audit_trail = "DB" db_name = "orcl" db_unique_name = "orcl_primary" open_cursors = 300 pga_aggregate_target = 150M dg_broker_start = TRUE dg_broker_config_file1 = "/u01/app/oracle/product/11.1.0/db_1/dbs/dr1orcl_primary.dat" dg_broker_config_file2 = "/u01/app/oracle/product/11.1.0/db_1/dbs/dr2orcl_primary.dat" diagnostic_dest = "/u01/app/oracle" Tue Aug 25 19:10:40 2009 PMON started with pid=2, OS id=12668 Tue Aug 25 19:10:40 2009 VKTM started with pid=3, OS id=12670 at elevated priority VKTM running at (20)ms precision Tue Aug 25 19:10:40 2009 DIAG started with pid=4, OS id=12674 Tue Aug 25 19:10:40 2009 PSP0 started with pid=6, OS id=12678 Tue Aug 25 19:10:40 2009 DBRM started with pid=5, OS id=12676 Tue Aug 25 19:10:40 2009 DSKM started with pid=7, OS id=12680 Tue Aug 25 19:10:40 2009 MMAN started with pid=7, OS id=12684 Tue Aug 25 19:10:40 2009 DBW0 started with pid=9, OS id=12686 Tue Aug 25 19:10:40 2009 DIA0 started with pid=8, OS id=12682 Tue Aug 25 19:10:40 2009 LGWR started with pid=10, OS id=12688 Tue Aug 25 19:10:40 2009 CKPT started with pid=11, OS id=12690 Tue Aug 25 19:10:41 2009 SMON started with pid=12, OS id=12692 Tue Aug 25 19:10:41 2009 MMON started with pid=14, OS id=12696 starting up 1 dispatcher(s) for network address '(ADDRESS=(PARTIAL=YES)(PROTOCOL=TCP))'... Tue Aug 25 19:10:41 2009 MMNL started with pid=15, OS id=12698 Tue Aug 25 19:10:41 2009 RECO started with pid=13, OS id=12694 starting up 1 shared server(s) ... ORACLE_BASE from environment = /u01/app/oracle Tue Aug 25 19:10:42 2009 DMON started with pid=18, OS id=12704 Tue Aug 25 19:10:42 2009 ALTER DATABASE MOUNT Setting recovery target incarnation to 3 ARCH: STARTING ARCH PROCESSES Tue Aug 25 19:10:48 2009 ARC1 started with pid=21, OS id=12713 ARC0: Archival started ARC1: Archival started ARC2: Archival started ARC3: Archival started ARCH: STARTING ARCH PROCESSES COMPLETE Tue Aug 25 19:10:49 2009 ARC3 started with pid=23, OS id=12717 ARC3: Becoming the 'no FAL' ARCH ARC3: Becoming the 'no SRL' ARCH ARC3: Thread not mounted Tue Aug 25 19:10:49 2009 ARC2 started with pid=22, OS id=12715 ARC2: Becoming the heartbeat ARCH Tue Aug 25 19:10:49 2009 ARC0 started with pid=20, OS id=12711 ARC0: Thread not mounted ARC1: Thread not mounted ARC2: Thread not mounted Successful mount of redo thread 1, with mount id 1223590068 Allocated 3981204 bytes in shared pool for flashback generation buffer Starting background process RVWR Tue Aug 25 19:10:49 2009 RVWR started with pid=24, OS id=12719 Physical Standby Database mounted. Lost write protection disabled Completed: ALTER DATABASE MOUNT Starting Data Guard Broker (DMON) Tue Aug 25 19:10:50 2009 INSV started with pid=25, OS id=12722 Tue Aug 25 19:11:27 2009 NSV1 started with pid=27, OS id=12901 Tue Aug 25 19:11:32 2009 RSM0 started with pid=28, OS id=12932 Using STANDBY_ARCHIVE_DEST parameter default value as /u01/app/oracle/flash_recovery_area/ORCL/ ALTER SYSTEM SET log_archive_dest_1='location="/u01/app/oracle/flash_recovery_area/ORCL/"','valid_for=(ALL_LOGFILES,ALL_ROLES)' SCOPE=BOTH SID='orcl_primary'; ALTER SYSTEM SET log_archive_dest_state_1='ENABLE' SCOPE=BOTH SID='orcl_primary'; ALTER SYSTEM SET log_archive_trace=0 SCOPE=BOTH SID='orcl_primary'; ALTER SYSTEM SET log_archive_format='log_%s_%t_%r.arc' SCOPE=SPFILE SID='orcl_primary'; ALTER SYSTEM SET standby_file_management='AUTO' SCOPE=BOTH SID='*'; ALTER SYSTEM SET archive_lag_target=0 SCOPE=BOTH SID='*'; ALTER SYSTEM SET log_archive_max_processes=4 SCOPE=BOTH SID='*'; ALTER SYSTEM SET log_archive_min_succeed_dest=1 SCOPE=BOTH SID='*'; ALTER SYSTEM SET fal_server='orcl_stdby1' SCOPE=BOTH; ALTER SYSTEM SET fal_client='orcl_primary' SCOPE=BOTH; ALTER DATABASE RECOVER MANAGED STANDBY DATABASE THROUGH ALL SWITCHOVER DISCONNECT USING CURRENT LOGFILE Attempt to start background Managed Standby Recovery process (orcl_primary) Tue Aug 25 19:11:38 2009 MRP0 started with pid=29, OS id=12934 MRP0: Background Managed Standby Recovery process started (orcl_primary) Fast Parallel Media Recovery enabled Managed Standby Recovery starting Real Time Apply parallel recovery started with 2 processes Waiting for all non-current ORLs to be archived... Clearing online redo logfile 1 /u01/app/oracle/oradata/orcl/redo01.log Clearing online log 1 of thread 1 sequence number 22 Tue Aug 25 19:11:45 2009 Completed: ALTER DATABASE RECOVER MANAGED STANDBY DATABASE THROUGH ALL SWITCHOVER DISCONNECT USING CURRENT LOGFILE Clearing online redo logfile 1 complete Clearing online redo logfile 2 /u01/app/oracle/oradata/orcl/redo02.log Clearing online log 2 of thread 1 sequence number 23 Tue Aug 25 19:11:50 2009 Clearing online redo logfile 2 complete Clearing online redo logfile 3 /u01/app/oracle/oradata/orcl/redo03.log Clearing online log 3 of thread 1 sequence number 24 Tue Aug 25 19:11:51 2009 Redo Shipping Client Connected as PUBLIC -- Connected User is Valid RFS[1]: Assigned to RFS process 12948 RFS[1]: Identified database type as 'physical standby' Tue Aug 25 19:11:51 2009 Redo Shipping Client Connected as PUBLIC -- Connected User is Valid RFS[2]: Assigned to RFS process 12950 RFS[2]: Identified database type as 'physical standby' Primary database is in MAXIMUM PERFORMANCE mode RFS LogMiner: Client disabled from further notification Primary database is in MAXIMUM PERFORMANCE mode RFS[1]: Successfully opened standby log 5: '/u01/app/oracle/oradata/orcl/srl02.log' kcrrvslf: active RFS archival for log 5 thread 1 sequence 1 RFS[2]: Successfully opened standby log 4: '/u01/app/oracle/oradata/orcl/srl01.log' Clearing online redo logfile 3 complete RFS[1]: Detected missing archivals for Branch(resetlogs_id): 693763762 RFS[1]: Last archived SCN: 0:5050137 Last change SCN: 0:5050219 RFS[1]: New Archival REDO Branch(resetlogs_id): 695847608 Prior: 693763762 RFS[1]: Archival Activation ID: 0x48ef146d Current: 0x0 RFS[1]: Effect of primary database OPEN RESETLOGS RFS[1]: Managed Standby Recovery process is active Setting recovery target incarnation to 4 RFS[1]: Incarnation entry added for Branch(resetlogs_id): 695847608 (orcl_primary) Tue Aug 25 19:11:58 2009 Setting recovery target incarnation to 4 Media Recovery Waiting for thread 1 sequence 24 MRP0: Incarnation has changed! Retry recovery... Errors in file /u01/app/oracle/diag/rdbms/orcl_primary/orcl_primary/trace/orcl_primary_mrp0_12934.trc: ORA-19906: recovery target incarnation changed during recovery Managed Standby Recovery not using Real Time Apply Shutting down recovery slaves due to error 19906 Recovery interrupted! Errors in file /u01/app/oracle/diag/rdbms/orcl_primary/orcl_primary/trace/orcl_primary_mrp0_12934.trc: ORA-19906: recovery target incarnation changed during recovery Tue Aug 25 19:12:19 2009 Fast Parallel Media Recovery enabled Managed Standby Recovery starting Real Time Apply parallel recovery started with 2 processes Media Recovery start incarnation depth : 1, target inc# : 4, irscn : 5050219 Waiting for all non-current ORLs to be archived... Media Recovery Waiting for thread 1 sequence 24 branch(resetlogs_id) 693763762 Tue Aug 25 19:12:44 2009 Redo Shipping Client Connected as PUBLIC -- Connected User is Valid RFS[3]: Assigned to RFS process 13154 RFS[3]: Identified database type as 'physical standby' RFS[3]: Archived Log: '/u01/app/oracle/flash_recovery_area/ORCL/log_24_1_693763762.arc' Tue Aug 25 19:12:51 2009 Media Recovery Log /u01/app/oracle/flash_recovery_area/ORCL/log_24_1_693763762.arc Identified End-Of-Redo for thread 1 sequence 24 Resetting standby activation ID 1221597403 (0x48d018db) Media Recovery End-Of-Redo indicator encountered Media Recovery Continuing Media Recovery Log /u01/app/oracle/flash_recovery_area/ORCL/log_1_1_695847608.arc Media Recovery Waiting for thread 1 sequence 2 (in transit) Recovery of Online Redo Log: Thread 1 Group 4 Seq 2 Reading mem 0 Mem# 0: /u01/app/oracle/oradata/orcl/srl01.log Tue Aug 25 19:14:39 2009 Primary database is in MAXIMUM PERFORMANCE mode Tue Aug 25 19:14:39 2009 Media Recovery Waiting for thread 1 sequence 3 (in transit) kcrrvslf: active RFS archival for log 4 thread 1 sequence 2 RFS[2]: Successfully opened standby log 5: '/u01/app/oracle/oradata/orcl/srl02.log' Recovery of Online Redo Log: Thread 1 Group 5 Seq 3 Reading mem 0 Mem# 0: /u01/app/oracle/oradata/orcl/srl02.log Media Recovery Waiting for thread 1 sequence 4 Tue Aug 25 19:14:49 2009 Primary database is in MAXIMUM PERFORMANCE mode