none
Error 197 With DPM 2012 R2 UR6 RRS feed

  • Question

  • hi,

    I'm facing the error 197 when I want to change a PG , but my version is 4.02.1312 therefore RU6 .

    I try to SQL script in this thread

    https://social.technet.microsoft.com/Forums/fr-FR/44c10c9b-18e6-42ec-8438-93cbb04f1737/error-197-after-applying-dpm-2012-r2-ur5?forum=dataprotectionmanager

    but ineffective.

    Juste one of my two PG is affect by this error, both protect system state, BMR, files. PG affect by error protecting hyper-v and cluster serveur's system state.

    I find :

    Microsoft.Internal.EnterpriseStorage.Dls.Utils.InvalidProtectedGroupSpecException: Invalid Intent Metadata Catalog XML: ProtectedObject 906f2cb7-0751-48a1-90a7-7443da620fd5 has duplicate entries.

    in   MSDPM.errlog :

    0DB4    2EEC    06/04    09:49:21.846    16    JobDefinitionManager.cs(129)            NORMAL     AddJobDefinition:a550e7ba-fb3b-497f-b280-baff659cfdf6
    0DB4    2EEC    06/04    09:49:21.846    16    JobManager.cs(349)            NORMAL     Trigger job:a550e7ba-fb3b-497f-b280-baff659cfdf6 with id:ff246cdd-8a23-41d6-aa05-67f9aa5bc44b
    0DB4    2EEC    06/04    09:49:21.846    16    Task.cs(235)            NORMAL     Changing task state from 'Invalid' -> 'Start' (b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2EEC    06/04    09:49:21.862    02    EventManager.cs(98)            NORMAL    Publishing event from ActiveJob.cs(299): JobStateChange, [JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b]
    0DB4    2348    06/04    09:49:21.862    16    ActiveJob.cs(317)            NORMAL     Start execution of job:'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b' in state:'Start'
    0DB4    2348    06/04    09:49:21.862    68    ActivityWindow.cs(75)            NORMAL     Add new wait : ff246cdd-8a23-41d6-aa05-67f9aa5bc44b(wait until: jeudi 11 juin 2015) @ jeudi 4 juin 2015UTC
    0DB4    2348    06/04    09:49:21.862    68    ActivityWindow.cs(198)            NORMAL     Setting timer for '604799,9843734's...
    0DB4    2348    06/04    09:49:21.862    16    ActiveJob.cs(1081)            NORMAL     Changing state of job: 'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b' from 'Start' -> 'GenerateWorkplan'
    0DB4    2348    06/04    09:49:21.862    02    EventManager.cs(98)            NORMAL    Publishing event from ActiveJob.cs(1100): JobStateChange, [JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b]
    0DB4    2348    06/04    09:49:21.862    16    ActiveJob.cs(581)            NORMAL     Begin GenerateWorkplan:JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b, ItemID=b36652ba-33cf-43b1-abf0-6aecc0834cc0
    0DB4    2348    06/04    09:49:21.862    01    TaskExecutor.cs(81)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    GenerateWorkplan(job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b) - Entering
    0DB4    2348    06/04    09:49:21.877    02    EventManager.cs(98)            NORMAL    Publishing event from TaskExecutor.cs(107): TaskCreated, [TaskID=b36652ba-33cf-43b1-abf0-6aecc0834cc0]
    0DB4    2348    06/04    09:49:21.877    01    TaskExecutor.cs(127)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    GenerateWorkplan(job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b) - Finished (error=False), executing GenerateWorkplanCallback
    0DB4    2348    06/04    09:49:21.877    16    Task.cs(235)            NORMAL     Changing task state from 'Start' -> 'GenerateWorkplan' (b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2348    06/04    09:49:21.877    16    ActiveJob.cs(1081)            NORMAL     Changing state of job: 'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b' from 'GenerateWorkplan' -> 'Execute'
    0DB4    2348    06/04    09:49:21.893    02    EventManager.cs(98)            NORMAL    Publishing event from ActiveJob.cs(1100): JobStateChange, [JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b]
    0DB4    2348    06/04    09:49:21.893    16    ActiveJob.cs(831)            NORMAL     Begin new execution batch at task:(True)-b36652ba-33cf-43b1-abf0-6aecc0834cc0
    0DB4    2348    06/04    09:49:21.893    16    ActiveJob.cs(851)            NORMAL     Dispatching task for execution:True-b36652ba-33cf-43b1-abf0-6aecc0834cc0
    0DB4    1998    06/04    09:49:21.893    16    ActiveJob.cs(581)            NORMAL     Begin ExecuteTask:JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b, ItemID=b36652ba-33cf-43b1-abf0-6aecc0834cc0
    0DB4    1998    06/04    09:49:21.893    01    TaskExecutor.cs(692)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Setting up Fsm: verb[84021b5e-b4dc-9b27-2b7e-3b99bb1225ff]
    0DB4    1998    06/04    09:49:21.893    01    TaskExecutor.cs(487)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    ExecuteTask(job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b) - Starting task instance
    0DB4    2C78    06/04    09:49:21.893    03    timer.cpp(490)            ACTIVITY    CTimerMgr::TimerThread
    0DB4    2C78    06/04    09:49:21.893    03    timer.cpp(503)    [0000000019A1F258]        ACTIVITY    CTimerMgr::TimerThreadInternal
    0DB4    1998    06/04    09:49:21.893    02    EventManager.cs(98)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Publishing event from TaskInstance.cs(406): TaskStart, [TaskID=b36652ba-33cf-43b1-abf0-6aecc0834cc0]
    0DB4    1998    06/04    09:49:21.924    01    TaskInstance.cs(442)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Task: Initial transition enqueued, startState='CreateShadowCopyMachine.StartState'
    0DB4    2348    06/04    09:49:21.924    27    OperationTypeLock.cs(582)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    In LockServer() of ReplicationOperationTypeLock, returning lockStatus = AcquiredLock
    0DB4    2348    06/04    09:49:21.924    02    EventManager.cs(98)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Publishing event from Replica.cs(2158): ReplicaStatusChange, [DataSourceID=0c3b517f-e295-4a83-91a5-638e9024ef81]
    0DB4    2348    06/04    09:49:21.924    27    OperationTypeLock.cs(487)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    In Lock() of ReplicationOperationTypeLock, returning lockStatus = AcquiredLock
    0DB4    2AA8    06/04    09:49:22.096    27    ShadowCopyMachine.cs(261)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    WARNING    ShadowCopyMachine : ClearCreateShadowCopyTaskIDLock
    0DB4    2AA8    06/04    09:49:22.096    02    EventManager.cs(98)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Publishing event from Replica.cs(2158): ReplicaStatusChange, [DataSourceID=0c3b517f-e295-4a83-91a5-638e9024ef81]
    0DB4    2AA8    06/04    09:49:22.096    27    OperationTypeLock.cs(518)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    In Unlock() of ReplicationOperationTypeLock, returning
    0DB4    2C78    06/04    09:49:22.127    03    timer.cpp(513)    [0000000019A1F258]        ACTIVITY    Shutting down timer thread.
    0DB4    2AA8    06/04    09:49:22.127    02    EventManager.cs(98)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Publishing event from TaskInstance.cs(823): TaskStop, [TaskID=b36652ba-33cf-43b1-abf0-6aecc0834cc0]
    0DB4    2AA8    06/04    09:49:22.127    01    TaskExecutor.cs(849)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Task stopped (state=Completed, error=ShadowCopyCreationSkipped; 0; None)
    0DB4    2AA8    06/04    09:49:22.127    16    Task.cs(235)            NORMAL     Changing task state from 'GenerateWorkplan' -> 'Execute' (b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2E74    06/04    09:49:22.127    16    ActiveJob.cs(904)            NORMAL     Dispatching RetireJob.
    0DB4    2E74    06/04    09:49:22.127    16    ActiveJob.cs(1081)            NORMAL     Changing state of job: 'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b' from 'Execute' -> 'Retire'
    0DB4    2E74    06/04    09:49:22.127    02    EventManager.cs(98)            NORMAL    Publishing event from ActiveJob.cs(1100): JobStateChange, [JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b]
    0DB4    2E74    06/04    09:49:22.127    16    Task.cs(235)            NORMAL     Changing task state from 'Execute' -> 'Retire' (b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2E74    06/04    09:49:22.127    01    TaskExecutor.cs(179)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    RetireWorkplan(job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b) - Calling derived RetireWorkplan
    0DB4    2E74    06/04    09:49:22.127    27    ProtectionRecoveryManager.cs(764)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Retiring task for (job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b)
    0DB4    2E74    06/04    09:49:22.127    01    TaskExecutor.cs(225)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    ACTIVITY    RetireWorkplan(job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b) - Retiring task: Completed
    0DB4    2E74    06/04    09:49:22.143    16    ActiveJob.cs(1201)            NORMAL     Processing end of job: ff246cdd-8a23-41d6-aa05-67f9aa5bc44b
    0DB4    2E74    06/04    09:49:22.143    68    ActivityWindow.cs(101)            NORMAL     RemoveWait wait : ff246cdd-8a23-41d6-aa05-67f9aa5bc44b
    0DB4    2E74    06/04    09:49:22.143    16    ActiveJob.cs(1224)            NORMAL     Final task state=Retire(b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2E74    06/04    09:49:22.143    16    Task.cs(235)            NORMAL     Changing task state from 'Retire' -> 'Succeeded' (b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2E74    06/04    09:49:22.143    16    ActiveJob.cs(1081)            NORMAL     Changing state of job: 'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b' from 'Retire' -> 'Succeeded'
    0DB4    2E74    06/04    09:49:22.159    02    EventManager.cs(98)            NORMAL    Publishing event from ActiveJob.cs(1100): JobStateChange, [JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b]
    0DB4    2E74    06/04    09:49:22.159    17    JobTranslator.cs(1229)            NORMAL    TryCancelJobsAndWaitForCancel: Final state of job ff246cdd-8a23-41d6-aa05-67f9aa5bc44b = Succeeded
    0DB4    2E74    06/04    09:49:22.159    16    JobManager.cs(741)            NORMAL     Removed job entry from active list:ff246cdd-8a23-41d6-aa05-67f9aa5bc44b
    0DB4    2E74    06/04    09:49:22.159    16    ActiveJob.cs(1297)            NORMAL    Completed execution of job:'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b', final job state:'Succeeded'
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL    Error in ConfigureProtectedGroup:
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL    Microsoft.Internal.EnterpriseStorage.Dls.Utils.InvalidProtectedGroupSpecException: Invalid Intent Metadata Catalog XML: ProtectedObject 906f2cb7-0751-48a1-90a7-7443da620fd5 has duplicate entries.
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.IntentManager.ValidateProtectedObjectDB(IMCatalogType IMCatalogPersist, IMCatalogDB catalogDB)
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.ProtectedGroupTranslator.PrepareForTranslation()
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.ProtectedGroupTranslator.TranslateGroup(IMCatalogType IMCatalogXmlPassed, String intentCatalogXml)
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL    *** Mojito error was: InvalidProtectedGroupSpec; 0; None
    0DB4    2EEC    06/04    09:49:29.832    15    SchedulerImpl.cs(640)            NORMAL    Entering ConsistencyCheck
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1020)            WARNING    No retry on exception Le @name ('') spécifié n'existe pas. while executing sp_delete_alert
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1027)            WARNING    SqlException encountered, SqlRetryCommand diag details - SqlCommandText  => Name=msdb.dbo.sp_delete_alert, CommandType=StoredProcedure
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1027)            WARNING    CommandDiagInfo => CanRetry=False, CommandTimeout=3600
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1027)            WARNING    CommandParams   => Count=2, InTx=True
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1027)            WARNING         Param[0]   => ParameterName=@name | Value= | Size=0 | DbType=NVarChar | Direction=Input | IsNullable=False
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1027)            WARNING         Param[1]   => ParameterName=@RETURN_VALUE | Value=1 | Size=0 | DbType=Int | Direction=ReturnValue | IsNullable=False
    0DB4    2EEC    06/04    09:49:30.363    17    IntentManager.cs(3531)            NORMAL    Resolving Alert for cloud policy inconsistent after successfull intent restart
    0DB4    2EEC    06/04    09:49:30.363    17    IntentManager.cs(3075)            NORMAL    Resetting PG TranslationStatus
    0DB4    2EEC    06/04    09:49:30.535    17    IntentManager.cs(3081)            NORMAL    Exiting Intent Manager Refresh, translated 0 datasources
    0DB4    2EEC    06/04    09:49:30.535    27    DatasourceGroupInputHelper.cs(129)            NORMAL    DatasourceGroupInputHelper : Reloading datasource groups Xml: C:\Program Files\Microsoft System Center 2012 R2\DPM\DPM\Config\DatasourceGroups.xml
    0DB4    2EEC    06/04    09:49:30.535    02    EventManager.cs(98)            NORMAL    Publishing event from Translate.cs(655): TranslationDone, [ProtectedGroupID=27ee7d0a-32bf-4634-aa46-511689ee703d]
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING    Caught Dls exception: Microsoft.Internal.EnterpriseStorage.Dls.Utils.InvalidProtectedGroupSpecException: Invalid Intent Metadata Catalog XML: ProtectedObject 906f2cb7-0751-48a1-90a7-7443da620fd5 has duplicate entries.
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.IntentManager.ValidateProtectedObjectDB(IMCatalogType IMCatalogPersist, IMCatalogDB catalogDB)
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.ProtectedGroupTranslator.PrepareForTranslation()
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.ProtectedGroupTranslator.TranslateGroup(IMCatalogType IMCatalogXmlPassed, String intentCatalogXml)
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.IntentTranslator.ConfigureProtectedGroup(String intentCatalogXml)
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Engine.CIntentServices.ConfigureProtectedGroup(UInt16* bstrIMCatalogXml)
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Engine.CCoreServices.ConfigureProtectedGroup(CCoreServices* , UInt16* bstrIMCatalogXml, tagSAFEARRAY** exceptionResult)
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING    *** Mojito error was: InvalidProtectedGroupSpec; 0; None

    Have you an idea ?

    Friday, June 5, 2015 7:02 AM

Answers

All replies

  • hi,

    I'm facing the error 197 when I want to change a PG , but my version is 4.02.1312 therefore RU6 .

    I try to SQL script in this thread

    https://social.technet.microsoft.com/Forums/fr-FR/44c10c9b-18e6-42ec-8438-93cbb04f1737/error-197-after-applying-dpm-2012-r2-ur5?forum=dataprotectionmanager

    but ineffective.

    Juste one of my two PG is affect by this error, both protect system state, BMR, files. PG affect by error protecting hyper-v and cluster serveur's system state.

    I find :

    Microsoft.Internal.EnterpriseStorage.Dls.Utils.InvalidProtectedGroupSpecException: Invalid Intent Metadata Catalog XML: ProtectedObject 906f2cb7-0751-48a1-90a7-7443da620fd5 has duplicate entries.

    in   MSDPM.errlog :

    0DB4    2EEC    06/04    09:49:21.846    16    JobDefinitionManager.cs(129)            NORMAL     AddJobDefinition:a550e7ba-fb3b-497f-b280-baff659cfdf6
    0DB4    2EEC    06/04    09:49:21.846    16    JobManager.cs(349)            NORMAL     Trigger job:a550e7ba-fb3b-497f-b280-baff659cfdf6 with id:ff246cdd-8a23-41d6-aa05-67f9aa5bc44b
    0DB4    2EEC    06/04    09:49:21.846    16    Task.cs(235)            NORMAL     Changing task state from 'Invalid' -> 'Start' (b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2EEC    06/04    09:49:21.862    02    EventManager.cs(98)            NORMAL    Publishing event from ActiveJob.cs(299): JobStateChange, [JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b]
    0DB4    2348    06/04    09:49:21.862    16    ActiveJob.cs(317)            NORMAL     Start execution of job:'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b' in state:'Start'
    0DB4    2348    06/04    09:49:21.862    68    ActivityWindow.cs(75)            NORMAL     Add new wait : ff246cdd-8a23-41d6-aa05-67f9aa5bc44b(wait until: jeudi 11 juin 2015) @ jeudi 4 juin 2015UTC
    0DB4    2348    06/04    09:49:21.862    68    ActivityWindow.cs(198)            NORMAL     Setting timer for '604799,9843734's...
    0DB4    2348    06/04    09:49:21.862    16    ActiveJob.cs(1081)            NORMAL     Changing state of job: 'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b' from 'Start' -> 'GenerateWorkplan'
    0DB4    2348    06/04    09:49:21.862    02    EventManager.cs(98)            NORMAL    Publishing event from ActiveJob.cs(1100): JobStateChange, [JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b]
    0DB4    2348    06/04    09:49:21.862    16    ActiveJob.cs(581)            NORMAL     Begin GenerateWorkplan:JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b, ItemID=b36652ba-33cf-43b1-abf0-6aecc0834cc0
    0DB4    2348    06/04    09:49:21.862    01    TaskExecutor.cs(81)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    GenerateWorkplan(job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b)  - Entering
    0DB4    2348    06/04    09:49:21.877    02    EventManager.cs(98)            NORMAL    Publishing event from TaskExecutor.cs(107): TaskCreated, [TaskID=b36652ba-33cf-43b1-abf0-6aecc0834cc0]
    0DB4    2348    06/04    09:49:21.877    01    TaskExecutor.cs(127)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    GenerateWorkplan(job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b)  - Finished (error=False), executing GenerateWorkplanCallback
    0DB4    2348    06/04    09:49:21.877    16    Task.cs(235)            NORMAL     Changing task state from 'Start' -> 'GenerateWorkplan' (b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2348    06/04    09:49:21.877    16    ActiveJob.cs(1081)            NORMAL     Changing state of job: 'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b' from 'GenerateWorkplan' -> 'Execute'
    0DB4    2348    06/04    09:49:21.893    02    EventManager.cs(98)            NORMAL    Publishing event from ActiveJob.cs(1100): JobStateChange, [JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b]
    0DB4    2348    06/04    09:49:21.893    16    ActiveJob.cs(831)            NORMAL     Begin new execution batch at task:(True)-b36652ba-33cf-43b1-abf0-6aecc0834cc0
    0DB4    2348    06/04    09:49:21.893    16    ActiveJob.cs(851)            NORMAL     Dispatching task for execution:True-b36652ba-33cf-43b1-abf0-6aecc0834cc0
    0DB4    1998    06/04    09:49:21.893    16    ActiveJob.cs(581)            NORMAL     Begin ExecuteTask:JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b, ItemID=b36652ba-33cf-43b1-abf0-6aecc0834cc0
    0DB4    1998    06/04    09:49:21.893    01    TaskExecutor.cs(692)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Setting up Fsm: verb[84021b5e-b4dc-9b27-2b7e-3b99bb1225ff]
    0DB4    1998    06/04    09:49:21.893    01    TaskExecutor.cs(487)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    ExecuteTask(job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b)  - Starting task instance
    0DB4    2C78    06/04    09:49:21.893    03    timer.cpp(490)            ACTIVITY    CTimerMgr::TimerThread
    0DB4    2C78    06/04    09:49:21.893    03    timer.cpp(503)    [0000000019A1F258]        ACTIVITY    CTimerMgr::TimerThreadInternal
    0DB4    1998    06/04    09:49:21.893    02    EventManager.cs(98)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Publishing event from TaskInstance.cs(406): TaskStart, [TaskID=b36652ba-33cf-43b1-abf0-6aecc0834cc0]
    0DB4    1998    06/04    09:49:21.924    01    TaskInstance.cs(442)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Task: Initial transition enqueued, startState='CreateShadowCopyMachine.StartState'
    0DB4    2348    06/04    09:49:21.924    27    OperationTypeLock.cs(582)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    In LockServer() of ReplicationOperationTypeLock, returning lockStatus = AcquiredLock
    0DB4    2348    06/04    09:49:21.924    02    EventManager.cs(98)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Publishing event from Replica.cs(2158): ReplicaStatusChange, [DataSourceID=0c3b517f-e295-4a83-91a5-638e9024ef81]
    0DB4    2348    06/04    09:49:21.924    27    OperationTypeLock.cs(487)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    In Lock() of ReplicationOperationTypeLock, returning lockStatus = AcquiredLock
    0DB4    2AA8    06/04    09:49:22.096    27    ShadowCopyMachine.cs(261)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    WARNING    ShadowCopyMachine : ClearCreateShadowCopyTaskIDLock
    0DB4    2AA8    06/04    09:49:22.096    02    EventManager.cs(98)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Publishing event from Replica.cs(2158): ReplicaStatusChange, [DataSourceID=0c3b517f-e295-4a83-91a5-638e9024ef81]
    0DB4    2AA8    06/04    09:49:22.096    27    OperationTypeLock.cs(518)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    In Unlock() of ReplicationOperationTypeLock, returning
    0DB4    2C78    06/04    09:49:22.127    03    timer.cpp(513)    [0000000019A1F258]        ACTIVITY    Shutting down timer thread.
    0DB4    2AA8    06/04    09:49:22.127    02    EventManager.cs(98)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Publishing event from TaskInstance.cs(823): TaskStop, [TaskID=b36652ba-33cf-43b1-abf0-6aecc0834cc0]
    0DB4    2AA8    06/04    09:49:22.127    01    TaskExecutor.cs(849)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Task stopped (state=Completed, error=ShadowCopyCreationSkipped; 0; None)
    0DB4    2AA8    06/04    09:49:22.127    16    Task.cs(235)            NORMAL     Changing task state from 'GenerateWorkplan' -> 'Execute' (b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2E74    06/04    09:49:22.127    16    ActiveJob.cs(904)            NORMAL     Dispatching RetireJob.
    0DB4    2E74    06/04    09:49:22.127    16    ActiveJob.cs(1081)            NORMAL     Changing state of job: 'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b' from 'Execute' -> 'Retire'
    0DB4    2E74    06/04    09:49:22.127    02    EventManager.cs(98)            NORMAL    Publishing event from ActiveJob.cs(1100): JobStateChange, [JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b]
    0DB4    2E74    06/04    09:49:22.127    16    Task.cs(235)            NORMAL     Changing task state from 'Execute' -> 'Retire' (b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2E74    06/04    09:49:22.127    01    TaskExecutor.cs(179)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    RetireWorkplan(job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b)  - Calling derived RetireWorkplan
    0DB4    2E74    06/04    09:49:22.127    27    ProtectionRecoveryManager.cs(764)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    NORMAL    Retiring task for (job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b)
    0DB4    2E74    06/04    09:49:22.127    01    TaskExecutor.cs(225)        B36652BA-33CF-43B1-ABF0-6AECC0834CC0    ACTIVITY    RetireWorkplan(job=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b)  - Retiring task: Completed
    0DB4    2E74    06/04    09:49:22.143    16    ActiveJob.cs(1201)            NORMAL     Processing end of job: ff246cdd-8a23-41d6-aa05-67f9aa5bc44b
    0DB4    2E74    06/04    09:49:22.143    68    ActivityWindow.cs(101)            NORMAL     RemoveWait wait : ff246cdd-8a23-41d6-aa05-67f9aa5bc44b
    0DB4    2E74    06/04    09:49:22.143    16    ActiveJob.cs(1224)            NORMAL     Final task state=Retire(b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2E74    06/04    09:49:22.143    16    Task.cs(235)            NORMAL     Changing task state from 'Retire' -> 'Succeeded' (b36652ba-33cf-43b1-abf0-6aecc0834cc0)
    0DB4    2E74    06/04    09:49:22.143    16    ActiveJob.cs(1081)            NORMAL     Changing state of job: 'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b' from 'Retire' -> 'Succeeded'
    0DB4    2E74    06/04    09:49:22.159    02    EventManager.cs(98)            NORMAL    Publishing event from ActiveJob.cs(1100): JobStateChange, [JobID=ff246cdd-8a23-41d6-aa05-67f9aa5bc44b]
    0DB4    2E74    06/04    09:49:22.159    17    JobTranslator.cs(1229)            NORMAL    TryCancelJobsAndWaitForCancel: Final state of job ff246cdd-8a23-41d6-aa05-67f9aa5bc44b = Succeeded
    0DB4    2E74    06/04    09:49:22.159    16    JobManager.cs(741)            NORMAL     Removed job entry from active list:ff246cdd-8a23-41d6-aa05-67f9aa5bc44b
    0DB4    2E74    06/04    09:49:22.159    16    ActiveJob.cs(1297)            NORMAL    Completed execution of job:'ff246cdd-8a23-41d6-aa05-67f9aa5bc44b', final job state:'Succeeded'
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL    Error in ConfigureProtectedGroup:
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL    Microsoft.Internal.EnterpriseStorage.Dls.Utils.InvalidProtectedGroupSpecException: Invalid Intent Metadata Catalog XML: ProtectedObject 906f2cb7-0751-48a1-90a7-7443da620fd5 has duplicate entries.
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.IntentManager.ValidateProtectedObjectDB(IMCatalogType IMCatalogPersist, IMCatalogDB catalogDB)
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.ProtectedGroupTranslator.PrepareForTranslation()
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.ProtectedGroupTranslator.TranslateGroup(IMCatalogType IMCatalogXmlPassed, String intentCatalogXml)
    0DB4    2EEC    06/04    09:49:29.832    17    Translate.cs(644)            FATAL    *** Mojito error was: InvalidProtectedGroupSpec; 0; None
    0DB4    2EEC    06/04    09:49:29.832    15    SchedulerImpl.cs(640)            NORMAL    Entering ConsistencyCheck
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1020)            WARNING    No retry on exception Le @name ('') spécifié n'existe pas. while executing sp_delete_alert
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1027)            WARNING    SqlException encountered, SqlRetryCommand diag details - SqlCommandText  => Name=msdb.dbo.sp_delete_alert, CommandType=StoredProcedure
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1027)            WARNING    CommandDiagInfo => CanRetry=False, CommandTimeout=3600
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1027)            WARNING    CommandParams   => Count=2, InTx=True
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1027)            WARNING         Param[0]   => ParameterName=@name | Value= | Size=0 | DbType=NVarChar | Direction=Input | IsNullable=False
    0DB4    2EEC    06/04    09:49:29.832    09    Catalog.cs(1027)            WARNING         Param[1]   => ParameterName=@RETURN_VALUE | Value=1 | Size=0 | DbType=Int | Direction=ReturnValue | IsNullable=False
    0DB4    2EEC    06/04    09:49:30.363    17    IntentManager.cs(3531)            NORMAL    Resolving Alert for cloud policy inconsistent after successfull intent restart
    0DB4    2EEC    06/04    09:49:30.363    17    IntentManager.cs(3075)            NORMAL    Resetting PG TranslationStatus
    0DB4    2EEC    06/04    09:49:30.535    17    IntentManager.cs(3081)            NORMAL    Exiting Intent Manager Refresh, translated 0 datasources
    0DB4    2EEC    06/04    09:49:30.535    27    DatasourceGroupInputHelper.cs(129)            NORMAL    DatasourceGroupInputHelper : Reloading datasource groups Xml: C:\Program Files\Microsoft System Center 2012 R2\DPM\DPM\Config\DatasourceGroups.xml
    0DB4    2EEC    06/04    09:49:30.535    02    EventManager.cs(98)            NORMAL    Publishing event from Translate.cs(655): TranslationDone, [ProtectedGroupID=27ee7d0a-32bf-4634-aa46-511689ee703d]
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING    Caught Dls exception: Microsoft.Internal.EnterpriseStorage.Dls.Utils.InvalidProtectedGroupSpecException: Invalid Intent Metadata Catalog XML: ProtectedObject 906f2cb7-0751-48a1-90a7-7443da620fd5 has duplicate entries.
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.IntentManager.ValidateProtectedObjectDB(IMCatalogType IMCatalogPersist, IMCatalogDB catalogDB)
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.ProtectedGroupTranslator.PrepareForTranslation()
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.ProtectedGroupTranslator.TranslateGroup(IMCatalogType IMCatalogXmlPassed, String intentCatalogXml)
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Intent.IntentTranslator.ConfigureProtectedGroup(String intentCatalogXml)
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Engine.CIntentServices.ConfigureProtectedGroup(UInt16* bstrIMCatalogXml)
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING       à Microsoft.Internal.EnterpriseStorage.Dls.Engine.CCoreServices.ConfigureProtectedGroup(CCoreServices* , UInt16* bstrIMCatalogXml, tagSAFEARRAY** exceptionResult)
    0DB4    2EEC    06/04    09:49:30.535    09    serviceutils.cpp(617)            WARNING    *** Mojito error was: InvalidProtectedGroupSpec; 0; None

    Have you an idea ?

    best regards


    Mathias Bouveret

    Monday, June 8, 2015 7:41 AM
  • Hi,

    I sent this issue to a related group. Will update when I got a reply. 

    Please remember to mark the replies as answers if they help and un-mark them if they provide no help. If you have feedback for TechNet Support, contact tnmff@microsoft.com.

    Monday, June 8, 2015 8:47 AM
    Moderator
  • Hi,

    From the reply I got, it could be a DPM database inconsistency issue. It is suggested to open a case with Microsoft online support for further investigating.


    Please remember to mark the replies as answers if they help and un-mark them if they provide no help. If you have feedback for TechNet Support, contact tnmff@microsoft.com.

    Tuesday, June 9, 2015 2:49 AM
    Moderator
  • thank you,
    
    I opened a case with Microsoft Online Support.
    
    I'll add the answer when I have itr from MS.


    Mathias Bouveret

    Tuesday, June 9, 2015 8:40 AM
  • Hi, I had a Microsoft support response . This issue seems to be the same as UR5.

    Microsoft is working on a fix.


    Mathias Bouveret

    Wednesday, June 10, 2015 9:15 AM
  • Hi,

    Has there been a fix for this yet?

    We have UR6 installed and the script does not resolve the issue.

    Wednesday, July 22, 2015 1:15 PM
  • Hi,

    No news from MS support for the moment.


    Mathias Bouveret

    Wednesday, July 22, 2015 2:47 PM
  • Hi,

    I have contacted Microsoft support today and they answer me that SCDPM2012R2UR7 arrive next week (07/28/2015).

    But they can't say if it's will correct the problem.


    Mathias Bouveret

    Friday, July 24, 2015 1:54 PM
  • Hi,

    today i install UR7 but error 197 always occure when trying to modify my PG.


    Mathias Bouveret

    Wednesday, July 29, 2015 7:08 AM
  • Hello all,

    I've got UR8 but one of my PG is giving my the dreaded 197 error.

    However looking in the MSDPMCurr.errlog file it's reporting something about duplicate entries, not sure how that could be.

    Please see below.

    Any advice much appreciated

    Rob

    Error in TranslateGroup:
    0DE0 17D4 11/03 09:53:39.797 17 Translate.cs(559) FATAL Microsoft.Internal.EnterpriseStorage.Dls.Utils.InvalidProtectedGroupSpecException: Invalid Intent Metadata Catalog XML: ProtectedObject 3475ad95-f3d8-4f0e-8df4-b5ba45fac0b0 has duplicate entries.
    0DE0 17D4 11/03 09:53:39.797 17 Translate.cs(559) FATAL   at Microsoft.Internal.EnterpriseStorage.Dls.Intent.IntentManager.ValidateProtectedObjectDB(IMCatalogType IMCatalogPersist, IMCatalogDB catalogDB)
    0DE0 17D4 11/03 09:53:39.797 17 Translate.cs(559) FATAL   at Microsoft.Internal.EnterpriseStorage.Dls.Intent.ProtectedGroupTranslator.PrepareForTranslation()
    0DE0 17D4 11/03 09:53:39.797 17 Translate.cs(559) FATAL   at Microsoft.Internal.EnterpriseStorage.Dls.Intent.ProtectedGroupTranslator.TranslateGroup(IMCatalogType IMCatalogXmlPassed, String intentCatalogXml)
    0DE0 17D4 11/03 09:53:39.797 17 Translate.cs(559) FATAL *** Mojito error was: InvalidProtectedGroupSpec; 0; None
    0DE0 17D4 11/03 09:53:39.797 17 IntentManager.cs(3533) NORMAL Resolving Alert for cloud policy inconsistent after successfull intent restart
    0DE0 17D4 11/03 09:53:39.797 17 IntentManager.cs(3077) NORMAL Resetting PG TranslationStatus
    0DE0 17D4 11/03 09:53:39.859 17 IntentManager.cs(3083) NORMAL Exiting Intent Manager Refresh, translated 0 datasources
    0DE0 17D4 11/03 09:53:39.859 27 DatasourceGroupInputHelper.cs(129) NORMAL DatasourceGroupInputHelper : Reloading datasource groups Xml: c:\Program Files\Microsoft DPM\DPM\Config\DatasourceGroups.xml
    0DE0 17D4 11/03 09:53:39.859 02 EventManager.cs(98) NORMAL Publishing event from Translate.cs(569): TranslationDone, [ProtectedGroupID=b252d492-1d75-4c17-88fe-0ad517034782]
    0DE0 17D4 11/03 09:53:39.859 09 serviceutils.cpp(617) WARNING Caught Dls exception: Microsoft.Internal.EnterpriseStorage.Dls.Utils.InvalidProtectedGroupSpecException: Invalid Intent Metadata Catalog XML: ProtectedObject 3475ad95-f3d8-4f0e-8df4-b5ba45fac0b0 has duplicate entries.
    0DE0 17D4 11/03 09:53:39.859 09 serviceutils.cpp(617) WARNING   at Microsoft.

    Tuesday, November 3, 2015 10:28 AM
  • HI Rob,

    you can try this:

    Here’s the clean-up fix (Follow the steps in the exact order):

    1. Close the UI and stop all DPM services
    2. Important:      Take a full database backup of the DPMDB to a safe location
    3. Run the SQL script on the DPM DB that is posted      below
    4. Start all DPM services, and open the UI
    5. Now try the Modify/Delete protection group that      failed before. It should succeed.
    6. Since this script cleans up any unprotected file      shares, you may need to do a “Refresh” on those servers to see them again.

    DELETE FROM dbo.tbl_IM_ProtectedObject

    WHERE ProtectedObjectId IN

    (

           SELECT PO.ProtectedObjectId

           FROM dbo.tbl_IM_ProtectedObject AS PO

           JOIN dbo.tbl_IM_DataSource AS DS

           ON PO.DataSourceId = DS.DataSourceId

           WHERE DS.AppId = '00000000-0000-0000-0000-000000000000'

           AND PO.ProtectedInPlan = 0

           AND PO.ProtectedObjectId NOT IN (SELECT ProtectedObjectId FROM dbo.tbl_IM_ProtectedObjectAlerts)

           AND CONVERT(XML,PO.LogicalPath).exist(

                  '/child::ArrayOfInquiryPathEntryType/child::InquiryPathEntryType[attribute::Type="NonRootTargetShare"]'

           ) = 1

        AND PO.ProtectedObjectId != PO.DataSourceId

    )

    If it don't work,You need to open a case to Microsoft support and give us a dump of your database

    Best regard

    Tuesday, November 3, 2015 11:48 AM
  • That worked a treat thank you
    Tuesday, November 3, 2015 2:52 PM