Subscribe

SnapCreator 3.4p2 with SMSQL (VM) and PM updates

[ Edited ]

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

Re: SnapCreator 3.4p2 with SMSQL (VM) and PM updates

No real answer, just a "me too".

I have exactly the same problem for SMSQL / SME as well as SnapDrive-only (non app Snapmanager) configurations.

Error is the same with SC 3.4.0, 3.4p2 and also with 3.5.0c

I think SC is missing to pass the "snapshot-unique-id" field to PM when Windows-based quiescing is used.

When I omit Snapdrive/Snapmanager quiescing, the PM updates work (the snapshot-unique-id field is then populated correctly).

Just my 2 cents,

Mark

Re: SnapCreator 3.4p2 with SMSQL (VM) and PM updates

I correct myself with regards to Snapdrive-based snapshots. Got that sorted, see here: https://communities.netapp.com/message/68729#68729

But the problem with SMSQL / SME still persists for me too. It is probably due to snapshot name format.

SC expects the primary snap to have a static naming format (at least for the PM integration): %SNAME-%SNAP_TYPE_%SNAP_TIME

[Mon Nov 28 23:36:58 2011] INFO: Registering snapshot exchsnap-daily_20111128233627 with Protection Manager dataset SC_V_EX27_SME_SG1


Unfortunately the SnapManagers use a different naming format: exchsnap__<servername>_<timestamp_format_of_snapmgr>__<mgmtgroup>, which cannot be customized AFAICT.
E.g. (in my case): exchsnap__V_EX27_11_29_2011_20_08_014__Daily 
or (with generic naming): exchsnap__V_EX27__recent

Both do not work with SC's "NTAP_PM*" options. Seems the Windows-SnapManager's snapname format is simply not (yet) cleanly implemented in SC. Tested with SC 3.5.0c.

I have no idea where / how to fix that ... obviously I'm not a developer

Re: SnapCreator 3.4p2 with SMSQL (VM) and PM updates

The issue here is that this is snapshot created by SMSQL:

smvi__sqlinfo__win2003sp2-2_11-19-2011_10.32.39__daily_20111119103304

This is the snapshot SC thinks is being created:

smsql-daily_20111119103229

SC does not understand the different snapshot naming conventions that all the SnapManagers have. The only way to get this to work is to create a snapshot that matches what SC expects, in this case smsql-daily_20111119103229.

SC supports running SnapManager remotely but integrating snapshots created by SnapManagers is not directly supported in SC, you need to change snapshot naming convention in SnapManagers/VSC to match that of SC.

We are in future working on a way to handle all the different snapshots by all the different snapmanagers but arent quite there yet. The idea is SC would simply do a snaplist and grab the last snapshot (ie: most recent) which should be the one created by SM since SC would run SM.

Some people have gotten this to work with SMVI / VSC since in the latest version you can change the snapshot format but this is sort of hacking things. Here is the info i have on that:

Add in smvi.override :

snapshot.name.format.timestamp={backupName}

Idea is you tell SMVI to take a SC friendly snapshot, so you pass SC snapshot name %SNAME-%SNAP_TYPE_%SNAP_TIME to SMVI somehow through SMSQL (not sur eif thats possible without SMSQL you can do it), then SC could register the snapshot in PM.

Regards,

Keith

Re: SnapCreator 3.4p2 with SMSQL (VM) and PM updates

Keith, this will work for SMVI/VSC backups. It does not work as soon as SMSQL, SME or even SMSP is involved.

I meanwhile found a way to integrate SME with PM (with a Qtree-Snapmirror Policy) using SnapCreator. Didn't have time to test it with SMSQL, but will let you know when I do.

It's also more or less a hack. And it cannot use the SME/SMSQL plugins for SC. You need to do the SnapManager backup and "snap rename" calls via NTAP_SNAPSHOT_CREATE_CMD0x or alternatively via PRE/POST parameters. The plugins definitely do not work with ProtectionManager integration.

My idea is to "steal" a (daily group) backup version from SnapManager and register it to PM, while SnapManager maintains its own (standard group) versions.. E.g. I use a powershell command that creates a daily "recent" SnapManager backup which does NOT include a transactionlog backup / does NOT truncate transactionlogs - this way SnapManager will not miss anything or complain when doing an "up-to-the-minute restore" from the primary storage node.

And (with a second config/schedule) I create a second SnapManager backup (including transactionlogs) with is not "stolen".

So, SC triggers the first SnapManager backup, and then just RENAMES the "recent" snapshot (exchsnap_recent on DB volume, eloginfo_recent on LOG volume; or sqlsnap, sqlinfo snap) to the format it expects for PM registration: %SNAME-%SNAP_TYPE_%SNAP_TIME ...

My example config entries for the "daily" config (very specific for SnapManager for EXCHANGE and to my lab setup - no guarantees!):

NTAP_SNAPSHOT_CREATE_CMD01=C:\WINDOWS\system32\WindowsPowerShell\v1.0\powershell.exe -psconsolefile "C:\Program Files\NetApp\SnapManager for Exchange\smeshell.psc1" -command "new-backup -Server 'v-w2k3-ex27-1' -GenericNaming -ManagementGroup 'Daily' -BackupType CopyBackup -RetainDays 2 -RetainUtmDays 2"
NTAP_SNAPSHOT_CREATE_CMD02="C:\Program Files\NetApp\SnapDrive\sdcli" snap rename -d G: -o exchsnap__v-w2k3-ex27-1__recent -n %SNAME-%SNAP_TYPE_%SNAP_TIME
NTAP_SNAPSHOT_CREATE_CMD03="C:\Program Files\NetApp\SnapDrive\sdcli" snap rename -d H: -o eloginfo__v-w2k3-ex27-1__recent -n %SNAME-%SNAP_TYPE_%SNAP_TIME

NTAP_SNAPSHOT_CREATE_CMD04="C:\Program Files\NetApp\SnapDrive\sdcli" snap delete -d H: -s exchsnap__v-w2k3-ex27-1__recent"

And my example for the "standard/hourly" config:

NTAP_SNAPSHOT_CREATE_CMD01=C:\WINDOWS\system32\WindowsPowerShell\v1.0\powershell.exe -psconsolefile "C:\Program Files\NetApp\SnapManager for Exchange\smeshell.psc1" -command "new-backup -Server 'v-w2k3-ex27-1' -GenericNaming -ManagementGroup 'Standard' -BackupType FullBackup -RetainDays 2 -RetainUtmDays 2"

This works for me (at least in my lab). But... it's hard to document. And does not work in all environments... But at least it's an idea. ;-)

At the end I have a version history in SnapManager (on primary storage - which can do up-to-the-minute restore from short-term backups), and I have a PM history of backups which are "invisible" for SnapManager, but transferred and retained on secondary storage.

Just my 2 cents, thought it might be interesting to anyone reading this thread ;-)

Mark

Re: SnapCreator 3.4p2 with SMSQL (VM) and PM updates

Yep we understand this

SC does not know SnapManager naming conventions except for SME and SMSQL but there we require _recent and _recent doesnt work for PM

What you are doing will work however you wont be able to do restore from SnapManager. If you rename snapshots to SC naming you can register them in PM but then cant only restore manually. If you rename them so SC can rename them in PM and then rename them back to SM naming then you can restore in SM but PM will complain since snapshot is gone.

I think best you can do is following:

Set RUN_PM_BACKUP=Y

1. rename last backup from SC naming to SM naming

2. Run Snap Manager backup

3. rename SM backup to SC naming

4. SC registers backup in PM

This would mean that PM should have vaulted snapshot before you rename it, so though PM would complain that primary backup is gone, the secondary should still be there

Like you said this is a hack but it is all we have...we are working hard to try and have SC understand SM naming conventions and provide ability to better integrate, this is a top priority

Regards,

Keith

Re: SnapCreator 3.4p2 with SMSQL (VM) and PM updates

couple of questions:

- in this setup, you are NOT using SMSQL or any SnapManager to directly interact with Protection Manager, is this correct? If not, can I ask why? The reason is, if you don't, then, ALL the SnapVault backups do not get exposed in SMSQL. THis is basically going back to steps documented in KB3990.

- anyways, if the intent was to actually use SC to perform SV updates as part of a post backup process, then, you need the right snapshot taken by SnapManagers to be passed into SC to perform SV update. Again, this is same as KB 3990.

Now, if you were to use SMSQL with Protection Manager integration, then, SC in this case would just be a simple & centralised scheduler. Whereby, SMSQL has been configured for remote backups during configuration wizard. Once this is done, any SMSQL backups that are initiated (as a scheduler by SC) or performed local admin type ad-hoc jobs will automatically perform SV updates via PM. and all these SV backups get exposed into SMSQL, and this enables DBA to perform GUI based recovery of a database directly from SV.

anyways, just thought of throwing this out!

Cheers, balbeer

Re: SnapCreator 3.4p2 with SMSQL (VM) and PM updates

Yes.

The reason is our customer need Qtree-SnapMirror, no Snapvault.

SMSQL/SME integration with PM is great when you have snapvault. It is not possible with QSM.

We are evaluating the above workarounds (of course according to KB 3990) in order to use a "DR Backup then Mirror" policy for SME and SMSQL backups...

Would love to see support for all kinds of protection policies within SMSQL/SME directly. But it's not there...

Re: SnapCreator 3.4p2 with SMSQL (VM) and PM updates

Hi balbeer

Another reason is if you are using VMDK Disks with SnapManager/Snapdrive.

Dataset integration with Protection Manager is not available for VMDK over NFS and VMFS datastore. (see SnapManager 5.1 guide under "What SnapManager does not do"). That's a big limitation!

The only solution (which i know at the moment) is to use SnapCreator with Protection Manager Integration...

But i have also this issue with "snapshot-unique-id must be specified in version-member-info"...

Thanks and regards

Reto


Re: SnapCreator 3.4p2 with SMSQL (VM) and PM updates

We are working on something for next release of SC that would allow SC to just grab last snapshot and not care about what it is called to register in into PM. It is still in engineering so no promises but it is being worked on which would help here.

Regards,

Keith