[Info   @13:06:19.078] [2012-03-24 12:57:12Z][Informational] Creating dictionary with 5 initial tokens:
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] 	FinalHostState => Started
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] 	DataTierConnectionString => Data Source=WIN-E3HU5V1N1FE;Initial Catalog=Tfs_TFSCollection;Integrated Security=True
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] 	CollectionName => TFSCollection
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] 	InstanceId => c3e1d031-44d3-46e5-82e3-25de3b694e41
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] 	DefaultDatabase (Value is null.)
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] Creating dictionary with 3 initial items:
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] 	CollectionProperties => c3e1d031-44d3-46e5-82e3-25de3b694e41 (TFSCollection)
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] 	RequestingIdentity => IdentityDescriptor (IdentityType: System.Security.Principal.WindowsIdentity; Identifier: S-1-5-21-3802025213-2559120611-2110391049-500)
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] 	DatabaseMap => System.Collections.Generic.Dictionary`2[System.String,System.String]
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] Acquiring servicing job lock 'Servicing-Configuration'
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] Acquiring servicing job lock 'Servicing-c3e1d031-44d3-46e5-82e3-25de3b694e41'
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] Adding item. Key: ServiceAccount. Value: IdentityDescriptor (IdentityType: System.Security.Principal.WindowsIdentity; Identifier: S-1-5-19).
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] Setting token. Key: JobId. Value: bab42fbc-974a-4ee1-a03c-c0b21c436ac3.
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] TFS_SERVICE_LEVEL is set to 'Tfs2010.SP1.KB2643415.P#10' on Tfs_TFSConfiguration database.
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] TFS_SERVICE_LEVEL is set to 'Tfs2010.SP1.KB2643415.P#10' on Tfs_TFSCollection database.
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] TFS_SERVICE_LEVEL is set to 'Tfs2010.SP1.KB2643415.P#10' on Tfs_TFSCollection database.
[Info   @13:06:19.079] [2012-03-24 12:57:13Z][Informational] Setting item. Key: OriginalCollectionId. Value: c3e1d031-44d3-46e5-82e3-25de3b694e41.
[Info   @13:06:19.081] [2012-03-24 12:57:13Z] Validating servicing step Validate Collection Properties. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.081] [2012-03-24 12:57:13Z][Informational] Step Performer: Framework
[Info   @13:06:19.081] [2012-03-24 12:57:13Z][Informational] Step Type: ValidateCollectionProperties
[Info   @13:06:19.081] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Validate Collection Properties was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Validating servicing step Update the collection connection strings. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Performer: Framework
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Type: UpdateAttachCollectionConnectionStrings
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Update the collection connection strings was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Validating servicing step Register the collection with the application. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Performer: Framework
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Type: RegisterCollection
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] TargetRequestContext found: False.
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] TemporaryTargetServiceHost found: False.
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Register the collection with the application was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Validating servicing step Attach identity domain. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Performer: Framework
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Type: MoveIdentityDomain
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Attach identity domain was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Validating servicing step Add core identities to groups. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Performer: Framework
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Type: AddCoreIdentities
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Add core identities to groups was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Validating servicing step Perform attach for collection location service. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Performer: Framework
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Type: PerformAttachForLocationService
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Perform attach for collection location service was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Validating servicing step Add the catalog data for the attached collection. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Performer: Framework
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Type: AttachCollectionCatalogData
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Add the catalog data for the attached collection was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Validating servicing step Add the job queue entries for the attached collection. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Performer: Framework
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Type: AttachCollectionJobQueueEntries
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Add the job queue entries for the attached collection was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Validating servicing step Add the catalog data for the team projects. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsIntegration)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Performer: Integration
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Type: AddTeamProjectCatalogEntriesForAttach
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Add the catalog data for the team projects was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsIntegration)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Validating servicing step Grant Permissions for global SharePoint group. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsIntegration)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Performer: SharePoint
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Type: GrantPermissions
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Grant Permissions for global SharePoint group was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsIntegration)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Validating servicing step Update TFS Group Security identifiers. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsWorkItemTracking)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Performer: WorkItemTracking
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Type: AttachIdentitiesToNewInstanceOrCollection
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Update TFS Group Security identifiers was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsWorkItemTracking)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Validating servicing step Set Sequence ID in Registry. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsWorkItemTracking)
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Performer: WorkItemTracking
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Type: SetSequenceIdInRegistry
[Info   @13:06:19.082] [2012-03-24 12:57:13Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:13Z] Servicing step Set Sequence ID in Registry was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsWorkItemTracking)
[Info   @13:06:19.082] [2012-03-24 12:57:14Z] Validating servicing step Attach SharePoint Settings. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsSharePoint)
[Info   @13:06:19.082] [2012-03-24 12:57:14Z][Informational] Step Performer: SharePoint
[Info   @13:06:19.082] [2012-03-24 12:57:14Z][Informational] Step Type: AttachSharePoint
[Info   @13:06:19.082] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.082] [2012-03-24 12:57:14Z] Servicing step Attach SharePoint Settings was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsSharePoint)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Prepare LabManagement for an attach operation. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsLabExecution)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: LabManagement
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: RestoreLabDataOnAttach
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Prepare LabManagement for an attach operation was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsLabExecution)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Prepare Build for an attach operation. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsBuild)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: Build
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: OnAttach
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Prepare Build for an attach operation was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsBuild)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Attach the Test Management Catalog Entries. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsTestManagement)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: TestManagement
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: AttachCatalogEntries
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Attach the Test Management Catalog Entries was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsTestManagement)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Attach the Project Server Catalog Entries. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsProjectServer)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: ProjectServer
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: AttachCatalogEntries
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Attach the Project Server Catalog Entries was validated. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsProjectServer)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Remove the snapshot database stamp if it exists. (ServicingOperation: AttachCollection; Step group: CleanUpSnapshot)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: RemoveSnapshotDatabaseStamp
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Remove the snapshot database stamp if it exists was validated. (ServicingOperation: AttachCollection; Step group: CleanUpSnapshot)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Drop existing framework snapshot schema if it exists. (ServicingOperation: AttachCollection; Step group: CleanUpSnapshot)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: ExecuteSql
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: <SqlStepData category="Framework" resource="TfsSnapshotDropSchema.sql" />
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: DatabaseCategory => Framework
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: SqlResource => TfsSnapshotDropSchema.sql
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: BatchTimeout => -1
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: Parameters => 
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: RequiresExclusiveDatabaseLock => False
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Drop existing framework snapshot schema if it exists was validated. (ServicingOperation: AttachCollection; Step group: CleanUpSnapshot)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Remove any service definitions that the catalog entries created. (ServicingOperation: AttachCollection; Step group: CleanUpSnapshot)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: CleanUpSnapshottedServiceDefinitions
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Remove any service definitions that the catalog entries created was validated. (ServicingOperation: AttachCollection; Step group: CleanUpSnapshot)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Remove the Catalog Property Artifact Kind. (ServicingOperation: AttachCollection; Step group: CleanUpSnapshot)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: DeletePropertyArtifactKind
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: <guid>AD359EF1-FDE8-4c78-B19F-4AB30E74E53C</guid>
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Remove the Catalog Property Artifact Kind was validated. (ServicingOperation: AttachCollection; Step group: CleanUpSnapshot)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Remove the snapshot registry entries. (ServicingOperation: AttachCollection; Step group: CleanUpSnapshot)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: CleanUpSnapshotRegistry
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Remove the snapshot registry entries was validated. (ServicingOperation: AttachCollection; Step group: CleanUpSnapshot)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Update default collection setting. (ServicingOperation: AttachCollection; Step group: FinishCollectionServicing)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: UpdateDefaultCollection
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Update default collection setting was validated. (ServicingOperation: AttachCollection; Step group: FinishCollectionServicing)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Remove the collection initializing database stamp. (ServicingOperation: AttachCollection; Step group: FinishCollectionServicing)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: RemoveCollectionInitializingDatabaseStamp
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Remove the collection initializing database stamp was validated. (ServicingOperation: AttachCollection; Step group: FinishCollectionServicing)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Write service level to stamp. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Type: WriteCollectionServiceLevelToStamp
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Servicing step Write service level to stamp was validated. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z] Validating servicing step Set Recovery Model to Simple. (ServicingOperation: StartInstallUpdates; Step group: SetRecoveryModelToSimple)
[Info   @13:06:19.083] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Type: ExecuteSql
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: <SqlStepData category="Framework" resource="SetRecoveryModelToSimple.sql" requiresExclusiveDatabaseLock="true" />
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: DatabaseCategory => Framework
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: SqlResource => SetRecoveryModelToSimple.sql
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: BatchTimeout => -1
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: Parameters => 
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: RequiresExclusiveDatabaseLock => True
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Servicing step Set Recovery Model to Simple was validated. (ServicingOperation: StartInstallUpdates; Step group: SetRecoveryModelToSimple)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Validating servicing step Turn off RCSI. (ServicingOperation: StartInstallUpdates; Step group: SetRecoveryModelToSimple)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Type: SetRcsi
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: <SetRcsiStepData category="Framework" enable="false" />
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Servicing step Turn off RCSI was validated. (ServicingOperation: StartInstallUpdates; Step group: SetRecoveryModelToSimple)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Validating servicing step Tfs2010SP1KB2643415Install SQL servicing step. (ServicingOperation: Tfs2010SP1KB2643415Install; Step group: Tfs2010SP1KB2643415Install)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Type: ExecuteSql
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: <SqlStepData category="Framework" resource="KB2643415CollectionInstall.sql" />
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: DatabaseCategory => Framework
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: SqlResource => KB2643415CollectionInstall.sql
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: BatchTimeout => -1
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: Parameters => 
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: RequiresExclusiveDatabaseLock => False
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Servicing step Tfs2010SP1KB2643415Install SQL servicing step was validated. (ServicingOperation: Tfs2010SP1KB2643415Install; Step group: Tfs2010SP1KB2643415Install)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Validating servicing step Restore Recovery Model. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Type: ExecuteSql
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: <SqlStepData category="Framework" resource="RestoreOriginalRecoveryModel.sql" requiresExclusiveDatabaseLock="true" />
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: DatabaseCategory => Framework
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: SqlResource => RestoreOriginalRecoveryModel.sql
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: BatchTimeout => -1
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: Parameters => 
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: RequiresExclusiveDatabaseLock => True
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Servicing step Restore Recovery Model was validated. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Validating servicing step Turn on RCSI. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Type: SetRcsi
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: <SetRcsiStepData category="Framework" enable="true" />
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Servicing step Turn on RCSI was validated. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Validating servicing step Copy service level stamp. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Type: CopyServiceLevelStamp
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Servicing step Copy service level stamp was validated. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Validating servicing step Stamp collection database product version. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Type: StampCollectionDatabaseProductVersion
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Servicing step Stamp collection database product version was validated. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Validating servicing step Update Schema Version. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Type: WriteSchemaVersion
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Step Data: 
[Info   @13:06:19.084] [2012-03-24 12:57:14Z] Servicing step Update Schema Version was validated. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)
[Info   @13:06:19.084] [2012-03-24 12:57:14Z][Informational] Setting item. Key: OriginalCollectionId. Value: c3e1d031-44d3-46e5-82e3-25de3b694e41.
[Info   @13:06:19.085] [2012-03-24 12:57:14Z] Executing servicing step Validate Collection Properties. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.085] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.085] [2012-03-24 12:57:14Z][Informational] Step Type:      ValidateCollectionProperties
[Info   @13:06:19.085] [2012-03-24 12:57:14Z][Informational] Step Data Text: 
[Info   @13:06:19.085] [2012-03-24 12:57:14Z] Servicing step Validate Collection Properties passed. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.085] [2012-03-24 12:57:14Z] Executing servicing step Update the collection connection strings. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.085] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.085] [2012-03-24 12:57:14Z][Informational] Step Type:      UpdateAttachCollectionConnectionStrings
[Info   @13:06:19.085] [2012-03-24 12:57:14Z][Informational] Step Data Text: 
[Info   @13:06:19.086] [2012-03-24 12:57:14Z][Informational] Setting item. Key: TemporaryTargetServiceHost. Value: c3e1d031-44d3-46e5-82e3-25de3b694e41 (TFSCollection).
[Info   @13:06:19.086] [2012-03-24 12:57:14Z][Informational] Setting item. Key: TargetRequestContext. Value: Microsoft.TeamFoundation.Framework.Server.TeamFoundationRequestContext.
[Info   @13:06:19.086] [2012-03-24 12:57:14Z] Servicing step Update the collection connection strings passed. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.086] [2012-03-24 12:57:14Z] Executing servicing step Register the collection with the application. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.086] [2012-03-24 12:57:14Z][Informational] Step Performer: Framework
[Info   @13:06:19.086] [2012-03-24 12:57:14Z][Informational] Step Type:      RegisterCollection
[Info   @13:06:19.086] [2012-03-24 12:57:14Z][Informational] Step Data Text: 
[Info   @13:06:19.086] [2012-03-24 12:57:14Z][Informational] TargetRequestContext found: True.
[Info   @13:06:19.086] [2012-03-24 12:57:14Z][Informational] TemporaryTargetServiceHost found: True.
[Info   @13:06:19.086] [2012-03-24 12:57:14Z][Informational] Stamping collection database as initializing.
[Info   @13:06:19.086] [2012-03-24 12:57:14Z][Informational] Creating service host for new collection.
[Info   @13:06:19.086] [2012-03-24 12:57:14Z][Informational] Retrieving existing job definition.
[Info   @13:06:19.086] [2012-03-24 12:57:14Z][Informational] ServicingOptions prior to clearing HostMustNotExist and setting HostMustExist: 1
[Info   @13:06:19.086] [2012-03-24 12:57:15Z][Informational] ServicingOptions after clearing HostMustNotExist and setting HostMustExist: 2
[Info   @13:06:19.086] [2012-03-24 12:57:15Z][Informational] Updating job definition.
[Info   @13:06:19.086] [2012-03-24 12:57:15Z][Informational] Retrieving registered service host for new collection.
[Info   @13:06:19.086] [2012-03-24 12:57:15Z][Informational] Creating a servicing target request context
[Info   @13:06:19.086] [2012-03-24 12:57:15Z][Informational] Disposing and removing temporary TargetRequestContext.
[Info   @13:06:19.086] [2012-03-24 12:57:15Z][Informational] Removing item. Key: TargetRequestContext.
[Info   @13:06:19.086] [2012-03-24 12:57:15Z][Informational] Disposing and removing TemporaryTargetServiceHost.
[Info   @13:06:19.086] [2012-03-24 12:57:15Z][Informational] Removing item. Key: TemporaryTargetServiceHost.
[Info   @13:06:19.088] [2012-03-24 12:57:15Z][Informational] Putting new servicing target request context in items bag.
[Info   @13:06:19.088] [2012-03-24 12:57:15Z][Informational] Adding item. Key: TargetRequestContext. Value: Microsoft.TeamFoundation.Framework.Server.TeamFoundationRequestContext.
[Info   @13:06:19.088] [2012-03-24 12:57:15Z] Servicing step Register the collection with the application passed. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.088] [2012-03-24 12:57:15Z] Executing servicing step Attach identity domain. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.088] [2012-03-24 12:57:15Z][Informational] Step Performer: Framework
[Info   @13:06:19.088] [2012-03-24 12:57:15Z][Informational] Step Type:      MoveIdentityDomain
[Info   @13:06:19.088] [2012-03-24 12:57:15Z][Informational] Step Data Text: 
[Info   @13:06:19.088] [2012-03-24 12:57:15Z][Informational] Retrieving target request context.
[Info   @13:06:19.088] [2012-03-24 12:57:15Z][Informational] Target request context has framework connection string: Data Source=WIN-E3HU5V1N1FE;Initial Catalog=Tfs_TFSCollection;Integrated Security=True.
[Info   @13:06:19.088] [2012-03-24 12:57:15Z][Informational] Upgrade Identities: Completed creating project scopes
[Info   @13:06:19.088] [2012-03-24 12:57:15Z][Informational] Upgrade Identities: Completed creating groups
[Info   @13:06:19.088] [2012-03-24 12:57:15Z][Informational] Upgrade Identities: Migrating inactive identities
[Info   @13:06:19.088] [2012-03-24 12:57:16Z] Servicing step Attach identity domain passed. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.088] [2012-03-24 12:57:16Z] Executing servicing step Add core identities to groups. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.088] [2012-03-24 12:57:16Z][Informational] Step Performer: Framework
[Info   @13:06:19.088] [2012-03-24 12:57:16Z][Informational] Step Type:      AddCoreIdentities
[Info   @13:06:19.088] [2012-03-24 12:57:16Z][Informational] Step Data Text: 
[Info   @13:06:19.088] [2012-03-24 12:57:16Z][Informational] Retrieving target request context.
[Info   @13:06:19.088] [2012-03-24 12:57:16Z][Informational] Target request context has framework connection string: Data Source=WIN-E3HU5V1N1FE;Initial Catalog=Tfs_TFSCollection;Integrated Security=True.
[Info   @13:06:19.088] [2012-03-24 12:57:18Z] Servicing step Add core identities to groups passed. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.088] [2012-03-24 12:57:18Z] Executing servicing step Perform attach for collection location service. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.088] [2012-03-24 12:57:18Z][Informational] Step Performer: Framework
[Info   @13:06:19.088] [2012-03-24 12:57:18Z][Informational] Step Type:      PerformAttachForLocationService
[Info   @13:06:19.088] [2012-03-24 12:57:19Z][Informational] Step Data Text: 
[Info   @13:06:19.088] [2012-03-24 12:57:19Z][Informational] Retrieving target request context.
[Info   @13:06:19.088] [2012-03-24 12:57:19Z][Informational] Target request context has framework connection string: Data Source=WIN-E3HU5V1N1FE;Initial Catalog=Tfs_TFSCollection;Integrated Security=True.
[Info   @13:06:19.088] [2012-03-24 12:57:19Z] Servicing step Perform attach for collection location service passed. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.088] [2012-03-24 12:57:19Z] Executing servicing step Add the catalog data for the attached collection. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.088] [2012-03-24 12:57:19Z][Informational] Step Performer: Framework
[Info   @13:06:19.088] [2012-03-24 12:57:19Z][Informational] Step Type:      AttachCollectionCatalogData
[Info   @13:06:19.088] [2012-03-24 12:57:19Z][Informational] Step Data Text: 
[Info   @13:06:19.088] [2012-03-24 12:57:19Z][Informational] Retrieving target request context.
[Info   @13:06:19.088] [2012-03-24 12:57:19Z][Informational] Target request context has framework connection string: Data Source=WIN-E3HU5V1N1FE;Initial Catalog=Tfs_TFSCollection;Integrated Security=True.
[Error  @13:06:19.088] [2012-03-24 12:57:19Z][Error] Object reference not set to an instance of an object.
[Info   @13:06:19.088] [2012-03-24 12:57:19Z][Informational] System.NullReferenceException: Object reference not set to an instance of an object.
   at Microsoft.TeamFoundation.Server.Servicing.TFCollection.FrameworkStepPerformer.AttachCollectionCatalogData(String stepData, ServicingContext servicingContext, Boolean validateOnly)
   at Microsoft.TeamFoundation.Framework.Server.TeamFoundationStepPerformerBase.Microsoft.TeamFoundation.Framework.Server.IStepPerformer.PerformStep(String servicingOperation, String stepType, String stepData, ServicingContext servicingContext)
   at Microsoft.TeamFoundation.Framework.Server.ServicingStepDriver.PerformServicingStep(ServicingStep step, ServicingContext servicingContext, ServicingStepGroup group, ServicingOperation servicingOperation, Boolean validateOnly)
[Info   @13:06:19.088] [2012-03-24 12:57:19Z] Servicing step Add the catalog data for the attached collection failed. (ServicingOperation: AttachCollection; Step group: AttachCollection.TfsFramework)
[Info   @13:06:19.088] [2012-03-24 12:57:19Z][Informational] Clearing dictionary, removing all items.
[Error  @13:06:19.089] The servicing operation failed.