一次rman恢复引起的nologging问题模拟

测试环境：

Oracle 11.2.0.4

SLES 11SP4

结论如下：

no force logging,table nologging, dml nologging，出现ora-26040

no force logging,table logging, dml nologging，未出现ora-26040

force logging, table nologging/logging dml nologging,未出现ora-26040

force logging, table nologging dml logging,未出现ora-26040

force logging, table nologging dml nologging,未出现ora-26040

只有在no force logging、table nologging、dml nologging情况下，recover数据会出现ora-26040,其他情况均正常。

测试过程如下：

sql> create table gyc01 as select * from dba_users;

Table created.

sql> insert into gyc01 select * from gyc01;

30 rows created.

sql> /

60 rows created.

sql> commit;

Commit complete.

sql> select table_name,logging from dba_tables where table_name='GYC01';

TABLE_NAME LOG

------------------------------ ---

GYC01 YES

sql> alter table gyc01 nologging;

Table altered.

sql> select table_name,logging from dba_tables where table_name='GYC01';

TABLE_NAME LOG

------------------------------ ---

GYC01 NO

sql> select count(*) from gyc01;

COUNT(*)

----------

120

sql> alter system switch logfile;

System altered.

sql> /

System altered.

sql> /

System altered.

sql> create table gyc02 nologging as select * from gyc01 nologging;

Table created.

sql> select count(*) from gyc02;

COUNT(*)

----------

120

sql> insert into gyc02 select * from gyc01 nologging;

120 rows created.

sql> commit;

Commit complete.

sql> alter system switch logfile;

System altered.

sql> /

System altered.

sql> /

System altered.

sql>

System altered.

sql> shutdown immediate^Cs

sql>

sql> select name from v$datafile;

NAME

--------------------------------------------------------------------------------

/app/oracle/product/10.2.0/oradata/gycdb/system01.dbf

/app/oracle/product/10.2.0/oradata/gycdb/sysaux01.dbf

/app/oracle/product/10.2.0/oradata/gycdb/undotbs01.dbf

/app/oracle/product/10.2.0/oradata/gycdb/users01.dbf

sql> shutdown immediate

;Database closed.

Database dismounted.

ORACLE instance shut down.

sql> startup mount;

SP2-0734: unkNown command beginning ";startup m..." - rest of line ignored.

sql>

sql> startup

ORA-00443: background process "VKTM" did not start

sql> sql> sql> shutdown immediate;

ORA-01012: not logged on

sql> conn / as sysdba

Connected to an idle instance.

sql> startup mount;

ORACLE instance started.

Total System Global Area 2137886720 bytes

Fixed Size 2230072 bytes

Variable Size 503318728 bytes

Database Buffers 1627389952 bytes

Redo Buffers 4947968 bytes

RMAN> recover database;

Starting recover at 07-MAY-18

using channel ORA_disK_1

starting media recovery

archived log for thread 1 with sequence 4 is already on disk as file /arch/1_4_975518966.dbf

archived log for thread 1 with sequence 5 is already on disk as file /arch/1_5_975518966.dbf

archived log for thread 1 with sequence 6 is already on disk as file /arch/1_6_975518966.dbf

archived log for thread 1 with sequence 7 is already on disk as file /arch/1_7_975518966.dbf

archived log for thread 1 with sequence 8 is already on disk as file /arch/1_8_975518966.dbf

archived log for thread 1 with sequence 9 is already on disk as file /arch/1_9_975518966.dbf

archived log for thread 1 with sequence 10 is already on disk as file /arch/1_10_975518966.dbf

archived log file name=/arch/1_4_975518966.dbf thread=1 sequence=4

archived log file name=/arch/1_5_975518966.dbf thread=1 sequence=5

archived log file name=/arch/1_6_975518966.dbf thread=1 sequence=6

archived log file name=/arch/1_7_975518966.dbf thread=1 sequence=7

archived log file name=/arch/1_8_975518966.dbf thread=1 sequence=8

archived log file name=/arch/1_9_975518966.dbf thread=1 sequence=9

archived log file name=/arch/1_10_975518966.dbf thread=1 sequence=10

RMAN-00571: ===========================================================

RMAN-00569: =============== ERROR MESSAGE STACK FOLLOWS ===============

RMAN-00571: ===========================================================

RMAN-03002: failure of recover command at 05/07/2018 18:46:50

ORA-00283: recovery session canceled due to errors

RMAN-11003: failure during parse/execution of sql statement: alter database recover logfile '/arch/1_10_975518966.dbf'

ORA-00283: recovery session canceled due to errors

ORA-00313: open Failed for members of log group 2 of thread 1

ORA-00312: online log 2 thread 1: '/app/oracle/product/10.2.0/oradata/gycdb/redo02.log'

ORA-27037: unable to obtain file status

Linux-x86_64 Error: 2: No such file or directory

Additional information: 3

RMAN> recover database;

Starting recover at 07-MAY-18

using channel ORA_disK_1

starting media recovery

media recovery complete, elapsed time: 00:00:00

Finished recover at 07-MAY-18

RMAN>

Database mounted.

sql> alter database open;

Database altered.

sql> select count(*) from gyc02;

select count(*) from gyc02

ERROR at line 1:

ORA-01578: ORACLE data block corrupted (file # 1, block # 89241)

ORA-01110: data file 1: '/app/oracle/product/10.2.0/oradata/gycdb/system01.dbf'

ORA-26040: Data block was loaded using the NOLOGGING option

sql> select count(*) from gyc01;

COUNT(*)

----------

120

==========================结论：no force logging,table nologging, dml nologging，出现ora-26040

RMAN> restore database;

Starting restore at 07-MAY-18

allocated channel: ORA_disK_1

channel ORA_disK_1: SID=189 device type=disK

channel ORA_disK_1: starting datafile backup set restore

channel ORA_disK_1: specifying datafile(s) to restore from backup set

channel ORA_disK_1: restoring datafile 00001 to /app/oracle/product/10.2.0/oradata/gycdb/system01.dbf

channel ORA_disK_1: restoring datafile 00002 to /app/oracle/product/10.2.0/oradata/gycdb/sysaux01.dbf

channel ORA_disK_1: restoring datafile 00003 to /app/oracle/product/10.2.0/oradata/gycdb/undotbs01.dbf

channel ORA_disK_1: restoring datafile 00004 to /app/oracle/product/10.2.0/oradata/gycdb/users01.dbf

channel ORA_disK_1: reading from backup piece /backup/gycdb_01t2afu8_1_1.bak

channel ORA_disK_1: piece handle=/backup/gycdb_01t2afu8_1_1.bak tag=TAG20180507T174128

channel ORA_disK_1: restored backup piece 1

channel ORA_disK_1: restore complete, elapsed time: 00:00:07

Finished restore at 07-MAY-18

RMAN> recover database;

Starting recover at 07-MAY-18

using channel ORA_disK_1

starting media recovery

archived log for thread 1 with sequence 4 is already on disk as file /arch/1_4_975518966.dbf

archived log for thread 1 with sequence 5 is already on disk as file /arch/1_5_975518966.dbf

archived log for thread 1 with sequence 6 is already on disk as file /arch/1_6_975518966.dbf

archived log for thread 1 with sequence 7 is already on disk as file /arch/1_7_975518966.dbf

archived log for thread 1 with sequence 8 is already on disk as file /arch/1_8_975518966.dbf

archived log for thread 1 with sequence 9 is already on disk as file /arch/1_9_975518966.dbf

archived log for thread 1 with sequence 10 is already on disk as file /arch/1_10_975518966.dbf

archived log for thread 1 with sequence 11 is already on disk as file /arch/1_11_975518966.dbf

archived log for thread 1 with sequence 12 is already on disk as file /arch/1_12_975518966.dbf

archived log for thread 1 with sequence 13 is already on disk as file /arch/1_13_975518966.dbf

archived log for thread 1 with sequence 14 is already on disk as file /arch/1_14_975518966.dbf

archived log file name=/arch/1_4_975518966.dbf thread=1 sequence=4

archived log file name=/arch/1_5_975518966.dbf thread=1 sequence=5

archived log file name=/arch/1_6_975518966.dbf thread=1 sequence=6

archived log file name=/arch/1_7_975518966.dbf thread=1 sequence=7

archived log file name=/arch/1_8_975518966.dbf thread=1 sequence=8

archived log file name=/arch/1_9_975518966.dbf thread=1 sequence=9

archived log file name=/arch/1_10_975518966.dbf thread=1 sequence=10

archived log file name=/arch/1_11_975518966.dbf thread=1 sequence=11

archived log file name=/arch/1_12_975518966.dbf thread=1 sequence=12

media recovery complete, elapsed time: 00:00:01

Finished recover at 07-MAY-18

sql> create table gyc03 as select * from gyc01;

Table created.

sql> alter system switch logfile;

System altered.

sql> select table_name,logging from dba_tables where table_name like 'GYC%';

TABLE_NAME LOG

------------------------------ ---

GYC03 YES

GYC01 NO

sql> truncate table gyc03;

Table truncated.

sql> insert into gyc03 select * from gyc01 nologging;

120 rows created.

sql> /

120 rows created.

sql> /

120 rows created.

sql> commit;

Commit complete.

sql> alter system switch logfile;

System altered.

sql> /

System altered.

sql> /

System altered.

sql> shutdown immediate

Database closed.

Database dismounted.

ORACLE instance shut down.

sql> startup mount;

ORACLE instance started.

Total System Global Area 2137886720 bytes

Fixed Size 2230072 bytes

Variable Size 503318728 bytes

Database Buffers 1627389952 bytes

Redo Buffers 4947968 bytes

Database mounted.

sql> alter database open;

Database altered.

sql> select count(*) from gyc03;

COUNT(*)

----------

360

sql> c/3/1

1* select count(*) from gyc01

sql> /

COUNT(*)

----------

120

sql>

==========================结论：no force logging,table logging, dml nologging，未出现ora-26040

force logging table nologging dml nologging

sql> select FORCE_LOGGING from v$database;

FOR

---

sql> select table_name,logging from dba_tables where table_name like 'GYC%';

TABLE_NAME LOG

------------------------------ ---

GYC01 NO

GYC03 YES

sql> create table gyc02 nologging as select * from gyc01;

Table created.

sql> select table_name,logging from dba_tables where table_name like 'GYC%';

TABLE_NAME LOG

------------------------------ ---

GYC01 NO

GYC03 YES

GYC02 NO

sql> alter system switch logfie;

alter system switch logfie

ERROR at line 1:

ORA-01900: LOGFILE keyword expected

sql> alter system switch logfile;

System altered.

sql> insert into gyc02 select * from gyc03 nologging^C

sql>

sql> alter database force logging;

Database altered.

sql> select FORCE_LOGGING from v$database;

FOR

---

YES

sql> select table_name,logging from dba_tables where table_name like 'GYC%';

TABLE_NAME LOG

------------------------------ ---

GYC01 NO

GYC03 YES

GYC02 NO

sql> select count(*) from gyc02;

COUNT(*)

----------

120

sql> truncate table gyc02;

Table truncated.

sql> select count(*) from gyc02;

COUNT(*)

----------

sql> insert into gyc02 select * from gyc03 nologging;

360 rows created.

sql> /

360 rows created.

sql> commit;

Commit complete.

sql> alter system switch logfile;

System altered.

sql> alter system switch logfile;

alter system switch logfile;

System altered.

sql>

System altered.

sql>

sql> create table gyc04 as select * from gyc01 where 1=2;

Table created.

sql> select table_name,logging from dba_tables where table_name like 'GYC%';

TABLE_NAME LOG

------------------------------ ---

GYC01 NO

GYC04 YES

GYC03 YES

GYC02 NO

sql> select count(*) from gyc04;

COUNT(*)

----------

sql> insert into gyc04 select * from gyc03 nologging;

360 rows created.

sql> /

360 rows created.

sql> commit;

Commit complete.

sql> alter system switch logfile;

System altered.

sql> /

System altered.

sql> shutdown immediate

Database closed.

Database dismounted.

ORACLE instance shut down.

sql> startup mount;

ORACLE instance started.

Total System Global Area 2137886720 bytes

Fixed Size 2230072 bytes

Variable Size 503318728 bytes

Database Buffers 1627389952 bytes

Redo Buffers 4947968 bytes

Database mounted.

sql>

Recovery Manager complete.

[oracle@DBA180321R00P ~]$ rman target /

Recovery Manager: Release 11.2.0.3.0 - Production on Tue May 8 10:38:24 2018

connected to target database: GYCDB (DBID=1624787828, not open)

RMAN> restore database;

Starting restore at 08-MAY-18

using target database control file instead of recovery catalog

allocated channel: ORA_disK_1

channel ORA_disK_1: SID=189 device type=disK

channel ORA_disK_1: starting datafile backup set restore

channel ORA_disK_1: specifying datafile(s) to restore from backup set

channel ORA_disK_1: restoring datafile 00001 to /app/oracle/product/10.2.0/oradata/gycdb/system01.dbf

channel ORA_disK_1: restoring datafile 00002 to /app/oracle/product/10.2.0/oradata/gycdb/sysaux01.dbf

channel ORA_disK_1: restoring datafile 00003 to /app/oracle/product/10.2.0/oradata/gycdb/undotbs01.dbf

channel ORA_disK_1: restoring datafile 00004 to /app/oracle/product/10.2.0/oradata/gycdb/users01.dbf

channel ORA_disK_1: reading from backup piece /backup/gycdb_01t2afu8_1_1.bak

channel ORA_disK_1: piece handle=/backup/gycdb_01t2afu8_1_1.bak tag=TAG20180507T174128

channel ORA_disK_1: restored backup piece 1

channel ORA_disK_1: restore complete, elapsed time: 00:00:03

Finished restore at 08-MAY-18

RMAN> recover database;

Starting recover at 08-MAY-18