none
не согласуется реплика DPM2010 bare metal/system state RRS feed

  • Общие обсуждения

  • Добрый день, уважаемые Коллеги.

     

    У нас возникла проблема с DPM 2010.

    Исходные данные:

    Кластер состоящий из 2-х нод и СХД. Операционная система на нодах - Windows Server 2008 R2 SP1

    4 сетевых адаптера. один идет во внешнюю сеть, 2 между собой и один в подсеть с СХД.

    Виртуальные машины крутящиеся на этом кластере.

    На ноды установлен агент DPM 2010

    Все прекрасно реплицируется, кроме bare metal/system state. На нодах так же установлен компонент "возможность архивации Windows Server".

    Антивирусного программного обеспечения на нодах не установлено. Брэндмауэр выключен.

    При попытке репликации - получаю:

    DPM не может создать резервную копию, так как служба windows server backups (WSB) на защищаемом компьютере обнаружила ошибку (идентификатор события WSB 517 код ошибки WSB 0x8078002A) Идентификатор 30229 Сведения: Код внутренней ошибки: 0x80990FB)

    После через командную строку c помощью wbadmin проверил на защищаемом сервере, может ли он бэкапировать system state. Все отработало на ура.

    Так же проверил на клиентах XML файл

    C:\Program Files\Microsoft Data Protection Manager\DPM\Datasources\PSDataSourceConfig.xml"

    На клиентах указан путь C:\WindowsImageBackup но такой папки не существовало. Создал.

    не помогло.

    На сервере DPM провел аналогичную операцию - только создал такую папку на диске E, как было указано в xml файле.

    На сервере DPM в логах обнаруживается ошибка 10009 - не удалось установить связь с компьютером <ComputerName> через один из настроенных протоколов.Через свойства компонентов посмотрел, что собственно там один протокол tcp/ip.

    dcdiag - ошибок не выявил

    Теперь куда копать? Необходимые логи могу выложить незамедлительно.

     

     

     

     

     


     


     

     

     

    20 апреля 2011 г. 9:16

Все ответы

  • Добрый день, Сергей. Копните в сторону событий в Журналы приложений и служб/Microsoft/Windows/Backup на защищаемом сервере. И там же загляните в логи DPM - C:\Program Files\Microsoft Data Protection Manager\DPM\Temp

    Результаты сообщите.

     


    My DPM blog ystartsev.wordpress.com
    21 апреля 2011 г. 11:06
    Модератор
  • Сергей, ваша проблема сохраняется?
    My DPM blog ystartsev.wordpress.com
    27 апреля 2011 г. 7:47
    Модератор
  • Да. Добрый день. Проблема сохраняется.

    ошибка в журнале архивации: код ошибки 2155348010

     

    - System

      - Provider

       [ Name]  Microsoft-Windows-Backup
       [ Guid]  {1DB28F2E-8F80-4027-8C5A-A11F7F10F62D}
     
       EventID 5
     
       Version 2
     
       Level 2
     
       Task 0
     
       Opcode 0
     
       Keywords 0x4000000000000000
     
      - TimeCreated

       [ SystemTime]  2011-05-12T12:09:24.564842400Z
     
       EventRecordID 74
     
       Correlation
     
      - Execution

       [ ProcessID]  1684
       [ ThreadID]  140
     
       Channel Microsoft-Windows-Backup
     
       Computer HyperV-1.domain.local
     
      - Security

       [ UserID]  S-1-5-18
     

    - EventData

      BackupTemplateID {2FC8DD86-5190-46E0-A134-B51D9B8211F6}
      HRESULT 2155348010
      BackupState 12
      BackupTarget \\WIN2008DPMS.domain.local\e783d9c5f28a455e8775f6f713750ff9
      NumOfVolumes 2
      BackupTime 2011-05-12T12:09:12.459164800Z
      HRESULT2 2155348010
      VolumesInfo <VolumeInfo><VolumeInfoItem Name="\\?\Volume{e4e1c56c-1e4c-11e0-9612-806e6f6e6963}\" OriginalAccessPath="" State="12" HResult="-2139619286" DetailedHResult="-2147024809" PreviousState="9" IsCritical="1" IsIncremental="0" BlockLevel="1" HasFiles="0" HasSystemState="1" IsCompacted="0" IsPruned="0" IsRecreateVhd="0" FullBackupReason="2" DataTransferred="0" NumUnreadableBytes="0" TotalSize="0" TotalNoOfFiles="0" Flags="554" BackupTypeDetermined="1" SSBTotalNoOfFiles="0" SSBTotalSizeOnDisk="0" /><VolumeInfoItem Name="C:" OriginalAccessPath="C:" State="15" HResult="-2139619228" DetailedHResult="0" PreviousState="0" IsCritical="1" IsIncremental="0" BlockLevel="1" HasFiles="0" HasSystemState="1" IsCompacted="0" IsPruned="0" IsRecreateVhd="0" FullBackupReason="2" DataTransferred="0" NumUnreadableBytes="0" TotalSize="0" TotalNoOfFiles="0" Flags="1576" BackupTypeDetermined="1" SSBTotalNoOfFiles="0" SSBTotalSizeOnDisk="0" /></VolumeInfo>
      DetailedHRESULT 2147942487
      SourceSnapStartTime 2011-05-12T12:09:12.443564700Z
      SourceSnapEndTime 2011-05-12T12:09:22.302827900Z
      PrepareBackupStartTime <TimesList><Time Time="2011-05-12T12:09:23.519Z" /><Time Time="1601-01-01T00:00:00.000Z" /></TimesList>
      PrepareBackupEndTime <TimesList><Time Time="2011-05-12T12:09:23.550Z" /><Time Time="1601-01-01T00:00:00.000Z" /></TimesList>
      BackupWriteStartTime <TimesList><Time Time="2011-05-12T12:09:23.566Z" /><Time Time="1601-01-01T00:00:00.000Z" /></TimesList>
      BackupWriteEndTime <TimesList><Time Time="2011-05-12T12:09:23.566Z" /><Time Time="1601-01-01T00:00:00.000Z" /></TimesList>
      TargetSnapStartTime 1601-01-01T00:00:00.000000000Z
      TargetSnapEndTime 1601-01-01T00:00:00.000000000Z
      DVDFormatStartTime <TimesList></TimesList>
      DVDFormatEndTime <TimesList></TimesList>
      MediaVerifyStartTime <TimesList></TimesList>
      MediaVerifyEndTime <TimesList></TimesList>
      BackupPreviousState 9
      ComponentStatus <ComponentStatus></ComponentStatus>
      SSBEnumerateStartTime 1601-01-01T00:00:00.000000000Z
      SSBEnumerateEndTime 1601-01-01T00:00:00.000000000Z
      SSBVhdCreationStartTime 1601-01-01T00:00:00.000000000Z
      SSBVhdCreationEndTime 1601-01-01T00:00:00.000000000Z
      SSBBackupStartTime 1601-01-01T00:00:00.000000000Z
      SSBBackupEndTime 1601-01-01T00:00:00.000000000Z
      SystemStateBackup <SystemState IsPresent="0" HResult="0" DetailedHResult="0" />
      BMR true
      VssFullBackup false
      UserInputBMR true
      UserInputSSB false
      BackupSuccessLogPath 
      BackupFailureLogPath 
      EnumerateBackupStartTime <TimesList><Time Time="1601-01-01T00:00:00.000Z" /><Time Time="1601-01-01T00:00:00.000Z" /></TimesList>
      EnumerateBackupEndTime <TimesList><Time Time="1601-01-01T00:00:00.000Z" /><Time Time="1601-01-01T00:00:00.000Z" /></TimesList>
      PruneBackupStartTime <TimesList><Time Time="1601-01-01T00:00:00.000Z" /><Time Time="1601-01-01T00:00:00.000Z" /></TimesList>
      PruneBackupEndTime <TimesList><Time Time="1601-01-01T00:00:00.000Z" /><Time Time="1601-01-01T00:00:00.000Z" /></TimesList>

     

     

    лог файлов DPM. Странно, что время в логе заканчивается на 12.41, хотя время сервера и время изменения файла на 4 часа больше. т.е. 16.41

    Однако проходит репликация всего кроме system state


    0D80    0EC4    05/12    12:30:07.799    18    fsutils.cpp(2944)        05E10580-52ED-45FF-83B8-871C037D3A4E    WARNING    Failed: Hr: = [0x80070057] : GetFileHandleById failed to open file, frn:0x0893000000005CDF
    0D80    0AA0    05/12    12:30:07.814    18    fsutils.cpp(2944)        05E10580-52ED-45FF-83B8-871C037D3A4E    WARNING    Failed: Hr: = [0x80070057] : GetFileHandleById failed to open file, frn:0x0895000000005CDF
    0D80    0AA0    05/12    12:30:07.814    18    fsutils.cpp(2944)        05E10580-52ED-45FF-83B8-871C037D3A4E    WARNING    Failed: Hr: = [0x80070057] : GetFileHandleById failed to open file, frn:0x0895000000005CDF
    0D80    0AA0    05/12    12:30:07.846    18    fsutils.cpp(2944)        05E10580-52ED-45FF-83B8-871C037D3A4E    WARNING    Failed: Hr: = [0x80070057] : GetFileHandleById failed to open file, frn:0x0897000000005CDF
    0D80    0AA0    05/12    12:30:07.846    18    fsutils.cpp(2944)        05E10580-52ED-45FF-83B8-871C037D3A4E    WARNING    Failed: Hr: = [0x80070057] : GetFileHandleById failed to open file, frn:0x0897000000005CDF
    0D80    047C    05/12    12:30:07.924    20    session.cpp(1693)    [0000000003D4D2F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    Hr: = [0x00000000] DM: Will attempt to post DM_SESSION_CLOSED, pSes=0000000003D4D2F0 rcv=0000000000000000 snd=0000000000000000 sesop=0000000003D4CB70 seserr=0000000000000000
    0D80    0EC4    05/12    12:30:07.924    18    dsmsendersubtaskbase.cpp(325)    [0000000003D4C9C0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDsmSubTaskBase received session closed completion in CLOSE state [0000000003D4C9C0]
    0D80    0EC4    05/12    12:30:07.924    31    readdatasetdeltasubtask.cpp(498)    [0000000003D4C4F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CReadDatasetDeltaSubTask::SubTaskDone [0000000003D4C4F0]
    0D80    0EC4    05/12    12:30:07.924    31    datasetfixupsubtaskbase.cpp(383)    [0000000003D4C4F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDatasetFixupSubTaskBase::SubTaskDone [0000000003D4C4F0] [0000000003D4C9C0]
    0D80    0EC4    05/12    12:30:07.924    31    datasetfixupsubtaskbase.cpp(393)    [0000000003D4C4F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDatasetFixupSubTaskBase::SubTaskDone - LWV
    0D80    0F5C    05/12    12:30:14.663    20    session.cpp(1693)    [0000000003D4CFE0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    Hr: = [0x00000000] DM: Will attempt to post DM_SESSION_CLOSED_BY_REMOTE, pSes=0000000003D4CFE0 rcv=0000000000000000 snd=0000000000000000 sesop=0000000000000000 seserr=0000000000000000
    0D80    0F5C    05/12    12:30:14.663    20    session.cpp(1693)    [0000000003D4CFE0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    Hr: = [0x00000000] DM: Will attempt to post DM_SESSION_CLOSED_BY_REMOTE, pSes=0000000003D4CFE0 rcv=0000000000000000 snd=0000000000000000 sesop=0000000000000000 seserr=0000000000000000
    0D80    0AA0    05/12    12:30:14.663    31    readdatasetdeltasubtask.cpp(498)    [0000000003D4C4F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CReadDatasetDeltaSubTask::SubTaskDone [0000000003D4C4F0]
    0D80    0AA0    05/12    12:30:14.663    31    datasetfixupsubtaskbase.cpp(383)    [0000000003D4C4F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDatasetFixupSubTaskBase::SubTaskDone [0000000003D4C4F0] [0000000003E21C80]
    0D80    0AA0    05/12    12:30:14.663    31    datasetfixupsubtaskbase.cpp(401)    [0000000003D4C4F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDatasetFixupSubTaskBase::SubTaskDone - HWV
    0D80    0E5C    05/12    12:30:14.663    18    dsmreceiversubtaskbase.cpp(324)    [0000000003E21C80]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDsmReceiverSubTaskBase received completion for session closed in CLEANUP state
    0D80    0998    05/12    12:30:22.915    18    fixupsendersubtask.cpp(347)    [0000000003D417A0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CFixupSenderSubTask::GetBuffer End of data
    0D80    0AB4    05/12    12:30:22.915    20    session.cpp(1693)    [0000000003D4CCD0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    Hr: = [0x00000000] DM: Will attempt to post DM_SESSION_CLOSED, pSes=0000000003D4CCD0 rcv=0000000000000000 snd=0000000000000000 sesop=0000000003D41950 seserr=0000000000000000
    0D80    0A04    05/12    12:30:22.915    18    dsmsendersubtaskbase.cpp(325)    [0000000003D417A0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDsmSubTaskBase received session closed completion in CLOSE state [0000000003D417A0]
    0D80    0A04    05/12    12:30:22.915    31    readdatasetdeltasubtask.cpp(498)    [0000000003D4C4F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CReadDatasetDeltaSubTask::SubTaskDone [0000000003D4C4F0]
    0D80    0A04    05/12    12:30:22.915    31    datasetfixupsubtaskbase.cpp(383)    [0000000003D4C4F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDatasetFixupSubTaskBase::SubTaskDone [0000000003D4C4F0] [0000000003D417A0]
    0D80    0A04    05/12    12:30:22.915    31    datasetfixupsubtaskbase.cpp(409)    [0000000003D4C4F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDatasetFixupSubTaskBase::SubTaskDone - Fixup
    0D80    0A04    05/12    12:30:22.915    31    readdatasetdeltasubtask.cpp(541)    [0000000003D4C4F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CReadDatasetDeltaSubTask::CleanUp [0000000003D4C4F0]
    0D80    0A04    05/12    12:30:22.915    31    datasetfixupsubtaskbase.cpp(361)    [0000000003D4C4F0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDatasetFixupSubTaskBase::CleanUp [0000000003D4C4F0]
    0D80    0A04    05/12    12:30:22.915    18    dsmsubtaskbase.cpp(88)    [0000000003D4C9C0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDsmSubTaskBase: destructor [0000000003D4C9C0]
    0D80    0A04    05/12    12:30:22.915    18    dsmsubtaskbase.cpp(88)    [0000000003E21C80]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDsmSubTaskBase: destructor [0000000003E21C80]
    0D80    0A04    05/12    12:30:22.915    18    dsmsubtaskbase.cpp(88)    [0000000003D417A0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CDsmSubTaskBase: destructor [0000000003D417A0]
    0D80    0A04    05/12    12:30:28.079    31    freesnapshotsubtask.cpp(46)    [0000000003E21C80]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CFreeSnapshotSubTask: constructor [0000000003E21C80]
    0D80    0A04    05/12    12:30:28.079    31    freesnapshotsubtask.cpp(123)    [0000000003E21C80]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CFreeSnapshotSubTask::TriggerDone [0000000003E21C80]
    0D80    0A04    05/12    12:30:28.266    31    freesnapshotsubtask.cpp(559)    [0000000003E21C80]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CFreeSnapshotSubTask: StartBackupComplete [0000000003E21C80]
    0D80    0A04    05/12    12:30:28.266    31    vssbaserequestor.cpp(1070)    [0000000003E7C780]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CVssBaseRequestor::StartGatherWriterStatus [0000000003E7C780]
    0D80    0A04    05/12    12:30:29.280    31    vssbaserequestor.cpp(946)    [0000000003E7C780]        NORMAL    QueryStatus returned 0x4230a, Releasing VssAsync [000000000061BDA0]
    0D80    0A04    05/12    12:30:29.280    31    freesnapshotsubtask.cpp(671)    [0000000003E21C80]        NORMAL    CFreeSnapshotSubTask: ReleaseSnapshot [0000000003E21C80]
    0D80    0A04    05/12    12:30:29.280    31    vssbaserequestor.cpp(1103)    [0000000003E7C780]        NORMAL    CVssBaseRequestor::CheckWriterStatus [0000000003E7C780]
    0D80    0A04    05/12    12:30:29.280    31    vsssnapshotrequestor.cpp(1141)    [0000000003E7C780]        NORMAL    CVssSnapshotRequestor::ReleaseSnaphot [0000000003E7C780]
    0D80    0A04    05/12    12:30:29.280    31    vsssnapshotrequestor.cpp(1763)    [0000000003E7C780]        NORMAL    CVssSnapshotRequestor::CleanUp [0000000003E7C780]
    0D80    0998    05/12    12:30:29.327    31    freesnapshotsubtask.cpp(181)    [0000000003E21C80]        NORMAL    CFreeSnapshotSubTask::GetFinalStatus [0000000003E21C80]
    0D80    0998    05/12    12:30:29.483    31    freesnapshotsubtask.cpp(63)    [0000000003E21C80]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CFreeSnapshotSubTask: destructor [0000000003E21C80]
    0D80    0998    05/12    12:30:29.998    31    vsssnapshotrequestor.cpp(94)    [0000000003E7C780]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CVssSnapshotRequestor::~CVssSnapshotRequestor [0000000003E7C780]
    0D80    0998    05/12    12:30:29.998    31    vsssnapshotrequestor.cpp(1763)    [0000000003E7C780]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CVssSnapshotRequestor::CleanUp [0000000003E7C780]
    0D80    0998    05/12    12:30:29.998    31    vssbaserequestor.cpp(80)    [0000000003E7C780]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    CVssBaseRequestor: destructor [0000000003E7C780]
    0D80    0998    05/12    12:30:29.998    03    workitem.cpp(272)    [0000000003D7D320]    05E10580-52ED-45FF-83B8-871C037D3A4E    ACTIVITY    WorkItem stopping
    0D80    0F14    05/12    12:31:37.577    03    service.cpp(147)    [000000000020F7F0]        ACTIVITY    CService::ServiceHandlerExInternal
    0D80    0F14    05/12    12:31:37.718    03    service.cpp(147)    [000000000020F7F0]        ACTIVITY    CService::ServiceHandlerExInternal
    0D80    0F14    05/12    12:31:37.858    03    service.cpp(147)    [000000000020F7F0]        ACTIVITY    CService::ServiceHandlerExInternal
    0D80    0F14    05/12    12:31:38.014    03    service.cpp(147)    [000000000020F7F0]        ACTIVITY    CService::ServiceHandlerExInternal
    0D80    0AB4    05/12    12:35:08.381    20    cc_extcalls.cpp(517)    [0000000003CDAFA0]    05E10580-52ED-45FF-83B8-871C037D3A4E    WARNING    DM: TempErr: err=0x40 read=1 write=0
    0D80    0AB4    05/12    12:35:08.381    20    cc_base.cpp(1226)    [0000000003CDAFA0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    DM: Aborting  http state machine for reason 0
    0D80    0AB4    05/12    12:35:15.386    20    cc_extcalls.cpp(517)    [0000000003CDD3B0]    05E10580-52ED-45FF-83B8-871C037D3A4E    WARNING    DM: TempErr: err=0x40 read=1 write=0
    0D80    0AB4    05/12    12:35:15.386    20    cc_base.cpp(1226)    [0000000003CDD3B0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    DM: Aborting  http state machine for reason 3473464
    0D80    0E38    05/12    12:35:27.944    20    destination.cpp(1283)    [0000000003E22550]        NORMAL    DM: Doing Connection TimeOut for  Destination 0000000003E22550: connection 0000000003CDF7C0 m_dwLastCompletionTime: 1837799, m_bUseLongDMConnectionTimeOut: 0, Diff: 305
    0D80    0E38    05/12    12:35:27.944    20    cc_base.cpp(388)    [0000000003CDF7C0]        NORMAL    DM: Going to timeout connection
    0D80    0AB4    05/12    12:35:27.944    20    cc_extcalls.cpp(517)    [0000000003CDF7C0]    05E10580-52ED-45FF-83B8-871C037D3A4E    WARNING    DM: TempErr: err=0x4d4 read=1 write=0
    0D80    0AB4    05/12    12:35:27.944    20    cc_base.cpp(1226)    [0000000003CDF7C0]    05E10580-52ED-45FF-83B8-871C037D3A4E    NORMAL    DM: Aborting  http state machine for reason 7471203
    0D80    03F8    05/12    12:35:30.003    03    runtime.cpp(1652)    [000000000043CFA0]        NORMAL    CDLSRuntime::ProcessIdleTimeout
    0D80    03F8    05/12    12:35:30.003    03    runtime.cpp(646)    [000000000043CFA0]        NORMAL    CDLSRuntime::Uninitialize, bForce: 0
    0D80    03F8    05/12    12:35:30.003    05    genericagent.cpp(264)    [0000000000439C50]        NORMAL    Agent Can Shutdown if there is only default wokitem active[21]
    0D80    06B0    05/12    12:39:17.546    22    genericthreadpool.cpp(804)    [000000000043CFA0]        NORMAL    Hr: = [0x80070002] CGenericThreadPool::m_dwMaximumNumberOfThreads[20]
    0D80    0F10    05/12    12:39:18.498    22    genericthreadpool.cpp(804)    [00000000004EDE60]        NORMAL    Hr: = [0x80070002] CGenericThreadPool::m_dwMaximumNumberOfThreads[256]
    0D80    03F8    05/12    12:40:30.009    03    runtime.cpp(1652)    [000000000043CFA0]        NORMAL    CDLSRuntime::ProcessIdleTimeout
    0D80    03F8    05/12    12:40:30.009    03    runtime.cpp(646)    [000000000043CFA0]        NORMAL    CDLSRuntime::Uninitialize, bForce: 0
    0D80    03F8    05/12    12:40:30.009    05    genericagent.cpp(264)    [0000000000439C50]        NORMAL    Agent Can Shutdown if there is only default wokitem active[21]
    0D80    0998    05/12    12:40:46.997    29    radefaultsubtask.cpp(337)    [00000000004F0B70]    ED364471-68C2-45BD-8EB7-099C663FD545    ACTIVITY    CRADefaultSubTask::CommandReceivedSpecific => Command SetActiveOwner Received
    0D80    0998    05/12    12:40:47.387    03    workitem.cpp(86)    [0000000003D7D390]    ED364471-68C2-45BD-8EB7-099C663FD545    ACTIVITY    Idle Timer created with timeout = 390000
    0D80    0998    05/12    12:40:47.793    31    vssbaserequestor.cpp(62)    [0000000003C94000]    ED364471-68C2-45BD-8EB7-099C663FD545    NORMAL    CVssBaseRequestor: constructor [0000000003C94000]
    0D80    0998    05/12    12:40:47.793    31    vsssnapshotrequestor.cpp(76)    [0000000003C94000]    ED364471-68C2-45BD-8EB7-099C663FD545    NORMAL    CVssSnapshotRequestor::CVssSnapshotRequestor [0000000003C94000]
    0D80    0998    05/12    12:40:47.793    31    vssbaserequestor.cpp(99)    [0000000003C94000]    ED364471-68C2-45BD-8EB7-099C663FD545    NORMAL    CVssBaseRequestor::Initialize [0000000003C94000]
    0D80    0998    05/12    12:40:47.793    31    vsssnapshotrequestor.cpp(170)    [0000000003C94000]    ED364471-68C2-45BD-8EB7-099C663FD545    NORMAL    CVssSnapshotRequestor::InitializeSnapshotCreation [0000000003C94000]
    0D80    0998    05/12    12:40:47.840    22    agentutils.cpp(2373)        ED364471-68C2-45BD-8EB7-099C663FD545    WARNING    Failed: Hr: = [0x80070002] Could not open HKEY_LOCAL_MACHINE\Software\Microsoft\Microsoft Data Protection Manager\Agent\2.0
    0D80    0998    05/12    12:40:48.074    31    vsssnapshotrequestor.cpp(94)    [0000000003C94000]    ED364471-68C2-45BD-8EB7-099C663FD545    NORMAL    CVssSnapshotRequestor::~CVssSnapshotRequestor [0000000003C94000]
    0D80    0998    05/12    12:40:48.074    31    vsssnapshotrequestor.cpp(1763)    [0000000003C94000]    ED364471-68C2-45BD-8EB7-099C663FD545    NORMAL    CVssSnapshotRequestor::CleanUp [0000000003C94000]
    0D80    0998    05/12    12:40:48.074    31    vssbaserequestor.cpp(80)    [0000000003C94000]    ED364471-68C2-45BD-8EB7-099C663FD545    NORMAL    CVssBaseRequestor: destructor [0000000003C94000]
    0D80    0998    05/12    12:40:48.074    03    workitem.cpp(272)    [0000000003D7D320]    ED364471-68C2-45BD-8EB7-099C663FD545    ACTIVITY    WorkItem stopping
    0D80    0998    05/12    12:40:48.495    29    radefaultsubtask.cpp(337)    [00000000004F0B70]    84CD494A-DAA3-4358-8916-8D9EC4297AD4    ACTIVITY    CRADefaultSubTask::CommandReceivedSpecific => Command SetActiveOwner Received
    0D80    0998    05/12    12:40:48.713    03    workitem.cpp(86)    [0000000003D7D390]    84CD494A-DAA3-4358-8916-8D9EC4297AD4    ACTIVITY    Idle Timer created with timeout = 390000
    0D80    0998    05/12    12:40:49.088    31    aaxmlprocessingutils.cpp(49)        84CD494A-DAA3-4358-8916-8D9EC4297AD4    WARNING    Failed: Hr: = [0x80070057] : F: lVal : E_INVALIDARG
    0D80    0998    05/12    12:40:49.275    31    basewriterhelperplugin.cpp(155)    [0000000008093B90]    84CD494A-DAA3-4358-8916-8D9EC4297AD4    NORMAL    SetOperationType(1)
    0D80    0998    05/12    12:40:49.275    31    createsnapshotsubtask.cpp(977)    [000000000809A970]    84CD494A-DAA3-4358-8916-8D9EC4297AD4    NORMAL    Executing PreSnap step ""C:\Program Files\Microsoft Data Protection Manager\DPM\bin\BMRBackup.cmd"  \\WIN2008DPMS.domain.local\e783d9c5f28a455e8775f6f713750ff9"
    0D80    0998    05/12    12:41:19.321    31    vastatemachineutils.cpp(843)    [0000000003D15940]        WARNING    Failed: Hr: = [0x809909fb] : F: lVal : m_pScriptExecutor->GetFailureHR()
    0D80    0998    05/12    12:41:19.321    05    fsmstate.cpp(167)    [0000000003C94C60]        WARNING    Failed: Hr: = [0x809909fb] : F: lVal : pTransition->Execute(pEvent)
    0D80    0998    05/12    12:41:19.321    05    genericfsm.cpp(225)    [0000000003E1B200]        WARNING    Failed: Hr: = [0x809909fb] : F: lVal : m_pCurrentState->SendEvent(pEvent, pNextState)
    0D80    0998    05/12    12:41:19.321    05    fsmtransition.cpp(111)    [0000000003D91EC0]        WARNING    Failed: Hr: = [0x809909fb] HasEventErrorCode: completion: 0x27, signature: 0xaabbcc00
    0D80    0998    05/12    12:41:19.336    22    eventsutil.cpp(88)    [000000000420F230]        WARNING    Failed: Hr: = [0x80070103] : F: lVal : m_pfnEvtNext(m_hQueryResult, 1, &m_hEvent, -1, 0, &dwEventsReturned)
    0D80    0998    05/12    12:41:19.336    31    aasubtask.cpp(1373)    [000000000809A970]        WARNING    Updating task status with hr: 809909fb8x, dls error code:30072
    0D80    0998    05/12    12:41:19.336    31    aasubtask.cpp(913)    [000000000809A970]        WARNING    <?xml version="1.0"?>
    0D80    0998    05/12    12:41:19.336    31    aasubtask.cpp(913)    [000000000809A970]        WARNING    <Status xmlns="http://schemas.microsoft.com/2003/dls/StatusMessages.xsd" StatusCode="-2137454085" Reason="Error" CommandID="RAPreBackup" CommandInstanceID="8ee17552-55db-486a-bee6-0d730c77287c" GuidWorkItem="d7e59daa-9bba-48c5-bdef-b202841d24cf" TETaskInstanceID="84cd494a-daa3-4358-8916-8d9ec4297ad4"><ErrorInfo xmlns="http://schemas.microsoft.com/2003/dls/GenericAgentStatus.xsd" ErrorCode="998" DetailedCode="-2137454085" DetailedSource="2"/><RAStatus><RAPreBackup xmlns="http://schemas.microsoft.com/2003/dls/ArchiveAgent/StatusMessages.xsd"><BackupTime>129496776793360000</BackupTime><DSStatus><ComponentName>System Protection</ComponentName><LogicalPath>Computer</LogicalPath><BackupStamp></BackupStamp><Metadata></Metadata></DSStatus></RAPreBackup></RAStatus></Status>
    0D80    0998    05/12    12:41:19.336    03    runtime.cpp(1599)    [000000000043CFA0]        FATAL    Subtask failure, sending status response XML=[<?xml version="1.0"?>
    0D80    0998    05/12    12:41:19.336    03    runtime.cpp(1599)    [000000000043CFA0]        FATAL    <Status xmlns="http://schemas.microsoft.com/2003/dls/StatusMessages.xsd" StatusCode="-2137454085" Reason="Error" CommandID="RAPreBackup" CommandInstanceID="8ee17552-55db-486a-bee6-0d730c77287c" GuidWorkItem="d7e59daa-9bba-48c5-bdef-b202841d24cf" TETaskInstanceID="84cd494a-daa3-4358-8916-8d9ec4297ad4"><ErrorInfo xmlns="http://schemas.microsoft.com/2003/dls/GenericAgentStatus.xsd" ErrorCode="30072" DetailedCode="-2137454085" DetailedSource="2"><Parameter Name="WindowsBackupErrorCode" Value="0x8078002A"/><Parameter Name="WindowsBackupEventID" Value="517"/><Parameter Name="AgentTargetServer" Value="HyperV-1.domain.local"/></ErrorInfo><RAStatus><RAPreBackup xmlns="http://schemas.microsoft.com/2003/dls/ArchiveAgent/StatusMessages.xsd"><BackupTime>129496776793360000</BackupTime><DSStatus><ComponentName>System Protection</ComponentName><LogicalPath>Computer</LogicalPath><BackupStamp></BackupStamp><Metadata></Metadata></DSStatus></RAPreBackup></RAStatus></Status>
    0D80    0998    05/12    12:41:19.336    03    runtime.cpp(1599)    [000000000043CFA0]        FATAL    ]
    0D80    0A04    05/12    12:41:20.085    31    freesnapshotsubtask.cpp(46)    [0000000003E200B0]    84CD494A-DAA3-4358-8916-8D9EC4297AD4    NORMAL    CFreeSnapshotSubTask: constructor [0000000003E200B0]
    0D80    0A04    05/12    12:41:20.085    31    freesnapshotsubtask.cpp(123)    [0000000003E200B0]    84CD494A-DAA3-4358-8916-8D9EC4297AD4    NORMAL    CFreeSnapshotSubTask::TriggerDone [0000000003E200B0]
    0D80    0A04    05/12    12:41:20.303    31    freesnapshotsubtask.cpp(559)    [0000000003E200B0]    84CD494A-DAA3-4358-8916-8D9EC4297AD4    NORMAL    CFreeSnapshotSubTask: StartBackupComplete [0000000003E200B0]
    0D80    0A04    05/12    12:41:20.303    31    freesnapshotsubtask.cpp(671)    [0000000003E200B0]    84CD494A-DAA3-4358-8916-8D9EC4297AD4    NORMAL    CFreeSnapshotSubTask: ReleaseSnapshot [0000000003E200B0]
    0D80    0998    05/12    12:41:20.303    31    freesnapshotsubtask.cpp(181)    [0000000003E200B0]    84CD494A-DAA3-4358-8916-8D9EC4297AD4    NORMAL    CFreeSnapshotSubTask::GetFinalStatus [0000000003E200B0]
    0D80    0998    05/12    12:41:20.491    31    freesnapshotsubtask.cpp(63)    [0000000003E200B0]    84CD494A-DAA3-4358-8916-8D9EC4297AD4    NORMAL    CFreeSnapshotSubTask: destructor [0000000

     


    <br/>
    

  • Проблема сохраняется. В данный момент события в логе, часть из которого Вы видите внизу находящийся в C:\Program Files\Microsoft Data Protection Manager\DPM\Temp пишутся с неправильным временем. т.е. с разницей в 4 часа. На нодах кластера и на сервере дпм время синхронизировано командой net time /set

    Есть ли какие-нибудь идеи. http://social.technet.microsoft.com/Forums/en-US/dataprotectionmanager/thread/6ce64707-e1d6-47d0-bd67-68b52f9a04f7 тоже не помогло.


    • Изменено musubius 26 мая 2011 г. 13:18
  • Время в служебных журналах приложений Microsoft пишется по отметкам UTC с разницей от МСК в 4 часа. Так что это нормально.

    Если я верно понял, системе, помимо прочего, не понравился запуск C:\Program Files\Microsoft Data Protection Manager\DPM\bin\BMRBackup.cmd - а что там в нем у вас? Не меняли содержимое?


    My DPM blog ystartsev.wordpress.com
    Модератор
  • Содержимое файла не менялось.

    @echo off

    if not exist %SystemRoot%\system32\wbadmin.exe goto returnError

    rem Start a wbadmin.exe to create a BMR backup
    start  /WAIT %SystemRoot%\system32\wbadmin.exe start backup -allcritical -quiet -backuptarget:%1

    rem If return error code, Pass this error code to caller else check whether it actually succeeded or not
    if %ERRORLEVEL% == 0 goto returnSuccess
    exit /B %ERRORLEVEL%

    :returnSuccess
    exit /B 0

    :returnError
    exit /B 1

  • Спасибо. Но к сожалению не помогло.
     Все осталось без изменений. Включая код ошибки.
    15 июня 2011 г. 8:08
  • http://support.microsoft.com/KB/2521348

    http://support.microsoft.com/kb/2494162

     

    Установите данные хотфиксы.

    23 июня 2011 г. 13:50
    Модератор
  • Сергей, данные исправления помогли?
    My DPM blog ystartsev.wordpress.com
    28 июня 2011 г. 11:36
    Модератор
  • Спасибо, Но это  не помогло. Но проблема уже потеряла свою актуальность. Спасибо за помощь.