Data Backup and Recovery

Job Status failed although OK

REISTTELECOM
3,919 Views

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?

6 REPLIES 6

sivar
3,919 Views

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

REISTTELECOM
3,919 Views

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

sivar
3,919 Views

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
3,919 Views

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

sivar
3,919 Views

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
3,919 Views

Are you using a blank password to NetApp storage controllers?

Public