none
Workstation protection doesnt work after upgrade to DPM 2012R2 RRS feed

  • Question

  • Hi, I have big problem with DPM 2012R2 on Windows Server 2012. After upgrade to R2 I found out that all my protection groups of typical workstations (Windows 7) contained old data. In DPM Console at Restore section I can see my protection groups, but I cant see and restore new files, that were created on users PC after DPM server and DPM agents upgrade. Yesterday I created virtual machine with DPM agent and now I can see the same problem. After server create restore checkpoint, at restore section in console there is no any new folders and files. If I do manual checkpoint creation I have the same problem. All synchronization processes on client work good, DPM server says OK on protection group and on client management. But new files don't add to server.

    Please help!

    P.S. Sorry for my English!

    Thursday, December 5, 2013 11:15 AM

Answers

  • Answer for myself and for Russians here. I don't know what it is, maybe bug!

    When we have a protected folder on target PC with Russian name - DPM doesn't work correctly. When I rename folder to English (for example "My documents" or "Work") - DPM can work stable.

    This situation was approved in two different companies with DPM2012.

    Good present from MS in 2013!

    • Marked as answer by s_pawel Monday, December 30, 2013 9:31 AM
    Monday, December 30, 2013 9:31 AM

All replies

  • Need help! Still problem!

    I reinstall DPM 2012 SP1 and it is does not help. I have one PC with Windows 7 and DPM Agent. On my server console under monitoring protection I can see task for recovery point creation. But it shows wrong information. There is task for synchronization (ALWAYS 0.13 MB) and recovery point item - 0 МБ.

    Under Recovery in the dpm mmc console I cant see new files, bit I can see all recovery points in calendar but they show old files.

    I see that client makes recovery points, under menu of protected folder in Previous Versions there are all recovery points. I think synchronization between server and agent is broken, but when I do synchronization from mmc console manually it works and I can see new files in new recovery point.

    When I am trying to see recovery points on the server from client in DPM agent GUI I cant see recovery points.

    Here is DPMRACurr.errlog when it does synchronization. 0.13 Mb again but there are a lot of new files and folders ((((

    05B8	0FEC	12/17	11:07:00.767	03	timer.cpp(490)			ACTIVITY	CTimerMgr::TimerThread
    05B8	0FEC	12/17	11:07:00.767	03	timer.cpp(503)	[006A4528]		ACTIVITY	CTimerMgr::TimerThreadInternal
    05B8	08AC	12/17	11:07:00.783	03	runtime.cpp(782)	[006A3DF8]		ACTIVITY	InitializeSecurity found 3 authorized entities
    05B8	08AC	12/17	11:07:00.783	03	runtime.cpp(841)	[006A3DF8]		ACTIVITY	InitializeSecurity added COM perm for DPMRADCOMTrustedMachines
    05B8	08AC	12/17	11:07:00.783	03	runtime.cpp(841)	[006A3DF8]		ACTIVITY	InitializeSecurity added COM perm for DPMRATrustedDPMRAs
    05B8	08AC	12/17	11:07:00.783	03	runtime.cpp(848)	[006A3DF8]		ACTIVITY	InitializeSecurity added DM perm for DPMRADmTrustedMachines
    05B8	08AC	12/17	11:07:00.783	03	runtime.cpp(94)			NORMAL	Found 1 writer account(s) under VSS access control.
    05B8	08AC	12/17	11:07:00.783	03	runtime.cpp(162)			NORMAL	Added writer account [0] 'NT Authority\NetworkService' to the DACL.
    05B8	08AC	12/17	11:07:00.799	22	genericthreadpool.cpp(824)	[006A3DF8]		NORMAL	Hr: = [0x80070002] CGenericThreadPool::m_dwMaximumNumberOfThreads[20]
    05B8	08AC	12/17	11:07:00.799	03	miscellaneousutils.cpp(969)			NORMAL	Error:ERROR_UNKNOWN_PRODUCT, While detecting DPM version. Assuming DPM isnt installed.
    05B8	08AC	12/17	11:07:00.814	20	commengine.cpp(345)	[0047E400]		ACTIVITY	Hr: = [0x80070002] ConnectionNoActivityTimeout: 300
    05B8	08AC	12/17	11:07:00.814	20	commengine.cpp(359)	[0047E400]		ACTIVITY	Hr: = [0x80070002] m_dwConnectionNoActivityTimeoutForSlowReceiver: 3600
    05B8	0A48	12/17	11:07:00.830	03	timer.cpp(490)			ACTIVITY	CTimerMgr::TimerThread
    05B8	0A48	12/17	11:07:00.830	03	timer.cpp(503)	[006EA900]		ACTIVITY	CTimerMgr::TimerThreadInternal
    05B8	08AC	12/17	11:07:00.830	29	dpmra.cpp(160)	[006A4348]		NORMAL	CDPMRA::Initialize [006A4348]
    05B8	08AC	12/17	11:07:00.830	31	vaagent.cpp(227)			NORMAL	CVAAgent: Initialize globals
    05B8	08AC	12/17	11:07:00.861	29	mta.cpp(126)			ACTIVITY	CMTA::AddSchemasToSchemaCache => Initialize loaded schemas
    05B8	08AC	12/17	11:07:00.892	29	dpmra.cpp(178)	[006A4348]		NORMAL	CDPMRA::Initialize loaded schemas 
    05B8	08AC	12/17	11:07:00.892	22	genericthreadpool.cpp(75)	[00710B28]		NORMAL	CGenericThreadPool: constructor
    05B8	08AC	12/17	11:07:00.892	22	genericthreadpool.cpp(824)	[00710B28]		NORMAL	Hr: = [0x80070002] CGenericThreadPool::m_dwMaximumNumberOfThreads[256]
    05B8	08AC	12/17	11:07:00.892	29	dpmra.cpp(265)	[006A4348]		NORMAL	CreateDefaultSubTask [006A4348]
    05B8	08AC	12/17	11:07:00.892	29	dpmra.cpp(188)	[006A4348]		NORMAL	Temporary storage path reg key found
    05B8	08AC	12/17	11:07:00.892	29	dpmra.cpp(204)	[006A4348]		NORMAL	MTA Temp location Path = [C:\Program Files\Microsoft Data Protection Manager\DPM\temp\MTA\]
    05B8	08AC	12/17	11:07:00.908	29	mtamethrottlehelper.cpp(113)	[007134E8]		NORMAL	Hr: = [0x80070002] Number of encryption threads = [2]
    05B8	08AC	12/17	11:07:00.908	03	datasourceresourcelimit.cpp(132)	[006A4254]		NORMAL	Resource Limit Config File: C:\Program Files\Microsoft Data Protection Manager\DPM\bin\DsResourceLimits.xml
    05B8	08AC	12/17	11:07:00.908	03	datasourceresourcelimit.cpp(138)	[006A4254]		NORMAL	Loaded DS Resource Limits
    05B8	08AC	12/17	11:07:00.908	03	service.cpp(298)	[0013FA78]		ACTIVITY	CService::AnnounceServiceStatus
    05B8	0F00	12/17	11:07:00.955	03	workitem.cpp(86)	[00713A54]	46EB28C5-8F45-4989-9394-CFE18A61D98F	ACTIVITY	Idle Timer created with timeout = 390000
    05B8	0F00	12/17	11:07:01.189	05	xmlprocessingutils.cpp(1469)		46EB28C5-8F45-4989-9394-CFE18A61D98F	WARNING	Failed: Hr: = [0x80070057] : Encountered Failure: : lVal : E_INVALIDARG
    05B8	0F00	12/17	11:07:01.205	31	vadatasourcestate.cpp(747)		46EB28C5-8F45-4989-9394-CFE18A61D98F	WARNING	Failed: Hr: = [0x8007007e] GetModuleHandle failed for Library [GenericNonVssDatasourceHelperPlugin], will try LoadLibrary
    0000	0F00	12/17	11:07:01.205	00	fsutils.cpp(4750)		46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CClientReadThrottler::InitializeWaitForClientRead Failed to read sleep time from registry [hr = 0x713628]. Setting default [50 ms].
    05B8	0F00	12/17	11:07:01.205	31	dllmain.cpp(39)		46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	GenericNonVssDatasourceHelperPlugin: DLL_PROCESS_ATTACH
    05B8	0F00	12/17	11:07:01.252	31	basewriterbackuphelper.cpp(159)	[00719F70]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	SetOperationType(2)
    05B8	0F00	12/17	11:07:01.252	31	vssbaserequestor.cpp(51)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssBaseRequestor: constructor [00719408]
    05B8	0F00	12/17	11:07:01.252	31	vsssnapshotrequestor.cpp(91)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssSnapshotRequestor::CVssSnapshotRequestor [00719408]
    05B8	0F00	12/17	11:07:01.252	31	vssbaserequestor.cpp(88)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssBaseRequestor::Initialize [00719408]
    05B8	0F00	12/17	11:07:01.252	31	vsssnapshotrequestor.cpp(185)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssSnapshotRequestor::InitializeSnapshotCreation [00719408]
    05B8	0F00	12/17	11:07:01.314	03	miscellaneousutils.cpp(669)		46EB28C5-8F45-4989-9394-CFE18A61D98F	WARNING	Failed: Hr: = [0x80070002] Could not open HKEY_LOCAL_MACHINE\Software\Microsoft\Microsoft Data Protection Manager\Agent\2.0
    05B8	0F00	12/17	11:07:01.314	31	vsssnapshotrequestor.cpp(391)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssSnapshotRequestor::AddVolumeForSnapshot [00719408]
    05B8	0F00	12/17	11:07:01.314	31	vsssnapshotrequestor.cpp(423)		46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	ssLocalVolumeGuid = [\\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}\], ssClusterVolGuid=[\\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}\]
    05B8	0F00	12/17	11:07:01.314	31	vsssnapshotrequestor.cpp(459)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssSnapshotRequestor::AddVolumeForSnapshot [00719408]
    05B8	0F00	12/17	11:07:01.314	31	vsssnapshotrequestor.cpp(482)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssSnapshotRequestor: AddVolumeForSnapshot - Marked volume c:\ to be snapshot
    05B8	0F00	12/17	11:07:01.314	18	usnjournalhelper.cpp(526)	[0353F86C]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnJournal::GetVolumeHandle Volume Path = \\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}
    05B8	0F00	12/17	11:07:01.314	18	usnjournalhelper.cpp(544)	[0353F86C]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnJournal::GetVolumeHandle Volume Path = \\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}, Volume Handle = 820
    05B8	0F00	12/17	11:07:01.314	18	usnjournalhelper.cpp(466)	[0353F86C]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnJournalHelper::GetNextUsnInternal(vol:820)
    05B8	0F00	12/17	11:07:01.314	18	usnjournalhelper.cpp(488)	[0353F86C]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	UsnJournalID    = 0x01CEE6CDD769A152
    05B8	0F00	12/17	11:07:01.314	18	usnjournalhelper.cpp(489)	[0353F86C]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	FirstUsn        = 0x0000000005880000
    05B8	0F00	12/17	11:07:01.330	18	usnjournalhelper.cpp(490)	[0353F86C]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	NextUsn         = 0x00000000096D9080
    05B8	0F00	12/17	11:07:01.330	18	usnjournalhelper.cpp(491)	[0353F86C]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	LowestValidUsn  = 0x0000000000000000
    05B8	0F00	12/17	11:07:01.330	18	usnjournalhelper.cpp(492)	[0353F86C]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	MaxUsn          = 0x7FFFFFFFFFFF0000
    05B8	0F00	12/17	11:07:01.330	18	usnjournalhelper.cpp(493)	[0353F86C]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	MaximumSize     = 0x0000000012C00000
    05B8	0F00	12/17	11:07:01.330	18	usnjournalhelper.cpp(494)	[0353F86C]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	AllocationDelta = 0x0000000001E00000
    05B8	0F00	12/17	11:07:01.330	31	vsssnapshotrequestor.cpp(544)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssSnapshotRequestor::StartPrepareForBackup [00719408]
    05B8	0F00	12/17	11:07:01.330	31	vsssnapshotrequestor.cpp(593)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssSnapshotRequestor: Using provider {B5946137-7B9F-4925-AF80-51ABD60B20D5} for volume \\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}\
    05B8	0F00	12/17	11:07:01.533	31	vssbaserequestor.cpp(1068)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssBaseRequestor::StartGatherWriterStatus [00719408]
    05B8	0F00	12/17	11:07:02.533	31	vssbaserequestor.cpp(944)	[00719408]		NORMAL	QueryStatus returned 0x4230a, Releasing VssAsync [002DA6A8]
    05B8	0F00	12/17	11:07:02.533	31	vssbaserequestor.cpp(1101)	[00719408]		NORMAL	CVssBaseRequestor::CheckWriterStatus [00719408]
    05B8	0F00	12/17	11:07:02.533	31	vsssnapshotrequestor.cpp(712)	[00719408]		NORMAL	CVssSnapshotRequestor::StartDoSnapshot [00719408]
    05B8	0F00	12/17	11:07:05.533	31	vssbaserequestor.cpp(944)	[00719408]		NORMAL	QueryStatus returned 0x4230a, Releasing VssAsync [002DA6A8]
    05B8	0F00	12/17	11:07:05.533	31	vssbaserequestor.cpp(1068)	[00719408]		NORMAL	CVssBaseRequestor::StartGatherWriterStatus [00719408]
    05B8	0F00	12/17	11:07:06.533	31	vssbaserequestor.cpp(944)	[00719408]		NORMAL	QueryStatus returned 0x4230a, Releasing VssAsync [002DA6A8]
    05B8	0F00	12/17	11:07:06.533	31	vsssnapshotrequestor.cpp(747)	[00719408]		NORMAL	CVssSnapshotRequestor::MapVolumesToSnapshotPaths [00719408]
    05B8	0F00	12/17	11:07:06.533	31	vsssnapshotrequestor.cpp(767)	[00719408]		NORMAL	CVssSnapshotRequestor:: ProviderId:[{B5946137-7B9F-4925-AF80-51ABD60B20D5}], h/w provider:[0], volume:[\\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}\]
    05B8	0F00	12/17	11:07:06.533	31	vssbaserequestor.cpp(1101)	[00719408]		NORMAL	CVssBaseRequestor::CheckWriterStatus [00719408]
    05B8	0DBC	12/17	11:07:06.533	31	genericnonvssdatasourcehelperplugin.cpp(136)	[00719F70]		NORMAL	DPM dataset metadata xml:
    05B8	0DBC	12/17	11:07:06.533	31	genericnonvssdatasourcehelperplugin.cpp(136)	[00719F70]		NORMAL	<VAA_METADATA><VAA_DATASET_METADATA><VAA_DATASET_BACKUP_DETAILS BackupType="Full" BackupMode="Regular"></VAA_DATASET_BACKUP_DETAILS></VAA_DATASET_METADATA></VAA_METADATA>
    05B8	0DBC	12/17	11:07:06.705	31	basewriterbackuphelper.cpp(178)	[00719F70]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	SetSystemFlags(8)
    05B8	0DBC	12/17	11:07:06.705	18	dsmsendersubtaskbase.cpp(47)	[0071D830]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmSenderSubTaskBase: constructor [0071D830] openovl[0071D92C] msgovl[0071D9F4] closeovl[0071D96C]
    05B8	0DBC	12/17	11:07:06.705	31	vsssnapshotrequestor.cpp(1276)		46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	ssLocalVolumeGuid = [\\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}\], ssClusterVolGuid=[\\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}\]
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(382)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnJournalHelper::GetFirstUsnOnSnapshot(vol:\\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}\, jrnl:0x01CEE6CDD769A152, nextUsnBeforeSnapshot:0x00000000096D9080, snapshotTm:0x01CEFB181DD2BFB8)
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(526)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnJournal::GetVolumeHandle Volume Path = \\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(544)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnJournal::GetVolumeHandle Volume Path = \\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}, Volume Handle = 894
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(466)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnJournalHelper::GetNextUsnInternal(vol:894)
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(488)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	UsnJournalID    = 0x01CEE6CDD769A152
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(489)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	FirstUsn        = 0x0000000005880000
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(490)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	NextUsn         = 0x00000000096DA518
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(491)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	LowestValidUsn  = 0x0000000000000000
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(492)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	MaxUsn          = 0x7FFFFFFFFFFF0000
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(493)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	MaximumSize     = 0x0000000012C00000
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(494)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	AllocationDelta = 0x0000000001E00000
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(443)	[02FCEE74]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnJournalHelper::GetFirstUsnOnSnapshot(deltaUsn: 0x00000000096DA280)
    05B8	0DBC	12/17	11:07:06.705	31	vsssnapshotrequestor.cpp(1276)		46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	ssLocalVolumeGuid = [\\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}\], ssClusterVolGuid=[\\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}\]
    05B8	0DBC	12/17	11:07:06.705	31	vsssnapshotrequestor.cpp(1276)		46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	ssLocalVolumeGuid = [\\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}\], ssClusterVolGuid=[\\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}\]
    05B8	0DBC	12/17	11:07:06.705	18	usnchangeiterator.cpp(106)	[0071DE90]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnChangeIterator::Init(vol:c:\, snap:\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy77, jrnl:0x01CEE6CDD769A152, start bs:0x00000000096C3E60, start:0x00000000096C60A0, last bs:0x00000000096D9080, last:0x00000000096DA280, checkpoint:0x0000000000000000)
    05B8	0DBC	12/17	11:07:06.705	18	usnchangeiterator.cpp(222)	[0071DE90]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnChangeIterator::SetFileSystemFlags (0x9) called
    05B8	0DBC	12/17	11:07:06.705	18	iteratorutils.cpp(648)		46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CaseSensitivity NOT enabled on the machine
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(526)	[0071E398]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnJournal::GetVolumeHandle Volume Path = \\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(544)	[0071E398]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnJournal::GetVolumeHandle Volume Path = \\?\Volume{ede7e82a-52c0-11e3-9891-806e6f6e6963}, Volume Handle = 898
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(466)	[0071E398]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnJournalHelper::GetNextUsnInternal(vol:898)
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(488)	[0071E398]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	UsnJournalID    = 0x01CEE6CDD769A152
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(489)	[0071E398]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	FirstUsn        = 0x0000000005880000
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(490)	[0071E398]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	NextUsn         = 0x00000000096DA518
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(491)	[0071E398]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	LowestValidUsn  = 0x0000000000000000
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(492)	[0071E398]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	MaxUsn          = 0x7FFFFFFFFFFF0000
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(493)	[0071E398]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	MaximumSize     = 0x0000000012C00000
    05B8	0DBC	12/17	11:07:06.705	18	usnjournalhelper.cpp(494)	[0071E398]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	AllocationDelta = 0x0000000001E00000
    05B8	0DBC	12/17	11:07:06.705	18	fsutils.cpp(1851)		46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	GetVolumeHandle Volume Path = \\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy77
    05B8	0DBC	12/17	11:07:06.705	18	filehandlecache.cpp(45)	[00715158]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnChangeIterator::Init(snap:\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy77\)
    05B8	0DBC	12/17	11:07:06.752	18	multivolumeusniterator.cpp(98)	[0047D4C8]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CMultiVolumeUsnIterator:AddIncludeFiles(filepath:c:\мои документы\, filespec:*, snapshotpath:\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy77\мои документы\) called
    05B8	0DBC	12/17	11:07:06.752	18	usnchangeiterator.cpp(267)	[0071DE90]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CUsnChangeIterator:AddIncludeFiles(filepath:c:\мои документы\, filespec:*, snapshotpath:\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy77\мои документы\, Recursive:true, ReaderOptions:0x0) called
    05B8	0DBC	12/17	11:07:06.799	18	dsmsendersubtaskbase.cpp(627)	[0071D830]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Page size to be used: 4096
    05B8	0DBC	12/17	11:07:06.799	18	dsmsendersubtaskbase.cpp(646)	[0071D830]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Failed to read MaxBufferSize from registry. Using default size: 262144
    05B8	0DBC	12/17	11:07:06.799	18	dsmreceiversubtaskbase.cpp(39)	[0071DA78]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmReceiverSubTaskBase: constructor [0071DA78] openovl[0071DB74] msgovl[0071DC38] closeovl[0071DBB4]
    05B8	0DBC	12/17	11:07:06.799	18	dsmsendersubtaskbase.cpp(47)	[00743000]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmSenderSubTaskBase: constructor [00743000] openovl[007430FC] msgovl[007431C4] closeovl[0074313C]
    05B8	0DBC	12/17	11:07:06.799	18	dsmsendersubtaskbase.cpp(627)	[00743000]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Page size to be used: 4096
    05B8	0DBC	12/17	11:07:06.799	18	dsmsendersubtaskbase.cpp(646)	[00743000]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Failed to read MaxBufferSize from registry. Using default size: 262144
    05B8	0DBC	12/17	11:07:06.799	18	fixupreaderiterator.cpp(175)	[00743250]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CFixupReaderIterator::SetFileSystemFlags (0x8) called
    05B8	0DBC	12/17	11:07:06.846	31	datasetfixupsubtaskbase.cpp(227)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDatasetFixupSubTaskBase::BeginDataMove [00714CD0]
    05B8	0DBC	12/17	11:07:06.861	20	nativeutils.hpp(42)		46EB28C5-8F45-4989-9394-CFE18A61D98F	WARNING	Failed: Hr: = [0x80070002] : Encountered Failure: : lVal : r.GetValue(pszKey, pT)
    05B8	0DBC	12/17	11:07:06.861	20	destination.cpp(1424)	[00747400]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	DM: GetPreferredProtocolFamilyToConnect for BACKUP : 2, PingBeforeConnect : 0
    05B8	0140	12/17	11:07:06.861	20	cc_extcalls.cpp(272)	[00747640]		NORMAL	Doing DNS Lookup for host:backup.penzagp.ru, Port: 5718, Address Family: 2
    05B8	0140	12/17	11:07:06.861	20	cc_extcalls.cpp(292)	[00747640]		NORMAL	DM: - Name lookup without DNS failed
    05B8	0140	12/17	11:07:06.861	20	cc_extcalls.cpp(307)	[00747640]		NORMAL	DM: - DNS Lookup SUCCESS
    05B8	0140	12/17	11:07:06.861	20	cc_extcalls.cpp(316)	[00747640]		NORMAL	BackupLan Count:0
    05B8	0DBC	12/17	11:07:06.861	20	nativeutils.hpp(42)		46EB28C5-8F45-4989-9394-CFE18A61D98F	WARNING	Failed: Hr: = [0x80070002] : Encountered Failure: : lVal : r.GetValue(pszKey, pT)
    05B8	0DBC	12/17	11:07:06.861	20	destination.cpp(1424)	[0074AB60]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	DM: GetPreferredProtocolFamilyToConnect for BACKUP : 2, PingBeforeConnect : 0
    05B8	0140	12/17	11:07:06.877	20	protocolhandshakehelper.cpp(331)	[007423E0]		NORMAL	CProtocolHandshake::handshakeSendList: 007423E0, m_dwRole = 2, m_MachineType = 1, dwProtocolList = 1, m_dwProtocolSelected = 1
    05B8	0140	12/17	11:07:06.877	20	protocolhandshakehelper.cpp(349)	[007423E0]		NORMAL	Protocol selected for host [HOST/backup.penzagp.ru@penzagp.ru] is [KERBEROS]
    05B8	0DBC	12/17	11:07:06.877	20	protocolhandshakehelper.cpp(331)	[00742428]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CProtocolHandshake::handshakeSendList: 00742428, m_dwRole = 2, m_MachineType = 1, dwProtocolList = 1, m_dwProtocolSelected = 1
    05B8	0DBC	12/17	11:07:06.877	20	protocolhandshakehelper.cpp(349)	[00742428]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Protocol selected for host [HOST/backup.penzagp.ru@penzagp.ru] is [KERBEROS]
    05B8	0674	12/17	11:07:06.877	20	cc_session.cpp(161)	[00747640]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Hr: = [0x80070002] DM: SetTcpSendWindow failed for connection 00747640, Ignoring the failure
    05B8	0674	12/17	11:07:06.877	20	cc_session.cpp(161)	[0074ADA0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Hr: = [0x80070002] DM: SetTcpSendWindow failed for connection 0074ADA0, Ignoring the failure
    05B8	0F00	12/17	11:07:06.877	18	dsmsendersubtaskbase.cpp(141)	[0071D830]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmSenderSubTaskBase received session opened completion in WAIT state
    05B8	0AFC	12/17	11:07:06.877	18	dsmreceiversubtaskbase.cpp(237)	[0071DA78]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmReceiverSubTaskBase received session opened completion in WAIT state
    05B8	0F00	12/17	11:07:06.877	18	usnjournal.cpp(94)	[0071DF30]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	DoFirstPass firstUsn: 0x0020002000200020
    05B8	0DBC	12/17	11:07:06.877	20	nativeutils.hpp(42)		46EB28C5-8F45-4989-9394-CFE18A61D98F	WARNING	Failed: Hr: = [0x80070002] : Encountered Failure: : lVal : r.GetValue(pszKey, pT)
    05B8	0DBC	12/17	11:07:06.877	20	destination.cpp(1424)	[00751970]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	DM: GetPreferredProtocolFamilyToConnect for BACKUP : 2, PingBeforeConnect : 0
    05B8	0DBC	12/17	11:07:06.892	20	protocolhandshakehelper.cpp(331)	[007426B0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CProtocolHandshake::handshakeSendList: 007426B0, m_dwRole = 2, m_MachineType = 1, dwProtocolList = 1, m_dwProtocolSelected = 1
    05B8	0DBC	12/17	11:07:06.892	20	protocolhandshakehelper.cpp(349)	[007426B0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Protocol selected for host [HOST/backup.penzagp.ru@penzagp.ru] is [KERBEROS]
    05B8	0CB0	12/17	11:07:06.892	20	cc_session.cpp(161)	[00751CB0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Hr: = [0x80070002] DM: SetTcpSendWindow failed for connection 00751CB0, Ignoring the failure
    05B8	0DBC	12/17	11:07:06.892	18	dsmsendersubtaskbase.cpp(141)	[00743000]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmSenderSubTaskBase received session opened completion in WAIT state
    05B8	0CB0	12/17	11:07:07.736	20	session.cpp(1560)	[00743860]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Hr: = [0x00000000] DM: Will attempt to post DM_SESSION_CLOSED, pSes=00743860 rcv=00000000 snd=00000000 sesop=0071D96C seserr=00000000
    05B8	0AFC	12/17	11:07:07.736	18	dsmsendersubtaskbase.cpp(325)	[0071D830]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmSubTaskBase received session closed completion in CLOSE state [0071D830]
    05B8	0AFC	12/17	11:07:07.736	31	readdatasetdeltasubtask.cpp(524)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CReadDatasetDeltaSubTask::SubTaskDone [00714CD0]
    05B8	0AFC	12/17	11:07:07.736	31	datasetfixupsubtaskbase.cpp(383)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDatasetFixupSubTaskBase::SubTaskDone [00714CD0] [0071D830]
    05B8	0AFC	12/17	11:07:07.736	31	datasetfixupsubtaskbase.cpp(393)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDatasetFixupSubTaskBase::SubTaskDone - LWV
    05B8	0F00	12/17	11:07:07.736	31	readdatasetdeltasubtask.cpp(524)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CReadDatasetDeltaSubTask::SubTaskDone [00714CD0]
    05B8	0F00	12/17	11:07:07.736	31	datasetfixupsubtaskbase.cpp(383)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDatasetFixupSubTaskBase::SubTaskDone [00714CD0] [0071DA78]
    05B8	0F00	12/17	11:07:07.736	31	datasetfixupsubtaskbase.cpp(401)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDatasetFixupSubTaskBase::SubTaskDone - HWV
    05B8	0674	12/17	11:07:07.736	20	session.cpp(1560)	[0074A920]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Hr: = [0x00000000] DM: Will attempt to post DM_SESSION_CLOSED, pSes=0074A920 rcv=00000000 snd=00000000 sesop=0071DBB4 seserr=00000000
    05B8	0DBC	12/17	11:07:07.736	18	dsmreceiversubtaskbase.cpp(320)	[0071DA78]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmReceiverSubTaskBase received completion for session closed in CLEANUP state
    05B8	0674	12/17	11:07:07.736	20	session.cpp(1560)	[00751730]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	Hr: = [0x00000000] DM: Will attempt to post DM_SESSION_CLOSED, pSes=00751730 rcv=00000000 snd=00000000 sesop=0074313C seserr=00000000
    05B8	0DBC	12/17	11:07:07.736	18	dsmsendersubtaskbase.cpp(325)	[00743000]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmSubTaskBase received session closed completion in CLOSE state [00743000]
    05B8	0DBC	12/17	11:07:07.736	31	readdatasetdeltasubtask.cpp(524)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CReadDatasetDeltaSubTask::SubTaskDone [00714CD0]
    05B8	0DBC	12/17	11:07:07.736	31	datasetfixupsubtaskbase.cpp(383)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDatasetFixupSubTaskBase::SubTaskDone [00714CD0] [00743000]
    05B8	0DBC	12/17	11:07:07.736	31	datasetfixupsubtaskbase.cpp(409)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDatasetFixupSubTaskBase::SubTaskDone - Fixup
    05B8	0DBC	12/17	11:07:07.736	31	readdatasetdeltasubtask.cpp(567)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CReadDatasetDeltaSubTask::CleanUp [00714CD0]
    05B8	0DBC	12/17	11:07:07.736	31	datasetfixupsubtaskbase.cpp(361)	[00714CD0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDatasetFixupSubTaskBase::CleanUp [00714CD0]
    05B8	0DBC	12/17	11:07:07.736	18	dsmsubtaskbase.cpp(86)	[0071D830]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmSubTaskBase: destructor [0071D830]
    05B8	0DBC	12/17	11:07:07.736	18	dsmsubtaskbase.cpp(86)	[0071DA78]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmSubTaskBase: destructor [0071DA78]
    05B8	0DBC	12/17	11:07:07.736	18	dsmsubtaskbase.cpp(86)	[00743000]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CDsmSubTaskBase: destructor [00743000]
    05B8	0DBC	12/17	11:07:12.002	31	freesnapshotsubtask.cpp(46)	[00751730]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CFreeSnapshotSubTask: constructor [00751730]
    05B8	0DBC	12/17	11:07:12.002	31	freesnapshotsubtask.cpp(123)	[00751730]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CFreeSnapshotSubTask::TriggerDone [00751730]
    05B8	0DBC	12/17	11:07:12.049	31	freesnapshotsubtask.cpp(562)	[00751730]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CFreeSnapshotSubTask: StartBackupComplete [00751730]
    05B8	0DBC	12/17	11:07:12.049	31	vssbaserequestor.cpp(1068)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssBaseRequestor::StartGatherWriterStatus [00719408]
    05B8	0DBC	12/17	11:07:13.049	31	vssbaserequestor.cpp(944)	[00719408]		NORMAL	QueryStatus returned 0x4230a, Releasing VssAsync [002DA6A8]
    05B8	0DBC	12/17	11:07:13.049	31	freesnapshotsubtask.cpp(674)	[00751730]		NORMAL	CFreeSnapshotSubTask: ReleaseSnapshot [00751730]
    05B8	0DBC	12/17	11:07:13.049	31	vssbaserequestor.cpp(1101)	[00719408]		NORMAL	CVssBaseRequestor::CheckWriterStatus [00719408]
    05B8	0DBC	12/17	11:07:13.049	31	vsssnapshotrequestor.cpp(1346)	[00719408]		NORMAL	CVssSnapshotRequestor::ReleaseSnaphot [00719408]
    05B8	0DBC	12/17	11:07:13.049	31	vsssnapshotrequestor.cpp(2014)	[00719408]		NORMAL	CVssSnapshotRequestor::CleanUp [00719408]
    05B8	0F00	12/17	11:07:13.096	31	freesnapshotsubtask.cpp(181)	[00751730]		NORMAL	CFreeSnapshotSubTask::GetFinalStatus [00751730]
    05B8	0F00	12/17	11:07:13.142	31	freesnapshotsubtask.cpp(63)	[00751730]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CFreeSnapshotSubTask: destructor [00751730]
    05B8	0F00	12/17	11:07:13.377	31	vsssnapshotrequestor.cpp(109)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssSnapshotRequestor::~CVssSnapshotRequestor [00719408]
    05B8	0F00	12/17	11:07:13.377	31	vsssnapshotrequestor.cpp(2014)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssSnapshotRequestor::CleanUp [00719408]
    05B8	0F00	12/17	11:07:13.377	31	vssbaserequestor.cpp(69)	[00719408]	46EB28C5-8F45-4989-9394-CFE18A61D98F	NORMAL	CVssBaseRequestor: destructor [00719408]
    05B8	0F00	12/17	11:07:13.377	03	workitem.cpp(272)	[00713A08]	46EB28C5-8F45-4989-9394-CFE18A61D98F	ACTIVITY	WorkItem stopping
    05B8	0868	12/17	11:12:07.799	20	destination.cpp(1283)	[00747400]		NORMAL	DM: Doing Connection TimeOut for  Destination 00747400: connection 00747640 m_dwLastCompletionTime: 5, m_bUseLongDMConnectionTimeOut: 0, Diff: 301
    05B8	0674	12/17	11:12:07.799	20	cc_extcalls.cpp(517)	[00747640]	46EB28C5-8F45-4989-9394-CFE18A61D98F	WARNING	DM: TempErr: err=0x4d4 read=1 write=0
    05B8	0868	12/17	11:12:07.799	20	destination.cpp(1283)	[0074AB60]		NORMAL	DM: Doing Connection TimeOut for  Destination 0074AB60: connection 0074ADA0 m_dwLastCompletionTime: 5, m_bUseLongDMConnectionTimeOut: 0, Diff: 301
    05B8	0868	12/17	11:12:07.799	20	destination.cpp(1283)	[00751970]		NORMAL	DM: Doing Connection TimeOut for  Destination 00751970: connection 00751CB0 m_dwLastCompletionTime: 5, m_bUseLongDMConnectionTimeOut: 0, Diff: 301
    05B8	0CB0	12/17	11:12:07.799	20	cc_extcalls.cpp(517)	[0074ADA0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	WARNING	DM: TempErr: err=0x4d4 read=1 write=0
    05B8	0144	12/17	11:12:07.799	20	cc_extcalls.cpp(517)	[00751CB0]	46EB28C5-8F45-4989-9394-CFE18A61D98F	WARNING	DM: TempErr: err=0x4d4 read=1 write=0
    05B8	0FEC	12/17	11:12:13.377	03	runtime.cpp(1429)	[006A3DF8]		NORMAL	CDLSRuntime::ProcessIdleTimeout
    05B8	0FEC	12/17	11:12:13.377	03	runtime.cpp(603)	[006A3DF8]		NORMAL	CDLSRuntime::Uninitialize, bForce: 0
    05B8	0FEC	12/17	11:12:13.377	05	genericagent.cpp(266)	[006A4348]		NORMAL	Agent Can Shutdown if there is only default wokitem active[1]
    05B8	0FEC	12/17	11:12:13.377	29	dpmra.cpp(354)	[006A4348]		NORMAL	CDPMRA::Shutting down dpmra, force-shutdown :yes
    05B8	0FEC	12/17	11:12:13.377	03	workitem.cpp(391)			NORMAL	Timing out WI [0047E638], WI GUID = {B71B4544-7067-4A30-B5FB-BA320B10D82A}, ..last DM activity happened 92035734msec back, WI Idle Timeout = 390000msec
    05B8	0FEC	12/17	11:12:13.377	22	genericthreadpool.cpp(684)	[00710B28]		NORMAL	CGenericThreadPool: Waiting for threads to exit
    05B8	0FEC	12/17	11:12:15.377	22	genericthreadpool.cpp(684)	[006A3DF8]		NORMAL	CGenericThreadPool: Waiting for threads to exit
    05B8	0A48	12/17	11:12:17.392	03	timer.cpp(513)	[006EA900]		ACTIVITY	Shutting down timer thread.
    05B8	0FEC	12/17	11:12:17.392	03	service.cpp(81)			ACTIVITY	CService::StopThisService
    05B8	0FEC	12/17	11:12:17.392	03	service.cpp(281)	[0013FA78]		ACTIVITY	CService::StopService()
    05B8	08AC	12/17	11:12:17.392	03	service.cpp(298)	[0013FA78]		ACTIVITY	CService::AnnounceServiceStatus


    • Edited by s_pawel Tuesday, December 17, 2013 11:25 AM
    Tuesday, December 17, 2013 11:20 AM
  • Answer for myself and for Russians here. I don't know what it is, maybe bug!

    When we have a protected folder on target PC with Russian name - DPM doesn't work correctly. When I rename folder to English (for example "My documents" or "Work") - DPM can work stable.

    This situation was approved in two different companies with DPM2012.

    Good present from MS in 2013!

    • Marked as answer by s_pawel Monday, December 30, 2013 9:31 AM
    Monday, December 30, 2013 9:31 AM