- Home
- Windows Server
- Ask the Directory Services Team
- Understanding DFSR debug logging (Part 15: Pre-Seeded Data Usage during Initial Sync)
- Subscribe to RSS Feed
- Mark as New
- Mark as Read
- Bookmark
- Subscribe
- Printer Friendly Page
- Report Inappropriate Content
First published on TechNet on Apr 07, 2009
In this scenario we will see a new replication group and replicated folder created, where data has been pre-seeded (i.e. pre-staged) on both servers. This is a common scenario, where data has existed on a few servers and been kept in sync through other mechanisms than DFSR (such as robocopy ). It’s also often used when building new DFSR servers in a depot to save initial sync time before deploying the server to the field.
(preseedupstream - Dfsr00012 - 2008.log and preseeddownstream - Dfsr00014 - 2008.log)
These are two Windows Server 2008 servers called 2008x86SRV10 and 2008x86SRV11 in the contoso.com domain. The logs are from 2008x86SRV10 where the file is created (upstream) and from 2008x86SRV11 where it is replicated (downstream). Both servers are participating in the SeededRG replication group for the PreseededRF replicated folder. The upstream server contains files ‘matched.dll’ (which has an identical file pre-seeded on the downstream) and ‘samenameonly.dll’ (which has the same name and path on the downstream but differs in its contents slightly). The downstream server also contains file ‘onlydownstream.dll’ which does not exist on the upstream and will be covered by pre-existing logic.
<Upstream> 20080910 16:22:24.423 2928 VLMG 1158 VolumeManager::Initialize Volume initialized. volId:\\.\C:
<Upstream> 20080910 16:22:24.423 3392 CSMG 2298 ContentSetManager::CheckContentSetState Content set csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} state:InitialBuilding << New replica set is being built
<Upstream> 20080910 16:22:24.501 3392 CSMG 3182 ContentSetManager::CreateRootRecord Adding root record csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf ghosted:0
<Upstream> 20080910 16:22:24.517 3392 CSMG 3298 ContentSetManager::CreateRootRecord LDB Inserting ID Record: << The DFSR database has a base record added for the new replica set.
+ fid 0x80000000000B3
+ usn 0x27f3650
+ uidVisible 1
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 16010101 00:00:00.000 GMT
+ present 1
+ nameConflict 0
+ attributes 0x10
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v10
+ uid {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ parent {00000000-0000-0000-0000-000000000000}-v0
+ fence 16010101 00:00:00.000 P
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 19:14:41.883 GMT (0x1c913797a31f6a8)
+ createTime 20080910 17:14:04.242 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 00000000-00000000-00000000-00000000
+ similarity 00000000-00000000-00000000-00000000
+ name preseededrf << name of the replicated folder
+
<Upstream> 20080910 16:22:24.657 3392 LDBX 4028 Ldb::Insert Inserting contentSetRecord: << The replicated folder record is add into the database
+ contentSetId: {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ memberId: {D964421D-59F0-434C-8826-ED91D268B143}
+ state: InitialBuilding << The RG is in an initial building state
+ startVersion: v9
+ authRebuilding: 0
+ stageVolumeSerialNumber: 0
+ stageFid: 0x0
+ isTombstone: 0
+ readOnlySince: 16010101 00:00:00.000
+ beingDeleted: 0
+ dbLossRecover: 0
+
<Upstream> 20080910 16:22:24.970 3392 STAG 2600 Staging::ScanStagingDirectory Staging space usage is: 0
<Upstream> 20080910 16:22:24.970 3392 STAG 6158 StagingManager::RegisterContentSetsOnPath {7F8CFBB0-4973-4B53-AAD5-3328870036FB} added to the replicated folder list.
<Upstream> 20080910 16:22:24.970 3392 LDBX 4062 Ldb::Update Updating contentSetRecord:
+ contentSetId: {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ memberId: {D964421D-59F0-434C-8826-ED91D268B143}
+ state: InitialBuilding
+ startVersion: v9
+ authRebuilding: 0
+ stageVolumeSerialNumber: 48e8de48e8de33c2
+ stageFid: 0x500000000BA72
+ isTombstone: 0
+ readOnlySince: 16010101 00:00:00.000
+ beingDeleted: 0
+ dbLossRecover: 0
+
<Upstream> 20080910 16:22:25.017 2444 VLMG 1391 VolumeManager::ScanForSimilarity Begin scanning DB for similarity data. volId:\\.\C:
<Upstream> 20080910 16:22:25.017 2444 VLMG 1474 VolumeManager::ScanForSimilarity End scanning DB for similarity data. volId:\\.\C:
<Upstream> 20080910 16:22:25.079 3392 CSMG 1274 ContentSetManager::Initialize Walk replica set to initialize or fix up database. csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf << Now the DFSR service must walk all the folders and files in the replicated folder and add them to the database and USN journal.
<Upstream> 20080910 16:22:25.079 3392 DIRW 515 DirWalkerTask::QueueMoveinJob Queuing move-in. uid:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1 fid:0x80000000000B3 name:preseededrf
<Upstream> 20080910 16:22:25.079 3392 DIRW 256 DirWalkerTask::Run Start walking directory.
<Upstream> 20080910 16:22:25.079 3392 DIRW 945 DirWalkerTask::MoveinStep Starting to process move-in job. uid:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1 << the Dir Walker (which finds all files and folders) is now executing.
<Upstream> 20080910 16:22:25.079 3392 DIRW 2428 DirWalkerTask::CreateOneRecord LDB Inserting ID Record: << our first file is added into the DFSR database.
+ fid 0x90000000000B6
+ usn 0x27f2eb8
+ uidVisible 0
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 16010101 00:00:00.000 GMT
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11 << UID and GVSN will match because the file has never been modified in the lifetime of this content set on this server.
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 P
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 17:15:13.698 GMT (0x1c91368c99fb791)
+ createTime 20080910 17:15:05.932 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 00000000-00000000-00000000-00000000
+ similarity 00000000-00000000-00000000-00000000
+ name matched.dll << this is the file which will be identical on the downstream server
+
<Upstream> 20080910 16:22:25.079 3392 DIRW 2428 DirWalkerTask::CreateOneRecord LDB Inserting ID Record: << The next file on the upstream server is added to the DFSR database
+ fid 0x60000000000B4
+ usn 0x27fe1c8
+ uidVisible 0
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 16010101 00:00:00.000 GMT
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 << UID and GVSN will match because the file has never been modified in the lifetime of this content set on this server.
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 P
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 19:57:01.398 GMT (0x1c9137f63dd4325)
+ createTime 20080910 17:15:05.916 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 00000000-00000000-00000000-00000000
+ similarity 00000000-00000000-00000000-00000000
+ name samenameonly.dll << this is the file which will be binarily slightly different on the downstream server (but same name, path, security, attributes)
+
<Upstream> 20080910 16:22:25.079 3392 DIRW 101 DirWalkerTask::Job::Finish MoveIn csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} uid:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1 << both of the files on the upstream server have been found by the dirwalker.
<Upstream> 20080910 16:22:25.079 3392 DIRW 793 DirWalkerTask::RemoveJob Removing job type:1 uid:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
<Upstream> 20080910 16:22:25.079 3392 LDBX 4386 Ldb::DeleteWalkerJob Deleting dirWalkerJob. uid:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
<Upstream> 20080910 16:22:25.095 3392 EVNT 725 EventLog::Report Logging eventId:4112 parameterCount:6 << The upstream DFSR server must now log 4412 event (in the DFSR event log) so that it is done with initial building tasks and is ready to serve files to downstream partners. The replicated folder is initialized.
<Upstream> 20080910 16:22:25.095 3392 EVNT 745 EventLog::Report eventId:4112 parameter1:7F8CFBB0-4973-4B53-AAD5-3328870036FB
<Upstream> 20080910 16:22:25.095 3392 EVNT 745 EventLog::Report eventId:4112 parameter2:C:\preseededrf
<Upstream> 20080910 16:22:25.095 3392 EVNT 745 EventLog::Report eventId:4112 parameter3:preseededrf
<Upstream> 20080910 16:22:25.095 3392 EVNT 745 EventLog::Report eventId:4112 parameter4:SeededRG
<Upstream> 20080910 16:22:25.095 3392 EVNT 745 EventLog::Report eventId:4112 parameter5:19AFE009-299A-417C-A0AA-E43178ECC158
<Upstream> 20080910 16:22:25.095 3392 EVNT 745 EventLog::Report eventId:4112 parameter6:D964421D-59F0-434C-8826-ED91D268B143
<Upstream> 20080910 16:22:25.095 3392 CSMG 4599 ContentSetManager::UpdateRootFence LDB Updating ID Record: << A ‘fence’ is set which marks a content set as authoritative for all conflicts
+ fid 0x80000000000B3
+ usn 0x27f3650
+ uidVisible 1
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 20080910 20:22:24.517 GMT
+ present 1
+ nameConflict 0
+ attributes 0x10
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v10
+ uid {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ parent {00000000-0000-0000-0000-000000000000}-v0
+ fence 16010101 00:00:00.000
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 19:14:41.883 GMT (0x1c913797a31f6a8)
+ createTime 20080910 17:14:04.242 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 00000000-00000000-00000000-00000000
+ similarity 00000000-00000000-00000000-00000000
+ name preseededrf
+
<Upstream> 20080910 16:22:25.095 3392 CSMG 2897 ContentSetManager::SetContentSetState Set content set state. csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} newState:Normal (auth:0) << the content set state is now set to Normal on the upstream server, meaning that it is done with initial sync/initial building operations locally and is ready to serve files to all partners.
<Upstream> 20080910 16:22:25.095 3392 LDBX 4062 Ldb::Update Updating contentSetRecord: << Normal state is set
+ contentSetId: {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ memberId: {D964421D-59F0-434C-8826-ED91D268B143}
+ state: Normal
+ startVersion: v9
+ authRebuilding: 0
+ stageVolumeSerialNumber: 48e8de48e8de33c2
+ stageFid: 0x500000000BA72
+ isTombstone: 0
+ readOnlySince: 16010101 00:00:00.000
+ beingDeleted: 0
+ dbLossRecover: 0
+
<Upstream> 20080910 16:22:25.173 3392 CSMG 2697 ContentSetManager::DbBuildComplete Finished scanning content set and building database info: {7F8CFBB0-4973-4B53-AAD5-3328870036FB} Transition from InitialBuilding to Normal
<Upstream> 20080910 16:22:25.173 3392 DIRW 303 DirWalkerTask::Run Exit.
<< snipped out long step of contacting other server successfully, getting ready to work – this is documented in previous scenarios>
<Upstream> 20080910 16:22:32.142 3392 EVNT 1126 EventLog::AuditInstall Audit source was found
<Upstream> 20080910 16:22:32.142 3392 MRSH 4615 Marshaller::Marshal FileAttrs in metadata : 0x20
<Upstream> 20080910 16:22:32.142 3392 JOIN 1122 Join::SubmitUpdate LDB Updating ID Record: << database is updated to include similarity and hash information
+ fid 0x90000000000B6
+ usn 0x27f2eb8
+ uidVisible 1
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 20080910 20:22:25.079 GMT
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 P
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 17:15:13.698 GMT (0x1c91368c99fb791)
+ createTime 20080910 17:15:05.932 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 12DEDDE4-FEE039FA-807CA87E-6A3BD51A << hash is set and can be used for comparison purposes with any downstream copies of the file to avoid/require replication.
+ similarity 331B142E-143C3835-0A240739-230C1618
+ name matched.dll
+
<Upstream> 20080910 16:22:32.158 3392 JOIN 1167 Join::SubmitUpdate Sent: uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11 name:matched.dll connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf << the update information for matched.dll is sent to the downstream partner.
<Upstream> 20080910 16:22:32.158 3392 MRSH 4615 Marshaller::Marshal FileAttrs in metadata : 0x20
<Upstream> 20080910 16:22:32.158 3392 JOIN 1122 Join::SubmitUpdate LDB Updating ID Record: << Hash/similarity information is added to the DFSR database for the other file, samenameonly.dll. This file is similar to the downstream partner (as we will see) in that it has same name, path, and most of its data is identical.
+ fid 0x60000000000B4
+ usn 0x27fe1c8
+ uidVisible 1
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 20080910 20:22:25.079 GMT
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 P
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 19:57:01.398 GMT (0x1c9137f63dd4325)
+ createTime 20080910 17:15:05.916 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 72D6A531-D011696C-D6D0983A-D953F419
+ similarity 06190414-382A030F-142D3A1A-10243A3F
+ name samenameonly.dll
+
<Upstream> 20080910 16:22:32.158 3392 JOIN 1167 Join::SubmitUpdate Sent: uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 name:samenameonly.dll connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf << As above, update information sent to downstream partner regarding the samenameonly.dll.
<Upstream> 20080910 16:22:32.158 1724 OUTC 784 OutConnection::OpenFile Received request for update:
+ present 1
+ nameConflict 0
+ attributes 0x10
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v10
+ uid {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ parent {00000000-0000-0000-0000-000000000000}-v0
+ fence 16010101 00:00:00.000
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 19:14:41.883 GMT (0x1c913797a31f6a8)
+ createTime 20080910 17:14:04.242 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 138816B1-4218E5A2-D9B2A1F3-8A52E6A4
+ similarity 00000000-00000000-00000000-00000000
+ name preseededrf
+ rdcDesired:1 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} rgName:SeededRG
20080910 16:22:32.158 1724 OUTC 1534 OutConnection::OpenFile Sent file uid:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v10 name:preseededrf fileSize:312 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} rgName:SeededRG
<Upstream> 20080910 16:22:32.158 1724 MRSH 4615 Marshaller::Marshal FileAttrs in metadata : 0x10
<Upstream> 20080910 16:22:32.158 1724 SRTR 2357 InitializeFileTransferAsyncState::ProcessIoCompletion Initialized connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} rdc:1 context:00000000,00000000,00000000 uid:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1 gvsn{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v10 ptr:008F2B28
<Upstream> 20080910 16:22:32.158 3680 SRTR 1880 SERVER_RequestVersionVector Received from connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} seqNumber:3 changeType:notify
<Downstream> 20080910 16:22:32.244 1708 MEET 3358 Meet::InstallOverwrite Moving away content that is in the way updateName:preseededrf uid:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v10 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf
<Downstream> 20080910 16:22:32.244 1708 NTFS 1257 NtfsFileSystem::SetAttributes oldFileAttributes:0x10 newFileAttributes:0x10
<Downstream> 20080910 16:22:32.244 1708 MEET 6746 Meet::UpdateIdRecord LDB Updating ID Record:
+ fid 0x100000000000B3
+ usn 0x2a60648
+ uidVisible 1
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 16010101 00:00:00.000 GMT
+ present 1
+ nameConflict 0
+ attributes 0x10
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v10
+ uid {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ parent {00000000-0000-0000-0000-000000000000}-v0
+ fence 16010101 00:00:00.000
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 19:14:41.883 GMT (0x1c913797a31f6a8)
+ createTime 20080910 17:14:04.242 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 138816B1-4218E5A2-D9B2A1F3-8A52E6A4
+ similarity 00000000-00000000-00000000-00000000
+ name preseededrf
+
<Downstream> 20080910 16:22:32.244 1708 MEET 3431 Meet::InstallOverwrite -> DONE Install-overwrite completed updateName:preseededrf uid:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v10 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}
<Downstream> 20080910 16:22:32.244 1708 ASYN 1291 AsyncUnbufferedFileReader::Close Async READ Statistics:
<Downstream> 20080910 16:22:32.244 1708 INCO 5897 InConnection::UpdateProcessed Received Update. updatesLeft:2 processed:1 failures:0 sessionId:1 open:0 updateType:0 processStatus:0 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf update:
+ present 1
+ nameConflict 0
+ attributes 0x10
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v10
+ uid {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ parent {00000000-0000-0000-0000-000000000000}-v0
+ fence 16010101 00:00:00.000
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 19:14:41.883 GMT (0x1c913797a31f6a8)
+ createTime 20080910 17:14:04.242 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 138816B1-4218E5A2-D9B2A1F3-8A52E6A4
+ similarity 00000000-00000000-00000000-00000000
+ name preseededrf
+
<Downstream> 20080910 16:22:32.244 1708 MEET 1207 Meet::Install Retries:0 updateName:matched.dll uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf updateType:remote << this is misleading – the file is not actually replicated at this point, but the log entry will occur even if the file is a perfect match. You will note as you read further down that there is no “MoveOut Moving contents and children out of replica” or “Download Succeeded” referencing the ‘matched.dll’ file, because no bits went on the wire other than metadata.
<Downstream> 20080910 16:22:32.244 1708 MRSH 4615 Marshaller::Marshal FileAttrs in metadata : 0x20
<Downstream> 20080910 16:22:32.244 1708 CSMG 4844 ContentSetManager::UpdateHash LDB Updating ID Record: << downstream server now must insert the hash information into its DFSR database regarding the matched.dll file. If these matched, there is no reason to replicate the file.
+ fid 0x120000000000B7
+ usn 0x2a4cc38
+ uidVisible 0
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 20080910 20:22:22.071 GMT
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F2A45CDC-59A7-472A-AECC-FD10628F046B}-v10
+ uid {F2A45CDC-59A7-472A-AECC-FD10628F046B}-v10 << the downstream server is now using the UID and GVSN information from the upstream server. Whatever was there locally before is discarded.
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 (I)
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 17:16:00.023 GMT (0x1c91368e53c4ffb)
+ createTime 20080910 17:16:00.023 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 12DEDDE4-FEE039FA-807CA87E-6A3BD51A << note the match from the hash on the upstream server in previous log entries.
+ similarity 331B142E-053C3835-0A240739-230C1618
+ name matched.dll
+
<Downstream> 20080910 16:22:32.244 3924 MEET 1207 Meet::Install Retries:0 updateName:samenameonly.dll uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf updateType:remote << metadata about the samenameonly.dll file is being sent.
<Downstream> 20080910 16:22:32.244 3924 MRSH 4615 Marshaller::Marshal FileAttrs in metadata : 0x20
<Downstream> 20080910 16:22:32.244 3924 CSMG 4844 ContentSetManager::UpdateHash LDB Updating ID Record: << Hash must be recorded in DB for the files in order to compare with an upstream copy
+ fid 0x400000000BBDD
+ usn 0x2a4f650
+ uidVisible 0
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 20080910 20:22:22.071 GMT
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F2A45CDC-59A7-472A-AECC-FD10628F046B}-v12
+ uid {F2A45CDC-59A7-472A-AECC-FD10628F046B}-v12
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 (I)
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 17:45:57.275 GMT (0x1c9136d147b4a51)
+ createTime 20080910 17:16:00.023 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash D2FCA79F-B5F5B8D1-1DA18CD4-2BFE9249 << note that the hash does not match what was on the upstream server version of the file.
+ similarity 06190401-382A030F-142D3A1A-10243A3F
+ name samenameonly.dll
+
<Downstream> 20080910 16:22:32.244 2600 UPMG 418 UpdateWorker::ConsumeUpdates No pending updates. connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}
<Downstream> 20080910 16:22:32.259 1708 MEET 6746 Meet::UpdateIdRecord LDB Updating ID Record: << the ‘matched.dll’ database entry must now be updated to include the new GVSN/UID from the upstream
+ fid 0x120000000000B7
+ usn 0x2a4cc38
+ uidVisible 1
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 20080910 20:22:22.071 GMT
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F2A45CDC-59A7-472A-AECC-FD10628F046B}-v10
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 (I)
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 17:16:00.023 GMT (0x1c91368e53c4ffb)
+ createTime 20080910 17:15:05.932 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 12DEDDE4-FEE039FA-807CA87E-6A3BD51A
+ similarity 331B142E-053C3835-0A240739-230C1618
+ name matched.dll
+
<Downstream> 20080910 16:22:32.259 1708 NTFS 1257 NtfsFileSystem::SetAttributes oldFileAttributes:0x20 newFileAttributes:0x20
<Downstream> 20080910 16:22:32.259 1708 MEET 6746 Meet::UpdateIdRecord LDB Updating ID Record: << UID/GVSN synced in database on downstream
+ fid 0x120000000000B7
+ usn 0x2a60908
+ uidVisible 1
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 16010101 00:00:00.000 GMT
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11 << GVSN/UID match now on the downstream partner for the ‘matched.dll’ file
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 17:15:13.698 GMT (0x1c91368c99fb791)
+ createTime 20080910 17:15:05.932 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 12DEDDE4-FEE039FA-807CA87E-6A3BD51A
+ similarity 331B142E-143C3835-0A240739-230C1618
+ name matched.dll
+
<Downstream> 20080910 16:22:32.259 1708 MEET 3589 Meet::InstallMove -> DONE Install-move completed updateName:matched.dll uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} << the ‘replication’ of the matched.dll file is now completed. This means that all the metadata about this file is now in sync between the upstream and downstream servers.
<Downstream> 20080910 16:22:32.259 1708 INCO 5897 InConnection::UpdateProcessed Received Update. updatesLeft:1 processed:2 failures:0 sessionId:1 open:0 updateType:0 processStatus:0 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf update: << All work for the ‘matched.dll’ is now done.
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v11
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 P
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 17:15:13.698 GMT (0x1c91368c99fb791)
+ createTime 20080910 17:15:05.932 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 12DEDDE4-FEE039FA-807CA87E-6A3BD51A
+ similarity 331B142E-143C3835-0A240739-230C1618
+ name matched.dll
+
<Downstream> 20080910 16:22:32.259 1708 UPMG 418 UpdateWorker::ConsumeUpdates No pending updates. connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}
<Downstream> 20080910 16:22:32.259 3924 MEET 6746 Meet::UpdateIdRecord LDB Updating ID Record: << Now the DFSR database record must be updated for the ‘samenameonly.dll’
+ fid 0x400000000BBDD
+ usn 0x2a4f650
+ uidVisible 1
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 20080910 20:22:22.071 GMT
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F2A45CDC-59A7-472A-AECC-FD10628F046B}-v12 << GVSN does not yet match
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 << UID now set to match via some replication of the metadata
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 (I)
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 17:45:57.275 GMT (0x1c9136d147b4a51)
+ createTime 20080910 17:15:05.916 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash D2FCA79F-B5F5B8D1-1DA18CD4-2BFE9249 << Hash does not match
+ similarity 06190401-382A030F-142D3A1A-10243A3F
+ name samenameonly.dll
+
<Downstream> 20080910 16:22:32.259 3924 MEET 5674 Meet::LocalDominates Conflicting gvsn:{F2A45CDC-59A7-472A-AECC-FD10628F046B}-v12 updateName:samenameonly.dll uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf << Conflict resolution must occur as the downstream server will lose all conflicts in an initial sync scenario. Review the ‘Conflicted file’ scenario above for more details. Remember that this event can be misleading as it implies the local copy of the file is going to win – this is just the function name, not the actual action.
<Upstream> 20080910 16:22:32.267 2304 OUTC 784 OutConnection::OpenFile Received request for update: << Upstream server receives a request from downstream to replicate the samenameonly.dll because they do not match
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 P
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 19:57:01.398 GMT (0x1c9137f63dd4325)
+ createTime 20080910 17:15:05.916 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 72D6A531-D011696C-D6D0983A-D953F419
+ similarity 06190414-382A030F-142D3A1A-10243A3F
+ name samenameonly.dll
+ rdcDesired:1 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} rgName:SeededRG
<Downstream> 20080910 16:22:32.337 3924 MRSH 4615 Marshaller::Marshal FileAttrs in metadata : 0x20
<Downstream> 20080910 16:22:32.431 3924 ASYN 510 AsyncUnbufferedFileWriter::Close Async WRITE Statistics:
<Downstream> 20080910 16:22:32.431 3924 STAG 799 StageWriter::CompleteDownloadStage Completed download or stage file 12-{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12-{F2A45CDC-59A7-472A-AECC-FD10628F046B}-v12-Downloaded.frx
<Downstream> 20080910 16:22:32.540 3924 ASYN 510 AsyncUnbufferedFileWriter::Close Async WRITE Statistics:
<Downstream> 20080910 16:22:32.540 3924 RDCX 1811 Rdc::SyncClientState::Flush Rdc Need Assembler Statistics: uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 fileName:samenameonly.dll connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} << Because the file was pre-seeded, we have the option to use RDC in this scenario (unlike a raw initial sync with no data on the downstream partner). Upstream requests RDC bits based on signatures exchanged previously.
+ TOTAL
+ Compression Ratio 51
+ Target Uncompress Size 69847
+ Target Compress Size 34383
+ Bytes Received 3444 Signatures: 528 Data: 2916
+ Signature Bytes Received 528 528
+ Number of remote calls 4 Signatures: 1 Needs: 1 Data: 2
+ SEED
+ Rdc Need Size 0 0
+ Xpress Blocks 9 9
+ Uncompressed Xpress Blocks 2 2
+ Blocks copied to target 7 7
+ SOURCE
+ Rdc Need Size 4106 0
+ Xpress Blocks 2 0
+ Uncompressed Xpress Blocks 1 0
+ Blocks copied to target 0 0
<Downstream> 20080910 16:22:32.540 3924 INCO 5610 InConnection::LogTransferActivity Received RDCGET uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 fileName:samenameonly.dll connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} stagedSize:34495 << RDC information logged for performance monitoring purposes
<Downstream> 20080910 16:22:32.540 3924 ASYN 1291 AsyncUnbufferedFileReader::Close Async READ Statistics:
<Downstream> 20080910 16:22:32.540 3924 STAG 799 StageWriter::CompleteDownloadStage Completed download or stage file 12-{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12-{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12-Downloaded.frx << the updated file is reassembled in the downstream staging folder. Only the different bits were sent over the wire
<Downstream> 20080910 16:22:32.540 3924 MRSH 3959 MarshalContext::Initialize Create file:[samenameonly-{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12.dll] with attributes:0x20
<Downstream> 20080910 16:22:32.540 3924 MEET 2585 Meet::TransferToInstalling Transferring content from staging area into Installing updateName:samenameonly.dll uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf << the file is copied to the Installing directory
<Downstream> 20080910 16:22:32.540 1708 STAG 108 StagingCleanupTask::Run Start to cleanup private staging directory. csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}
<Downstream> 20080910 16:22:32.540 3924 ASYN 1291 AsyncUnbufferedFileReader::Close Async READ Statistics:
<Downstream> 20080910 16:22:32.540 3924 MEET 2032 Meet::Download Download Succeeded : true updateName:samenameonly.dll uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} << replication of the file is complete as far as the upstream is concerned
<Downstream> 20080910 16:22:32.540 3924 MEET 5674 Meet::LocalDominates Conflicting gvsn:{F2A45CDC-59A7-472A-AECC-FD10628F046B}-v12 updateName:samenameonly.dll uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf << the file is going to be conflicted and the original downstream version moved into the ConflictAndDeleted folder.
<Downstream> 20080910 16:22:32.540 3924 MEET 2772 Meet::InstallRename Move out previous version of same file updateName:samenameonly.dll uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf << older file to be supplanted
<Downstream> 20080910 16:22:32.540 3924 MEET 4867 Meet::MoveOut Moving contents and children out of replica. newName:samenameonly-{F2A45CDC-59A7-472A-AECC-FD10628F046B}-v12.dll updateName:samenameonly.dll uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf record: << older version of the file is now being moved to the ConflictAndDeleted folder. The ConflictAndDeletedManifest.xml will be updated.
+ fid 0x400000000BBDD
+ usn 0x2a4f650
+ uidVisible 1
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 0
+ meetReanimated 0
+ recUpdateTime 20080910 20:22:22.071 GMT
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F2A45CDC-59A7-472A-AECC-FD10628F046B}-v12
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 (I)
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 17:45:57.275 GMT (0x1c9136d147b4a51)
+ createTime 20080910 17:15:05.916 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash D2FCA79F-B5F5B8D1-1DA18CD4-2BFE9249
+ similarity 06190401-382A030F-142D3A1A-10243A3F
+ name samenameonly.dll
+
<Downstream> 20080910 16:22:32.540 3924 EVNT 725 EventLog::Report Logging eventId:4412 parameterCount:9 << event logged in the DFSR event log marking the conflict operation having occurred and this downstream server as having lost.
<Downstream> 20080910 16:22:32.540 3924 EVNT 745 EventLog::Report eventId:4412 parameter1:7F8CFBB0-4973-4B53-AAD5-3328870036FB
<Downstream> 20080910 16:22:32.540 3924 EVNT 745 EventLog::Report eventId:4412 parameter2:C:\preseededrf\samenameonly.dll
<Downstream> 20080910 16:22:32.540 3924 EVNT 745 EventLog::Report eventId:4412 parameter3:C:\preseededrf
<Downstream> 20080910 16:22:32.540 3924 EVNT 745 EventLog::Report eventId:4412 parameter4:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12
<Downstream> 20080910 16:22:32.540 3924 EVNT 745 EventLog::Report eventId:4412 parameter5:preseededrf
<Downstream> 20080910 16:22:32.540 3924 EVNT 745 EventLog::Report eventId:4412 parameter6:SeededRG
<Downstream> 20080910 16:22:32.540 3924 EVNT 745 EventLog::Report eventId:4412 parameter7:19AFE009-299A-417C-A0AA-E43178ECC158
<Downstream> 20080910 16:22:32.540 3924 EVNT 745 EventLog::Report eventId:4412 parameter8:FFB459B6-EB2D-4DEB-911F-D948C4FDEF6E
<Downstream> 20080910 16:22:32.540 3924 EVNT 745 EventLog::Report eventId:4412 parameter9:samenameonly-{F2A45CDC-59A7-472A-AECC-FD10628F046B}-v12.dll
<Downstream> 20080910 16:22:32.540 3924 MEET 2837 Meet::InstallRename File moved. rootVolume:{3C84BB07-22D1-11DD-862B-806E6F6E6963} parentFid:0x100000000000B3 fidInInstalling:0x400000000B9F3 usn:0x2a631d0 updateName:samenameonly.dll uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf << The new version of the file is moved into the replicated folder.
<Downstream> 20080910 16:22:32.540 3924 MEET 6746 Meet::UpdateIdRecord LDB Updating ID Record: << DFSR database updated to reflect all current information about the file now that it is fully replicated and available to end users.
+ fid 0x400000000B9F3
+ usn 0x2a631d0
+ uidVisible 1
+ filtered 0
+ journalWrapped 0
+ slowRecoverCheck 0
+ pendingTombstone 0
+ internalUpdate 0
+ dirtyShutdownMismatch 0
+ meetInstallUpdate 1
+ meetReanimated 0
+ recUpdateTime 16010101 00:00:00.000 GMT
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 << Note GVSN and UID now match and are identical to what was on the upstream server
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 19:57:01.398 GMT (0x1c9137f63dd4325)
+ createTime 20080910 17:15:05.916 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 72D6A531-D011696C-D6D0983A-D953F419 << Hash now matches upstream
+ similarity 06190414-382A030F-142D3A1A-10243A3F
+ name samenameonly.dll
+
<Downstream> 20080910 16:22:32.540 3924 MEET 2949 Meet::InstallRename -> DONE Install-rename completed updateName:samenameonly.dll uid:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 gvsn:{F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}
<Downstream> 20080910 16:22:32.540 3924 INCO 5897 InConnection::UpdateProcessed Received Update. updatesLeft:0 processed:3 failures:0 sessionId:1 open:0 updateType:0 processStatus:0 connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf update:
+ present 1
+ nameConflict 0
+ attributes 0x20
+ ghostedHeader 0
+ data 0
+ gvsn {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12
+ uid {F96F1AF0-1F39-4EDB-8493-590B7A9E44EC}-v12
+ parent {7F8CFBB0-4973-4B53-AAD5-3328870036FB}-v1
+ fence 16010101 00:00:00.000 P
+ clockDecrementedInDirtyShutdown 0
+ clock 20080910 19:57:01.398 GMT (0x1c9137f63dd4325)
+ createTime 20080910 17:15:05.916 GMT
+ csId {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ hash 72D6A531-D011696C-D6D0983A-D953F419
+ similarity 06190414-382A030F-142D3A1A-10243A3F
+ name samenameonly.dll
+
<Downstream> 20080910 16:22:32.540 3924 ISYN 65 InitialSyncManager::ReturnToken InitialSync sync step finished. Delete all session tasks.
<Downstream> 20080910 16:22:32.540 3924 CSMG 2746 ContentSetManager::InitialSyncStepSyncComplete InitialSync sync step completed. csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} transition from InitialSync(Sync) to InitialSync(Cleanup).
<Downstream> 20080910 16:22:32.540 3924 CSMG 2897 ContentSetManager::SetContentSetState Set content set state. csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} newState:InitialSync(Cleanup) (auth:0)
<Downstream> 20080910 16:22:32.540 3924 LDBX 4062 Ldb::Update Updating contentSetRecord:
+ contentSetId: {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ memberId: {FFB459B6-EB2D-4DEB-911F-D948C4FDEF6E}
+ state: InitialSync(Cleanup)
+ startVersion: v9
+ authRebuilding: 0
+ stageVolumeSerialNumber: 48e8de48e8de33c2
+ stageFid: 0x400000000B9EE
+ isTombstone: 0
+ readOnlySince: 16010101 00:00:00.000
+ beingDeleted: 0
+ dbLossRecover: 0
+
<Downstream> 20080910 16:22:32.540 3924 INCO 6194 InConnection::CommitSession Connection in sync connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf commitedSessionsWithUpdateFailures:0
<Downstream> 20080910 16:22:32.540 3924 UPMG 418 UpdateWorker::ConsumeUpdates No pending updates. connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}
<Downstream> 20080910 16:22:32.540 1708 CSMG 1757 ContentSetManager::Run csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf state:InitialSync(Sync)
<Downstream> 20080910 16:22:32.540 1708 UPMG 535 UpdateManager::Finalize Finalizing UpdateManager connId:{EB1242D3-E048-4212-AECF-3C703EE2CC92} csName:preseededrf csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}
<Downstream> 20080910 16:22:32.540 1708 CSMG 1779 ContentSetManager::Run Scheduling restart now csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf ptr:00ED9340
<Downstream> 20080910 16:22:32.540 1708 CSMG 1757 ContentSetManager::Run csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf state:InitialSync(Sync)
<Downstream> 20080910 16:22:32.540 1708 CSMG 837 ContentSetManager::Initialize csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf ptr:00ED9340
<Downstream> 20080910 16:22:32.540 1708 CSMG 2262 ContentSetManager::CheckContentSetState Updating content set record csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} csName:preseededrf ghosted:0 readOnly:0 readOnlySince:16010101 00:00:00.000
<Downstream> 20080910 16:22:32.540 1708 CSMG 2298 ContentSetManager::CheckContentSetState Content set csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} state:InitialSync(Cleanup)
<Downstream> 20080910 16:22:32.556 1708 STAG 2600 Staging::ScanStagingDirectory Staging space usage is: 40960
<Downstream> 20080910 16:22:32.556 1708 CSMG 2769 ContentSetManager::StartInitialSyncCleanup csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} << now any pre-existing files must be removed.
<Downstream> 20080910 16:22:32.556 3924 ISYN 97 InitialSyncCleanupTask::Run csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB}
<Downstream> 20080910 16:22:32.556 3924 ISYN 364 InitialSyncCleanupTask::MoveOut Moving \\.\C:\preseededrf\onlydownstream.dll to pre-existing:0x400000000B9F4 csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} << the ‘onlydownstream.dll’ file (which did not exist on the downstream) is now moved into PreExisting folder and the PreExistingManifest.XML is updated.
<Downstream> 20080910 16:22:32.556 3924 CSMG 5884 ContentSetManager::DeleteChildren LDB Deleting ID Record. uid:{F2A45CDC-59A7-472A-AECC-FD10628F046B}-v11 << Any references to the preexisting file are removed from the DFSR database.
<Downstream> 20080910 16:22:32.556 3924 CSMG 2785 ContentSetManager::InitialSyncStepCleanupComplete InitialSync cleanup step completed. csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} transition from InitialSync(Cleanup) to Normal << no further preexisting files found.
<Downstream> 20080910 16:22:32.556 3924 EVNT 725 EventLog::Report Logging eventId:4104 parameterCount:6 << A 4104 event is logged in the DFSR event log to show that the initial sync is done downstream and the server is entering normal replication mode for this content set.
<Downstream> 20080910 16:22:32.556 3924 EVNT 745 EventLog::Report eventId:4104 parameter1:7F8CFBB0-4973-4B53-AAD5-3328870036FB
<Downstream> 20080910 16:22:32.556 3924 EVNT 745 EventLog::Report eventId:4104 parameter2:C:\preseededrf
<Downstream> 20080910 16:22:32.556 3924 EVNT 745 EventLog::Report eventId:4104 parameter3:preseededrf
<Downstream> 20080910 16:22:32.556 3924 EVNT 745 EventLog::Report eventId:4104 parameter4:SeededRG
<Downstream> 20080910 16:22:32.556 3924 EVNT 745 EventLog::Report eventId:4104 parameter5:19AFE009-299A-417C-A0AA-E43178ECC158
<Downstream> 20080910 16:22:32.556 3924 EVNT 745 EventLog::Report eventId:4104 parameter6:FFB459B6-EB2D-4DEB-911F-D948C4FDEF6E
<Downstream> 20080910 16:22:32.556 3924 CSMG 2897 ContentSetManager::SetContentSetState Set content set state. csId:{7F8CFBB0-4973-4B53-AAD5-3328870036FB} newState:Normal (auth:0) << state now normal, no more fencing or initial sync.
<Downstream> 20080910 16:22:32.556 3924 LDBX 4062 Ldb::Update Updating contentSetRecord:
+ contentSetId: {7F8CFBB0-4973-4B53-AAD5-3328870036FB}
+ memberId: {FFB459B6-EB2D-4DEB-911F-D948C4FDEF6E}
+ state: Normal
+ startVersion: v9
+ authRebuilding: 0
+ stageVolumeSerialNumber: 48e8de48e8de33c2
+ stageFid: 0x400000000B9EE
+ isTombstone: 0
+ readOnlySince: 16010101 00:00:00.000
+ beingDeleted: 0
+ dbLossRecover: 0
+
<Downstream> 20080910 16:22:32.556 3924 CSMG 2829 ContentSetManager::InitialSyncComplete Finished initial sync. csId: {7F8CFBB0-4973-4B53-AAD5-3328870036FB}. transition from InitialSync(Cleanup) to Normal << initial sync is done.
Understanding DFSR debug logging (Part 1: Logging Levels, Log Format, GUID’s)
Understanding DFSR debug logging (Part 2: Nested Fields, Module ID's)
Understanding DFSR debug logging (Part 3: The Log Scenario Format, File Added to Replicated Folder ....
Understanding DFSR debug logging (Part 4: A Very Small File Added to Replicated Folder on Windows S....
Understanding DFSR debug logging (Part 5: File Modified on Windows Server 2003 R2)
Understanding DFSR debug logging (Part 6: Microsoft Office Word 97-2003 File Modified on Windows Se....
Understanding DFSR debug logging (Part 7: Microsoft Office Word 2007 File Modified on Windows Serve....
Understanding DFSR debug logging (Part 8: File Deleted from Windows Server 2003 R2)
Understanding DFSR debug logging (Part 9: File is Renamed on Windows Server 2003 R2)
Understanding DFSR debug logging (Part 10: File Conflicted between two Windows Server 2008)
Understanding DFSR debug logging (Part 11: Directory created on Windows Server 2003 R2)
Understanding DFSR debug logging (Part 12: Domain Controller Bind and Config Polling on Windows Ser....
Understanding DFSR debug logging (part 13: A New Replication Group and Replicated Folder between tw....
Understanding DFSR debug logging (Part 14: A sharing violation due to a file locked upstream betwee....
Understanding DFSR debug logging (Part 15: Pre-Seeded Data Usage during Initial Sync)
Understanding DFSR debug logging (Part 16: File modification with RDC in very granular detail (uses....
Understanding DFSR debug logging (Part 17: Replication failing because of blocked RPC ports (uses d....
Understanding DFSR debug logging (Part 18: LDAP queries failing due to network (uses debug severity....
Understanding DFSR debug logging (Part 19: File Blocked Inbound by a File Screen Filter Driver (use....
Understanding DFSR debug logging (Part 20: Skipped temporary and filtered files (uses debug severit....
Understanding DFSR debug logging (Part 21: File replication performance from throttling (uses debug....
You must be a registered user to add a comment. If you've already registered, sign in. Otherwise, register and sign in.