Re: Job Status failed although OK

REISTTELECOM · ‎2013-04-29

I have a problem regarding the Job Status display in SnapCreator 3.6. When I manually run the job (Snapshot with SnapDrive and SnapVault to secondary), the job completes successfully but the job status shows failed. I can even see the snapshot in SnapDrive GUI, on the primary filer and the secondary filer.

Does anybody know the reason for this behaviour?

sivar · ‎2013-04-29

Please copy paste the debug logs output here or email me to sivar at netapp.com

REISTTELECOM · ‎2013-04-29

Hi Sivar,

thx for your help.

here is the debug log:

[Mon Apr 29 14:46:23 2013] INFO: Logfile timestamp: 20130429144623

[Mon Apr 29 14:46:23 2013] DEBUG: GMT - Mon Apr 29 12:46:23 2013

[Mon Apr 29 14:46:23 2013] DEBUG: Version: NetApp Snap Creator Framework 3.6.0

[Mon Apr 29 14:46:23 2013] DEBUG: Profile: LXBI-TE

[Mon Apr 29 14:46:23 2013] DEBUG: Config Type: STANDARD

[Mon Apr 29 14:46:23 2013] DEBUG: Action: snap

[Mon Apr 29 14:46:23 2013] DEBUG: Application Plugin: null

[Mon Apr 29 14:46:23 2013] DEBUG: File System Plugin: null

[Mon Apr 29 14:46:23 2013] DEBUG: Policy: daily

[Mon Apr 29 14:46:23 2013] DEBUG: Snapshot Name: LXBITEWEB10_OS-daily_20130429144623

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

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing VOLUMES - controller: rt-zrh-dss-103 volume: v_lx_lxbiteweb10_000_boot

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing NTAP_USERS - controller: rt-zrh-dss-103 user: u_snapcreator

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing NTAP_USERS - controller: rt-zrh-dss-301 user: u_snapcreator

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing NTAP_SNAPSHOT_RETENTIONS - policy: daily retention: 5

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing NTAP_SNAPVAULT_RETENTIONS - sv policy: daily retention: 40

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing SNAPVAULT_VOLUMES - controller: rt-zrh-dss-103 sv volume: v_lx_lxbiteweb10_000_boot

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

[Mon Apr 29 14:46:23 2013] INFO: No commands defined

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

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

[Mon Apr 29 14:46:23 2013] INFO: No commands defined

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

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

[Mon Apr 29 14:46:23 2013] INFO: No commands defined

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

########## PRE COMMANDS ##########

[Mon Apr 29 14:46:23 2013] INFO: No commands defined

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

[Mon Apr 29 14:46:23 2013] DEBUG: Loaded Config File C:/Program Files/NetApp/NetApp_Snap_Creator_Framework/scServer3.6.0/configs/LXBI-TE/LXBITEWEB10_OS.conf Successfully

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

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing VOLUMES - controller: rt-zrh-dss-103 volume: v_lx_lxbiteweb10_000_boot

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing NTAP_USERS - controller: rt-zrh-dss-103 user: u_snapcreator

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing NTAP_USERS - controller: rt-zrh-dss-301 user: u_snapcreator

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing NTAP_SNAPSHOT_RETENTIONS - policy: daily retention: 5

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing NTAP_SNAPVAULT_RETENTIONS - sv policy: daily retention: 40

[Mon Apr 29 14:46:23 2013] DEBUG: Parsing SNAPVAULT_VOLUMES - controller: rt-zrh-dss-103 sv volume: v_lx_lxbiteweb10_000_boot

[Mon Apr 29 14:46:23 2013] DEBUG: ZAPI REQUEST

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

[Mon Apr 29 14:46:24 2013] TRACE: ZAPI RESULT

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

<minor-version>19</minor-version>

</results>

[Mon Apr 29 14:46:24 2013] DEBUG: creating executor for storage controller rt-zrh-dss-103

########## Detecting Data OnTap mode for rt-zrh-dss-103 ##########

[Mon Apr 29 14:46:24 2013] DEBUG: ZAPI REQUEST

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

[Mon Apr 29 14:46:24 2013] TRACE: ZAPI RESULT

<version>NetApp Release 8.1.2P1 7-Mode: Mon Jan 7 15:55:05 PST 2013</version>

<is-clustered>false</is-clustered>

</results>

[Mon Apr 29 14:46:24 2013] INFO: Data OnTap 7 mode detected

[Mon Apr 29 14:46:24 2013] DEBUG: ZAPI REQUEST

<options-get>

<name>ndmpd.preferred_interface</name>

</options-get>

[Mon Apr 29 14:46:24 2013] TRACE: ZAPI RESULT

<value>disable</value>

<cluster-constraint>only_one</cluster-constraint>

<cluster_constraint>only_one</cluster_constraint>

</results>

[Mon Apr 29 14:46:24 2013] DEBUG: Connected to rt-zrh-dss-103 using API Version 1.19

########## Generating Info ASUP on rt-zrh-dss-103 ##########

[Mon Apr 29 14:46:24 2013] DEBUG: ZAPI REQUEST

<ems-autosupport-log>

<app-version>NetApp Snap Creator Framework 3.6.0</app-version>

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

<category>Backup Started</category>

<computer-name>RT-ZRH-SRV-108 [146.216.97.35:9090]</computer-name>

<event-description>INFO: NetApp Snap Creator Framework 3.6.0 Backup for LXBITEWEB10_OS ACTION: snap POLICY: daily Plugin: null - Supported Volumes: rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot Started</event-description>

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

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

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

</ems-autosupport-log>

[Mon Apr 29 14:46:24 2013] TRACE: ZAPI RESULT

[Mon Apr 29 14:46:24 2013] INFO: ASUP create on rt-zrh-dss-103 finished successfully

########## Gathering Information for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot ##########

[Mon Apr 29 14:46:24 2013] INFO: Performing Snapshot Inventory for v_lx_lxbiteweb10_000_boot on rt-zrh-dss-103

[Mon Apr 29 14:46:24 2013] DEBUG: ZAPI REQUEST

<snapshot-list-info>

<volume>v_lx_lxbiteweb10_000_boot</volume>

</snapshot-list-info>

[Mon Apr 29 14:46:24 2013] TRACE: ZAPI RESULT

<snapshot-info>

<name>rt-zrh-dss-301(2014176755)_sv_lx_lxbiteweb10_000_boot_Cxx-src.0</name>

<access-time>1367237490</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-instance-uuid>eca92876-b0c5-11e2-850b-123478563412</snapshot-instance-uuid>

<snapshot-version-uuid>eca92876-b0c5-11e2-850b-123478563412</snapshot-version-uuid>

</snapshot-info>

</snapshots>

</results>

[Mon Apr 29 14:46:24 2013] INFO: Snapshot Inventory of v_lx_lxbiteweb10_000_boot on rt-zrh-dss-103 completed Successfully

########## Running Snapshot Rename on Primary rt-zrh-dss-103 ##########

[Mon Apr 29 14:46:24 2013] INFO: LXBITEWEB10_OS-daily_20130429144623 is the first snapshot taken for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot, Skipping!

########## Creating snapshot(s) ##########

########## SNAPSHOT CREATE COMMANDS ##########

[Mon Apr 29 14:46:24 2013] INFO: Running snapshot create command NTAP_SNAPSHOT_CREATE_CMD01 ["C:\Program Files\NetApp\SnapDrive\sdcli" snap create -s LXBITEWEB10_OS-daily_20130429144623 -D C:]

[Mon Apr 29 14:46:30 2013] [146.216.97.35:9090(3.6.0.1)] DEBUG: Executing command ["C:\Program Files\NetApp\SnapDrive\sdcli" snap create -s LXBITEWEB10_OS-daily_20130429144623 -D C:]

[Mon Apr 29 14:46:33 2013] [146.216.97.35:9090(3.6.0.1)] TRACE: Command ["C:\Program Files\NetApp\SnapDrive\sdcli" snap create -s LXBITEWEB10_OS-daily_20130429144623 -D C:] finished with

exit code: [0]

stdout: [Initializing...LXBI-TEWEB10 : Checking policies

LXBI-TEWEB10 : Checking access control

LXBI-TEWEB10 : Preparing LUNs for Snapshot creation

LXBI-TEWEB10 : Ready to create snapshot copy

LXBI-TEWEB10 : Creating a Snapshot for the LUN

LXBI-TEWEB10 : Initiating snapmirror update for any source volumes.

The operation completed successfully.

]

stderr: []

[Mon Apr 29 14:46:33 2013] [146.216.97.35:9090(3.6.0.1)] DEBUG: Command ["C:\Program Files\NetApp\SnapDrive\sdcli" snap create -s LXBITEWEB10_OS-daily_20130429144623 -D C:] finished successfully with message

[Initializing...LXBI-TEWEB10 : Checking policies

LXBI-TEWEB10 : Checking access control

LXBI-TEWEB10 : Preparing LUNs for Snapshot creation

LXBI-TEWEB10 : Ready to create snapshot copy

LXBI-TEWEB10 : Creating a Snapshot for the LUN

LXBI-TEWEB10 : Initiating snapmirror update for any source volumes.

The operation completed successfully.

]

[Mon Apr 29 14:46:33 2013] INFO: Running snapshot create command ["C:\Program Files\NetApp\SnapDrive\sdcli" snap create -s LXBITEWEB10_OS-daily_20130429144623 -D C:] finished successfully

########## SNAPSHOT CREATE COMMANDS FINISHED SUCCESSFULLY ##########

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

[Mon Apr 29 14:46:33 2013] INFO: No commands defined

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

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

[Mon Apr 29 14:46:33 2013] INFO: No commands defined

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

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

[Mon Apr 29 14:46:34 2013] INFO: No commands defined

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

########## Generating Info ASUP on rt-zrh-dss-103 ##########

[Mon Apr 29 14:46:34 2013] DEBUG: ZAPI REQUEST

<ems-autosupport-log>

<app-version>NetApp Snap Creator Framework 3.6.0</app-version>

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

<category>Backup Completed</category>

<computer-name>RT-ZRH-SRV-108 [146.216.97.35:9090]</computer-name>

<event-description>INFO: NetApp Snap Creator Framework 3.6.0 Backup for LXBITEWEB10_OS ACTION: snap POLICY: daily Plugin: null - Supported Volumes: rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot Completed</event-description>

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

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

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

</ems-autosupport-log>

[Mon Apr 29 14:46:34 2013] TRACE: ZAPI RESULT

[Mon Apr 29 14:46:34 2013] INFO: ASUP create on rt-zrh-dss-103 finished successfully

[Mon Apr 29 14:46:36 2013] INFO: Getting Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot

[Mon Apr 29 14:46:36 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-start></snapvault-primary-relationship-status-list-iter-start>

[Mon Apr 29 14:46:36 2013] TRACE: ZAPI RESULT

</results>

[Mon Apr 29 14:46:36 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-next>

</snapvault-primary-relationship-status-list-iter-next>

[Mon Apr 29 14:46:36 2013] TRACE: ZAPI RESULT

<status-list>

<snapvault-status-info>

<source-system>rt-zrh-dss-103</source-system>

<source-path>/vol/v_lx_lxbitemoss10_000_boot/q0</source-path>

<destination-system>rt-zrh-dss-301</destination-system>

<destination-path>/vol/a_baer_test/q0</destination-path>

<state>source</state>

<current-transfer-type>-</current-transfer-type>

<current-transfer-error>transfer aborted because of network error</current-transfer-error>

</snapvault-status-info>

<snapvault-status-info>

<source-system>rt-zrh-dss-103</source-system>

<source-path>/vol/v_lx_lxbiteweb10_000_boot/q0</source-path>

<destination-system>rt-zrh-dss-301</destination-system>

<destination-path>/vol/sv_lx_lxbiteweb10_000_boot/Cxx</destination-path>

<lag-time>2105</lag-time>

<mirror-timestamp>1367237490</mirror-timestamp>

<last-transfer-size>50784792</last-transfer-size>

<last-transfer-duration>832</last-transfer-duration>

<state>source</state>

<base-snapshot>rt-zrh-dss-301(2014176755)_sv_lx_lxbiteweb10_000_boot_Cxx-src.0</base-snapshot>

</snapvault-status-info>

</status-list>

</results>

[Mon Apr 29 14:46:36 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-end>

</snapvault-primary-relationship-status-list-iter-end>

[Mon Apr 29 14:46:36 2013] TRACE: ZAPI RESULT

[Mon Apr 29 14:46:36 2013] INFO: Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot finished successfully

########## Running Snapvault Update using source rt-zrh-dss-103:/vol/v_lx_lxbiteweb10_000_boot/q0 ##########

[Mon Apr 29 14:46:38 2013] DEBUG: ZAPI REQUEST

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

[Mon Apr 29 14:46:38 2013] TRACE: ZAPI RESULT

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

<minor-version>19</minor-version>

</results>

[Mon Apr 29 14:46:38 2013] DEBUG: creating executor for storage controller rt-zrh-dss-301

########## Detecting Data OnTap mode for rt-zrh-dss-301 ##########

[Mon Apr 29 14:46:38 2013] DEBUG: ZAPI REQUEST

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

[Mon Apr 29 14:46:38 2013] TRACE: ZAPI RESULT

<version>NetApp Release 8.1.2 7-Mode: Tue Oct 30 19:56:51 PDT 2012</version>

<is-clustered>false</is-clustered>

</results>

[Mon Apr 29 14:46:38 2013] INFO: Data OnTap 7 mode detected

[Mon Apr 29 14:46:38 2013] DEBUG: ZAPI REQUEST

<options-get>

<name>ndmpd.preferred_interface</name>

</options-get>

[Mon Apr 29 14:46:38 2013] TRACE: ZAPI RESULT

<value>disable</value>

<cluster-constraint>only_one</cluster-constraint>

<cluster_constraint>only_one</cluster_constraint>

</results>

[Mon Apr 29 14:46:38 2013] DEBUG: Connected to rt-zrh-dss-301 using API Version 1.19

[Mon Apr 29 14:46:39 2013] DEBUG: ZAPI REQUEST

<ems-autosupport-log>

<app-version>NetApp Snap Creator Framework 3.6.0</app-version>

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

<category>Snapvault Backup Started</category>

<computer-name>RT-ZRH-SRV-108 [146.216.97.35:9090]</computer-name>

<event-description>INFO: NetApp Snap Creator Framework 3.6.0 Snapvault backup for LXBITEWEB10_OS ACTION: snap POLICY: daily Plugin: null - Supported Volumes: rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot Started</event-description>

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

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

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

</ems-autosupport-log>

[Mon Apr 29 14:46:39 2013] TRACE: ZAPI RESULT

[Mon Apr 29 14:46:39 2013] INFO: ASUP create on rt-zrh-dss-301 finished successfully

[Mon Apr 29 14:46:39 2013] INFO: Running Snapvault Update on destination rt-zrh-dss-301:/vol/sv_lx_lxbiteweb10_000_boot/Cxx using snapshot LXBITEWEB10_OS-daily_20130429144623

[Mon Apr 29 14:46:39 2013] DEBUG: ZAPI REQUEST

<snapvault-secondary-initiate-incremental-transfer>

<primary-snapshot>LXBITEWEB10_OS-daily_20130429144623</primary-snapshot>

<secondary-path>/vol/sv_lx_lxbiteweb10_000_boot/Cxx</secondary-path>

</snapvault-secondary-initiate-incremental-transfer>

[Mon Apr 29 14:46:41 2013] TRACE: ZAPI RESULT

[Mon Apr 29 14:46:41 2013] INFO: Snapvault Update on destination rt-zrh-dss-301:/vol/sv_lx_lxbiteweb10_000_boot/Cxx Started Successfully

[Mon Apr 29 14:46:41 2013] INFO: Getting Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot

[Mon Apr 29 14:46:42 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-start></snapvault-primary-relationship-status-list-iter-start>

[Mon Apr 29 14:46:42 2013] TRACE: ZAPI RESULT

</results>

[Mon Apr 29 14:46:42 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-next>

</snapvault-primary-relationship-status-list-iter-next>

[Mon Apr 29 14:46:42 2013] TRACE: ZAPI RESULT

<status-list>

<snapvault-status-info>

<source-system>rt-zrh-dss-103</source-system>

<source-path>/vol/v_lx_lxbitemoss10_000_boot/q0</source-path>

<destination-system>rt-zrh-dss-301</destination-system>

<destination-path>/vol/a_baer_test/q0</destination-path>

<state>source</state>

<current-transfer-type>-</current-transfer-type>

<current-transfer-error>transfer aborted because of network error</current-transfer-error>

</snapvault-status-info>

<snapvault-status-info>

<source-system>rt-zrh-dss-103</source-system>

<source-path>/vol/v_lx_lxbiteweb10_000_boot/q0</source-path>

<destination-system>rt-zrh-dss-301</destination-system>

<destination-path>/vol/sv_lx_lxbiteweb10_000_boot/Cxx</destination-path>

<lag-time>2111</lag-time>

<transfer-progress>16</transfer-progress>

<mirror-timestamp>1367237490</mirror-timestamp>

<last-transfer-size>50784792</last-transfer-size>

<last-transfer-duration>832</last-transfer-duration>

<status>transferring</status>

<state>source</state>

<base-snapshot>rt-zrh-dss-301(2014176755)_sv_lx_lxbiteweb10_000_boot_Cxx-src.0</base-snapshot>

<current-transfer-type>-</current-transfer-type>

</snapvault-status-info>

</status-list>

</results>

[Mon Apr 29 14:46:42 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-end>

</snapvault-primary-relationship-status-list-iter-end>

[Mon Apr 29 14:46:42 2013] TRACE: ZAPI RESULT

[Mon Apr 29 14:46:42 2013] INFO: Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot finished successfully

[Mon Apr 29 14:46:42 2013] WARN: Snapvault Update on destination rt-zrh-dss-301:/vol/sv_lx_lxbiteweb10_000_boot/Cxx is running, status transferring! Sleeping 1 minute

[Mon Apr 29 14:47:42 2013] INFO: Getting Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot

[Mon Apr 29 14:47:42 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-start></snapvault-primary-relationship-status-list-iter-start>

[Mon Apr 29 14:47:42 2013] TRACE: ZAPI RESULT

</results>

[Mon Apr 29 14:47:42 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-next>

</snapvault-primary-relationship-status-list-iter-next>

[Mon Apr 29 14:47:42 2013] TRACE: ZAPI RESULT

<status-list>

<snapvault-status-info>

<source-system>rt-zrh-dss-103</source-system>

<source-path>/vol/v_lx_lxbitemoss10_000_boot/q0</source-path>

<destination-system>rt-zrh-dss-301</destination-system>

<destination-path>/vol/a_baer_test/q0</destination-path>

<state>source</state>

<current-transfer-type>-</current-transfer-type>

<current-transfer-error>transfer aborted because of network error</current-transfer-error>

</snapvault-status-info>

<snapvault-status-info>

<source-system>rt-zrh-dss-103</source-system>

<source-path>/vol/v_lx_lxbiteweb10_000_boot/q0</source-path>

<destination-system>rt-zrh-dss-301</destination-system>

<destination-path>/vol/sv_lx_lxbiteweb10_000_boot/Cxx</destination-path>

<lag-time>70</lag-time>

<mirror-timestamp>1367239592</mirror-timestamp>

<last-transfer-size>9008</last-transfer-size>

<last-transfer-duration>7</last-transfer-duration>

<state>source</state>

<base-snapshot>LXBITEWEB10_OS-daily_20130429144623</base-snapshot>

</snapvault-status-info>

</status-list>

</results>

[Mon Apr 29 14:47:42 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-end>

</snapvault-primary-relationship-status-list-iter-end>

[Mon Apr 29 14:47:42 2013] TRACE: ZAPI RESULT

[Mon Apr 29 14:47:42 2013] INFO: Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot finished successfully

[Mon Apr 29 14:47:42 2013] INFO: Snapvault Update on destination rt-zrh-dss-301:/vol/sv_lx_lxbiteweb10_000_boot/Cxx finished successfully

########## POST DATA TRANSFER COMMANDS ##########

[Mon Apr 29 14:47:44 2013] INFO: No commands defined

########## POST DATA TRANSFER COMMANDS FINISHED SUCCESSFULLY ##########

[Mon Apr 29 14:47:44 2013] INFO: Performing Snapshot Snapvault Inventory for sv_lx_lxbiteweb10_000_boot on rt-zrh-dss-301

[Mon Apr 29 14:47:44 2013] DEBUG: ZAPI REQUEST

<snapshot-list-info>

<volume>sv_lx_lxbiteweb10_000_boot</volume>

</snapshot-list-info>

[Mon Apr 29 14:47:44 2013] TRACE: ZAPI RESULT

<snapshot-info>

<name>rt-zrh-dss-301(2014176755)_sv_lx_lxbiteweb10_000_boot-base.0</name>

<access-time>1367239614</access-time>

<dependency>busy,snapvault</dependency>

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

<percentage-of-used-blocks>1</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-instance-uuid>ded7b505-b0ca-11e2-8062-123478563412</snapshot-instance-uuid>

<snapshot-version-uuid>ded7b505-b0ca-11e2-8062-123478563412</snapshot-version-uuid>

</snapshot-info>

</snapshots>

</results>

[Mon Apr 29 14:47:44 2013] INFO: Snapshot Inventory of sv_lx_lxbiteweb10_000_boot on rt-zrh-dss-301 completed Successfully

########## Running Snapshot Rename on Secondary rt-zrh-dss-301 ##########

[Mon Apr 29 14:47:45 2013] INFO: LXBITEWEB10_OS-SV_daily_20130429144623 is the first snapshot taken for rt-zrh-dss-301:sv_lx_lxbiteweb10_000_boot, Skipping!

########## Running Snapshot Create on Secondary rt-zrh-dss-301 ##########

[Mon Apr 29 14:47:45 2013] INFO: Creating Snapvault Snapshot for sv_lx_lxbiteweb10_000_boot on rt-zrh-dss-301

[Mon Apr 29 14:47:45 2013] DEBUG: ZAPI REQUEST

<snapshot-create>

<snapshot>LXBITEWEB10_OS-SV_daily_20130429144623</snapshot>

<volume>sv_lx_lxbiteweb10_000_boot</volume>

</snapshot-create>

[Mon Apr 29 14:47:46 2013] TRACE: ZAPI RESULT

[Mon Apr 29 14:47:46 2013] INFO: Snapshot Create of LXBITEWEB10_OS-SV_daily_20130429144623 on rt-zrh-dss-301:sv_lx_lxbiteweb10_000_boot Completed Successfully

########## Running Snapshot Delete on Secondary rt-zrh-dss-301 ##########

########## Running Snapshot Delete on Primary rt-zrh-dss-103 ##########

########## POST COMMANDS ##########

[Mon Apr 29 14:48:01 2013] INFO: No commands defined

########## POST COMMANDS FINISHED SUCCESSFULLY ##########

########## ARCHIVE COMMANDS ##########

[Mon Apr 29 14:48:01 2013] INFO: No commands defined

########## ARCHIVE COMMANDS FINISHED SUCCESSFULLY ##########

########## NetApp Snap Creator Framework 3.6.0 finished successfully ##########

[Mon Apr 29 14:48:01 2013] INFO: INFO: Snap Creator finished successfully ( Action: snap )

[Mon Apr 29 14:48:01 2013] DEBUG: Loaded Config File C:/Program Files/NetApp/NetApp_Snap_Creator_Framework/scServer3.6.0/configs/LXBI-TE/LXBITEWEB10_OS.conf Successfully

sivar · ‎2013-04-29

There seems to be confusion of the snapvault entries.(as seen from the source system)

from the source RT-ZRH-DSS-103 - snapvault status yields this.

Source: rt-zrh-dss-103:/vol/v_lx_lxbitemoss10_000_boot/q0

Destination: rt-zrh-dss-301:/vol/a_baer_test/q0

But, looks like your config file needs only this relationship.

Source: rt-zrh-dss-103:/vol/v_lx_lxbiteweb10_000_boot/q0

Destination: rt-zrh-dss-301:/vol/sv_lx_lxbiteweb10_000_boot/Cxx

Please release the a_baer_test relationship and try a backup again.

REISTTELECOM · ‎2013-04-30

Hi Siva,

thx for your help.

I released the snapvault relationship as proposed and ran a snapcreator backup afterwards.

Unfortunately it still shows status failed and I cannot find any error in the debug log.

Here is the debug log:

[Tue Apr 30 13:22:27 2013] INFO: Logfile timestamp: 20130430132227

[Tue Apr 30 13:22:27 2013] DEBUG: GMT - Tue Apr 30 11:22:27 2013

[Tue Apr 30 13:22:27 2013] DEBUG: Version: NetApp Snap Creator Framework 3.6.0

[Tue Apr 30 13:22:27 2013] DEBUG: Profile: LXBI-TE

[Tue Apr 30 13:22:27 2013] DEBUG: Config Type: STANDARD

[Tue Apr 30 13:22:27 2013] DEBUG: Action: snap

[Tue Apr 30 13:22:27 2013] DEBUG: Application Plugin: null

[Tue Apr 30 13:22:27 2013] DEBUG: File System Plugin: null

[Tue Apr 30 13:22:27 2013] DEBUG: Policy: daily

[Tue Apr 30 13:22:27 2013] DEBUG: Snapshot Name: LXBITEWEB10_OS-daily_20130430132227

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

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing VOLUMES - controller: rt-zrh-dss-103 volume: v_lx_lxbiteweb10_000_boot

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing NTAP_USERS - controller: rt-zrh-dss-103 user: u_snapcreator

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing NTAP_USERS - controller: rt-zrh-dss-301 user: u_snapcreator

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing NTAP_SNAPSHOT_RETENTIONS - policy: daily retention: 5

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing NTAP_SNAPVAULT_RETENTIONS - sv policy: daily retention: 40

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing SNAPVAULT_VOLUMES - controller: rt-zrh-dss-103 sv volume: v_lx_lxbiteweb10_000_boot

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

[Tue Apr 30 13:22:27 2013] INFO: No commands defined

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

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

[Tue Apr 30 13:22:27 2013] INFO: No commands defined

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

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

[Tue Apr 30 13:22:27 2013] INFO: No commands defined

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

########## PRE COMMANDS ##########

[Tue Apr 30 13:22:27 2013] INFO: No commands defined

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

[Tue Apr 30 13:22:27 2013] DEBUG: Loaded Config File C:/Program Files/NetApp/NetApp_Snap_Creator_Framework/scServer3.6.0/configs/LXBI-TE/LXBITEWEB10_OS.conf Successfully

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

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing VOLUMES - controller: rt-zrh-dss-103 volume: v_lx_lxbiteweb10_000_boot

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing NTAP_USERS - controller: rt-zrh-dss-103 user: u_snapcreator

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing NTAP_USERS - controller: rt-zrh-dss-301 user: u_snapcreator

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing NTAP_SNAPSHOT_RETENTIONS - policy: daily retention: 5

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing NTAP_SNAPVAULT_RETENTIONS - sv policy: daily retention: 40

[Tue Apr 30 13:22:27 2013] DEBUG: Parsing SNAPVAULT_VOLUMES - controller: rt-zrh-dss-103 sv volume: v_lx_lxbiteweb10_000_boot

[Tue Apr 30 13:22:27 2013] DEBUG: ZAPI REQUEST

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

[Tue Apr 30 13:22:27 2013] TRACE: ZAPI RESULT

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

<minor-version>19</minor-version>

</results>

[Tue Apr 30 13:22:27 2013] DEBUG: creating executor for storage controller rt-zrh-dss-103

########## Detecting Data OnTap mode for rt-zrh-dss-103 ##########

[Tue Apr 30 13:22:27 2013] DEBUG: ZAPI REQUEST

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

[Tue Apr 30 13:22:27 2013] TRACE: ZAPI RESULT

<version>NetApp Release 8.1.2P1 7-Mode: Mon Jan 7 15:55:05 PST 2013</version>

<is-clustered>false</is-clustered>

</results>

[Tue Apr 30 13:22:27 2013] INFO: Data OnTap 7 mode detected

[Tue Apr 30 13:22:27 2013] DEBUG: ZAPI REQUEST

<options-get>

<name>ndmpd.preferred_interface</name>

</options-get>

[Tue Apr 30 13:22:28 2013] TRACE: ZAPI RESULT

<value>disable</value>

<cluster-constraint>only_one</cluster-constraint>

<cluster_constraint>only_one</cluster_constraint>

</results>

[Tue Apr 30 13:22:28 2013] DEBUG: Connected to rt-zrh-dss-103 using API Version 1.19

########## Generating Info ASUP on rt-zrh-dss-103 ##########

[Tue Apr 30 13:22:28 2013] DEBUG: ZAPI REQUEST

<ems-autosupport-log>

<app-version>NetApp Snap Creator Framework 3.6.0</app-version>

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

<category>Backup Started</category>

<computer-name>RT-ZRH-SRV-108 [146.216.97.35:9090]</computer-name>

<event-description>INFO: NetApp Snap Creator Framework 3.6.0 Backup for LXBITEWEB10_OS ACTION: snap POLICY: daily Plugin: null - Supported Volumes: rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot Started</event-description>

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

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

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

</ems-autosupport-log>

[Tue Apr 30 13:22:28 2013] TRACE: ZAPI RESULT

[Tue Apr 30 13:22:28 2013] INFO: ASUP create on rt-zrh-dss-103 finished successfully

########## Gathering Information for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot ##########

[Tue Apr 30 13:22:28 2013] INFO: Performing Snapshot Inventory for v_lx_lxbiteweb10_000_boot on rt-zrh-dss-103

[Tue Apr 30 13:22:28 2013] DEBUG: ZAPI REQUEST

<snapshot-list-info>

<volume>v_lx_lxbiteweb10_000_boot</volume>

</snapshot-list-info>

[Tue Apr 30 13:22:28 2013] TRACE: ZAPI RESULT

<snapshot-info>

<name>LXBITEWEB10_OS-daily_20130429155049</name>

<access-time>1367243461</access-time>

<dependency>snapvault</dependency>

<busy>false</busy>

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

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

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

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

<snapshot-instance-uuid>d3d20d51-b0d3-11e2-850b-123478563412</snapshot-instance-uuid>

<snapshot-version-uuid>d3d20d51-b0d3-11e2-850b-123478563412</snapshot-version-uuid>

</snapshot-info>

<snapshot-info>

<name>LXBITEWEB10_OS-daily_20130429144623</name>

<access-time>1367239592</access-time>

<busy>false</busy>

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

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

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

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

<snapshot-instance-uuid>d221bfaf-b0ca-11e2-850b-123478563412</snapshot-instance-uuid>

<snapshot-version-uuid>d221bfaf-b0ca-11e2-850b-123478563412</snapshot-version-uuid>

</snapshot-info>

</snapshots>

</results>

[Tue Apr 30 13:22:28 2013] INFO: Snapshot Inventory of v_lx_lxbiteweb10_000_boot on rt-zrh-dss-103 completed Successfully

########## Running Snapshot Rename on Primary rt-zrh-dss-103 ##########

########## Creating snapshot(s) ##########

########## SNAPSHOT CREATE COMMANDS ##########

[Tue Apr 30 13:22:28 2013] INFO: Running snapshot create command NTAP_SNAPSHOT_CREATE_CMD01 ["C:\Program Files\NetApp\SnapDrive\sdcli" snap create -s LXBITEWEB10_OS-daily_20130430132227 -D C:]

[Tue Apr 30 13:22:33 2013] [146.216.97.35:9090(3.6.0.1)] DEBUG: Executing command ["C:\Program Files\NetApp\SnapDrive\sdcli" snap create -s LXBITEWEB10_OS-daily_20130430132227 -D C:]

[Tue Apr 30 13:22:45 2013] [146.216.97.35:9090(3.6.0.1)] TRACE: Command ["C:\Program Files\NetApp\SnapDrive\sdcli" snap create -s LXBITEWEB10_OS-daily_20130430132227 -D C:] finished with

exit code: [0]

stdout: [Initializing...LXBI-TEWEB10 : Checking policies

LXBI-TEWEB10 : Checking access control

LXBI-TEWEB10 : Preparing LUNs for Snapshot creation

LXBI-TEWEB10 : Ready to create snapshot copy

LXBI-TEWEB10 : Creating a Snapshot for the LUN

LXBI-TEWEB10 : Initiating snapmirror update for any source volumes.

The operation completed successfully.

]

stderr: []

[Tue Apr 30 13:22:45 2013] [146.216.97.35:9090(3.6.0.1)] DEBUG: Command ["C:\Program Files\NetApp\SnapDrive\sdcli" snap create -s LXBITEWEB10_OS-daily_20130430132227 -D C:] finished successfully with message

[Initializing...LXBI-TEWEB10 : Checking policies

LXBI-TEWEB10 : Checking access control

LXBI-TEWEB10 : Preparing LUNs for Snapshot creation

LXBI-TEWEB10 : Ready to create snapshot copy

LXBI-TEWEB10 : Creating a Snapshot for the LUN

LXBI-TEWEB10 : Initiating snapmirror update for any source volumes.

The operation completed successfully.

]

[Tue Apr 30 13:22:45 2013] INFO: Running snapshot create command ["C:\Program Files\NetApp\SnapDrive\sdcli" snap create -s LXBITEWEB10_OS-daily_20130430132227 -D C:] finished successfully

########## SNAPSHOT CREATE COMMANDS FINISHED SUCCESSFULLY ##########

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

[Tue Apr 30 13:22:45 2013] INFO: No commands defined

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

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

[Tue Apr 30 13:22:45 2013] INFO: No commands defined

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

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

[Tue Apr 30 13:22:45 2013] INFO: No commands defined

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

########## Generating Info ASUP on rt-zrh-dss-103 ##########

[Tue Apr 30 13:22:45 2013] DEBUG: ZAPI REQUEST

<ems-autosupport-log>

<app-version>NetApp Snap Creator Framework 3.6.0</app-version>

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

<category>Backup Completed</category>

<computer-name>RT-ZRH-SRV-108 [146.216.97.35:9090]</computer-name>

<event-description>INFO: NetApp Snap Creator Framework 3.6.0 Backup for LXBITEWEB10_OS ACTION: snap POLICY: daily Plugin: null - Supported Volumes: rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot Completed</event-description>

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

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

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

</ems-autosupport-log>

[Tue Apr 30 13:22:45 2013] TRACE: ZAPI RESULT

[Tue Apr 30 13:22:45 2013] INFO: ASUP create on rt-zrh-dss-103 finished successfully

[Tue Apr 30 13:22:47 2013] INFO: Getting Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot

[Tue Apr 30 13:22:47 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-start></snapvault-primary-relationship-status-list-iter-start>

[Tue Apr 30 13:22:47 2013] TRACE: ZAPI RESULT

</results>

[Tue Apr 30 13:22:47 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-next>

</snapvault-primary-relationship-status-list-iter-next>

[Tue Apr 30 13:22:47 2013] TRACE: ZAPI RESULT

<status-list>

<snapvault-status-info>

<source-system>rt-zrh-dss-103</source-system>

<source-path>/vol/v_lx_lxbiteweb10_000_boot/q0</source-path>

<destination-system>rt-zrh-dss-301</destination-system>

<destination-path>/vol/sv_lx_lxbiteweb10_000_boot/Cxx</destination-path>

<lag-time>77506</lag-time>

<mirror-timestamp>1367243461</mirror-timestamp>

<last-transfer-size>43260</last-transfer-size>

<last-transfer-duration>47</last-transfer-duration>

<state>source</state>

<base-snapshot>LXBITEWEB10_OS-daily_20130429155049</base-snapshot>

</snapvault-status-info>

</status-list>

</results>

[Tue Apr 30 13:22:47 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-end>

</snapvault-primary-relationship-status-list-iter-end>

[Tue Apr 30 13:22:47 2013] TRACE: ZAPI RESULT

[Tue Apr 30 13:22:47 2013] INFO: Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot finished successfully

########## Running Snapvault Update using source rt-zrh-dss-103:/vol/v_lx_lxbiteweb10_000_boot/q0 ##########

[Tue Apr 30 13:22:49 2013] DEBUG: ZAPI REQUEST

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

[Tue Apr 30 13:22:49 2013] TRACE: ZAPI RESULT

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

<minor-version>19</minor-version>

</results>

[Tue Apr 30 13:22:49 2013] DEBUG: creating executor for storage controller rt-zrh-dss-301

########## Detecting Data OnTap mode for rt-zrh-dss-301 ##########

[Tue Apr 30 13:22:49 2013] DEBUG: ZAPI REQUEST

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

[Tue Apr 30 13:22:50 2013] TRACE: ZAPI RESULT

<version>NetApp Release 8.1.2 7-Mode: Tue Oct 30 19:56:51 PDT 2012</version>

<is-clustered>false</is-clustered>

</results>

[Tue Apr 30 13:22:50 2013] INFO: Data OnTap 7 mode detected

[Tue Apr 30 13:22:50 2013] DEBUG: ZAPI REQUEST

<options-get>

<name>ndmpd.preferred_interface</name>

</options-get>

[Tue Apr 30 13:22:50 2013] TRACE: ZAPI RESULT

<value>disable</value>

<cluster-constraint>only_one</cluster-constraint>

<cluster_constraint>only_one</cluster_constraint>

</results>

[Tue Apr 30 13:22:50 2013] DEBUG: Connected to rt-zrh-dss-301 using API Version 1.19

[Tue Apr 30 13:22:50 2013] DEBUG: ZAPI REQUEST

<ems-autosupport-log>

<app-version>NetApp Snap Creator Framework 3.6.0</app-version>

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

<category>Snapvault Backup Started</category>

<computer-name>RT-ZRH-SRV-108 [146.216.97.35:9090]</computer-name>

<event-description>INFO: NetApp Snap Creator Framework 3.6.0 Snapvault backup for LXBITEWEB10_OS ACTION: snap POLICY: daily Plugin: null - Supported Volumes: rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot Started</event-description>

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

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

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

</ems-autosupport-log>

[Tue Apr 30 13:22:50 2013] TRACE: ZAPI RESULT

[Tue Apr 30 13:22:50 2013] INFO: ASUP create on rt-zrh-dss-301 finished successfully

[Tue Apr 30 13:22:50 2013] INFO: Running Snapvault Update on destination rt-zrh-dss-301:/vol/sv_lx_lxbiteweb10_000_boot/Cxx using snapshot LXBITEWEB10_OS-daily_20130430132227

[Tue Apr 30 13:22:50 2013] DEBUG: ZAPI REQUEST

<snapvault-secondary-initiate-incremental-transfer>

<primary-snapshot>LXBITEWEB10_OS-daily_20130430132227</primary-snapshot>

<secondary-path>/vol/sv_lx_lxbiteweb10_000_boot/Cxx</secondary-path>

</snapvault-secondary-initiate-incremental-transfer>

[Tue Apr 30 13:22:51 2013] TRACE: ZAPI RESULT

[Tue Apr 30 13:22:51 2013] INFO: Snapvault Update on destination rt-zrh-dss-301:/vol/sv_lx_lxbiteweb10_000_boot/Cxx Started Successfully

[Tue Apr 30 13:22:51 2013] INFO: Getting Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot

[Tue Apr 30 13:22:51 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-start></snapvault-primary-relationship-status-list-iter-start>

[Tue Apr 30 13:22:51 2013] TRACE: ZAPI RESULT

</results>

[Tue Apr 30 13:22:51 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-next>

</snapvault-primary-relationship-status-list-iter-next>

[Tue Apr 30 13:22:52 2013] TRACE: ZAPI RESULT

<status-list>

<snapvault-status-info>

<source-system>rt-zrh-dss-103</source-system>

<source-path>/vol/v_lx_lxbiteweb10_000_boot/q0</source-path>

<destination-system>rt-zrh-dss-301</destination-system>

<destination-path>/vol/sv_lx_lxbiteweb10_000_boot/Cxx</destination-path>

<lag-time>77510</lag-time>

<transfer-progress>16</transfer-progress>

<mirror-timestamp>1367243461</mirror-timestamp>

<last-transfer-size>43260</last-transfer-size>

<last-transfer-duration>47</last-transfer-duration>

<status>transferring</status>

<state>source</state>

<base-snapshot>LXBITEWEB10_OS-daily_20130429155049</base-snapshot>

<current-transfer-type>-</current-transfer-type>

</snapvault-status-info>

</status-list>

</results>

[Tue Apr 30 13:22:52 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-end>

</snapvault-primary-relationship-status-list-iter-end>

[Tue Apr 30 13:22:52 2013] TRACE: ZAPI RESULT

[Tue Apr 30 13:22:52 2013] INFO: Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot finished successfully

[Tue Apr 30 13:22:52 2013] WARN: Snapvault Update on destination rt-zrh-dss-301:/vol/sv_lx_lxbiteweb10_000_boot/Cxx is running, status transferring! Sleeping 1 minute

[Tue Apr 30 13:23:52 2013] INFO: Getting Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot

[Tue Apr 30 13:23:52 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-start></snapvault-primary-relationship-status-list-iter-start>

[Tue Apr 30 13:23:52 2013] TRACE: ZAPI RESULT

</results>

[Tue Apr 30 13:23:52 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-next>

</snapvault-primary-relationship-status-list-iter-next>

[Tue Apr 30 13:23:52 2013] TRACE: ZAPI RESULT

<status-list>

<snapvault-status-info>

<source-system>rt-zrh-dss-103</source-system>

<source-path>/vol/v_lx_lxbiteweb10_000_boot/q0</source-path>

<destination-system>rt-zrh-dss-301</destination-system>

<destination-path>/vol/sv_lx_lxbiteweb10_000_boot/Cxx</destination-path>

<lag-time>70</lag-time>

<mirror-timestamp>1367320962</mirror-timestamp>

<last-transfer-size>853816</last-transfer-size>

<last-transfer-duration>25</last-transfer-duration>

<state>source</state>

<base-snapshot>LXBITEWEB10_OS-daily_20130430132227</base-snapshot>

</snapvault-status-info>

</status-list>

</results>

[Tue Apr 30 13:23:52 2013] DEBUG: ZAPI REQUEST

<snapvault-primary-relationship-status-list-iter-end>

</snapvault-primary-relationship-status-list-iter-end>

[Tue Apr 30 13:23:52 2013] TRACE: ZAPI RESULT

[Tue Apr 30 13:23:52 2013] INFO: Snapvault Status for rt-zrh-dss-103:v_lx_lxbiteweb10_000_boot finished successfully

[Tue Apr 30 13:23:52 2013] INFO: Snapvault Update on destination rt-zrh-dss-301:/vol/sv_lx_lxbiteweb10_000_boot/Cxx finished successfully

########## POST DATA TRANSFER COMMANDS ##########

[Tue Apr 30 13:23:54 2013] INFO: No commands defined

########## POST DATA TRANSFER COMMANDS FINISHED SUCCESSFULLY ##########

[Tue Apr 30 13:23:54 2013] INFO: Performing Snapshot Snapvault Inventory for sv_lx_lxbiteweb10_000_boot on rt-zrh-dss-301

[Tue Apr 30 13:23:54 2013] DEBUG: ZAPI REQUEST

<snapshot-list-info>

<volume>sv_lx_lxbiteweb10_000_boot</volume>

</snapshot-list-info>

[Tue Apr 30 13:23:54 2013] TRACE: ZAPI RESULT

<snapshot-info>

<name>rt-zrh-dss-301(2014176755)_sv_lx_lxbiteweb10_000_boot-base.0</name>

<access-time>1367320999</access-time>

<dependency>busy,snapvault</dependency>

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

<percentage-of-used-blocks>2</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-instance-uuid>5c59e74d-b188-11e2-8062-123478563412</snapshot-instance-uuid>

<snapshot-version-uuid>5c59e74d-b188-11e2-8062-123478563412</snapshot-version-uuid>

</snapshot-info>

<snapshot-info>

<name>LXBITEWEB10_OS-SV_daily_20130429155049</name>

<access-time>1367243570</access-time>

<busy>false</busy>

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

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

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

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

<snapshot-instance-uuid>10447cbf-b0d4-11e2-8062-123478563412</snapshot-instance-uuid>

<snapshot-version-uuid>10447cbf-b0d4-11e2-8062-123478563412</snapshot-version-uuid>

</snapshot-info>

<snapshot-info>

<name>LXBITEWEB10_OS-SV_daily_20130429144623</name>

<access-time>1367239664</access-time>

<busy>false</busy>

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

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

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

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

<snapshot-instance-uuid>fce84d9f-b0ca-11e2-8062-123478563412</snapshot-instance-uuid>

<snapshot-version-uuid>fce84d9f-b0ca-11e2-8062-123478563412</snapshot-version-uuid>

</snapshot-info>

</snapshots>

</results>

[Tue Apr 30 13:23:54 2013] INFO: Snapshot Inventory of sv_lx_lxbiteweb10_000_boot on rt-zrh-dss-301 completed Successfully

########## Running Snapshot Rename on Secondary rt-zrh-dss-301 ##########

########## Running Snapshot Create on Secondary rt-zrh-dss-301 ##########

[Tue Apr 30 13:23:54 2013] INFO: Creating Snapvault Snapshot for sv_lx_lxbiteweb10_000_boot on rt-zrh-dss-301

[Tue Apr 30 13:23:54 2013] DEBUG: ZAPI REQUEST

<snapshot-create>

<snapshot>LXBITEWEB10_OS-SV_daily_20130430132227</snapshot>

<volume>sv_lx_lxbiteweb10_000_boot</volume>

</snapshot-create>

[Tue Apr 30 13:23:55 2013] TRACE: ZAPI RESULT

[Tue Apr 30 13:23:55 2013] INFO: Snapshot Create of LXBITEWEB10_OS-SV_daily_20130430132227 on rt-zrh-dss-301:sv_lx_lxbiteweb10_000_boot Completed Successfully

########## Running Snapshot Delete on Secondary rt-zrh-dss-301 ##########

########## Running Snapshot Delete on Primary rt-zrh-dss-103 ##########

########## POST COMMANDS ##########

[Tue Apr 30 13:24:10 2013] INFO: No commands defined

########## POST COMMANDS FINISHED SUCCESSFULLY ##########

########## ARCHIVE COMMANDS ##########

[Tue Apr 30 13:24:10 2013] INFO: No commands defined

########## ARCHIVE COMMANDS FINISHED SUCCESSFULLY ##########

########## NetApp Snap Creator Framework 3.6.0 finished successfully ##########

[Tue Apr 30 13:24:10 2013] INFO: INFO: Snap Creator finished successfully ( Action: snap )

[Tue Apr 30 13:24:10 2013] DEBUG: Loaded Config File C:/Program Files/NetApp/NetApp_Snap_Creator_Framework/scServer3.6.0/configs/LXBI-TE/LXBITEWEB10_OS.conf Successfully

sivar · ‎2013-04-30

I also don't see any errors in the log file.

I have few questions.

1) the snapvault update takes a second - is it true? from 13:23:54 to 13:23:55 the snapvault update is completed

Please verify whether the snapvault update completes as mentioned.

Can you provide me with "snapvault status rt-zrh-dss-301:/vol/sv_lx_lxbiteweb10_000_boot/Cxx"

2) You may try two things for me.

a) "C:\Program Files\NetApp\SnapDrive\sdcli" snap create -s LXBITEWEB10_OS-daily_20130430132227 -D C:\ -u no

Include the C:\ (slash) for the drive letter and also -u no to tell snapdrive not to initiate snapmirror update

Check your backup and see if the job is shown correctly in the GUI.

b) If you still have a job status failed, then try to set SC_AGENT_LOG_ENABLE=N in your snapcreator config file and rerun a backup and see if the job status change

c) If you are able to restart snapcreator server and agent services, please do so, and try a backup again.

3) If the job status is still showing as failed, please collect a "scdump" for your backup config and email me zip file to sivar at netapp.com

sivar · ‎2013-04-30

Are you using a blank password to NetApp storage controllers?