Community

Subscribe
Highlighted

Job Status failed although OK

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?

Re: Job Status failed although OK

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

Re: Job Status failed although OK

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

<results status="passed">

    <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

<results status="passed">

    <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

<results status="passed">

    <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

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

[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>

    <terse>true</terse>

</snapshot-list-info>

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

<results status="passed">

    <snapshots>

        <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

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

[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 status="passed">

    <tag>14906043082902797</tag>

    <records>2</records>

</results>

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

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

    <maximum>2</maximum>

    <tag>14906043082902797</tag>

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

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

<results status="passed">

    <records>2</records>

    <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>

            <status>idle</status>

            <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>

            <status>idle</status>

            <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>

    <tag>14906043082902797</tag>

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

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

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

[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

<results status="passed">

    <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

<results status="passed">

    <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

<results status="passed">

    <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

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

[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

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

[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 status="passed">

    <tag>14906056252188469</tag>

    <records>2</records>

</results>

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

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

    <maximum>2</maximum>

    <tag>14906056252188469</tag>

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

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

<results status="passed">

    <records>2</records>

    <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>

            <status>idle</status>

            <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>

    <tag>14906056252188469</tag>

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

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

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

[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 status="passed">

    <tag>14906193112096461</tag>

    <records>2</records>

</results>

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

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

    <maximum>2</maximum>

    <tag>14906193112096461</tag>

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

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

<results status="passed">

    <records>2</records>

    <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>

            <status>idle</status>

            <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>

            <status>idle</status>

            <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>

    <tag>14906193112096461</tag>

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

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

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

[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>

    <terse>true</terse>

</snapshot-list-info>

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

<results status="passed">

    <snapshots>

        <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>

            <busy>true</busy>

            <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

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

[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

Re: Job Status failed although OK

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.

Re: Job Status failed although OK

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

<results status="passed">

<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

<results status="passed">

<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

<results status="passed">

<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

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

[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>

<terse>true</terse>

</snapshot-list-info>

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

<results status="passed">

<snapshots>

<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>

<dependency></dependency>

<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

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

[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 status="passed">

<tag>15090478144584532</tag>

<records>1</records>

</results>

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

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

<maximum>1</maximum>

<tag>15090478144584532</tag>

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

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

<results status="passed">

<records>1</records>

<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>

<status>idle</status>

<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>

<tag>15090478144584532</tag>

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

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

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

[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

<results status="passed">

<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

<results status="passed">

<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

<results status="passed">

<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

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

[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

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

[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 status="passed">

<tag>15090488050668184</tag>

<records>1</records>

</results>

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

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

<maximum>1</maximum>

<tag>15090488050668184</tag>

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

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

<results status="passed">

<records>1</records>

<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>

<tag>15090488050668184</tag>

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

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

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

[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 status="passed">

<tag>15090624919395214</tag>

<records>1</records>

</results>

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

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

<maximum>1</maximum>

<tag>15090624919395214</tag>

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

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

<results status="passed">

<records>1</records>

<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>

<status>idle</status>

<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>

<tag>15090624919395214</tag>

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

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

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

[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>

<terse>true</terse>

</snapshot-list-info>

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

<results status="passed">

<snapshots>

<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>

<busy>true</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>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>

<dependency></dependency>

<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>

<dependency></dependency>

<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

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

[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

Re: Job Status failed although OK

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

Re: Job Status failed although OK

Are you using a blank password to NetApp storage controllers?