TFS 2010 SP1 Default Collection Offline - Servicing Collection : Queued

Question

After upgrading to SP1 on my application tier and my database tier I'm stuck with my DefaultCollection offline.

The status tab tells me that "The project collection is being serviced." But the activity below says that the status is "queued".

If I click Rerun Job it pops a window up with no status and moving progress bar.

---

B.Young

Answer 1

A log file named TPC_DefaultCollection_ApplyPatch_1028_210835 with the following contents

[Info   @03:35:12.458] [2010-10-28 21:08:42Z][Informational] Creating dictionary with 5 initial tokens:

[Info   @03:35:12.458] [2010-10-28 21:08:42Z][Informational] FinalHostState => Started

[Info   @03:35:12.458] [2010-10-28 21:08:42Z][Informational] DataTierConnectionString => Data Source=XXXX;Initial Catalog=Tfs_DefaultCollection;Integrated Security=True

[Info   @03:35:12.458] [2010-10-28 21:08:42Z][Informational] CollectionName => DefaultCollection

[Info   @03:35:12.458] [2010-10-28 21:08:42Z][Informational] InstanceId => ffecacef-c63d-4b4b-89f4-36ae18732658

[Info   @03:35:12.458] [2010-10-28 21:08:42Z][Informational] DefaultDatabase (Value is null.)

[Info   @03:35:12.458] [2010-10-28 21:08:42Z][Informational] Creating dictionary with 3 initial items:

[Info   @03:35:12.458] [2010-10-28 21:08:42Z][Informational] CollectionProperties => ffecacef-c63d-4b4b-89f4-36ae18732658 (DefaultCollection)

[Info   @03:35:12.458] [2010-10-28 21:08:42Z][Informational] RequestingIdentity => IdentityDescriptor (IdentityType: System.Security.Principal.WindowsIdentity; Identifier: S-1-5-21-1446652074-2081759121-1486667791-2505)

[Info   @03:35:12.458] [2010-10-28 21:08:42Z][Informational] DatabaseMap => System.Collections.Generic.Dictionary`2[System.String,System.String]

[Info   @03:35:12.459] [2010-10-28 21:08:42Z][Informational] Acquiring servicing job lock 'Servicing-Configuration'

[Info   @03:35:12.459] [2010-10-28 21:08:42Z][Informational] Acquiring servicing job lock 'Servicing-ffecacef-c63d-4b4b-89f4-36ae18732658'

[Info   @03:35:12.459] [2010-10-28 21:08:42Z][Informational] Adding item. Key: ServiceAccount. Value: IdentityDescriptor (IdentityType: System.Security.Principal.WindowsIdentity; Identifier: S-1-5-21-1446652074-2081759121-1486667791-2505).

[Info   @03:35:12.459] [2010-10-28 21:08:42Z][Informational] Setting token. Key: JobId. Value: 359f5448-2617-4a52-8103-86399a483fea.

[Info   @03:35:12.459] [2010-10-28 21:08:42Z][Informational] Setting item. Key: TargetRequestContext. Value: Microsoft.TeamFoundation.Framework.Server.TeamFoundationRequestContext.

[Info   @03:35:12.459] [2010-10-28 21:08:47Z][Informational] TFS_SERVICE_LEVEL is set to 'Tfs2010.RTM.KB983537.P#5' on Tfs_Configuration database.

[Info   @03:35:12.459] [2010-10-28 21:08:47Z][Informational] TFS_SERVICE_LEVEL is set to 'TFS2010.RTM' on Tfs_DefaultCollection database.

[Info   @03:35:12.459] [2010-10-28 21:08:47Z][Informational] TFS_SERVICE_LEVEL is set to 'TFS2010.RTM' on Tfs_DefaultCollection database.

[Info   @03:35:12.459] [2010-10-28 21:08:47Z][Informational] TFS_SCHEMA_VERSION is set to 'Microsoft Team Foundation Server 2010 (RTM)' on Tfs_DefaultCollection database.

[Info   @03:35:12.462] [2010-10-28 21:08:48Z] Validating servicing step Set Recovery Model to Simple. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.462] [2010-10-28 21:08:48Z][Informational] Step Performer: Framework

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Type: ExecuteSql

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: <SqlStepData category="Framework" resource="SetRecoveryModelToSimple.sql" requiresExclusiveDatabaseLock="true" />

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: DatabaseCategory => Framework

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: SqlResource => SetRecoveryModelToSimple.sql

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: BatchTimeout => -1

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: Parameters => 

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: RequiresExclusiveDatabaseLock => True

[Info   @03:35:12.463] [2010-10-28 21:08:48Z] Servicing step Set Recovery Model to Simple was validated. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.463] [2010-10-28 21:08:48Z] Validating servicing step Turn off RCSI. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Performer: Framework

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Type: SetRcsi

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: <SetRcsiStepData category="Framework" enable="false" />

[Info   @03:35:12.463] [2010-10-28 21:08:48Z] Servicing step Turn off RCSI was validated. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.463] [2010-10-28 21:08:48Z] Validating servicing step Write service level to stamp. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Performer: Framework

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Type: WriteCollectionServiceLevelToStamp

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: 

[Info   @03:35:12.463] [2010-10-28 21:08:48Z] Servicing step Write service level to stamp was validated. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.463] [2010-10-28 21:08:48Z] Validating servicing step Restore Recovery Model. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Performer: Framework

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Type: ExecuteSql

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: <SqlStepData category="Framework" resource="RestoreOriginalRecoveryModel.sql" requiresExclusiveDatabaseLock="true" />

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: DatabaseCategory => Framework

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: SqlResource => RestoreOriginalRecoveryModel.sql

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: BatchTimeout => -1

[Info   @03:35:12.463] [2010-10-28 21:08:48Z][Informational] Step Data: Parameters => 

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Data: RequiresExclusiveDatabaseLock => True

[Info   @03:35:12.464] [2010-10-28 21:08:48Z] Servicing step Restore Recovery Model was validated. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)

[Info   @03:35:12.464] [2010-10-28 21:08:48Z] Validating servicing step Turn on RCSI. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Performer: Framework

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Type: SetRcsi

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Data: <SetRcsiStepData category="Framework" enable="true" />

[Info   @03:35:12.464] [2010-10-28 21:08:48Z] Servicing step Turn on RCSI was validated. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)

[Info   @03:35:12.464] [2010-10-28 21:08:48Z] Validating servicing step Copy service level stamp. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Performer: Framework

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Type: CopyServiceLevelStamp

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Data: 

[Info   @03:35:12.464] [2010-10-28 21:08:48Z] Servicing step Copy service level stamp was validated. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.464] [2010-10-28 21:08:48Z] Validating servicing step Stamp collection database product version. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Performer: Framework

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Type: StampCollectionDatabaseProductVersion

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Data: 

[Info   @03:35:12.464] [2010-10-28 21:08:48Z] Servicing step Stamp collection database product version was validated. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.464] [2010-10-28 21:08:48Z] Validating servicing step Update Schema Version. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Performer: Framework

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Type: WriteSchemaVersion

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Data: 

[Info   @03:35:12.464] [2010-10-28 21:08:48Z] Servicing step Update Schema Version was validated. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.464] [2010-10-28 21:08:48Z] Executing servicing step Set Recovery Model to Simple. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.464] [2010-10-28 21:08:48Z][Informational] Step Performer: Framework

[Info   @03:35:12.465] [2010-10-28 21:08:48Z][Informational] Step Type:      ExecuteSql

[Info   @03:35:12.465] [2010-10-28 21:08:48Z][Informational] Step Data Text: <SqlStepData category="Framework" resource="SetRecoveryModelToSimple.sql" requiresExclusiveDatabaseLock="true" />

[Info   @03:35:12.465] [2010-10-28 21:08:48Z][Informational] Step Data: DatabaseCategory => Framework

[Info   @03:35:12.465] [2010-10-28 21:08:48Z][Informational] Step Data: SqlResource => SetRecoveryModelToSimple.sql

[Info   @03:35:12.465] [2010-10-28 21:08:48Z][Informational] Step Data: BatchTimeout => -1

[Info   @03:35:12.465] [2010-10-28 21:08:48Z][Informational] Step Data: Parameters => 

[Info   @03:35:12.465] [2010-10-28 21:08:48Z][Informational] Step Data: RequiresExclusiveDatabaseLock => True

[Info   @03:35:12.465] [2010-10-28 21:08:48Z][Informational] Executing SetRecoveryModelToSimple.sql with zero sql parameters.

[Info   @03:35:12.465] [2010-10-28 21:08:48Z][Informational] Data source: XXXX

[Info   @03:35:12.465] [2010-10-28 21:08:48Z][Informational] Database name: Tfs_DefaultCollection

[Info   @03:35:12.465] [2010-10-28 21:20:06Z][Informational] SQL Info: Backing up recovery model: RECOVERY_MODEL_BACKUP

Setting recovery model to Simple.

[Info   @03:35:12.465] [2010-10-28 21:20:06Z] Servicing step Set Recovery Model to Simple passed. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.465] [2010-10-28 21:20:06Z] Executing servicing step Turn off RCSI. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.465] [2010-10-28 21:20:06Z][Informational] Step Performer: Framework

[Info   @03:35:12.465] [2010-10-28 21:20:06Z][Informational] Step Type:      SetRcsi

[Info   @03:35:12.465] [2010-10-28 21:20:06Z][Informational] Step Data Text: <SetRcsiStepData category="Framework" enable="false" />

[Info   @03:35:12.465] [2010-10-28 21:20:06Z][Informational] Executing TurnOffRCSI.sql with zero sql parameters.

[Info   @03:35:12.465] [2010-10-28 21:20:06Z][Informational] Data source: XXXX

[Info   @03:35:12.465] [2010-10-28 21:20:06Z][Informational] Database name: Tfs_DefaultCollection

[Info   @03:35:12.465] [2010-10-28 21:23:42Z][Informational] SQL Info: Nonqualified transactions are being rolled back. Estimated rollback completion: 100%.

[Info   @03:35:12.465] [2010-10-28 21:23:43Z] Servicing step Turn off RCSI passed. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.465] [2010-10-28 21:23:43Z] Executing servicing step Write service level to stamp. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.465] [2010-10-28 21:23:43Z][Informational] Step Performer: Framework

[Info   @03:35:12.465] [2010-10-28 21:23:43Z][Informational] Step Type:      WriteCollectionServiceLevelToStamp

[Info   @03:35:12.465] [2010-10-28 21:23:43Z][Informational] Step Data Text: 

[Info   @03:35:12.465] [2010-10-28 21:23:43Z] Servicing step Write service level to stamp passed. (ServicingOperation: StartInstallUpdates; Step group: StartInstallUpdates)

[Info   @03:35:12.465] [2010-10-28 21:23:43Z] Executing servicing step Restore Recovery Model. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)

[Info   @03:35:12.465] [2010-10-28 21:23:43Z][Informational] Step Performer: Framework

[Info   @03:35:12.465] [2010-10-28 21:23:43Z][Informational] Step Type:      ExecuteSql

[Info   @03:35:12.465] [2010-10-28 21:23:43Z][Informational] Step Data Text: <SqlStepData category="Framework" resource="RestoreOriginalRecoveryModel.sql" requiresExclusiveDatabaseLock="true" />

[Info   @03:35:12.465] [2010-10-28 21:23:43Z][Informational] Step Data: DatabaseCategory => Framework

[Info   @03:35:12.465] [2010-10-28 21:23:43Z][Informational] Step Data: SqlResource => RestoreOriginalRecoveryModel.sql

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Step Data: BatchTimeout => -1

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Step Data: Parameters => 

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Step Data: RequiresExclusiveDatabaseLock => True

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Executing RestoreOriginalRecoveryModel.sql with zero sql parameters.

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Data source: XXXX

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Database name: Tfs_DefaultCollection

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] SQL Info: Restoring recovery model to: FULL

[Info   @03:35:12.466] [2010-10-28 21:23:43Z] Servicing step Restore Recovery Model passed. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)

[Info   @03:35:12.466] [2010-10-28 21:23:43Z] Executing servicing step Turn on RCSI. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Step Performer: Framework

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Step Type:      SetRcsi

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Step Data Text: <SetRcsiStepData category="Framework" enable="true" />

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Executing TurnOnRCSI.sql with zero sql parameters.

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Data source: XXXX

[Info   @03:35:12.466] [2010-10-28 21:23:43Z][Informational] Database name: Tfs_DefaultCollection

[Info   @03:35:12.466] [2010-10-28 21:23:45Z][Informational] SQL Info: Nonqualified transactions are being rolled back. Estimated rollback completion: 100%.

[Info   @03:35:12.466] [2010-10-28 21:23:45Z] Servicing step Turn on RCSI passed. (ServicingOperation: FinishInstallUpdates; Step group: RestoreRecoveryModelAndTurnOnRCSI)

[Info   @03:35:12.466] [2010-10-28 21:23:45Z] Executing servicing step Copy service level stamp. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.466] [2010-10-28 21:23:45Z][Informational] Step Performer: Framework

[Info   @03:35:12.466] [2010-10-28 21:23:45Z][Informational] Step Type:      CopyServiceLevelStamp

[Info   @03:35:12.466] [2010-10-28 21:23:45Z][Informational] Step Data Text: 

[Info   @03:35:12.466] [2010-10-28 21:23:45Z] Servicing step Copy service level stamp passed. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.466] [2010-10-28 21:23:45Z] Executing servicing step Stamp collection database product version. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.466] [2010-10-28 21:23:45Z][Informational] Step Performer: Framework

[Info   @03:35:12.466] [2010-10-28 21:23:45Z][Informational] Step Type:      StampCollectionDatabaseProductVersion

[Info   @03:35:12.466] [2010-10-28 21:23:45Z][Informational] Step Data Text: 

[Info   @03:35:12.466] [2010-10-28 21:23:45Z] Servicing step Stamp collection database product version passed. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.466] [2010-10-28 21:23:45Z] Executing servicing step Update Schema Version. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.466] [2010-10-28 21:23:45Z][Informational] Step Performer: Framework

[Info   @03:35:12.466] [2010-10-28 21:23:45Z][Informational] Step Type:      WriteSchemaVersion

[Info   @03:35:12.466] [2010-10-28 21:23:45Z][Informational] Step Data Text: 

[Info   @03:35:12.467] [2010-10-28 21:23:45Z] Servicing step Update Schema Version passed. (ServicingOperation: FinishInstallUpdates; Step group: CopyServiceLevel)

[Info   @03:35:12.467] [2010-10-28 21:23:50Z][Informational] Clearing dictionary, removing all items.

---

B.Young

Answer 2

Problem solved.

I finally got an error that the script could not lock the db to make the necessary changes.

I killed all the active process in SQL Management Studio and restarted the TFSJobAgent.

Job restarted and succeeded immediatly.

---

B.Young

Answer 3

I have just resolved this problem :

• Detach the collection using the TFS console
• Attach back the collection (using the TFS console)