Data Backup and Recovery

Snap Creator Snap Mirror Relationship SCF-00017

TIMOTHY_P_LANE
3,161 Views

I created a configuration to snap to snap mirror a lun.  The snap mirror relationship was precreated and added to the SC Configuration. When we run the job we get a SCF-00017 Error.

Attached is the debug log.  Please ignore the CACHE errors as the DB software has not been installed yet.

########## Detecting Data ONTAP mode for prinetaappc2 ##########
[2014-01-17 15:36:57,251] INFO: STORAGE-03031: System version details of [prinetaappc2].
[2014-01-17 15:36:57,251] DEBUG: <system-get-version/>

[2014-01-17 15:36:57,251] DEBUG: <system-get-ontapi-version/>

[2014-01-17 15:36:57,251] INFO: STORAGE-03032: System version details of [prinetaappc2] finished successfully.
[2014-01-17 15:36:57,719] INFO: STORAGE-03031: System version details of [prinetappdr1].
[2014-01-17 15:36:57,719] DEBUG: <system-get-version/>

[2014-01-17 15:36:57,719] DEBUG: <system-get-ontapi-version/>

[2014-01-17 15:36:57,719] INFO: STORAGE-03032: System version details of [prinetappdr1] finished successfully.
########## Agent validation ##########
[2014-01-17 15:36:57,953] INFO: Agent validation completed successfuly for agent 10.255.4.80:9090
########## Plugin validation ##########
[2014-01-17 15:36:57,969] INFO: Plugin validation file for plugin CACHE not found, skipping validation
########## PRE APPLICATION QUIESCE COMMANDS ##########
[2014-01-17 15:36:57,969] INFO: Pre application quiesce commands are not defined
########## PRE APPLICATION QUIESCE COMMANDS FINISHED SUCCESSFULLY  ##########
[2014-01-17 15:36:57,969] INFO: Application auto discovery is not enabled skipping .
[2014-01-17 15:36:57,969] INFO: Validate volume is not enabled skipping validate volume task.
########## Application Quiesce ##########
[2014-01-17 15:36:58,671] DEBUG: [10.255.4.80:9090 (4.0.0.1)] CACHE::setENV finished successfully
[2014-01-17 15:36:58,671] INFO: [10.255.4.80:9090 (4.0.0.1)] Quiescing instance CACHE
[2014-01-17 15:36:58,671] DEBUG: [10.255.4.80:9090 (4.0.0.1)] Executing command: su SCBackup "-c /usr/local//csession CACHE -U%SYS '##Class(Backup.General).ExternalFreeze(\"\",\"NetApp SnapCreator attempting to freeze instance...\",1,\"\",\"\",1,\"\",\"\",\"\")'"
[2014-01-17 15:36:58,671] ERROR: [10.255.4.80:9090 (4.0.0.1)] Unexpected Return Code 127 (should be 5 for success and 3 for failure)
[2014-01-17 15:36:58,671] INFO: [10.255.4.80:9090 (4.0.0.1)] Quiescing instance not finished: backup failed
[2014-01-17 15:36:58,671] ERROR: [10.255.4.80:9090(4.0.0.1)] SCF-00038: Application quiesce for plugin [CACHE] failed with exit code [101], continuing with backup
[2014-01-17 15:36:59,092] INFO: STORAGE-05016: Creating OM Event [script:critical-event] on [opmgr01].
[2014-01-17 15:36:59,092] DEBUG: <event-generate>
<event-name>script:critical-event</event-name>
<source>1</source>
<event-message>SNAPCREATOR [10.255.4.80:9090(4.0.0.1)] SCF-00038: Application quiesce for plugin [CACHE] failed with exit code [101], continuing with backup(Config: EPIC_CACHE_POC Name: EPIC_POC_CACHE Policy: daily)</event-message>
</event-generate>

[2014-01-17 15:36:59,092] INFO: STORAGE-05017: OM Event [script:critical-event] on [opmgr01] created successfully.
########## Application Quiesce finished successfully ##########
########## POST APPLICATION QUIESCE COMMANDS ##########
[2014-01-17 15:36:59,092] INFO: Post application quiesce commands are not defined
########## POST APPLICATION QUIESCE COMMANDS FINISHED SUCCESSFULLY  ##########
########## PRE NTAP COMMANDS ##########
[2014-01-17 15:36:59,092] INFO: Pre ntap commands are not defined
########## PRE NTAP COMMANDS FINISHED SUCCESSFULLY ##########
########## Generating Info ASUP on prinetaappc2 ##########
[2014-01-17 15:36:59,451] INFO: STORAGE-01001: Creating autosupport message with event id [0], category [Backup Started], description [INFO: Snap Creator Framework 4.0p1 Backup for EPIC_POC_CACHE ACTION: backup POLICY: daily Plugin: CACHE Not supported Volumes: fc_lun_epic_aix_poc_mksys_b_1, Started], level [INFO], hostname [NTAPWFAPP01 [10.255.4.80:9090]].
[2014-01-17 15:36:59,451] DEBUG: <ems-autosupport-log>
<computer-name>NTAPWFAPP01 [10.255.4.80:9090]</computer-name>
<event-id>0</event-id>
<event-source>SNAPCREATOR</event-source>
<app-version>Snap Creator Framework 4.0p1</app-version>
<category>Backup Started</category>
<event-description>INFO: Snap Creator Framework 4.0p1 Backup for EPIC_POC_CACHE ACTION: backup POLICY: daily Plugin: CACHE Not supported Volumes: fc_lun_epic_aix_poc_mksys_b_1, Started</event-description>
<log-level>6</log-level>
<auto-support>false</auto-support>
</ems-autosupport-log>

[2014-01-17 15:36:59,451] INFO: STORAGE-01002: Creating autosupport message with event id [0], category [Backup Started], description [INFO: Snap Creator Framework 4.0p1 Backup for EPIC_POC_CACHE ACTION: backup POLICY: daily Plugin: CACHE Not supported Volumes: fc_lun_epic_aix_poc_mksys_b_1, Started], level [INFO], hostname [NTAPWFAPP01 [10.255.4.80:9090]] finished successfully.
[2014-01-17 15:36:59,451] INFO: ASUP finished successfully on prinetaappc2
########## Running Snapshot copy Rename on Primary devices ##########
[2014-01-17 15:36:59,451] DEBUG: Performing snaplist operation on prinetaappc2:fc_lun_epic_aix_poc_mksys_b_1
[2014-01-17 15:36:59,809] INFO: STORAGE-02090: Listing Snapshot copies on volume [fc_lun_epic_aix_poc_mksys_b_1].
[2014-01-17 15:36:59,809] DEBUG: <snapshot-list-info>
<volume>fc_lun_epic_aix_poc_mksys_b_1</volume>
<terse>true</terse>
</snapshot-list-info>

[2014-01-17 15:36:59,809] INFO: STORAGE-02091: Listing Snapshot copies on volume [fc_lun_epic_aix_poc_mksys_b_1] finished successfully.
########## Snapshot copy Rename finished successfully ! ##########
########## File system plug-in not defined. Skipping file system quiesce ##########
########## SNAPSHOT CREATE COMMANDS ##########
########## Snapshot copy create commands finished successfully ##########
[2014-01-17 15:36:59,809] INFO: Skipping SIS clone backup.
########## Starting Consistency Group Snapshot copy for all controllers ##########
[2014-01-17 15:36:59,809] INFO: Starting Consistency Group Snapshot copy for prinetaappc2
[2014-01-17 15:37:00,153] INFO: STORAGE-02001: Creating consistency group Snapshot copy [EPIC_POC_CACHE-daily_recent] on volumes [[fc_lun_epic_aix_poc_mksys_b_1]].
[2014-01-17 15:37:00,153] DEBUG: <cg-start>
<snapshot>EPIC_POC_CACHE-daily_recent</snapshot>
<timeout>medium</timeout>
<volumes>
  <volume-name>fc_lun_epic_aix_poc_mksys_b_1</volume-name>
</volumes>
</cg-start>

[2014-01-17 15:37:00,153] INFO: STORAGE-02002: Creating consistency group Snapshot copy [EPIC_POC_CACHE-daily_recent] on volumes [[fc_lun_epic_aix_poc_mksys_b_1]] finished successfully
########## Committing Consistency Group Snapshot copy for all controllers ##########
[2014-01-17 15:37:01,728] INFO: STORAGE-02004: Committing consistency group Snapshot copy on [12].
[2014-01-17 15:37:01,728] DEBUG: <cg-commit>
<cg-id>12</cg-id>
</cg-commit>

[2014-01-17 15:37:01,728] INFO: STORAGE-02005: Consistency group Snapshot copy commit [12] on [prinetaappc2] finished successfully.
########## CG finished successfully ##########
[2014-01-17 15:37:01,744] INFO: Skipping snapshot creation using ZAPI.
########## File system plug-in not defined. Skipping file system unquiesce ##########
########## PRE APPLICATION UNQUIESCE COMMANDS ##########
[2014-01-17 15:37:01,744] INFO: Pre application unquiesce commands are not defined
########## PRE APPLICATION UNQUIESCE COMMANDS FINISHED SUCCESSFULLY  ##########
########## Application Unquiesce ##########
[2014-01-17 15:37:02,103] DEBUG: [10.255.4.80:9090 (4.0.0.1)] CACHE::setENV finished successfully
[2014-01-17 15:37:02,103] INFO: [10.255.4.80:9090 (4.0.0.1)] Unquiescing instance CACHE
[2014-01-17 15:37:02,103] DEBUG: [10.255.4.80:9090 (4.0.0.1)] Executing command: su SCBackup "-c /usr/local//csession CACHE -U%SYS '##Class(Backup.General).ExternalThaw'"
[2014-01-17 15:37:02,103] ERROR: [10.255.4.80:9090 (4.0.0.1)] Unexpected Return Code 127 (should be 5 for success and 3 for failure)
[2014-01-17 15:37:02,103] INFO: [10.255.4.80:9090 (4.0.0.1)] Unquiescing instance not finished
[2014-01-17 15:37:02,103] ERROR: SCF-00033: Application unquiesce for plugin [CACHE] failed with exit code [101], proceeding with backup!
[2014-01-17 15:37:02,305] INFO: STORAGE-05016: Creating OM Event [script:critical-event] on [opmgr01].
[2014-01-17 15:37:02,305] DEBUG: <event-generate>
<event-name>script:critical-event</event-name>
<source>1</source>
<event-message>SNAPCREATOR SCF-00033: Application unquiesce for plugin [CACHE] failed with exit code [101], proceeding with backup!(Config: EPIC_CACHE_POC Name: EPIC_POC_CACHE Policy: daily)</event-message>
</event-generate>

[2014-01-17 15:37:02,305] INFO: STORAGE-05017: OM Event [script:critical-event] on [opmgr01] created successfully.
########## Application Unquiesce finished successfully ##########
########## POST APPLICATION UNQUIESCE COMMANDS ##########
[2014-01-17 15:37:02,321] INFO: Post application unquiesce commands are not defined
########## POST APPLICATION UNQUIESCE COMMANDS FINISHED SUCCESSFULLY  ##########
########## Generating Info ASUP on prinetaappc2 ##########
[2014-01-17 15:37:02,649] INFO: STORAGE-01001: Creating autosupport message with event id [0], category [Backup Completed], description [INFO: Snap Creator Framework 4.0p1 Backup for EPIC_POC_CACHE ACTION: backup POLICY: daily Plugin: CACHE Not supported Volumes: fc_lun_epic_aix_poc_mksys_b_1, finished], level [INFO], hostname [NTAPWFAPP01 [10.255.4.80:9090]].
[2014-01-17 15:37:02,649] DEBUG: <ems-autosupport-log>
<computer-name>NTAPWFAPP01 [10.255.4.80:9090]</computer-name>
<event-id>0</event-id>
<event-source>SNAPCREATOR</event-source>
<app-version>Snap Creator Framework 4.0p1</app-version>
<category>Backup Completed</category>
<event-description>INFO: Snap Creator Framework 4.0p1 Backup for EPIC_POC_CACHE ACTION: backup POLICY: daily Plugin: CACHE Not supported Volumes: fc_lun_epic_aix_poc_mksys_b_1, finished</event-description>
<log-level>6</log-level>
<auto-support>false</auto-support>
</ems-autosupport-log>

[2014-01-17 15:37:02,649] INFO: STORAGE-01002: Creating autosupport message with event id [0], category [Backup Completed], description [INFO: Snap Creator Framework 4.0p1 Backup for EPIC_POC_CACHE ACTION: backup POLICY: daily Plugin: CACHE Not supported Volumes: fc_lun_epic_aix_poc_mksys_b_1, finished], level [INFO], hostname [NTAPWFAPP01 [10.255.4.80:9090]] finished successfully.
[2014-01-17 15:37:02,649] INFO: ASUP finished successfully on prinetaappc2
########## Running Snapmirror Update using source prinetaappc2 ##########
[2014-01-17 15:37:12,664] INFO: Getting Snapmirror Status for prinetaappc2
[2014-01-17 15:37:13,475] INFO: STORAGE-02070: Retrieving SnapMirror status.
[2014-01-17 15:37:13,475] DEBUG: <snapmirror-get-status/>

[2014-01-17 15:37:13,475] INFO: STORAGE-02071: Retrieving SnapMirror status finished successfully.
[2014-01-17 15:37:13,475] DEBUG: Ignoring the snapmirror relation with source Prinetaappc2:nfs_vmds_server_t1_p2 and destination prinetappdr1:ds_vmds_server_prod_mirror_Prinetaappc2_nfs_vmds_server_t1_p_14 with source status source
[2014-01-17 15:37:13,475] DEBUG: Ignoring the snapmirror relation with source Prinetaappc2:nfs_vmds_server_t1_p16 and destination prinetappdr1:ds_vmds_server_prod_mirror_Prinetaappc2_nfs_vmds_server_t1_p_16 with source status source
[2014-01-17 15:37:13,475] DEBUG: Ignoring the snapmirror relation with source Prinetaappc2:nfs_vmds_server_t1_p3 and destination prinetappdr1:ds_vmds_server_prod_mirror_Prinetaappc2_nfs_vmds_server_t1_p_17 with source status source
[2014-01-17 15:37:13,475] DEBUG: Ignoring the snapmirror relation with source Prinetaappc2:nfs_vmds_server_t1_p3 and destination prinetappdr1:ds_vmds_server_prod_mirror_Prinetaappc2_nfs_vmds_server_t1_p_18 with source status source
[2014-01-17 15:37:13,475] DEBUG: Ignoring the snapmirror relation with source Prinetaappc2:fc_lun_epic_aix_poc_mksys_b_1 and destination prinetappdr1:fc_lun_epic_aix_poc_mksys_b_1_SnapMirror_17012014_120810 with source status source
[2014-01-17 15:37:13,475] DEBUG: Ignoring the snapmirror relation with source Prinetaappc2:nfs_vmds_server_t1_p2 and destination prinetappdr2:ds_vmds_server_prod_mirror_Prinetaappc2_nfs_vmds_server_t1_p_6 with source status source
[2014-01-17 15:37:13,475] ERROR: SCF-00017: No Snapmirror Relationship for [prinetaappc2]:[fc_lun_epic_aix_poc_mksys_b_1] exist! Exiting
########## Snap Creator Framework 4.0p1 failed ##########
[2014-01-17 15:37:13,475] INFO: Pre Exit commands are not defined. Skipping !
[2014-01-17 15:37:13,693] INFO: STORAGE-05016: Creating OM Event [script:critical-event] on [opmgr01].
[2014-01-17 15:37:13,693] DEBUG: <event-generate>
<event-name>script:critical-event</event-name>
<source>1</source>
<event-message>SNAPCREATOR SCF-00017: No Snapmirror Relationship for [prinetaappc2]:[fc_lun_epic_aix_poc_mksys_b_1] exist! Exiting(Config: EPIC_CACHE_POC Name: EPIC_POC_CACHE Policy: daily)</event-message>
</event-generate>

[2014-01-17 15:37:13,693] INFO: STORAGE-05017: OM Event [script:critical-event] on [opmgr01] created successfully.

2 REPLIES 2

spinks
3,161 Views

Tim,

You mention that the SnapMirror relationship was already created - was it also initialized?

I've hit a similar error before.

ONTAP is very particular about using the proper case.

In the error message I see:

<event-message>SNAPCREATOR SCF-00017: No Snapmirror Relationship for [prinetaappc2]:[fc_lun_epic_aix_poc_mksys_b_1] exist! Exiting(Config: EPIC_CACHE_POC Name: EPIC_POC_CACHE Policy: daily)</event-message>

Confirm that in the config file that the volume names are EXACTLY what ONTAP has - If you have Prinetaappc2 and ONTAP has prinetaappc2 then the command will fail.

(Click the config file the check the Connections & Volumes tab and see what the settings are)

Thanks!

John

TIMOTHY_P_LANE
3,161 Views

John,

The snapmirror relationship was initialized.

The case on all of the controllers and volumes are correct.

How does SC identify the snapmirror relationship? Does the destination volume need to have the same name as the source volume? Or can they be different?

Thanks

Tim

Public