6 Replies Latest reply: Apr 30, 2013 12:07 PM by Siva Ramanathan RSS

Job Status failed although OK

REISTTELECOM
Currently Being Moderated

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
    Siva Ramanathan
    Currently Being Moderated

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

    • Re: Job Status failed although OK
      REISTTELECOM
      Currently Being Moderated

      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
        Siva Ramanathan
        Currently Being Moderated

        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
          REISTTELECOM
          Currently Being Moderated

          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
            Siva Ramanathan
            Currently Being Moderated

            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

More Like This

  • Retrieving data ...