Very Very slow restore with SMO

[pinrell]

Hello

I own a AIX 5.3 TL9 with Java 1.4.2, Oracle version 11g,  Smo 3.2 and SnapDrive 4.2,

I can say that the backups are done well, but we have made a FULL test Oracle Restore Control Files and now takes about 6 hours.

Anyone know what might be going to go so slow restoration.

thanks

Note:

Each copy of Data is taking about 40 minutes and is only 12 Gb each Data

_____________________________________________________________________________________

--[ INFO] SMO-13036: Starting operation Backup Restore on host saptest2

--[ INFO] SMO-13046: Operation GUID 402881ab399b23ea01399b23f49f0001 starting on Profile BCKT02

--[ INFO] SMO-07431: Saving starting state of the database: T02(SHUTDOWN).

--[ INFO] ORACLE-20000: Changing state for database instance T02 from SHUTDOWN to STARTED.

--[ INFO] SMO-07431: Saving starting state of the database: T02(SHUTDOWN).

--[ INFO] SMO-07127: Locked database for SnapManager operations - created lock file "/oracle/T02/11203/dbs/.sm_lock_T02" on host saptest2

--[ INFO] SMO-07200: Beginning restore of database "T02".

--[ INFO] SD-00022: Querying for snapshot NETAPP1:/vol/volSaptest02BinSas:smo_bckt02_t02_f_h_2_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00023: Finished querying for snapshot NETAPP1:/vol/volSaptest02BinSas:smo_bckt02_t02_f_h_2_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00022: Querying for snapshot NETAPP1:/vol/volSaptest02DafileSas:smo_bckt02_t02_f_h_2_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00023: Finished querying for snapshot NETAPP1:/vol/volSaptest02DafileSas:smo_bckt02_t02_f_h_2_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00022: Querying for snapshot NETAPP1:/vol/volSaptest02ArchiveSas:smo_bckt02_t02_f_h_2_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00023: Finished querying for snapshot NETAPP1:/vol/volSaptest02ArchiveSas:smo_bckt02_t02_f_h_2_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00022: Querying for snapshot NETAPP1:/vol/volSaptest02RedoSas:smo_bckt02_t02_f_h_2_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00023: Finished querying for snapshot NETAPP1:/vol/volSaptest02RedoSas:smo_bckt02_t02_f_h_2_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00016: Discovering storage resources for /oracle/T02.

--[ INFO] SD-00017: Finished storage discovery for /oracle/T02.

--[ INFO] SD-00016: Discovering storage resources for /oracle/T02/origlogA.

--[ INFO] SD-00017: Finished storage discovery for /oracle/T02/origlogA.

--[ INFO] SD-00040: Beginning to discover filesystem(s) upon host volume group saptest02redovg.

--[ INFO] SD-00041: Finished discovering filesystem(s) upon host volume group saptest02redovg.

--[ INFO] SD-00040: Beginning to discover filesystem(s) upon host volume group saptest02vg.

--[ INFO] SD-00041: Finished discovering filesystem(s) upon host volume group saptest02vg.

--[ INFO] SD-00040: Beginning to discover filesystem(s) upon host volume group saptest02binvg.

--[ INFO] SD-00041: Finished discovering filesystem(s) upon host volume group saptest02binvg.

--[ INFO] SD-00025: Beginning to connect filesystem(s) [/oracle/T02, /oracle/T02/origlogA, /oracle/T02/sapdata1] from snapshot smo_bckt02_t02_f_h_2_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00016: Discovering storage resources for /opt/Ontap/smo/mnt/-oracle-T02-20120906123503721_0.

--[ INFO] SD-00017: Finished storage discovery for /opt/Ontap/smo/mnt/-oracle-T02-20120906123503721_0.

--[ INFO] SD-00016: Discovering storage resources for /opt/Ontap/smo/mnt/-oracle-T02-origlogA-20120906123503710_0.

--[ INFO] SD-00017: Finished storage discovery for /opt/Ontap/smo/mnt/-oracle-T02-origlogA-20120906123503710_0.

--[ INFO] SD-00016: Discovering storage resources for /opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906123503719_0.

--[ INFO] SD-00017: Finished storage discovery for /opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906123503719_0.

--[ INFO] SD-00026: Finished connecting filesystem(s) [/oracle/T02, /oracle/T02/origlogA, /oracle/T02/sapdata1] from snapshot smo_bckt02_t02_f_h_2_402881ab399abe8701399abe90040001_0.

--[ INFO] PLAT-00001: Copying file "/opt/Ontap/smo/mnt/-oracle-T02-20120906123503721_0/saparch/cntrl/SMOBakCtl_1346920993479_2" to "/oracle/T02/saparch/cntrl/SMOBakCtl_1346920993479_2".

--[ INFO] PLAT-00001: Copying file "/opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906123503719_0/system_1/cntrl/SMOBakCtl_1346920992408_0" to "/oracle/T02/sapdata1/system_1/cntrl/SMOBakCtl_1346920992408_0".

--[ INFO] PLAT-00001: Copying file "/opt/Ontap/smo/mnt/-oracle-T02-origlogA-20120906123503710_0/cntrl/SMOBakCtl_1346920992976_1" to "/oracle/T02/origlogA/cntrl/SMOBakCtl_1346920992976_1".

--[ INFO] SD-00016: Discovering storage resources for saptest02vg_0.

--[ INFO] SD-00017: Finished storage discovery for saptest02vg_0.

--[ INFO] SD-00016: Discovering storage resources for saptest02redo_0.

--[ INFO] SD-00017: Finished storage discovery for saptest02redo_0.

--[ INFO] SD-00016: Discovering storage resources for saptest02binv_0.

--[ INFO] SD-00017: Finished storage discovery for saptest02binv_0.

--[ INFO] SD-00037: Beginning to disconnect volume groups [saptest02redo_0, saptest02vg_0, saptest02binv_0].

--[ INFO] SD-00038: Finished disconnecting volume groups [saptest02redo_0, saptest02vg_0, saptest02binv_0].

--[ INFO] PLAT-00001: Copying file "/oracle/T02/saparch/cntrl/SMOBakCtl_1346920993479_2" to "/oracle/T02/saparch/cntrl/cntrlT02.dbf".

--[ INFO] PLAT-00001: Copying file "/oracle/T02/saparch/cntrl/SMOBakCtl_1346920993479_2" to "/oracle/T02/sapdata1/system_1/cntrl/cntrlT02.dbf".

--[ INFO] PLAT-00001: Copying file "/oracle/T02/saparch/cntrl/SMOBakCtl_1346920993479_2" to "/oracle/T02/origlogA/cntrl/cntrlT02.dbf".

--[ INFO] ORACLE-20000: Changing state for database instance T02 from STARTED to MOUNTED.

--[ INFO] ORACLE-20009: Attempting to reconnect to instance T02 after shutdown/startup.

--[ INFO] ORACLE-20011: Reconnect to instance T02 successful.

--[ INFO] SD-00022: Querying for snapshot NETAPP1:/vol/volSaptest02DafileSas:smo_bckt02_t02_f_h_1_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00023: Finished querying for snapshot NETAPP1:/vol/volSaptest02DafileSas:smo_bckt02_t02_f_h_1_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00016: Discovering storage resources for /oracle/T02/sapdata1.

--[ INFO] SD-00017: Finished storage discovery for /oracle/T02/sapdata1.

--[ INFO] SD-00016: Discovering storage resources for /oracle/T02/sapdata1.

--[ INFO] SD-00017: Finished storage discovery for /oracle/T02/sapdata1.

--[ INFO] SD-00016: Discovering storage resources for /oracle/T02/origlogB.

--[ INFO] SD-00017: Finished storage discovery for /oracle/T02/origlogB.

--[ INFO] SD-00016: Discovering storage resources for /oracle/T02/mirrlogA.

--[ INFO] SD-00017: Finished storage discovery for /oracle/T02/mirrlogA.

--[ INFO] SD-00016: Discovering storage resources for /oracle/T02.

--[ INFO] SD-00017: Finished storage discovery for /oracle/T02.

--[ INFO] SD-00016: Discovering storage resources for /oracle/T02/mirrlogB.

--[ INFO] SD-00017: Finished storage discovery for /oracle/T02/mirrlogB.

--[ INFO] SD-00016: Discovering storage resources for /oracle/T02/origlogA.

--[ INFO] SD-00017: Finished storage discovery for /oracle/T02/origlogA.

--[ INFO] SD-00040: Beginning to discover filesystem(s) upon host volume group saptest02redovg.

--[ INFO] SD-00041: Finished discovering filesystem(s) upon host volume group saptest02redovg.

--[ INFO] SD-00040: Beginning to discover filesystem(s) upon host volume group saptest02vg.

--[ INFO] SD-00041: Finished discovering filesystem(s) upon host volume group saptest02vg.

--[ INFO] SD-00040: Beginning to discover filesystem(s) upon host volume group saptest02binvg.

--[ INFO] SD-00041: Finished discovering filesystem(s) upon host volume group saptest02binvg.

--[ INFO] SD-00025: Beginning to connect filesystem(s) [/oracle/T02/sapdata1] from snapshot smo_bckt02_t02_f_h_1_402881ab399abe8701399abe90040001_0.

--[ INFO] SD-00016: Discovering storage resources for /opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906124052655_1.

--[ INFO] SD-00017: Finished storage discovery for /opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906124052655_1.

--[ INFO] SD-00026: Finished connecting filesystem(s) [/oracle/T02/sapdata1] from snapshot smo_bckt02_t02_f_h_1_402881ab399abe8701399abe90040001_0.

--[ INFO] PLAT-00001: Copying file "/opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906124052655_1/p01_3/p01.data3" to "/oracle/T02/sapdata1/p01_3/p01.data3".

--[ INFO] PLAT-00001: Copying file "/opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906124052655_1/p01_4/p01.data4" to "/oracle/T02/sapdata1/p01_4/p01.data4".

--[ INFO] PLAT-00001: Copying file "/opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906124052655_1/p01_5/p01.data5" to "/oracle/T02/sapdata1/p01_5/p01.data5".

--[ INFO] PLAT-00001: Copying file "/opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906124052655_1/p01_12/p01.data12" to "/oracle/T02/sapdata1/p01_12/p01.data12".

--[ INFO] PLAT-00001: Copying file "/opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906124052655_1/p01_10/p01.data10" to "/oracle/T02/sapdata1/p01_10/p01.data10".

--[ INFO] PLAT-00001: Copying file "/opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906124052655_1/p01_6/p01.data6" to "/oracle/T02/sapdata1/p01_6/p01.data6".

--[ INFO] PLAT-00001: Copying file "/opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906124052655_1/p01_11/p01.data11" to "/oracle/T02/sapdata1/p01_11/p01.data11".

--[ INFO] PLAT-00001: Copying file "/opt/Ontap/smo/mnt/-oracle-T02-sapdata1-20120906124052655_1/p01_7/p01.data7" to "/oracle/T02/sapdata1/p01_7/p01.data7".

Each copy of Data is taking about 40 minutes and is only 12 Mb each Data

[Mathias_Robichon]

Hi,

Basically, I've been on the same problem since yesterday. IHAC encountering the same problem.

The root cause seems to be with mount options. Data files are mounted with option "cio". When the SnapShot is connected (with this option) the copy is very slow (sequential access are slower).

I don't have any idea how get around it.

Regards,

Mathias

[pinrell]

hello mrobicho

Indeed mount points were with the "CIO", we proceeded to change the file "filesystems", dismount and mount the mount points

Nowtherestorehas improved,the transfer rateis 1.5minutes the 1 GB of data.

We have won twice as long. !!

We have seen that done a lot of paging and do not know if there is any way to improve paging.

thanks