Collection name : NewProducts Operation class : CreateProject Time Zone : Egypt Daylight Time Queue time : 08/06/2026 18:07:52 Start time : 08/06/2026 18:07:53 ==================================================================== [18:07:53.027] Creating dictionary with 2 initial items: [18:07:53.027] CollectionProperties => Value: 89168bf2-ed0c-481f-8740-a34d34c45b46 (NewProducts) [18:07:53.027] RequestingIdentity => Value: System.Security.Principal.WindowsIdentity;S-1-5-21-1489344139-2237543953-4140829407-1116 [18:07:53.027] Acquiring servicing job lock 'Servicing-89168bf2-ed0c-481f-8740-a34d34c45b46' [18:07:53.037] Setting token. Key: JobId. Value: 8b442882-3e7f-461e-89cb-087879edbccc. [18:07:53.037] OperationClass: CreateProject [18:07:53.037] Framework connection string was not found in servicing items [18:07:53.037] Setting item. Key: ConnectionInfo. Value: Data Source=dev;Initial Catalog=AzureDevOps_NewProducts;Integrated Security=True;Encrypt=False. [18:07:53.037] Dbo connection string was not found in servicing items [18:07:53.037] Setting item. Key: DboConnectionInfo. Value: Data Source=dev;Initial Catalog=AzureDevOps_NewProducts;Integrated Security=True;Encrypt=False. [18:07:53.040] Setting token. Key: ServiceLevel. Value: Dev19.M235.2. [18:07:53.040] Setting token. Key: OnlineUpdate. Value: true. [18:07:53.040] Registering the servicing job as running. [18:07:53.043] Registered the job. [18:07:53.043] Setting token. Key: IsL2TestDeployment. Value: false. [18:07:53.043] Setting item. Key: HostedDeployment. Value: False. [18:07:53.043] Adding item. Key: ProcessTemplate. Value: Microsoft.Azure.Boards.ProcessTemplates.LegacyProcessPackage. [18:07:53.047] Adding item. Key: PluginTaskXml. Value: System.Collections.Generic.Dictionary`2[System.String,System.String] { [Microsoft.ProjectCreationWizard.ClassificationAndGroups, [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] ], [Microsoft.ProjectCreationWizard.Classification, [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] ], [Microsoft.ProjectCreationWizard.Groups, [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] ], [Microsoft.ProjectCreationWizard.WorkItemTracking, [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] ], [Microsoft.ProjectCreationWizard.VersionControl, [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] ], [Microsoft.ProjectCreationWizard.Build, [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] ], [Microsoft.ProjectCreationWizard.Lab, [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] ], [Microsoft.ProjectCreationWizard.TestManagement, [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] ], [Microsoft.ProjectCreationWizard.Reporting, [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] [18:07:53.047] ] }. [18:07:53.047] +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++ [18:07:53.047] ++ Executing - Operation: ProjectCreate, Group: ProjectCreate.TfsFramework [18:07:53.047] +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++ [18:07:53.047] Executing step: Provision VssfAdmin [18:07:53.047] Executing step: 'Provision VssfAdmin' Sql.ExecuteSql (1 of 10) [18:07:53.047] SqlResources => upd_ProvisionVssfAdminUser.sql [18:07:53.047] AcquireLock => False [18:07:53.047] InTransaction => False [18:07:53.047] BatchTimeout => 0 [18:07:53.047] SnapshotOnly => False [18:07:53.047] Database: AzureDevOps_NewProducts, Sql Instance: dev [18:07:53.050] SQL Info: @SPID=107 [18:07:53.050] SQL Info: Adding VssfAdmin to the db_owner role [18:07:53.060] Execution of script "upd_ProvisionVssfAdminUser.sql" took 0:00:00.016. [18:07:53.060] Step passed: Provision VssfAdmin. Execution time: 13 milliseconds. [18:07:53.060] [StepDuration] 0.019 [18:07:53.060] +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++ [18:07:53.060] Executing step: Ensure Team Project Tokens [18:07:53.060] Executing step: 'Ensure Team Project Tokens' Framework.EnsureTeamProjectTokens (2 of 10) [18:07:53.060] Begin EnsureTeamProjectTokens. [18:07:53.060] Setting token. Key: ProjectUri. Value: vstfs:///Classification/TeamProject/10695073-313d-463a-9868-09dd2f6215cd. [18:07:53.080] Setting item. Key: RequestingUserContext. Value: Request Context Details [18:07:53.080] Url: [18:07:53.080] Method: [18:07:53.080] Parameters: [18:07:53.080] Activity Id: 9af0838c-09ec-44e0-9949-e3f0eec4c99f [18:07:53.080] Type: Microsoft.TeamFoundation.Framework.Server.DefaultRequestContext [18:07:53.080] User Name: [18:07:53.080] User Agent: [18:07:53.080] Unique Id: 00000000-0000-0000-0000-000000000000. [18:07:53.080] Step passed: Ensure Team Project Tokens. Execution time: 20 milliseconds. [18:07:53.080] [StepDuration] 0.0133 [18:07:53.080] +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++ [18:07:53.080] Executing step: Create the Team Project [18:07:53.080] Executing step: 'Create the Team Project' Framework.CreateTeamProject (3 of 10) [18:07:53.080] Creating a team project named qqqqq using Scrum template. Collection: Scrum, enterprise: 89168bf2-ed0c-481f-8740-a34d34c45b46 (NewProducts) [18:07:53.307] Step passed: Create the Team Project. Execution time: 227 milliseconds. [18:07:53.307] [StepDuration] 0.2268 [18:07:53.307] [GroupDuration] 0.2622 [18:07:53.307] +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++ [18:07:53.307] ++ Executing - Operation: ProjectCreate, Group: ProjectCreate.TfsIntegration [18:07:53.307] +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++ [18:07:53.307] Executing step: Create the Team Project [18:07:53.307] Executing step: 'Create the Team Project' Integration.CreateTeamProject (4 of 10) [18:07:53.397] [Error] Invalid collation 'Latin1_General_CI_AI_KI_WS'. [18:07:53.403] System.Data.SqlClient.SqlException (0x80131904): Invalid collation 'Latin1_General_CI_AI_KI_WS'. [18:07:53.403] at Microsoft.TeamFoundation.Framework.Server.TeamFoundationSqlResourceComponent.MapException(SqlException ex, QueryExecutionState queryState) [18:07:53.403] at Microsoft.TeamFoundation.Framework.Server.TeamFoundationSqlResourceComponent.HandleException(Exception exception) [18:07:53.403] at Microsoft.TeamFoundation.Framework.Server.TeamFoundationSqlResourceComponent.ExecuteCommand(ExecuteType executeType, CommandBehavior behavior, String performanceGroupName) [18:07:53.403] at Microsoft.VisualStudio.Services.CircuitBreaker.Command.<>c__DisplayClass7_0.b__0() [18:07:53.403] at Microsoft.VisualStudio.Services.CircuitBreaker.CommandAsync.d__30.MoveNext() [18:07:53.403] --- End of stack trace from previous location where exception was thrown --- [18:07:53.403] at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw() [18:07:53.403] at Microsoft.VisualStudio.Services.CircuitBreaker.CommandAsync.d__30.MoveNext() [18:07:53.403] --- End of stack trace from previous location where exception was thrown --- [18:07:53.403] at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw() [18:07:53.403] at Microsoft.VisualStudio.Services.CircuitBreaker.Command.Execute(Action run, Action fallback) [18:07:53.403] at Microsoft.TeamFoundation.Framework.Server.DatabaseCircuitBreaker.ExecuteCommandWithBaseCircuitBreaker(ExecuteType executeType, CommandBehavior behavior, String performanceGroupName) [18:07:53.403] at Microsoft.TeamFoundation.Framework.Server.TeamFoundationSqlResourceComponent.ExecuteReader() [18:07:53.403] at Microsoft.VisualStudio.Services.Identity.GroupComponent18.CreateGroups(Guid scopeId, Boolean errorOnDuplicate, GroupDescription[] groups, Boolean addActiveScopeMembership) [18:07:53.403] at Microsoft.VisualStudio.Services.Identity.PlatformIdentityStore.CreateGroups(IVssRequestContext requestContext, IdentityDomain hostDomain, Guid scopeId, Boolean errorOnDuplicate, Boolean addActiveScopeMembership, GroupDescription[] groupDescriptions) [18:07:53.403] at Microsoft.VisualStudio.Services.Identity.PlatformIdentityService.CreateGroup(IVssRequestContext requestContext, Guid scopeId, Guid groupId, String groupSid, String groupName, String groupDescription, SpecialGroupType specialType, Boolean scopeLocal, Boolean hasRestrictedVisibility) [18:07:53.403] at Microsoft.VisualStudio.Services.Identity.IdentityServiceBase.CreateGroup(IVssRequestContext requestContext, Guid scopeId, String groupSid, String groupName, String groupDescription, SpecialGroupType specialType, Boolean scopeLocal, Boolean hasRestrictedVisibility) [18:07:53.403] at Microsoft.VisualStudio.Services.Identity.CompositeIdentityService.CreateGroup(IVssRequestContext requestContext, Guid scopeId, String groupSid, String groupName, String groupDescription, SpecialGroupType specialType, Boolean scopeLocal, Boolean hasRestrictedVisibility) [18:07:53.403] at Microsoft.TeamFoundation.Server.Core.TeamFoundationIdentityService.CreateApplicationGroup(IVssRequestContext requestContext, String projectUri, String groupName, String groupDescription, Boolean scopeLocal, Boolean hasRestrictedVisibility) [18:07:53.403] at Microsoft.TeamFoundation.Server.Core.Team.TeamService.CreateTeam(IVssRequestContext requestContext, String projectUri, String name, String description) [18:07:53.403] at Microsoft.TeamFoundation.Integration.Server.CommonStructureService.CreateInitialNodesAndDefaultTeam(IVssRequestContext requestContext, String projectName, Guid projectId, String projectUri, XmlNode[] nodes) [18:07:53.403] at Microsoft.TeamFoundation.Integration.Server.CommonStructureService.CreateInitialNodesAndDefaultTeam(IVssRequestContext requestContext, String projectName, Guid projectId, String projectUri, XmlElement structure) [18:07:53.403] at Microsoft.TeamFoundation.Server.Servicing.TFCollection.IntegrationStepPerformer.CreateTeamProject(IVssRequestContext targetRequestContext, IServicingContext servicingContext) [18:07:53.403] at Microsoft.TeamFoundation.Framework.Server.TeamFoundationStepPerformerBase.PerformHostStep(String servicingOperation, ServicingOperationTarget target, IServicingStep servicingStep, String stepData, ServicingContext servicingContext) [18:07:53.403] at Microsoft.TeamFoundation.Framework.Server.TeamFoundationStepPerformerBase.PerformStep(String servicingOperation, ServicingOperationTarget target, String stepType, String stepData, ServicingContext servicingContext) [18:07:53.403] at Microsoft.TeamFoundation.Framework.Server.ServicingStepDriver.PerformServicingStep(ServicingStep step, ServicingContext servicingContext, ServicingStepGroup group, ServicingOperation servicingOperation, Int32 stepNumber, Int32 totalSteps) [18:07:53.403] ClientConnectionId:97deab8c-21be-485e-a52d-87882a8defad [18:07:53.403] Error Number:448,State:1,Class:16 [18:07:53.403] Step failed: Create the Team Project. Execution time: 96 milliseconds. [18:07:53.403] [StepDuration] 0.0889 [18:07:53.403] [GroupDuration] 0.0958 [18:07:53.420] [OperationDuration] 0.3745 [18:07:53.423] Clearing dictionary, removing all items. ====================================================================================================== Step execution times in descending order ====================================================================================================== Create the Team Project (ProjectCreate.TfsFramework, ProjectCreate) - 227 milliseconds Create the Team Project (ProjectCreate.TfsIntegration, ProjectCreate) - 96 milliseconds Ensure Team Project Tokens (ProjectCreate.TfsFramework, ProjectCreate) - 20 milliseconds Provision VssfAdmin (ProjectCreate.TfsFramework, ProjectCreate) - 13 milliseconds ====================================================================================================== Execution times by group in descending order ====================================================================================================== ProjectCreate.TfsFramework (ProjectCreate) - 260 milliseconds ProjectCreate.TfsIntegration (ProjectCreate) - 96 milliseconds