[ INFO] SMO-13036: Starting operation Backup Restore on host xxxxxxx [ INFO] SMO-13036: Starting operation Backup Restore on host xxxxxxx [ INFO] SMO-13046: Operation GUID 402882b44ff9a00f014ff9a014440001 starting on Profile ERMESTEL [ INFO] SMO-22001: Started adding the Backup Restore operation in history. [ INFO] SMO-07431: Saving starting state of the database: ermestel1(SHUTDOWN). [ INFO] ORACLE-20000: Changing state for database instance ermestel1 from SHUTDOWN to STARTED. [ INFO] SMO-07431: Saving starting state of the database: ermestel1(SHUTDOWN), ermestel2(SHUTDOWN), ermestel3(SHUTDOWN). [ INFO] SMO-07127: Locked database for SnapManager operations - created lock file "/u01/app/oracle/product/11.2.0/db/dbs/.sm_lock_ermestel" on host xxxxxxx. [ INFO] SMO-07200: Beginning restore of database "ERMESTEL". [ INFO] SD-00022: Querying for snapshot SVM_iSCSI:/vol/LUN_PRUEBA:smo_ermestel_ermestel1_f_h_2_402882b44ff5d8e9014ff5d8ee430001_0. [ INFO] SD-00023: Finished querying for snapshot SVM_iSCSI:/vol/LUN_PRUEBA:smo_ermestel_ermestel1_f_h_2_402882b44ff5d8e9014ff5d8ee430001_0. [ INFO] SD-00019: Discovering storage resources for all system devices. [ INFO] SD-00020: Finished storage discovery for all system devices. [ INFO] SD-00028: Beginning to connect device(s) [/dev/mapper/3600a0980383030536c5d45716a6b6d57] from snapshot smo_ermestel_ermestel1_f_h_2_402882b44ff5d8e9014ff5d8ee430001_0. [ INFO] SD-00029: Finished connecting device(s) [/dev/mapper/3600a0980383030536c5d45716a6b6d57] from snapshot smo_ermestel_ermestel1_f_h_2_402882b44ff5d8e9014ff5d8ee430001_0. [ INFO] ASM-10002: Assigning ASMLib disk identifier SM_DSK_1443002096200 to device /dev/mapper/3600a0980383030536c5d45716a6b6d6b. [ INFO] ASM-00500: Changing ASM Disk Group name in header of disk /dev/mapper/3600a0980383030536c5d45716a6b6d6b from PRUEBA_NETAPP to SM_DG_1443002096229. [ INFO] ASM-00000: Mounting ASM Disk Group SM_DG_1443002096229 on host xxxxxxx. [ INFO] ASM-00008: Querying ASM extent map for file +PRUEBA_NETAPP/ERMESTEL/CONTROLFILE/smobakctl_1442938733519_0. [ INFO] ASM-00008: Querying ASM extent map for file +SM_DG_1443002096229/ERMESTEL/CONTROLFILE/SMOBakCtl_1442938733519_0. [ INFO] RES-10002: Extent map for active ASM file +PRUEBA_NETAPP/ermestel/controlfile/SMOBakCtl_1442938733519_0 does not match extent map for snapshot ASM file +SM_DG_1443002096229/ermestel/controlfile/SMOBakCtl_1442938733519_0. [ INFO] ORACLE-00111: Restoring file via RMAN from location +SM_DG_1443002096229/ermestel/controlfile/SMOBakCtl_1442938733519_0 to location +PRUEBA_NETAPP/ermestel/controlfile/SMOBakCtl_1442938733519_0. [ INFO] ASM-00002: Dropping ASM Disk Group SM_DG_1443002096229. [ERROR] FLOW-11019: Failure in ExecuteRestoreSteps: ORACLE-10003: Error executing SQL "DROP DISKGROUP SM_DG_1443002096229 INCLUDING CONTENTS" against Oracle database +ASM1: ORA-15039: diskgroup not dropped ORA-15027: active use of diskgroup "SM_DG_1443002096229" precludes its dismount [ERROR] FLOW-11010: Operation transitioning to abort due to prior failure. [ INFO] SD-00019: Discovering storage resources for all system devices. [ INFO] SD-00020: Finished storage discovery for all system devices. [ INFO] ASM-10003: Deleting ASMLib disk SM_DSK_1443002096200. [ INFO] SD-00019: Discovering storage resources for all system devices. [ INFO] SD-00020: Finished storage discovery for all system devices. [ INFO] SD-00034: Beginning to disconnect device(s) [/dev/mapper/3600a0980383030536c5d45716a6b6d6b]. [ INFO] SD-00035: Finished disconnecting device(s) [/dev/mapper/3600a0980383030536c5d45716a6b6d6b]. [ WARN] FLOW-11011: Operation aborted [ERROR] FLOW-11008: Operation failed: ORACLE-10003: Error executing SQL "DROP DISKGROUP SM_DG_1443002096229 INCLUDING CONTENTS" against Oracle database +ASM1: ORA-15039: diskgroup not dropped ORA-15027: active use of diskgroup "SM_DG_1443002096229" precludes its dismount [ERROR] SMO-11005: Error restoring Snapshot copy: FLOW-11019: Failure in ExecuteRestoreSteps: ORACLE-10003: Error executing SQL "DROP DISKGROUP SM_DG_1443002096229 INCLUDING CONTENTS" against Oracle database +ASM1: ORA-15039: diskgroup not dropped ORA-15027: active use of diskgroup "SM_DG_1443002096229" precludes its dismount [ERROR] SMO-13032: Cannot perform operation: Backup Restore. Root cause: SMO-11005: Error restoring Snapshot copy: FLOW-11019: Failure in ExecuteRestoreSteps: ORACLE-10003: Error executing SQL "DROP DISKGROUP SM_DG_1443002096229 INCLUDING CONTENTS" against Oracle database +ASM1: ORA-15039: diskgroup not dropped ORA-15027: active use of diskgroup "SM_DG_1443002096229" precludes its dismount [ INFO] SMO-07131: Unlocked database for SnapManager operations - removed lock file "/u01/app/oracle/product/11.2.0/db/dbs/.sm_lock_ermestel" on host xxxxxxx. [ INFO] SMO-07433: Returning the database to its initial state: ermestel1(SHUTDOWN), ermestel2(SHUTDOWN), ermestel3(SHUTDOWN). [ INFO] ORACLE-20000: Changing state for database instance ermestel1 from STARTED to SHUTDOWN. [ INFO] SMO-13039: Successfully aborted operation: Backup Restore [ERROR] SMO-13048: Backup Restore Operation Status: FAILED [ INFO] SMO-22002: Successfully recorded the Backup Restore operation in history. [ INFO] SMO-13049: Elapsed Time: 0:05:47.780 REMOTE-00004: RemoteObjectFactory shutdown gracefully. 2015-09-23 12:00:07,902 [qtp1247017815-16] [DEBUG]: Os Name Linux 2015-09-23 12:00:07,903 [qtp1247017815-16] [DEBUG]: Looking for OperatingSystem handler com.netapp.common.platform.linux.OperatingSystemImpl 2015-09-23 12:00:07,918 [qtp1247017815-16] [DEBUG]: HostAuthenticator secured method pointcut for method: getRestoreStatus. 2015-09-23 12:00:07,921 [qtp1247017815-16] [DEBUG]: Authorization succeeded continuing with operation: getRestoreStatus. 2015-09-23 12:00:07,922 [qtp1247017815-16] [DEBUG]: ....SnapManager Server starting operation: getRestoreStatus 2015-09-23 12:00:07,924 [qtp1247017815-16] [DEBUG]: Profile name: ERMESTEL, client username: root, effective username: root 2015-09-23 12:00:07,949 [qtp1247017815-16] [DEBUG]: SMO-13036: Starting operation Status[Nd9b67ee8357ab7a9689ab15bbe6b82c3] on host xxxxxxx 2015-09-23 12:00:07,949 [qtp1247017815-16] [DEBUG]: operationMap of size 0 adding getRestoreStatus ID Nd9b67ee8357ab7a9689ab15bbe6b82c3 2015-09-23 12:00:08,022 [qtp1247017815-16] [DEBUG]: RepositoryDescriptor.getRawJdbcConnection smouser connected to: jdbc:oracle:thin:@//[xxxxxxx]:1521/xxxxxxx 2015-09-23 12:00:08,024 [qtp1247017815-16] [DEBUG]: SMO-20016: User "root" is authenticated for repository "smouser@xxxxxxx/xxxxxxx:1521". 2015-09-23 12:00:08,088 [qtp1247017815-16] [DEBUG]: RepositoryDescriptor.getRawJdbcConnection smouser connected to: jdbc:oracle:thin:@//[xxxxxxx]:1521/xxxxxxx 2015-09-23 12:00:08,102 [qtp1247017815-16] [DEBUG]: OperationCycle status: FAILED, rootErrorCode: 13032, rootErrorMessage: SMO-13032: Cannot perform operation: Backup Restore. Root cause: SMO-11005: Error restoring Snapshot copy: FLOW-11019: Failure in ExecuteRestoreSteps: ORACLE-10003: Error executing SQL "DROP DISKGROUP SM_DG_1443002096229 INCLUDING CONTENTS" against Oracle database +ASM1: ORA-15039: diskgroup not dropped ORA-15027: active use of diskgroup "SM_DG_1443002096229" precludes its dismount , opId: 402882b44ff9a00f014ff9a014440001