Hello,
I'm trying to restore a Backup. After the unmout of the NFS mount u01 on volume v20_s041_003 (there is a empty u02 on the same vol., see below) the restore of the Snapshot is hanging. Has anybody a Idea what the Problem could be or where I could get future information on the system about the cause of the hang ?
Here is the log from /var/log/sd-recover.log :
16319: BEGIN 15-08-2012 09:54:25 snapdrive snap restore -fs /AUMT/u01 -snapname smo_aumt_aumt_f_h_1_8a9a9aa03924e1b1013924e1b7280001_0 -force -noprompt
16319: BEGIN 15-08-2012 09:54:29 create snapshot: smo_aumt_aumt_f_h_1_8a9a9aa03924e1b1013924e1b7280001_0.RESTORE_ROLLBACK_08152012_095426_16318 on n20
16319: END 15-08-2012 09:54:29 create snapshot: smo_aumt_aumt_f_h_1_8a9a9aa03924e1b1013924e1b7280001_0.RESTORE_ROLLBACK_08152012_095426_16318 on n20,n20 successful
16319: BEGIN 15-08-2012 09:54:29 unmount file system: /AUMT/u01
16319: END 15-08-2012 09:54:29 unmount file system: /AUMT/u01 successful
16319: BEGIN 15-08-2012 09:54:29 restore from snapshot: n20:/vol/v20_s041_003:smo_aumt_aumt_f_h_1_8a9a9aa03924e1b1013924e1b7280001_0
Thats output of df -k on the Server, AUMT/u01 is unmounted :
Filesystem | size | used | avail used% Mounted |
/vol/v20_s041_003/AUMT/u02 | 20G | 4.5G | 16G 23% /AUMT/u02 |
/vol/v20_s041_004/AUMT/u00 | 10G | 1.2G | 8.8G 13% /AUMT/u00 |
/vol/v20_s041_004/AUMT/u50 | 10G | 1.2G | 8.8G 13% /AUMT/u50 |
/vol/v20_s041_004/AUMT/u51 | 10G | 1.2G | 8.8G 13% /AUMT/u51 |
/vol/v20_s041_004/AUMT/u60 | 10G | 1.2G | 8.8G 13% /AUMT/u60 |
/vol/v20_s041_005/AUMT/u99 | 20G | 5.0G | 15G 25% /AUMT/u99 |
Here output fom a ps command :
| root 16313 16312 0 09:54:25 ? | 0:05 /usr/sbin/snapdrive snap restore -fs /AUMT/u01 -snapname smo_aumt_aumt_f_h_1_8a |
| root 16318 11188 0 09:54:25 ? | 0:12 snapdrived start |
| root 26934 6996 0 Jul 24 pts/5 | 40:34 /usr/jre1.6.0_25/bin/java -jar snapcreator.jar |
| root 11188 | 1 0 May 08 ? | 36:24 snapdrived start |
| root 5103 | 1 0 Jul 24 pts/5 | 0:05 ./snapcreator --start-agent 9399 -debug -verbose |
| root 16312 15498 0 09:54:25 ? | 0:00 /bin/sh -c "/usr/sbin/snapdrive" "snap" "restore" "-fs" "/AUMT/u01" "-snapname" |
And here the log from SMO :
--[ INFO] SMO-13036: Starting operation Backup Restore on host abc
--[ INFO] SMO-13046: Operation GUID 8a9a9aa03929460f0139294616320001 starting on Profile AUMT
--[ INFO] SMO-07431: Saving starting state of the database: AUMT(OPEN).
--[ INFO] SMO-07431: Saving starting state of the database: AUMT(OPEN).
--[ INFO] SMO-07127: Locked database for SnapManager operations - created lock file "/u00/app/oracle/product/11.2.0.1.2/dbs/.sm_lock_AUMT" on host krebs.
--[ INFO] ORACLE-20000: Changing state for database instance AUMT from OPEN to MOUNTED.
--[ INFO] ORACLE-20009: Attempting to reconnect to instance AUMT after shutdown/startup.
--[ INFO] ORACLE-20009: Attempting to reconnect to instance AUMT after shutdown/startup.
--[ INFO] ORACLE-20009: Attempting to reconnect to instance AUMT after shutdown/startup.
--[ INFO] ORACLE-20011: Reconnect to instance AUMT successful.
--[ INFO] SMO-07200: Beginning restore of database "AUMT".
--[ INFO] SD-00022: Querying for snapshot n20:/vol/v20_s041_003:smo_aumt_aumt_f_h_1_8a9a9aa03924e1b1013924e1b7280001_0.
--[ INFO] SD-00023: Finished querying for snapshot n20:/vol/v20_s041_003:smo_aumt_aumt_f_h_1_8a9a9aa03924e1b1013924e1b7280001_0.
--[ INFO] SD-00016: Discovering storage resources for /AUMT/u60.
--[ INFO] SD-00017: Finished storage discovery for /AUMT/u60.
--[ INFO] SD-00016: Discovering storage resources for /AUMT/u99.
--[ INFO] SD-00017: Finished storage discovery for /AUMT/u99.
--[ INFO] SD-00016: Discovering storage resources for /AUMT/u50.
--[ INFO] SD-00017: Finished storage discovery for /AUMT/u50.
--[ INFO] SD-00016: Discovering storage resources for /AUMT/u51.
--[ INFO] SD-00017: Finished storage discovery for /AUMT/u51.
--[ INFO] SD-00004: Beginning restore of filesystem(s) [/AUMT/u01] from snapshot smo_aumt_aumt_f_h_1_8a9a9aa03924e1b1013924e1b7280001_0.