Data Backup and Recovery

SnapCreator 3.4p2 with SMSQL (VM) and PM updates

jhubert
8,654 Views

I encountered this issue while at a customer site and have recreated it within lab on demand - Protection Manager Updates fail.

The SMSQL powershell script is run correctly, however the PM updates fail with this error:

(At the customer site, I was getting return code 0 from the powershell, but no output (and no snapshot).  I will have to re-run the agent in debug mode to get more information.  In my test lab, I found I had to restart the services several times to get it to run successfully - is there a certain order to making this work?)

<results reason="snapshot-unique-id must be specified in version-member-info." errno="13001" status="failed"></results>

[Sat Nov 19 10:33:20 2011] ERROR: [scf-00013] snapshot-unique-id must be specified in version-member-info. (code = 13001)

--

[Sat Nov 19 10:32:29 2011] DEBUG: GMT - Sat Nov 19 15:32:29 2011

[Sat Nov 19 10:32:29 2011] DEBUG: Version: NetApp Snap Creator Framework 3.4p2

[Sat Nov 19 10:32:29 2011] DEBUG: Profile: SMSQL

[Sat Nov 19 10:32:29 2011] DEBUG: Config Type: STANDARD

[Sat Nov 19 10:32:29 2011] DEBUG: Action: snap

[Sat Nov 19 10:32:29 2011] DEBUG: Plugin: smsql

[Sat Nov 19 10:32:29 2011] DEBUG: Policy: daily

[Sat Nov 19 10:32:29 2011] DEBUG: Snapshot Name: smsql-daily_20111119103229

[Sat Nov 19 10:32:29 2011] DEBUG: Timestamp: 20111119103229

[Sat Nov 19 10:32:29 2011] INFO: Removing log SMSQLtest.out.20111118163854

[Sat Nov 19 10:32:29 2011] INFO: Removing log SMSQLtest.debug.20111118163854

########## Parsing Environment Parameters ##########

[Sat Nov 19 10:32:29 2011] DEBUG: Parsing VOLUMES - filer: fas01 volume: vmware_nfs

[Sat Nov 19 10:32:29 2011] DEBUG: Parsing NTAP_USERS - filer: fas01 user: root

[Sat Nov 19 10:32:29 2011] DEBUG: Parsing NTAP_SNAPSHOT_RETENTIONS - policy: daily retention: 7

[Sat Nov 19 10:32:29 2011] DEBUG: Parsing NTAP_DFM_DATA_SET - filer: fas01 volume: vmware_nfs DFM dataset: snapcreator_SMSQLtest

########## PRE APPLICATION QUIESCE COMMANDS ##########

[Sat Nov 19 10:32:29 2011] INFO: No commands defined

########## PRE APPLICATION QUIESCE COMMANDS FINISHED SUCCESSFULLY ##########

########## Application quiesce ##########

[Sat Nov 19 10:32:29 2011] [192.168.0.62:9090(3.4.0.1)] DEBUG: Executing command [%SystemRoot%\system32\WindowsPowerShell\v1.0\powershell.exe -psconsolefile "C:\Program Files\NetApp\SnapManager for SQL Server\smsqlshell.psc1" -command "new-backup -svr 'win2003sp2-2' -RetainBackups 7 -lb -bksif -RetainSNapofSnapInfo 8 -trlog -mgmt daily"]

[Sat Nov 19 10:33:16 2011] [192.168.0.62:9090(3.4.0.1)] DEBUG: Command [%SystemRoot%\system32\WindowsPowerShell\v1.0\powershell.exe -psconsolefile "C:\Program Files\NetApp\SnapManager for SQL Server\smsqlshell.psc1" -command "new-backup -svr 'win2003sp2-2' -RetainBackups 7 -lb -bksif -RetainSNapofSnapInfo 8 -trlog -mgmt daily"] finished with

exit code: [0]

stdout: [Initializing [new-backup]

Connecting to server: [win2003sp2-2]

Initialization of Backup Input Data&colon; [win2003sp2-2][processing]

Setup verification server default value [processing]

Retrieving SnapManager Configuration Information: [processing]

Retrieving SnapManager Configuration Information: [completed]

Initialization of Backup Input Data&colon; [win2003sp2-2][completed]

Host: win2003sp2-2

Authentication Mode: Windows

The input parameters are being validated.

The following SQL Server instances in host [win2003sp2-2] will be backed up.

This is a back up only job. It is highly recommended to do  verification of the

backed up databases and logs.

The input parameters validation successful.

Proceeding with [new-backup]

Invoking backup...

*** SnapManager for SQL Server Report

Backup Time Stamp: 11-19-2011_10.32.39

Getting SQL Server Database Information, please wait...

Getting virtual disks information...

Querying SQL Server instances installed...

Querying installed SQL Server instances on [win2003sp2-2]...

Allocating ODBC environment handle...

Setting environment attribute...

Setting SQLBrowseConnect option...

SQLBrowseConnect...

Parsing SQLBrowseConnect server string...

Creating threads for enumerating databases of different SQL Server instances.

Waiting for the threads to finish enumerating databases.

Connecting to SQL Server instance - WIN2003SP2-2...

Getting SQL Server version information...

Querying database information...

All threads finished enumerating databases.

Validating database configuration...

Setting database disk configuration...

Setting database locating on SQLRoot path...

Querying database disk volume configuration...

Getting SnapInfo directories configuration...

Checking dataset availability, connecting to SnapDrive...

Dataset feature is currently available.

Getting SnapManager dataset info from...

Dataset info file has not yet created.

Getting SnapMirror Destinations Volumes...

Update selected destination volumes information for SQL Server WIN2003SP2-2 suc

ceeded.

Get all destination volumes state successfully.

Full database backup

Maximum databases of concurrent backup: 255

Naming convention: Unique

Run transaction log backup after full database backup: Yes

Transaction logs will be truncated after backup: Yes

Archive backup to SnapVault secondary: No

Keep the oldest 7 full backups.

Retain up-to-the-minute restore ability for older backups in other management g

roups: Yes

Leave database attached after DBCC: No

Run DBCC CHECKDB with Live Database Before Backup: No

Run DBCC CHECKDB with Live Database After Backup: No

Backup Management Group: Daily

Update SnapMirror after operation: No

Run Command before the operation: No

Run Command after the operation: No

Backup Database 1: WIN2003SP2-2 : master

Backup Database 2: WIN2003SP2-2 : model

Backup Database 3: WIN2003SP2-2 : msdb

Backup database list:

1 - WIN2003SP2-2 : master

2 - WIN2003SP2-2 : model

3 - WIN2003SP2-2 : msdb

Getting SnapInfo directory info...

1 - Database [master] of [WIN2003SP2-2] SnapInfo directory located at:

F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__master

2 - Database [model] of [WIN2003SP2-2] SnapInfo directory located at:

F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__model

3 - Database [msdb] of [WIN2003SP2-2] SnapInfo directory located at:

F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__msdb

CHECK SNAPMANAGER LICENSE ON LUN.

Querying LUN license: F...

Querying LUN license: E...

*** FULL DATABASE BACKUP

FULL DATABASE BACKUP [#1]

Full database backup location for database [master]:

F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__master\FG__\StreamFullBackup\11-19-2011

_10.32.39__master__Daily.FBK

Connecting to SQL Server WIN2003SP2-2...

Starting full database backup for database [master]...

Database [WIN2003SP2-2:master] Percent: 6 percent processed.6

Database [WIN2003SP2-2:master] Percent: 10 percent processed.10

Database [WIN2003SP2-2:master] Percent: 15 percent processed.15

Database [WIN2003SP2-2:master] Percent: 21 percent processed.21

Database [WIN2003SP2-2:master] Percent: 25 percent processed.25

Database [WIN2003SP2-2:master] Percent: 30 percent processed.30

Database [WIN2003SP2-2:master] Percent: 36 percent processed.36

Database [WIN2003SP2-2:master] Percent: 41 percent processed.41

Database [WIN2003SP2-2:master] Percent: 45 percent processed.45

Database [WIN2003SP2-2:master] Percent: 51 percent processed.51

Database [WIN2003SP2-2:master] Percent: 56 percent processed.56

Database [WIN2003SP2-2:master] Percent: 60 percent processed.60

Database [WIN2003SP2-2:master] Percent: 66 percent processed.66

Database [WIN2003SP2-2:master] Percent: 71 percent processed.71

Database [WIN2003SP2-2:master] Percent: 75 percent processed.75

Database [WIN2003SP2-2:master] Percent: 82 percent processed.82

Database [WIN2003SP2-2:master] Percent: 86 percent processed.86

Database [WIN2003SP2-2:master] Percent: 90 percent processed.90

Database [WIN2003SP2-2:master] Percent: 95 percent processed.95

Database [WIN2003SP2-2:master] Percent: 100 percent processed.100

Database [WIN2003SP2-2:master] BACKUP DATABASE successfully processed 371 pages

in 0.150 seconds (19.296 MB/sec).

Full database backup of database [master] completed.

FULL DATABASE BACKUP [#2]

Full database backup location for database [model]:

F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__model\FG__\StreamFullBackup\11-19-2011_

10.32.39__model__Daily.FBK

Starting full database backup for database [model]...

Database [WIN2003SP2-2:model] Percent: 9 percent processed.9

Database [WIN2003SP2-2:model] Percent: 14 percent processed.14

Database [WIN2003SP2-2:model] Percent: 19 percent processed.19

Database [WIN2003SP2-2:model] Percent: 24 percent processed.24

Database [WIN2003SP2-2:model] Percent: 29 percent processed.29

Database [WIN2003SP2-2:model] Percent: 34 percent processed.34

Database [WIN2003SP2-2:model] Percent: 39 percent processed.39

Database [WIN2003SP2-2:model] Percent: 44 percent processed.44

Database [WIN2003SP2-2:model] Percent: 49 percent processed.49

Database [WIN2003SP2-2:model] Percent: 54 percent processed.54

Database [WIN2003SP2-2:model] Percent: 59 percent processed.59

Database [WIN2003SP2-2:model] Percent: 64 percent processed.64

Database [WIN2003SP2-2:model] Percent: 69 percent processed.69

Database [WIN2003SP2-2:model] Percent: 74 percent processed.74

Database [WIN2003SP2-2:model] Percent: 79 percent processed.79

Database [WIN2003SP2-2:model] Percent: 84 percent processed.84

Database [WIN2003SP2-2:model] Percent: 89 percent processed.89

Database [WIN2003SP2-2:model] Percent: 94 percent processed.94

Database [WIN2003SP2-2:model] Percent: 99 percent processed.99

Database [WIN2003SP2-2:model] Percent: 100 percent processed.100

Database [WIN2003SP2-2:model] BACKUP DATABASE successfully processed 162 pages

in 0.046 seconds (27.428 MB/sec).

Full database backup of database [model] completed.

FULL DATABASE BACKUP [#3]

Full database backup location for database [msdb]:

F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__msdb\FG__\StreamFullBackup\11-19-2011_1

0.32.39__msdb__Daily.FBK

Starting full database backup for database [msdb]...

Database [WIN2003SP2-2:msdb] Percent: 10 percent processed.10

Database [WIN2003SP2-2:msdb] Percent: 20 percent processed.20

Database [WIN2003SP2-2:msdb] Percent: 30 percent processed.30

Database [WIN2003SP2-2:msdb] Percent: 40 percent processed.40

Database [WIN2003SP2-2:msdb] Percent: 50 percent processed.50

Database [WIN2003SP2-2:msdb] Percent: 60 percent processed.60

Database [WIN2003SP2-2:msdb] Percent: 70 percent processed.70

Database [WIN2003SP2-2:msdb] Percent: 80 percent processed.80

Database [WIN2003SP2-2:msdb] Percent: 90 percent processed.90

Database [WIN2003SP2-2:msdb] Percent: 99 percent processed.99

Database [WIN2003SP2-2:msdb] Percent: 100 percent processed.100

Database [WIN2003SP2-2:msdb] BACKUP DATABASE successfully processed 1277 pages

in 0.267 seconds (37.365 MB/sec).

Full database backup of database [msdb] completed.

*** TRANSACTION LOG BACKUP AFTER FULL DATABASE BACKUP

Transaction log backup for database [master] skipped (Simple Recovery Model).

Transaction log backup for database [msdb] skipped (Simple Recovery Model).

TRANSACTION LOG BACKUP AFTER FULL BACKUP [#1]

Transaction log backup location for database [model]:

F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__model\LogBackup\11-19-2011_10.32.39__mo

del.TRB

Connecting to SQL server WIN2003SP2-2...

Checking recovery model...

Database [model] recovery model is Full.

Transaction log backup for database [model] will truncate logs...

Starting to backup transaction log for database [model]...

Database [WIN2003SP2-2:model] Percent: 100 percent processed.100

Database [WIN2003SP2-2:model] BACKUP LOG successfully processed 4 pages in 0.00

7 seconds (4.464 MB/sec).

Transaction log backup of database [model] completed.

*** BACKUP SNAPINFO DISK

UPDATE MOST RECENT SNAPINFO DRIVE SNAPSHOT NAME

Rename Snapshot for LUN [F] from [sqlinfo__win2003sp2-2__recent]

To [sqlinfo__win2003sp2-2_11-19-2011_10.06.45__daily]

[SnapDrive Error]: Rename of backup (sqlinfo__win2003sp2-2__recent) failed. Rea

son: (The backup sqlinfo__win2003sp2-2__recent was not renamed successfully. Re

ason : Could not find current snapshot name smvi__sqlinfo__win2003sp2-2__recent

_20111119100709 or original snapshot name smvi__sqlinfo__win2003sp2-2__recent_2

0111119100709 for volume 192.168.0.101:/vol/vmware_nfs).

(SnapDrive Error Code: 0xc0041053)

SNAPSHOT SNAPINFO DRIVE

Creating Snapshot of LUN(s):

LUN [F]

Snapshot name: sqlinfo__win2003sp2-2_11-19-2011_10.32.39__daily

Snapshot SnapInfo drive(s) successfully completed.

*** DELETE THE OLDEST STREAM BASED BACKUP DATA SETS

*** DELETING PREVIOUS BACKUPS: master

Querying stream based full database backup for [master]...

Start to delete [2] the oldest backup sets.

Deleting full backup [#1] at F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__master\FG_

_\StreamFullBackup\11-18-2011_16.20.09__master__Daily.FBK...

Deleting full backup [#2] at F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__master\FG_

_\StreamFullBackup\11-18-2011_16.53.18__master__Daily.FBK...

DELETING TRANSACTION LOG BACKUPS [#1]: master

Transaction log backups older than [11-18-2011_16.54.34] (last full backup) wil

l be deleted.

No transaction log backup sets for master were detected.

No transaction log backup of [master] will be deleted.

*** DELETING PREVIOUS BACKUPS: model

Querying stream based full database backup for [model]...

Start to delete [2] the oldest backup sets.

Deleting full backup [#1] at F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__model\FG__

\StreamFullBackup\11-18-2011_16.20.09__model__Daily.FBK...

Deleting full backup [#2] at F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__model\FG__

\StreamFullBackup\11-18-2011_16.53.18__model__Daily.FBK...

DELETING TRANSACTION LOG BACKUPS [#2]: model

Transaction log backups older than [11-18-2011_16.54.34] (last full backup) wil

l be deleted.

Deleting log backup [#1] at F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__model\LogBa

ckup\11-18-2011_16.20.09__model.TRB...

Deleting log backup [#2] at F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__model\LogBa

ckup\11-18-2011_16.53.18__model.TRB...

Transaction log backups on and after the time stamp [11-18-2011_16.54.34] will

not be deleted.

*** DELETING PREVIOUS BACKUPS: msdb

Querying stream based full database backup for [msdb]...

Start to delete [2] the oldest backup sets.

Deleting full backup [#1] at F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__msdb\FG__\

StreamFullBackup\11-18-2011_16.20.09__msdb__Daily.FBK...

Deleting full backup [#2] at F:\SMSQL_SnapInfo\SQL__WIN2003SP2-2\DB__msdb\FG__\

StreamFullBackup\11-18-2011_16.53.18__msdb__Daily.FBK...

DELETING TRANSACTION LOG BACKUPS [#3]: msdb

Transaction log backups older than [11-18-2011_16.54.34] (last full backup) wil

l be deleted.

No transaction log backup sets for msdb were detected.

No transaction log backup of [msdb] will be deleted.

*** DELETING LOG SNAPINFO DISK SNAPSHOT

Get log snapshot list from LUN [F]: ...

DELETING SNAPINFO LUN SNAPSHOT - [#1]

Deleting snapshot copy sqlinfo__win2003sp2-2_11-18-2011_16.20.09__daily of LUN

F

Deletion of snapshot copy succeeded.

**** FULL DATABASE BACKUP RESULT SUMMARY ****

Backup Time: 11-19-2011_10.32.39

Backup Group [#1]:

#1 : [WIN2003SP2-2 - master] : OK

#2 : [WIN2003SP2-2 - model] : OK

#3 : [WIN2003SP2-2 - msdb] : OK

*** SNAPMANAGER BACKUP JOB ENDED AT: [11-19-2011 10.33.15]

Full database backup completed successfully.

Preparing for sending filer AutoSupport...

No filer autosupport message is sent on success.

Please check the SnapManager Reports for more details.

The new-backup cmdlet has completed processing successfully.

]

stderr: []

[Sat Nov 19 10:33:17 2011] [192.168.0.62:9090(3.4.0.1)] DEBUG: Executing of external script finished successfully

[Sat Nov 19 10:33:17 2011] [192.168.0.62:9090(3.4.0.1)] INFO: Quiescing databases finished successfully

########## POST APPLICATION QUIESCE COMMANDS ##########

[Sat Nov 19 10:33:17 2011] INFO: No commands defined

########## POST APPLICATION QUIESCE COMMANDS FINISHED SUCCESSFULLY ##########

########## PRE NETAPP COMMANDS ##########

[Sat Nov 19 10:33:17 2011] INFO: No commands defined

########## PRE NETAPP COMMANDS FINISHED SUCCESSFULLY ##########

[Sat Nov 19 10:33:17 2011] DEBUG: ZAPI REQUEST

<system-get-ontapi-version></system-get-ontapi-version>

[Sat Nov 19 10:33:17 2011] DEBUG: ZAPI RESULT

<results status="passed">

    <major-version>1</major-version>

    <minor-version>9</minor-version>

</results>

[Sat Nov 19 10:33:17 2011] DEBUG: creating filer/vfiler executor for filer fas01

########## Detecting Data OnTap mode for fas01 ##########

[Sat Nov 19 10:33:17 2011] INFO: Data OnTap 7 mode detected

[Sat Nov 19 10:33:17 2011] DEBUG: Connected to fas01 using API Version 1.9

########## Generating Info ASUP on fas01 ##########

[Sat Nov 19 10:33:17 2011] DEBUG: ZAPI REQUEST

<ems-autosupport-log>

    <app-version>NetApp Snap Creator Framework 3.4p2</app-version>

    <auto-support>false</auto-support>

    <category>Backup Started</category>

    <computer-name>VCS1 [192.168.0.62:9090]</computer-name>

    <event-description>INFO: NetApp Snap Creator Framework 3.4p2 Backup for smsql ACTION: snap POLICY: daily Plugin: smsql Volumes: fas01:vmware_nfs Started</event-description>

    <event-id>0</event-id>

    <event-source>SNAPCREATOR</event-source>

    <log-level>6</log-level>

</ems-autosupport-log>

[Sat Nov 19 10:33:18 2011] DEBUG: ZAPI RESULT

<results status="passed"></results>

[Sat Nov 19 10:33:18 2011] INFO: NetApp ASUP create on fas01 finished successfully

########## Gathering Information for fas01:vmware_nfs ##########

[Sat Nov 19 10:33:18 2011] INFO: Performing NetApp Snapshot Inventory for vmware_nfs on fas01

[Sat Nov 19 10:33:18 2011] DEBUG: ZAPI REQUEST

<snapshot-list-info>

    <volume>vmware_nfs</volume>

    <terse>true</terse>

</snapshot-list-info>

[Sat Nov 19 10:33:18 2011] DEBUG: ZAPI RESULT

<results status="passed">

    <snapshots>

        <snapshot-info>

            <name>smvi__sqlinfo__win2003sp2-2_11-19-2011_10.32.39__daily_20111119103304</name>

            <access-time>1321716784</access-time>

            <dependency></dependency>

            <busy>false</busy>

            <cumulative-percentage-of-used-blocks>0</cumulative-percentage-of-used-blocks>

            <percentage-of-used-blocks>0</percentage-of-used-blocks>

            <cumulative-percentage-of-total-blocks>0</cumulative-percentage-of-total-blocks>

            <percentage-of-total-blocks>0</percentage-of-total-blocks>

        </snapshot-info>

        <snapshot-info>

            <name>fas02(0135044848)_vault_snapcreator_SMSQLtest_fas01_vmware_nfs-src.0</name>

            <access-time>1321714579</access-time>

            <dependency>snapvault</dependency>

            <busy>false</busy>

            <cumulative-percentage-of-used-blocks>0</cumulative-percentage-of-used-blocks>

            <percentage-of-used-blocks>0</percentage-of-used-blocks>

            <cumulative-percentage-of-total-blocks>0</cumulative-percentage-of-total-blocks>

            <percentage-of-total-blocks>0</percentage-of-total-blocks>

        </snapshot-info>

        <snapshot-info>

            <name>inf1-fs01(0135018268)_vmware_nfs_v1_4.2</name>

            <access-time>1297264552</access-time>

            <dependency></dependency>

            <busy>false</busy>

            <cumulative-percentage-of-used-blocks>55</cumulative-percentage-of-used-blocks>

            <percentage-of-used-blocks>55</percentage-of-used-blocks>

            <cumulative-percentage-of-total-blocks>20</cumulative-percentage-of-total-blocks>

            <percentage-of-total-blocks>20</percentage-of-total-blocks>

        </snapshot-info>

        <snapshot-info>

            <name>inf1-fs01(0135018268)_vmware_nfs_v1_4.1</name>

            <access-time>1296770740</access-time>

            <dependency></dependency>

            <busy>false</busy>

            <cumulative-percentage-of-used-blocks>55</cumulative-percentage-of-used-blocks>

            <percentage-of-used-blocks>1</percentage-of-used-blocks>

            <cumulative-percentage-of-total-blocks>20</cumulative-percentage-of-total-blocks>

            <percentage-of-total-blocks>0</percentage-of-total-blocks>

        </snapshot-info>

    </snapshots>

</results>

[Sat Nov 19 10:33:18 2011] INFO: NetApp Snapshot Inventory of vmware_nfs on fas01 completed Successfully

########## PRE APPLICATION UNQUIESCE COMMANDS ##########

[Sat Nov 19 10:33:18 2011] INFO: No commands defined

########## PRE APPLICATION UNQUIESCE COMMANDS FINISHED SUCCESSFULLY ##########

########## Application unquiesce ##########

[Sat Nov 19 10:33:17 2011] [192.168.0.62:9090(3.4.0.1)] INFO: Skipping unquiesce, nothing needed for SMSQL integration

########## POST APPLICATION UNQUIESCE COMMANDS ##########

[Sat Nov 19 10:33:18 2011] INFO: No commands defined

########## POST APPLICATION UNQUIESCE COMMANDS FINISHED SUCCESSFULLY ##########

########## Generating Info ASUP on fas01 ##########

[Sat Nov 19 10:33:18 2011] DEBUG: ZAPI REQUEST

<ems-autosupport-log>

    <app-version>NetApp Snap Creator Framework 3.4p2</app-version>

    <auto-support>false</auto-support>

    <category>Backup Completed</category>

    <computer-name>VCS1 [192.168.0.62:9090]</computer-name>

    <event-description>INFO: NetApp Snap Creator Framework 3.4p2 Backup for smsql ACTION: snap POLICY: daily Plugin: smsql Volumes: fas01:vmware_nfs Completed</event-description>

    <event-id>0</event-id>

    <event-source>SNAPCREATOR</event-source>

    <log-level>6</log-level>

</ems-autosupport-log>

[Sat Nov 19 10:33:18 2011] DEBUG: ZAPI RESULT

<results status="passed"></results>

[Sat Nov 19 10:33:18 2011] INFO: NetApp ASUP create on fas01 finished successfully

[Sat Nov 19 10:33:18 2011] DEBUG: creating dfm executor for dfm 192.168.0.61

[Sat Nov 19 10:33:18 2011] DEBUG: ZAPI REQUEST

<dfm-about></dfm-about>

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI RESULT

<results status="passed">

    <version>5.0.0.7636 (5.0)</version>

    <serial-number>1-50-122033</serial-number>

    <edition>Standard edition of DataFabric Manager server</edition>

    <host-name>win2003sp2-1</host-name>

    <node-limit>250 (currently managing 2)</node-limit>

    <prov-mgr-node-limit>250 (currently managing 2)</prov-mgr-node-limit>

    <prot-mgr-node-limit>250 (currently managing 2)</prot-mgr-node-limit>

    <operating-system>Microsoft Windows 2003 Service Pack 2 (Build 3790) x86 based </operating-system>

    <cpu-count>2</cpu-count>

    <system-memory>3839</system-memory>

    <install-dir>D:/Program Files/NetApp/DataFabric Manager/DFM</install-dir>

    <system-time>1321716798</system-time>

    <dfm-directories>

        <directory-info>

            <type>install</type>

            <name>D:/Program Files/NetApp/DataFabric Manager/DFM</name>

        </directory-info>

        <directory-info>

            <type>performance_advisor</type>

            <name>D:/Program Files/NetApp/DataFabric Manager/DFM/perfdata</name>

        </directory-info>

        <directory-info>

            <type>data_export</type>

            <name>D:/Program Files/NetApp/DataFabric Manager/DFM/dataExport</name>

        </directory-info>

        <directory-info>

            <type>database_backup</type>

            <name>D:\Program Files\NetApp\DataFabric Manager\DFM\data</name>

        </directory-info>

        <directory-info>

            <type>reports_archive</type>

            <name>D:\Program Files\NetApp\DataFabric Manager\DFM\reports</name>

        </directory-info>

    </dfm-directories>

    <licensed-features>

        <feature>

            <name>core</name>

            <summary>installed</summary>

        </feature>

        <feature>

            <name>bizcon</name>

            <summary>Installed</summary>

        </feature>

        <feature>

            <name>dataprotection</name>

            <summary>Installed</summary>

        </feature>

        <feature>

            <name>provisioning</name>

            <summary>Installed</summary>

        </feature>

        <feature>

            <name>disasterrecovery</name>

            <summary>Installed</summary>

        </feature>

    </licensed-features>

    <installed-plugins>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.1</product-version>

            <special-build>6.5.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.2</product-version>

            <special-build>6.5.2</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.3</product-version>

            <special-build>6.5.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.4</product-version>

            <special-build>6.5.4</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.5</product-version>

            <special-build>6.5.5</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.6</product-version>

            <special-build>6.5.6</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.7</product-version>

            <special-build>6.5.7</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0</product-version>

            <special-build>7.0.0.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.1</product-version>

            <special-build>7.0.1.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.2</product-version>

            <special-build>7.0.2</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.3</product-version>

            <special-build>7.0.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.4</product-version>

            <special-build>7.0.4</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.5</product-version>

            <special-build>7.0.5</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.6</product-version>

            <special-build>7.0.6</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.7</product-version>

            <special-build>7.0.7</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.1</product-version>

            <special-build>7.1.0.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.1.1</product-version>

            <special-build>7.1.1.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.1.2</product-version>

            <special-build>7.1.2.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.1.3</product-version>

            <special-build>7.1.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2</product-version>

            <special-build>7.2</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.1</product-version>

            <special-build>7.2.1.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.2</product-version>

            <special-build>7.2.2</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.3</product-version>

            <special-build>7.2.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.4</product-version>

            <special-build>7.2.4</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.5</product-version>

            <special-build>7.2.5.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.6</product-version>

            <special-build>7.2.6.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.7</product-version>

            <special-build>7.2.7</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3</product-version>

            <special-build>7.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3.1</product-version>

            <special-build>7.3.1.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3.2</product-version>

            <special-build>7.3.2</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3.3</product-version>

            <special-build>7.3.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3.4</product-version>

            <special-build>7.3.4</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3.5</product-version>

            <special-build>7.3.5RC1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>8.0</product-version>

            <special-build>8.0</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>8.0.1</product-version>

            <special-build>8.0.1RC3</special-build>

        </plugin>

    </installed-plugins>

</results>

[Sat Nov 19 10:33:19 2011] DEBUG: creating proxy executor for dfm 192.168.0.61

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI REQUEST

<dfm-about></dfm-about>

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI RESULT

<results status="passed">

    <version>5.0.0.7636 (5.0)</version>

    <serial-number>1-50-122033</serial-number>

    <edition>Standard edition of DataFabric Manager server</edition>

    <host-name>win2003sp2-1</host-name>

    <node-limit>250 (currently managing 2)</node-limit>

    <prov-mgr-node-limit>250 (currently managing 2)</prov-mgr-node-limit>

    <prot-mgr-node-limit>250 (currently managing 2)</prot-mgr-node-limit>

    <operating-system>Microsoft Windows 2003 Service Pack 2 (Build 3790) x86 based </operating-system>

    <cpu-count>2</cpu-count>

    <system-memory>3839</system-memory>

    <install-dir>D:/Program Files/NetApp/DataFabric Manager/DFM</install-dir>

    <system-time>1321716798</system-time>

    <dfm-directories>

        <directory-info>

            <type>install</type>

            <name>D:/Program Files/NetApp/DataFabric Manager/DFM</name>

        </directory-info>

        <directory-info>

            <type>performance_advisor</type>

            <name>D:/Program Files/NetApp/DataFabric Manager/DFM/perfdata</name>

        </directory-info>

        <directory-info>

            <type>data_export</type>

            <name>D:/Program Files/NetApp/DataFabric Manager/DFM/dataExport</name>

        </directory-info>

        <directory-info>

            <type>database_backup</type>

            <name>D:\Program Files\NetApp\DataFabric Manager\DFM\data</name>

        </directory-info>

        <directory-info>

            <type>reports_archive</type>

            <name>D:\Program Files\NetApp\DataFabric Manager\DFM\reports</name>

        </directory-info>

    </dfm-directories>

    <licensed-features>

        <feature>

            <name>core</name>

            <summary>installed</summary>

        </feature>

        <feature>

            <name>bizcon</name>

            <summary>Installed</summary>

        </feature>

        <feature>

            <name>dataprotection</name>

            <summary>Installed</summary>

        </feature>

        <feature>

            <name>provisioning</name>

            <summary>Installed</summary>

        </feature>

        <feature>

            <name>disasterrecovery</name>

            <summary>Installed</summary>

        </feature>

    </licensed-features>

    <installed-plugins>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.1</product-version>

            <special-build>6.5.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.2</product-version>

            <special-build>6.5.2</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.3</product-version>

            <special-build>6.5.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.4</product-version>

            <special-build>6.5.4</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.5</product-version>

            <special-build>6.5.5</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.6</product-version>

            <special-build>6.5.6</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>6.5.7</product-version>

            <special-build>6.5.7</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0</product-version>

            <special-build>7.0.0.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.1</product-version>

            <special-build>7.0.1.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.2</product-version>

            <special-build>7.0.2</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.3</product-version>

            <special-build>7.0.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.4</product-version>

            <special-build>7.0.4</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.5</product-version>

            <special-build>7.0.5</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.6</product-version>

            <special-build>7.0.6</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.0.7</product-version>

            <special-build>7.0.7</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.1</product-version>

            <special-build>7.1.0.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.1.1</product-version>

            <special-build>7.1.1.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.1.2</product-version>

            <special-build>7.1.2.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.1.3</product-version>

            <special-build>7.1.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2</product-version>

            <special-build>7.2</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.1</product-version>

            <special-build>7.2.1.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.2</product-version>

            <special-build>7.2.2</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.3</product-version>

            <special-build>7.2.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.4</product-version>

            <special-build>7.2.4</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.5</product-version>

            <special-build>7.2.5.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.6</product-version>

            <special-build>7.2.6.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.2.7</product-version>

            <special-build>7.2.7</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3</product-version>

            <special-build>7.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3.1</product-version>

            <special-build>7.3.1.1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3.2</product-version>

            <special-build>7.3.2</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3.3</product-version>

            <special-build>7.3.3</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3.4</product-version>

            <special-build>7.3.4</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>7.3.5</product-version>

            <special-build>7.3.5RC1</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>8.0</product-version>

            <special-build>8.0</special-build>

        </plugin>

        <plugin>

            <plugin-type>filer-config</plugin-type>

            <product-version>8.0.1</product-version>

            <special-build>8.0.1RC3</special-build>

        </plugin>

    </installed-plugins>

</results>

########## Checking Protection Manager dataset snapcreator_SMSQLtest ##########

[Sat Nov 19 10:33:19 2011] INFO: Checking if Protection Manager dataset snapcreator_SMSQLtest is conformant

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI REQUEST

<dataset-list-info-iter-start>

    <object-name-or-id>snapcreator_SMSQLtest</object-name-or-id>

</dataset-list-info-iter-start>

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI RESULT

<results status="passed">

    <records>1</records>

    <tag>dataset4636_20028</tag>

</results>

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI REQUEST

<dataset-list-info-iter-next>

    <maximum>1</maximum>

    <tag>dataset4636_20028</tag>

</dataset-list-info-iter-next>

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI RESULT

<results status="passed">

    <datasets>

        <dataset-info>

            <dataset-name>snapcreator_SMSQLtest</dataset-name>

            <dataset-id>183</dataset-id>

            <dataset-description>NetApp Snap Creator Framework dataset</dataset-description>

            <volume-qtree-name-prefix></volume-qtree-name-prefix>

            <secondary-qtree-name-format></secondary-qtree-name-format>

            <snapshot-name-format></snapshot-name-format>

            <primary-volume-name-format></primary-volume-name-format>

            <secondary-volume-name-format></secondary-volume-name-format>

            <is-dp-suspended>false</is-dp-suspended>

            <is-suspended>false</is-suspended>

            <is-application-data>true</is-application-data>

            <is-allow-custom-settings>false</is-allow-custom-settings>

            <is-enable-write-guarantee-checks>true</is-enable-write-guarantee-checks>

            <application-info>

                <application-name>NetApp Snap Creator Framework</application-name>

                <application-version>NetApp Snap Creator Framework 3.4p2</application-version>

                <application-server-name>snapcreator</application-server-name>

                <is-application-responsible-for-primary-backup>true</is-application-responsible-for-primary-backup>

                <carry-primary-backup-retention>true</carry-primary-backup-retention>

                <is-application-managing-primary-backup-retention>false</is-application-managing-primary-backup-retention>

            </application-info>

            <requires-non-disruptive-restore>false</requires-non-disruptive-restore>

            <dataset-owner></dataset-owner>

            <dataset-contact></dataset-contact>

            <is-dp-ignored>false</is-dp-ignored>

            <is-protected>true</is-protected>

            <has-protection-policy>true</has-protection-policy>

            <has-application-policy>false</has-application-policy>

            <can-contain-application-resources>false</can-contain-application-resources>

            <can-contain-storage-resources>true</can-contain-storage-resources>

            <has-protection>false</has-protection>

            <is-dr-capable>false</is-dr-capable>

            <member-count>2</member-count>

            <dynamic-reference-count>0</dynamic-reference-count>

            <storageset-name>snapcreator_SMSQLtest:root</storageset-name>

            <storageset-id>182</storageset-id>

            <online-migration>false</online-migration>

            <storageset-timezone></storageset-timezone>

            <protection-policy-name>Remote backups only</protection-policy-name>

            <protection-policy-id>76</protection-policy-id>

            <dataset-map>

                <dataset-map-entry>

                    <online-migration>false</online-migration>

                    <dp-node-name>Primary data</dp-node-name>

                    <dp-node-id>1</dp-node-id>

                    <storageset-name>snapcreator_SMSQLtest:root</storageset-name>

                    <storageset-id>182</storageset-id>

                    <storageset-timezone></storageset-timezone>

                    <is-dr-capable>false</is-dr-capable>

                </dataset-map-entry>

                <dataset-map-entry>

                    <online-migration>false</online-migration>

                    <dp-node-name>Backup</dp-node-name>

                    <dp-node-id>2</dp-node-id>

                    <storageset-name>snapcreator_SMSQLtest:Backup</storageset-name>

                    <storageset-id>184</storageset-id>

                    <storageset-timezone></storageset-timezone>

                    <is-dr-capable>false</is-dr-capable>

                </dataset-map-entry>

            </dataset-map>

            <dataset-status>

                <resource-status>normal</resource-status>

                <conformance-status>conformant</conformance-status>

                <performance-status>unknown</performance-status>

                <protection-status>uninitialized</protection-status>

                <space-status>unknown</space-status>

            </dataset-status>

        </dataset-info>

    </datasets>

    <records>1</records>

</results>

[Sat Nov 19 10:33:19 2011] INFO: Protection Manager dataset snapcreator_SMSQLtest is conformant

[Sat Nov 19 10:33:19 2011] INFO: Protection Manager dataset snapcreator_SMSQLtest resource status normal

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI REQUEST

<dataset-list-info-iter-end>

    <tag>dataset4636_20028</tag>

</dataset-list-info-iter-end>

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI RESULT

<results status="passed"></results>

########## Gathering Information for fas01:vmware_nfs ##########

[Sat Nov 19 10:33:19 2011] INFO: Performing NetApp Snapshot Inventory for vmware_nfs on fas01

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI REQUEST

<snapshot-list-info>

    <volume>vmware_nfs</volume>

    <terse>true</terse>

</snapshot-list-info>

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI RESULT

<results status="passed">

    <snapshots>

        <snapshot-info>

            <name>smvi__sqlinfo__win2003sp2-2_11-19-2011_10.32.39__daily_20111119103304</name>

            <access-time>1321716784</access-time>

            <dependency></dependency>

            <busy>false</busy>

            <cumulative-percentage-of-used-blocks>0</cumulative-percentage-of-used-blocks>

            <percentage-of-used-blocks>0</percentage-of-used-blocks>

            <cumulative-percentage-of-total-blocks>0</cumulative-percentage-of-total-blocks>

            <percentage-of-total-blocks>0</percentage-of-total-blocks>

        </snapshot-info>

        <snapshot-info>

            <name>fas02(0135044848)_vault_snapcreator_SMSQLtest_fas01_vmware_nfs-src.0</name>

            <access-time>1321714579</access-time>

            <dependency>snapvault</dependency>

            <busy>false</busy>

            <cumulative-percentage-of-used-blocks>0</cumulative-percentage-of-used-blocks>

            <percentage-of-used-blocks>0</percentage-of-used-blocks>

            <cumulative-percentage-of-total-blocks>0</cumulative-percentage-of-total-blocks>

            <percentage-of-total-blocks>0</percentage-of-total-blocks>

        </snapshot-info>

        <snapshot-info>

            <name>inf1-fs01(0135018268)_vmware_nfs_v1_4.2</name>

            <access-time>1297264552</access-time>

            <dependency></dependency>

            <busy>false</busy>

            <cumulative-percentage-of-used-blocks>55</cumulative-percentage-of-used-blocks>

            <percentage-of-used-blocks>55</percentage-of-used-blocks>

            <cumulative-percentage-of-total-blocks>20</cumulative-percentage-of-total-blocks>

            <percentage-of-total-blocks>20</percentage-of-total-blocks>

        </snapshot-info>

        <snapshot-info>

            <name>inf1-fs01(0135018268)_vmware_nfs_v1_4.1</name>

            <access-time>1296770740</access-time>

            <dependency></dependency>

            <busy>false</busy>

            <cumulative-percentage-of-used-blocks>55</cumulative-percentage-of-used-blocks>

            <percentage-of-used-blocks>1</percentage-of-used-blocks>

            <cumulative-percentage-of-total-blocks>20</cumulative-percentage-of-total-blocks>

            <percentage-of-total-blocks>0</percentage-of-total-blocks>

        </snapshot-info>

    </snapshots>

</results>

[Sat Nov 19 10:33:19 2011] INFO: NetApp Snapshot Inventory of vmware_nfs on fas01 completed Successfully

########## Creating Protection Manager Backup Version for volume vmware_nfs dataset snapcreator_SMSQLtest ##########

[Sat Nov 19 10:33:19 2011] INFO: Finding all members associated with Protection Manager dataset snapcreator_SMSQLtest

[Sat Nov 19 10:33:19 2011] DEBUG: ZAPI REQUEST

<dataset-member-list-info-iter-start>

    <dataset-name-or-id>snapcreator_SMSQLtest</dataset-name-or-id>

</dataset-member-list-info-iter-start>

[Sat Nov 19 10:33:20 2011] DEBUG: ZAPI RESULT

<results status="passed">

    <records>2</records>

    <tag>dataset-member4637_27713</tag>

</results>

[Sat Nov 19 10:33:20 2011] DEBUG: ZAPI REQUEST

<dataset-member-list-info-iter-next>

    <maximum>1</maximum>

    <tag>dataset-member4637_27713</tag>

</dataset-member-list-info-iter-next>

[Sat Nov 19 10:33:20 2011] DEBUG: ZAPI RESULT

<results status="passed">

    <dataset-members>

        <dataset-member-info>

            <member-id>136</member-id>

            <member-type>volume</member-type>

            <member-name>fas01:/vmware_nfs</member-name>

            <member-status>normal</member-status>

            <member-perf-status>unknown</member-perf-status>

            <storageset-id>182</storageset-id>

            <storageset-name>snapcreator_SMSQLtest:root</storageset-name>

            <dp-node-name>Primary data</dp-node-name>

            <is-deleted>false</is-deleted>

        </dataset-member-info>

    </dataset-members>

    <records>1</records>

</results>

[Sat Nov 19 10:33:20 2011] DEBUG: ZAPI REQUEST

<dataset-member-list-info-iter-next>

    <maximum>1</maximum>

    <tag>dataset-member4637_27713</tag>

</dataset-member-list-info-iter-next>

[Sat Nov 19 10:33:20 2011] DEBUG: ZAPI RESULT

<results status="passed">

    <dataset-members>

        <dataset-member-info>

            <member-id>185</member-id>

            <member-type>volume</member-type>

            <member-name>fas02:/vault</member-name>

            <member-status>normal</member-status>

            <member-perf-status>unknown</member-perf-status>

            <storageset-id>184</storageset-id>

            <storageset-name>snapcreator_SMSQLtest:Backup</storageset-name>

            <dp-node-name>Backup</dp-node-name>

            <is-deleted>false</is-deleted>

        </dataset-member-info>

    </dataset-members>

    <records>1</records>

</results>

[Sat Nov 19 10:33:20 2011] DEBUG: ZAPI REQUEST

<dataset-member-list-info-iter-end>

    <tag>dataset-member4637_27713</tag>

</dataset-member-list-info-iter-end>

[Sat Nov 19 10:33:20 2011] DEBUG: ZAPI RESULT

<results status="passed"></results>

[Sat Nov 19 10:33:20 2011] INFO: All members of Protection Manager dataset snapcreator_SMSQLtest Successfully discovered

[Sat Nov 19 10:33:20 2011] INFO: Added member fas01:/vmware_nfs/- from dataset snapcreator_SMSQLtest to Protection Manager Backup Version

########## Running NetApp Protection Manager Backup Version Create for dataset snapcreator_SMSQLtest ##########

[Sat Nov 19 10:33:20 2011] INFO: Registering snapshot smsql-daily_20111119103229 with Protection Manager dataset snapcreator_SMSQLtest

[Sat Nov 19 10:33:20 2011] DEBUG: ZAPI REQUEST

<dp-backup-version-create>

    <backup-description>NetApp Snap Creator Framework Backup</backup-description>

    <dataset-name-or-id>snapcreator_SMSQLtest</dataset-name-or-id>

    <retention-type>daily</retention-type>

    <version-timestamp></version-timestamp>

    <version-members>

        <version-member-info>

            <snapshot-name>smsql-daily_20111119103229</snapshot-name>

            <snapshot-contents>

                <snapshot-member-info>

                    <primary-name>fas01:/vmware_nfs/-</primary-name>

                </snapshot-member-info>

            </snapshot-contents>

            <volume-name>fas01:/vmware_nfs</volume-name>

        </version-member-info>

    </version-members>

</dp-backup-version-create>

[Sat Nov 19 10:33:20 2011] DEBUG: ZAPI RESULT

<results reason="snapshot-unique-id must be specified in version-member-info." errno="13001" status="failed"></results>

[Sat Nov 19 10:33:20 2011] ERROR: [scf-00013] snapshot-unique-id must be specified in version-member-info. (code = 13001)

########## PRE EXIT COMMANDS ##########

[Sat Nov 19 10:33:20 2011] INFO: No commands defined

########## PRE EXIT COMMANDS FINISHED SUCCESSFULLY ##########

[Sat Nov 19 10:33:20 2011] DEBUG: Loaded Config File C:/Program Files/Netapp/NetApp_Snap_Creator_Framework/scServer3.4p2/configs/SMSQL/SMSQLtest.conf Successfully

[Sat Nov 19 10:33:20 2011] INFO: Creating OM Event (script:critical-event) on 192.168.0.61

[Sat Nov 19 10:33:20 2011] DEBUG: ZAPI REQUEST

<event-generate>

    <event-name>script:critical-event</event-name>

    <source>1</source>

    <event-message>SNAPCREATOR ERROR: [scf-00013] snapshot-unique-id must be specified in version-member-info. (code = 13001) (Config: SMSQLtest Name: smsql Policy: daily)</event-message>

</event-generate>

[Sat Nov 19 10:33:21 2011] DEBUG: ZAPI RESULT

<results status="passed"></results>

[Sat Nov 19 10:33:21 2011] INFO: OM Event (script:critical-event) on 192.168.0.61 created successfully

[Sat Nov 19 10:33:21 2011] DEBUG: Exiting with error code - 1

31 REPLIES 31

ktenzer
4,067 Views

There is another thread on the snapshot-name must be specified in version-member-info error. Basically SC only allows you to register 1 snapshot with a dataset per configuration file. Protection Manager checks and if the snapshot doesnt exist on one of volumes in dataset it wont let you register. My guess is the sqlinfo snap doesnt exist on one of the volumes in dataset.

SMSQL creates two snapshots an info and a data snapshot. SC can only register however one of these per config. You probably need to create two configs 1) can run SM and register just the data snapshot 2) the other can just register the sqlinfo snapshot. Like I said I have never set this up so these are just ideas.

Let me know if this helps?

Keith

marklomas
4,067 Views

Hi Keith.

Is this a bug?  If you look through my debug log of this (attached), at every step of the way, SC server succesfully 'discovers' the name of the snapshot for /both/ volumes. The snapshot names appear again and again through out the log for both volumes, but then at the all important last step (where it does appear to be having an /attempt/ at registering a snapshot with PM for both volumes) it seems to just 'forget' the snapshot names, and leaves the field empty (ie, <snapshot-name></snapshot-name>)

I guess what I'm saying is, that to me it looks like SC is supposed to be able to do this, but somewhere in the process it's just not populating the relevant fields, even though it has succesfully discovered what should be going in those fields!!

I have attached my debug log file from SC Server.

--

Mark Lomas

ktenzer
4,067 Views

No it is not a bug SC was not designed and it isnt supported to register multiple snapshots with different names to same dataset (try to do this with SC taking backup, it is impossible to have multiple snapshot names). Certainly we can take this as a feature and look at improving SC's PM integration in future but it was not designed to work this way as mentioned which is why it only tries to register 1 snapshot instead of both.

Again SC only ever creates one snapshot when it does a backup regardless of plugin and the Protection Manager integration SC has is used by SC therefore there was never a reason to support or allow multiple different snapshots to be registered to SC. In addition SMSQL and SME have their own Protection Manager integrations so there were no use cases where we heard customers wanted to use SC to do this for SnapManager. Can I ask, why are you trying to use SC to register backups with Protection Manager for SMSQL when SMSQL as its own integration is better designed to work with how SMSQL manages and takes snapshots?

You should still be able to get this to work if you setup multiple configs. SC is a framework so just because something doesnt work out-of-box as maybe expected, doesnt mean there isnt a solution to be had.

I will take this back to dev and see if this is something we can support in future (multiple snapshot registration in PM) but that doesnt help you now unfortunately so either you need to try something else or not use SC in this case as it doesnt solve things the way you would like.

I do agree that because external snapshot feature allows for situation where we could have multiple different snapshots that we should support this everywhere otherwise external snapshot feature is somewhat limited at least with other capabilities.

Regards,

Keith

marklomas
3,763 Views

Hi Keith;

I'm not trying to register multiple snapshots with different names - I'm trying to register ONE snapshot on multiple volumes!  SC /does/ appear to be at least trying to do this: you'll note that at the end of my log file where it trys to make the registration, there are TWO '<version-member-info>' entries - but within both of them, the 'snapshot-name' field is empty!  See below:

<dp-backup-version-create>

<backup-description>NetApp Snap Creator Framework 3.6.0 Backup</backup-description>

<dataset-name-or-id>snapcreator_SQL2</dataset-name-or-id>

<retention-type>hourly</retention-type>

<version-timestamp>1344354191</version-timestamp>

<version-members>

  <version-member-info>

   <snapshot-unique-id>1344354186</snapshot-unique-id>

   <snapshot-name></snapshot-name>

   <snapshot-contents>

    <snapshot-member-info>

     <primary-name>fas-ico1b:/SRV_SCA1/-</primary-name>

    </snapshot-member-info>

   </snapshot-contents>

   <volume-name>fas-ico1b:/SRV_SCA1</volume-name>

  </version-member-info>

  <version-member-info>

   <snapshot-unique-id>1344354191</snapshot-unique-id>

   <snapshot-name></snapshot-name>

   <snapshot-contents>

    <snapshot-member-info>

     <primary-name>fas-ico1b:/SRV_SCA_Snapinfo/-</primary-name>

    </snapshot-member-info>

   </snapshot-contents>

   <volume-name>fas-ico1b:/SRV_SCA_Snapinfo</volume-name>

  </version-member-info>

</version-members>

</dp-backup-version-create>

The reason we want to use SC to register snapshots into PM, is so that we can use PM as a way to verify that snapshots are actually happening by making use of the snapshot lag monitoring capabilities built into PM.  We can use PM to flag up that snapshots haven't been taken in a while if there has been a problem.  SnapManager PM integration does NOT allow for this, since it doesn't actually register snapshots, it only 'prods' PM into updating a SnapVault relationship, but it does NOT register the snapshot in PM (beleive me, we've tried, and we've worked with several people at Netapp to see if it can be made to work.  The response ended up being that ir doesn't work, and there are no plans to implement it any time soon!).

Naturally when I saw that SC can register snapshots into PM, I jumped at the chance to explore this option.  If SC can schedule SM operations, AND update PM by registering the Snapshots - it's problem solved! Finally we'd have solultion to use to allow PM to actually tell us  if snapshots haven't happened in a whole on a dataset!  From what I'm seeing in our logs, it seems like SC could do this without configuring multiple configs or jobs to do this, however if it can't do it natively right now, I'll try the steps you requested.  I would appreciate it if you could feedback to development - it would be a great option to be able to get this sort of thing working!

(As an aside, when it comes to SME or SMSQL, valid setups for that software include having multiple volumes for different databases, so, being able to register snapshots into PM using one config really would help keep things simple!).

To make this work for now then, I guess what you're saying is I should try running my config to activate the SM process, but not include the 'snapinfo' volume in my config, and should then have a second job which also has the external flag set to pull in the snapinfo volume?

marklomas
4,067 Views

Hi Keith, further to my last response.

I've tried it again, and removed the SnapInfo Volume from the config, leaving in just the volume that contains the database.

Still the same error : [Fri Aug 10 14:15:48 2012] ERROR: [scf-00013] snapshot-name must be specified in version-member-info. (code = 13001).

Once again, further up in the debug log, I can see that it has sucesfully identified the correct name of the 'external' snapshot - but the relevant name doesn't seem to make it into the ZAPI call to the PM server.

The SnapInfo volume is still a part of the dataset on the PM server - and SC seems to 'discover' that, during the job.  This is despite me removing the SnapInfo volume from the 'NTAP_DFM_DATA_SET value in my config.  Do I have to put the SnapInfo volume in a seperate dataset?  (I hope not - as it would rather seem to undermine the point of having datasets in the first place!!).

Any help or advice appreciated

--

Mark Lomas

ktenzer
4,070 Views

Hi Mark,

Ok yeah for whatever reason the snapshot-name is not getting added to dataset, could be because dataset is not configured correctly. Can you send me info about dataset? Need to see members, volume names, or qtrees, etc. There is CLI command to show dataset.

Maybe you can try without SMSQL?

1) create snapshot blablalbla

2) point SC at this volume and add it to PM dataset (you can just have dataset with primary volume)

3) see what happens?

Regards,

Keith

marklomas
3,763 Views

OK - I've actually managed to get it to work!

I've got a config to run the SM job, a config to register the data volume snapshot, and another to register the snapinfo volume snapshot.  They now all work correctly, and the relevant data gets registered into PM!

I hope to continue testing with this to make sure snapshots get correctly 'expired' after retention time has passed etc, but this is now looking better.

I hope you'll feed back to development that there is a legitimate reason for using SC to run SM jobs /and/ register the resulting snapshots into PM.  The PM integration featured in SM just doesn't do it.  Certainly, the ability to register the snapshots taken by SM and have it done in one single config would be a much simpler affair, but if this is what we have to do, then we'll work with it for now and see if we can make it stick.

Huge thanks for your help!

--

Mark Lomas

ktenzer
3,183 Views

Hi Mark,

I am a member of the development team (dont tell anyone, I am a field guy that moonlights as a developer)

All I can say is you rock for figuring this out before I could. Can you please share what the issue was and what you did to get things working? I think issue was simply that SC can only register one snapshot per config and you were 1) trying to do 2 or 2) had dataset setup with volume that wasnt in SC config. SO I assume this required two datasets and two SC configs?

Snap Creator integration with SnapManager is a top priority, we are working on really getting true integration and this is huge area of focus so I am glad it sounds like we are in alignment. Keep feeding us requirements!

Regards,

Keith

marklomas
3,183 Views

Hi;

No, I've left both volumes in the one dataset.  I simply configured my existing SM config not to update PM, and then created two new configs for each volume.  So, that would indicate there might something else lurking in my original SC job that is preventing it update at least one of the volumes in PM.

I'll carry on with my testing and I'll keep you updated as to whether I encounter any other problems!

Great news that you'll be working on tighter SM integration!  The guys we spoke to about direct integration between SM and PM literally said that there are no plans to make it any better. SC seems like it may therefore ride to the rescue on helping us get a single console cobbled together that we can use to keep ourselves abreast of where we our with our snapshotting processes.

Many thanks again!

--

Mark Lomas

ktenzer
3,704 Views

By all means try it with APP_NAME=smsql and set NTAP_USE_EXTERNAL_SNAPSHOT=Y then enable PM settings.

Keith

ktenzer
3,704 Views

Oh and use SC 3.6 it is available on NOW site (released July 19th), not the community release 3.6c (community release was in april and are typically good for test envs but I would always go with NOW release for production):

Keith

Public