Data Backup and Recovery

SnapCreator 3.4p2 with SMSQL (VM) and PM updates

jhubert

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

erpsourcing

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


ktenzer

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

erpsourcing

Hi Keith

Wow, that would be awesome!

In which version of Snap Creator is this planned? I'm very interested in this feature and would be happy to test a beta version 😉

Snap Creator is one of the best software from NetApp, i love it 😉

Regards

Reto

ktenzer

Hi Reto,

This would be available in SC 3.6. Community version will be up on www.snapcreator.com in March hopefully but I can get you beta build sooner. NetApp supported release of SC 3.6 June 2012.

Are you signed up on www.snapcreator.com? If you don't have access we can do webex to get you signed up, we haven't yet opened it up to everyone w/NOW account.

SC 3.5 releases this Friday Jan 20 so too late for that but it has lots of cool new stuff so check it out!

Regards,

Keith

erpsourcing

Hi Keith

Sorry for my late response...

Yes, i'm already registered on SnapCreator.com.

I have found the following thread https://github.com/NetApp/SC-Framework/issues/294

with your commit "SC now can support evternal snapshots from other SMs" https://github.com/NetApp/SC-Framework/commit/9bd35a0fd997fd1f8a49bef6526e9d36b42ee5d5

Wow, you are really fast 🙂

We have installed Snap Creator Server on Windows, how we can "compile" the newest SnapCreator version as ".exe" from github? Or should we use better Snap Creator Server on Linux?

Thanks for your help!

Best regards

Reto

ktenzer

Hi Reto,

You can run source code to test. You need to install ActivePerl and dependent perl modules:

https://snapcreator.netapp.com/get-started/running-the-code.aspx

Also use Linux else it will be a headache ;D

1. Install ActiveState Perl

2. set PATH, PATH=/path/to/ASPerl/bin:$PATH

3. Run ./snapcreator.pl and you will get error that something is missing like Crypt::CBC. So you use ppm under bin dir of AsPerl to install ie: ppm install Crypt::CBC. The system also needs internet access to pull down packages.

I am working on getting a weekly build available to community but we aren't quite there yet, so in future this will be much easier

To get the source code you can look here:

https://snapcreator.netapp.com/get-started/getting-the-code.aspx

https://github.com/NetApp/SC-Framework/wiki/HELP-NetApp-Repository-@Github.com

Also if you want to IM, we have IRC chat where some of the developers hang out, myself included, and you can ask us questions about anything:

https://github.com/NetApp/SC-Framework/wiki/HELP-IRC-Chat

Regards,

Keith

erpsourcing

Hi Keith

I've now successfully installed Active Perl & Snap Creator test source code is now running !

The new feature works, but i think that we have found a little bug in PM snapshot register function with multiple volumes, see my post on: https://github.com/NetApp/SC-Framework/issues/294

Thanks for your help and best regards

Reto

ktenzer

Hi Reto,

Yep thanks I will be looking to address this issue. I think I know the problem. One thing though is that you will have to change to not use _recent snapshots, these wont work in PM since SC cant rename the snapshots since they are controlled by SM.

Regards,

Keith

marklomas

Hi;  I've been experiencing the issue of SM-created snapshots not being registered into PM via SC due to the naming issue.  I'm not using _recent naming, but SC thinks the snapshot name is different to the one SnapManager created.

As I understand it, newer versions of SC were going to resolve this issue, but I'm running 3.6, and the issue still occurs - is there a configuration setting I need to set or change to make this happen, or is it only fixed in 3.6c?

Many thanks

--

Mark Lomas.

ktenzer

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

ktenzer

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

Keith

ktenzer

Yes SC doesnt support outright registering a SnapManager in protection manager or claim to. It simply runs the SnapManager but SC is a framework so this is definitely possible. In 3.6 what it supports is EXTNERAL snapshots. So SC can find an external snapshot, that is snapshot not taken from SC and register it into PM. There is option in config to use this. Not sure it works with the SnapManager plugin but certainly you can call powershell via a script on SC Agent and use EXTERNAL snapshot capability to register it with PM. This would involve commenting out APP_NAME and using APP_QUIESCE_CMD01 to run the SM from CLI though the agent.

SC will find whatever is recent snapshot and register it for given volume or volumes. You can also give a regex like "snap*" this would match snap and anything after it and the most recent.

Hope this helps

Keith

marklomas

Hi Keith;

Thanks for the reply.  I think the reason I'd assumed this would be supported is that when I create a SMSQL or SME config using the GUI, the screens to ask if I want to do a PM update are still displayed, which strongly implies this should work!

The config I've created using SC is a SMSQL config, so the APP_NAME variable is already set to 'smsql' - however if I also set the 'NTAP_USE_EXTERNAL_SNAPSHOT' variable to 'Y' then I get an error:

"[scf-00157] The NTAP_USE_EXTERNAL_SNAPSHOT option is not supported with sme or smsql plug-ins!"

So .. what does this leave for me?  Do I need to run a second job after the SMSQL job has completed to in some way 'fetch' the name of the snapshot that SMSQL has created and register it into PM?

Many thanks.

[UPDATE]

Can I also inquire ... earlier in this thread, you stated that in 3.6, you were working on a feature that would allow SC to simply 'grab' the name of the snapshot, and push it into PM.  And another person replied stating they had tried the then community release of 3.6 and it had worked.  Has this feature just not made it into the supported version?

--

Mark Lomas

ktenzer

Yes this feature works and is part of 3.6 and is supported. The option to allow SC to suck in a snapshot and then do something with it is called NTAP_USE_EXTERNAL_SNAPSHOT. It isnt compatible with the SME and SMSQL plugins as you point out, I wasnt sure if it was or wasnt.

I think the best option based on this info is following:

Dont use SME and SMSQL plugins...alll they do is simply run powershell through agent. You can run SMSQL just the same from command line call. So if you configure SC_AGENT you can for example use APP_QUIESCE_CMD01=inster command to run backup for snapmanager here

You need to add command to the scAgent3.6.0/config/agent.conf to authorize it, all cmds not used by plugins must be authorized

This would then create snapshot, and SC would find it from NTAP_USE_EXTERNAL_SNAPSHOT and finally then is PM piece where SC can register it in PM

I know this is a bit tricky, we are working on improving integration with SM and SC so expect some good strides in future but we arent quite there yet. Still you can do a lot and SC is a framework so there are a lot of options to get things working.

Keith

marklomas

Hi Keith;

I've created a new config that doesn't use the SMSQL plugin, but just executes a command with the external flag set to yes.

SC seems to evaluate the name of the snapshot correctly now, however I'm still getting an error, see below:

99 ########## Running Protection Manager Backup Version Create for dataset snapcreator_SQL2 ##########

100 [Tue Aug  7 16:46:09 2012] INFO: Registering snapshot sqlinfo__srv-sca1_08-07-2012_16.42.54 with Protection Manager dataset snapcreator_SQL2

101 [Tue Aug  7 16:46:09 2012] ERROR: [scf-00013] snapshot-name must be specified in version-member-info. (code = 13001)

The snapshot name SC has 'discovered' seems to be bang on - but the error being thrown is exactly the same as the one I encountered when it was picking up the wrong name.

Any ideas where I might be going wrong?

(Also, glad to hear ongoing development is continuing on this, we're keen to get a centralised management system in place, and SC seems to be a key peice of the puzzle).

--

Mark Lomas

ktenzer

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

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

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

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

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

Announcements
NetApp on Discord Image

We're on Discord, are you?

Live Chat, Watch Parties, and More!

Explore Banner

Meet Explore, NetApp’s digital sales platform

Engage digitally throughout the sales process, from product discovery to configuration, and handle all your post-purchase needs.

NetApp Insights to Action
I2A Banner
Public