Cannot get TfsAreaAndIterationProcessor to create areas and iterations

[djbyrd]

Can someone assist me with getting TfsAreaAndIterationProcessor to create areas and iterations? I am trying to split a product out of an existing project, including remapping the areas and iterations. The TfsAreaAndIterationProcessor loads but does nothing. The WorkItemMigration processor remaps the areas and iterations but can't continue because they don't exist. Apparently I need to configure something to feed input into TfsAreaAndIterationProcessor so it will do something, but I haven't found what that is. I've tried adding TfsNodeStructure as a processor enricher for TfsAreaAndIterationProcessor but it doesn't have any impact. It seems like the processor just isn't getting any input.

Below are the config and log from a test run with sample projects. Any assistance is greatly appreciated.

{
  "ChangeSetMappingFile": null,
  "Source": {
    "$type": "TfsTeamProjectConfig",
    "Collection": "https://dev.azure.com/myOrg/",
    "Project": "Test1",
    "ReflectedWorkItemIDFieldName": "Custom.ReflectedWorkItemId",
    "AllowCrossProjectLinking": false,
    "AuthenticationMode": "AccessToken",
    "PersonalAccessToken": "private",
    "LanguageMaps": {
      "AreaPath": "Area",
      "IterationPath": "Iteration"
    }
  },
  "Target": {
    "$type": "TfsTeamProjectConfig",
    "Collection": "https://dev.azure.com/myOrg/",
    "Project": "Test2",
    "ReflectedWorkItemIDFieldName": "Custom.ReflectedWorkItemId",
    "AllowCrossProjectLinking": false,
    "AuthenticationMode": "AccessToken",
    "PersonalAccessToken": "private",
    "LanguageMaps": {
      "AreaPath": "Area",
      "IterationPath": "Iteration"
    }
  },
  "FieldMaps": null,
  "GitRepoMapping": null,
  "LogLevel": "Verbose",
  "CommonEnrichersConfig": [
    {
      "$type": "TfsNodeStructureOptions",
      "PrefixProjectToNodes": false,
      "NodeBasePaths": [
        "ProductName"
      ],
      "AreaMaps": {
        "^Test1\\\\ProductName(.*)": "Test2$1"
      },
      "IterationMaps": {
        "^Test1\\\\ProductName(.*)": "Test2$1"
      },
      "ShouldCreateMissingRevisionPaths": true,
      "ReplicateAllExistingNodes": true
    }
  ],
  "Processors": [
    {
      "$type": "TfsAreaAndIterationProcessorOptions",
      "Enabled": true,
      "PrefixProjectToNodes": false,
      "NodeBasePaths": [
        "ProductName"
      ],
      "AreaMaps": {
        "^Test1\\\\ProductName(.*)": "Test2$1"
      },
      "IterationMaps": {
        "^Test1\\\\ProductName(.*)": "Test2$1"
      },
      "ProcessorEnrichers": null,
      "SourceName": "SourceAI",
      "TargetName": "TargetAI"
    },
    {
      "$type": "WorkItemMigrationConfig",
      "Enabled": true,
      "UpdateCreatedDate": true,
      "UpdateCreatedBy": true,
      "WIQLQuery": "SELECT [System.Id] FROM WorkItems WHERE [System.TeamProject] = @TeamProject AND [System.WorkItemType] NOT IN ('Initative', 'Test Suite', 'Test Plan','Shared Steps','Shared Parameter','Feedback Request') AND [System.AreaPath] UNDER 'Test1\\ProductName' ORDER BY [System.ChangedDate] desc",
      "AttachmentMigration": true,
      "AttachmentWorkingPath": "c:\\temp\\WorkItemAttachmentWorkingFolder\\",
      "FixHtmlAttachmentLinks": true,
      "SkipToFinalRevisedWorkItemType": false,
      "WorkItemCreateRetryLimit": 5,
      "FilterWorkItemsThatAlreadyExistInTarget": false,
      "PauseAfterEachWorkItem": false,
      "AttachmentMaxSize": 480000000,
      "AttachRevisionHistory": true,
      "LinkMigrationSaveEachAsAdded": false,
      "GenerateMigrationComment": true,
      "WorkItemIDs": null,
      "MaxGracefulFailures": 0,
      "SkipRevisionWithInvalidIterationPath": false,
      "SkipRevisionWithInvalidAreaPath": false,
      "NodeBasePaths": []
    }
  ],
  "Version": "15.0",
  "workaroundForQuerySOAPBugEnabled": false,
  "WorkItemTypeDefinition": {
    "sourceWorkItemTypeName": "targetWorkItemTypeName"
  },
  "Endpoints": {
    "TfsWorkItemEndpoints": [
      {
        "Name": "SourceAI",
        "AuthenticationMode": "AccessToken",
        "AccessToken": "private",
        "Organisation": "https://dev.azure.com/myOrg/",
        "Project": "Test1",
        "Query": {
          "Query": "SELECT [System.Id], [System.Tags] FROM WorkItems WHERE [System.TeamProject] = @TeamProject AND [System.WorkItemType] NOT IN ('Test Suite', 'Test Plan') ORDER BY [System.ChangedDate] desc"
        },
        "ReflectedWorkItemIdField": "Custom.ReflectedWorkItemId",
        "AllowCrossProjectLinking": false,
        "LanguageMaps": {
          "AreaPath": "Area",
          "IterationPath": "Iteration"
        }
      },
      {
        "Name": "TargetAI",
        "AuthenticationMode": "AccessToken",
        "AccessToken": "private",
        "Organisation": "https://dev.azure.com/myOrg/",
        "Project": "Test2",
        "Query": {
          "Query": "SELECT [System.Id], [System.Tags] FROM WorkItems WHERE [System.TeamProject] = @TeamProject AND [System.WorkItemType] NOT IN ('Test Suite', 'Test Plan') ORDER BY [System.ChangedDate] desc"
        },
        "ReflectedWorkItemIdField": "Custom.ReflectedWorkItemId",
        "AllowCrossProjectLinking": false,
        "LanguageMaps": {
          "AreaPath": "Area",
          "IterationPath": "Iteration"
        }
      }
    ]
  }
}

2024-02-13 19:58:16.820 -05:00 [INF] Config Found, creating engine host
2024-02-13 19:58:17.315 -05:00 [INF] Creating Migration Engine b789be4a-b2be-423b-b575-658a81503dfd
2024-02-13 19:58:17.324 -05:00 [INF] ProcessorContainer: Of 2 configured Processors only 2 are enabled
2024-02-13 19:58:17.610 -05:00 [INF] ProcessorContainer: Adding Processor TfsAreaAndIterationProcessor
2024-02-13 19:58:17.635 -05:00 [INF] Processor::Configure
2024-02-13 19:58:17.635 -05:00 [INF] Processor::Configure Processor Type TfsAreaAndIterationProcessor
2024-02-13 19:58:17.637 -05:00 [INF] Creating endpoint with name SourceAI
2024-02-13 19:58:17.641 -05:00 [DBG] Endpoint::Configure
2024-02-13 19:58:17.642 -05:00 [WRN] No Enrichers have been Configured
2024-02-13 19:58:17.643 -05:00 [DBG] TfsEndpoint::Configure
2024-02-13 19:58:17.643 -05:00 [DBG] TfsWorkItemEndPoint::Configure
2024-02-13 19:58:17.643 -05:00 [INF] Creating endpoint with name TargetAI
2024-02-13 19:58:17.644 -05:00 [DBG] Endpoint::Configure
2024-02-13 19:58:17.644 -05:00 [WRN] No Enrichers have been Configured
2024-02-13 19:58:17.644 -05:00 [DBG] TfsEndpoint::Configure
2024-02-13 19:58:17.644 -05:00 [DBG] TfsWorkItemEndPoint::Configure
2024-02-13 19:58:17.648 -05:00 [DBG] ProcessorEnricherContainer::ConfigureEnrichers
2024-02-13 19:58:17.651 -05:00 [WRN] No Enrichers have been Configured
2024-02-13 19:58:17.651 -05:00 [INF] TfsAreaAndIterationProcessor::Configure
2024-02-13 19:58:17.652 -05:00 [INF] ProcessorContainer: Adding Processor WorkItemMigrationContext
2024-02-13 19:58:17.660 -05:00 [WRN] TfsRevisionManagerOptions Default used! Add a TfsRevisionManagerOptions entry to CommonEnrichersConfig to customise the settings.
2024-02-13 19:58:17.661 -05:00 [WRN] TfsWorkItemLinkEnricherOptions Default used! Add a TfsWorkItemLinkEnricherOptions entry to CommonEnrichersConfig to customise the settings.
2024-02-13 19:58:17.671 -05:00 [INF] Logging has been configured and is set to: Information.
2024-02-13 19:58:17.671 -05:00 [INF] Max Logfile: Verbose.
2024-02-13 19:58:17.671 -05:00 [INF] Max Console: Debug.
2024-02-13 19:58:17.671 -05:00 [INF] Max Application Insights: Error.
2024-02-13 19:58:17.671 -05:00 [INF] The Max log levels above show where to go look for extra info. e.g. Even if you set the log level to Verbose you will only see that info in the Log File, however everything up to Debug will be in the Console.
2024-02-13 19:58:17.677 -05:00 [INF] Beginning run of 2 processors
2024-02-13 19:58:17.681 -05:00 [INF] Processor: TfsAreaAndIterationProcessor
2024-02-13 19:58:17.683 -05:00 [INF] Migration Context Start: TfsAreaAndIterationProcessor
2024-02-13 19:58:17.684 -05:00 [INF] Processor::InternalExecute::Start
2024-02-13 19:58:17.685 -05:00 [INF] Processor::EnsureConfigured
2024-02-13 19:58:17.686 -05:00 [INF] ProcessorEnricherContainer::ProcessorExecutionBegin
2024-02-13 19:58:17.686 -05:00 [INF] Migrating all Nodes before the Processor run.
2024-02-13 19:58:17.695 -05:00 [DBG] TfsMigrationClient::GetDependantTfsCollection:AuthenticationMode(AccessToken)
2024-02-13 19:58:17.696 -05:00 [INF] Connecting with AccessToken
2024-02-13 19:58:17.742 -05:00 [DBG] MigrationClient: Connecting to "https://dev.azure.com/myOrg/"
2024-02-13 19:58:18.705 -05:00 [VRB] MigrationClient: validating security for {"IsContainer":false,"UniqueName":"private","Descriptor":{"Data":null,"Identifier":"private","IdentityType":"Microsoft.IdentityModel.Claims.ClaimsIdentity","$type":"IdentityDescriptor"},"DisplayName":"name","IsActive":true,"MemberOf":[],"Members":[],"TeamFoundationId":"private","UniqueUserId":0,"$type":"TeamFoundationIdentity"}
2024-02-13 19:58:18.714 -05:00 [INF] Access granted to "https://dev.azure.com/myOrg/" for name (private)
2024-02-13 19:58:18.717 -05:00 [DBG] TfsWorkItemMigrationClient::GetWorkItemStore("https://dev.azure.com/myOrg/", "BypassRules")
2024-02-13 19:58:19.108 -05:00 [INF] Work Item Store connected to "https://dev.azure.com/myOrg/" with BypassRules set to true
2024-02-13 19:58:19.757 -05:00 [DBG] TfsMigrationClient::GetDependantTfsCollection:AuthenticationMode(AccessToken)
2024-02-13 19:58:19.757 -05:00 [INF] Connecting with AccessToken
2024-02-13 19:58:19.757 -05:00 [DBG] MigrationClient: Connecting to "https://dev.azure.com/myOrg/"
2024-02-13 19:58:20.009 -05:00 [VRB] MigrationClient: validating security for {"IsContainer":false,"UniqueName":"private","Descriptor":{"Data":null,"Identifier":"private","IdentityType":"Microsoft.IdentityModel.Claims.ClaimsIdentity","$type":"IdentityDescriptor"},"DisplayName":"name","IsActive":true,"MemberOf":[],"Members":[],"TeamFoundationId":"private","UniqueUserId":0,"$type":"TeamFoundationIdentity"}
2024-02-13 19:58:20.009 -05:00 [INF] Access granted to "https://dev.azure.com/myOrg/" for name (private)
2024-02-13 19:58:20.010 -05:00 [DBG] TfsWorkItemMigrationClient::GetWorkItemStore("https://dev.azure.com/myOrg/", "BypassRules")
2024-02-13 19:58:20.240 -05:00 [INF] Work Item Store connected to "https://dev.azure.com/myOrg/" with BypassRules set to true
2024-02-13 19:58:20.749 -05:00 [INF] ProcessorEnricherContainer::ProcessorExecutionEnd
2024-02-13 19:58:20.750 -05:00 [INF] Processor::InternalExecute::End
2024-02-13 19:58:20.750 -05:00 [INF] Migration Processor Complete TfsAreaAndIterationProcessor
2024-02-13 19:58:20.750 -05:00 [INF] TfsAreaAndIterationProcessor completed in 00:00:03.0667174
2024-02-13 19:58:20.753 -05:00 [INF] Processor: WorkItemMigration
2024-02-13 19:58:20.755 -05:00 [INF] Migration Context Start: WorkItemMigration
2024-02-13 19:58:20.762 -05:00 [DBG] WorkItemMigrationContext::InternalExecute
2024-02-13 19:58:20.765 -05:00 [INF] Migrating all Nodes before the Processor run.
2024-02-13 19:58:21.364 -05:00 [INF] Querying items to be migrated: SELECT [System.Id] FROM WorkItems WHERE [System.TeamProject] = @teamproject AND [System.WorkItemType] NOT IN ('Initative', 'Test Suite', 'Test Plan','Shared Steps','Shared Parameter','Feedback Request') AND [System.AreaPath] UNDER 'Test1\ProductName' ORDER BY [System.ChangedDate] desc ...
2024-02-13 19:58:21.385 -05:00 [DBG] WorkItemQuery: ===========GetWorkItems=============
2024-02-13 19:58:21.386 -05:00 [DBG] WorkItemQuery: TeamProjectCollection: https://dev.azure.com/myOrg/
2024-02-13 19:58:21.389 -05:00 [DBG] WorkItemQuery: Query: SELECT [System.Id] FROM WorkItems WHERE [System.TeamProject] = 'Test1' AND [System.WorkItemType] NOT IN ('Initative', 'Test Suite', 'Test Plan','Shared Steps','Shared Parameter','Feedback Request') AND [System.AreaPath] UNDER 'Test1\ProductName' ORDER BY [System.ChangedDate] desc
2024-02-13 19:58:21.400 -05:00 [DBG] WorkItemQuery: Parameters: {"TeamProject":"Test1","ReflectedWorkItemIdFieldName":"Custom.ReflectedWorkItemId"}
2024-02-13 19:58:21.436 -05:00 [DBG] WorkItemQuery: TeamProject: Test1
2024-02-13 19:58:21.454 -05:00 [DBG] WorkItemQuery: ReflectedWorkItemIdFieldName: Custom.ReflectedWorkItemId
2024-02-13 19:58:21.472 -05:00 [DBG] Query sent
2024-02-13 19:58:21.911 -05:00 [INF] 6 Work items received, verifying
2024-02-13 19:58:22.380 -05:00 [INF] Loading 6 Work Items
2024-02-13 19:58:32.351 -05:00 [DBG] 6 Work Items loaded
2024-02-13 19:58:32.352 -05:00 [INF] Replay all revisions of 6 work items?
2024-02-13 19:58:32.352 -05:00 [INF] Validating::Check all Target Work Items have the RefectedWorkItemId field
2024-02-13 19:58:32.358 -05:00 [DBG] ValidatingRequiredField: Feature contains Custom.ReflectedWorkItemId
2024-02-13 19:58:32.361 -05:00 [DBG] ValidatingRequiredField: User Story contains Custom.ReflectedWorkItemId
2024-02-13 19:58:32.365 -05:00 [INF] Validating::Check that all work item types needed in the Target exist or are mapped
2024-02-13 19:58:32.366 -05:00 [DBG] Validating::WorkItemTypes::sourceWorkItemTypes: 2 WorkItemTypes in the full source history Feature,User Story
2024-02-13 19:58:32.368 -05:00 [DBG] Validating::WorkItemTypes::targetWorkItemTypes::15 WorkItemTypes in Target process: Bug,Code Review Request,Code Review Response,Epic,Feature,Feedback Request,Feedback Response,Shared Steps,Task,Test Case,Test Plan,Test Suite,User Story,Issue,Shared Parameter
2024-02-13 19:58:32.368 -05:00 [INF] Validating::Check that all Area & Iteration paths from Source have a valid mapping on Target
2024-02-13 19:58:32.372 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths
2024-02-13 19:58:32.374 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths::AreaNodes::2
2024-02-13 19:58:32.377 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:Checking::null
2024-02-13 19:58:32.377 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:Checking::{"anchored":true,"sourcePath":"Test1\ProductName\offshore team","sourceSystemPath":null,"targetPath":null,"targetSystemPath":null,"nodeType":"Area","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:32.379 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName(Test1\ProductName\offshore team, Area)
2024-02-13 19:58:32.382 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::Mappers
2024-02-13 19:58:32.387 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperToRun::^Test1\ProductName(.)
2024-02-13 19:58:32.387 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperMatched::^Test1\ProductName(.)
2024-02-13 19:58:32.387 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperMatched::^Test1\ProductName(.)::replaceWith(Test2\offshore team)
2024-02-13 19:58:32.388 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:GetNewNodeName::{"anchored":true,"sourcePath":"Test1\ProductName\offshore team","sourceSystemPath":null,"targetPath":"Test2\offshore team","targetSystemPath":null,"nodeType":"Area","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:32.389 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:CheckTarget::\Test2\Area\offshore team
2024-02-13 19:58:32.789 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:CheckTarget::NOTFOUND:\Test2\Area\offshore team
2024-02-13 19:58:32.789 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:CheckTarget::LOG-ONLY::{"anchored":true,"sourcePath":"Test1\ProductName\offshore team","sourceSystemPath":"\Test1\Area\ProductName\offshore team","targetPath":"Test2\offshore team","targetSystemPath":"\Test2\Area\offshore team","nodeType":"Area","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:32.789 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:Checking::null
2024-02-13 19:58:32.789 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:Checking::{"anchored":true,"sourcePath":"Test1\ProductName\pn team","sourceSystemPath":null,"targetPath":null,"targetSystemPath":null,"nodeType":"Area","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:32.789 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName(Test1\ProductName\pn team, Area)
2024-02-13 19:58:32.790 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::Mappers
2024-02-13 19:58:32.790 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperToRun::^Test1\ProductName(.)
2024-02-13 19:58:32.790 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperMatched::^Test1\ProductName(.)
2024-02-13 19:58:32.790 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperMatched::^Test1\ProductName(.)::replaceWith(Test2\pn team)
2024-02-13 19:58:32.790 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:GetNewNodeName::{"anchored":true,"sourcePath":"Test1\ProductName\pn team","sourceSystemPath":null,"targetPath":"Test2\pn team","targetSystemPath":null,"nodeType":"Area","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:32.791 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:CheckTarget::\Test2\Area\pn team
2024-02-13 19:58:33.014 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:CheckTarget::NOTFOUND:\Test2\Area\pn team
2024-02-13 19:58:33.015 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:CheckTarget::LOG-ONLY::{"anchored":true,"sourcePath":"Test1\ProductName\pn team","sourceSystemPath":"\Test1\Area\ProductName\pn team","targetPath":"Test2\pn team","targetSystemPath":"\Test2\Area\pn team","nodeType":"Area","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:33.015 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths
2024-02-13 19:58:33.015 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths::IterationNodes::4
2024-02-13 19:58:33.015 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:Checking::null
2024-02-13 19:58:33.016 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:Checking::{"anchored":true,"sourcePath":"Test1\ProductName","sourceSystemPath":null,"targetPath":null,"targetSystemPath":null,"nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:33.016 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName(Test1\ProductName, Iteration)
2024-02-13 19:58:33.016 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::Mappers
2024-02-13 19:58:33.016 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperToRun::^Test1\ProductName(.)
2024-02-13 19:58:33.017 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperMatched::^Test1\ProductName(.)
2024-02-13 19:58:33.017 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperMatched::^Test1\ProductName(.)::replaceWith(Test2)
2024-02-13 19:58:33.017 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:GetNewNodeName::{"anchored":true,"sourcePath":"Test1\ProductName","sourceSystemPath":null,"targetPath":"Test2","targetSystemPath":null,"nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:33.017 -05:00 [DBG] TfsNodeStructure:PopulateIterationDatesFronSource:\Test1\Iteration\ProductName
2024-02-13 19:58:33.207 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:CheckTarget::\Test2\Iteration
2024-02-13 19:58:33.414 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:CheckTarget::FOUND::{"anchored":true,"sourcePath":"Test1\ProductName","sourceSystemPath":"\Test1\Iteration\ProductName","targetPath":"Test2","targetSystemPath":"\Test2\Iteration","nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":true,"$type":"NodeStructureItem"}::FOUND
2024-02-13 19:58:33.414 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:Checking::null
2024-02-13 19:58:33.415 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:Checking::{"anchored":true,"sourcePath":"Test1\ProductName\sprint 11","sourceSystemPath":null,"targetPath":null,"targetSystemPath":null,"nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:33.415 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName(Test1\ProductName\sprint 11, Iteration)
2024-02-13 19:58:33.415 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::Mappers
2024-02-13 19:58:33.415 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperToRun::^Test1\ProductName(.)
2024-02-13 19:58:33.415 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperMatched::^Test1\ProductName(.)
2024-02-13 19:58:33.415 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperMatched::^Test1\ProductName(.)::replaceWith(Test2\sprint 11)
2024-02-13 19:58:33.416 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:GetNewNodeName::{"anchored":true,"sourcePath":"Test1\ProductName\sprint 11","sourceSystemPath":null,"targetPath":"Test2\sprint 11","targetSystemPath":null,"nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:33.416 -05:00 [DBG] TfsNodeStructure:PopulateIterationDatesFronSource:\Test1\Iteration\ProductName\sprint 11
2024-02-13 19:58:33.608 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:CheckTarget::\Test2\Iteration\sprint 11
2024-02-13 19:58:33.918 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:CheckTarget::NOTFOUND:\Test2\Iteration\sprint 11
2024-02-13 19:58:33.918 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:CheckTarget::LOG-ONLY::{"anchored":true,"sourcePath":"Test1\ProductName\sprint 11","sourceSystemPath":"\Test1\Iteration\ProductName\sprint 11","targetPath":"Test2\sprint 11","targetSystemPath":"\Test2\Iteration\sprint 11","nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":true,"$type":"NodeStructureItem"}
2024-02-13 19:58:33.919 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:Checking::null
2024-02-13 19:58:33.919 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:Checking::{"anchored":true,"sourcePath":"Test1\ProductName\sprint 10","sourceSystemPath":null,"targetPath":null,"targetSystemPath":null,"nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:33.919 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName(Test1\ProductName\sprint 10, Iteration)
2024-02-13 19:58:33.919 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::Mappers
2024-02-13 19:58:33.919 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperToRun::^Test1\ProductName(.)
2024-02-13 19:58:33.919 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperMatched::^Test1\ProductName(.)
2024-02-13 19:58:33.920 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperMatched::^Test1\ProductName(.)::replaceWith(Test2\sprint 10)
2024-02-13 19:58:33.920 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:GetNewNodeName::{"anchored":true,"sourcePath":"Test1\ProductName\sprint 10","sourceSystemPath":null,"targetPath":"Test2\sprint 10","targetSystemPath":null,"nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:33.920 -05:00 [DBG] TfsNodeStructure:PopulateIterationDatesFronSource:\Test1\Iteration\ProductName\sprint 10
2024-02-13 19:58:34.299 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:CheckTarget::\Test2\Iteration\sprint 10
2024-02-13 19:58:34.593 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:CheckTarget::NOTFOUND:\Test2\Iteration\sprint 10
2024-02-13 19:58:34.593 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:CheckTarget::LOG-ONLY::{"anchored":true,"sourcePath":"Test1\ProductName\sprint 10","sourceSystemPath":"\Test1\Iteration\ProductName\sprint 10","targetPath":"Test2\sprint 10","targetSystemPath":"\Test2\Iteration\sprint 10","nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":true,"$type":"NodeStructureItem"}
2024-02-13 19:58:34.593 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:Checking::null
2024-02-13 19:58:34.594 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:Checking::{"anchored":true,"sourcePath":"Test1","sourceSystemPath":null,"targetPath":null,"targetSystemPath":null,"nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:34.594 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName(Test1, Iteration)
2024-02-13 19:58:34.594 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::Mappers
2024-02-13 19:58:34.594 -05:00 [DBG] NodeStructureEnricher.GetNewNodeName::MapperToRun::^Test1\ProductName(.)
2024-02-13 19:58:34.594 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:GetNewNodeName::{"anchored":true,"sourcePath":"Test1","sourceSystemPath":null,"targetPath":"Test2","targetSystemPath":null,"nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":false,"$type":"NodeStructureItem"}
2024-02-13 19:58:34.594 -05:00 [DBG] TfsNodeStructure:PopulateIterationDatesFronSource:\Test1\Iteration
2024-02-13 19:58:34.972 -05:00 [DBG] TfsNodeStructure:CheckForMissingPaths:CheckTarget::\Test2\Iteration
2024-02-13 19:58:35.078 -05:00 [VRB] TfsNodeStructure:CheckForMissingPaths:CheckTarget::FOUND::{"anchored":true,"sourcePath":"Test1","sourceSystemPath":"\Test1\Iteration","targetPath":"Test2","targetSystemPath":"\Test2\Iteration","nodeType":"Iteration","startDate":null,"finishDate":null,"workItems":null,"sourcePathExists":true,"$type":"NodeStructureItem"}::FOUND
2024-02-13 19:58:35.079 -05:00 [WRN] !! There are MISSING Area or Iteration Paths
2024-02-13 19:58:35.080 -05:00 [WRN] !! There are 4 Nodes (Area or Iteration) found in the history of the Source that are missing from the Target! These MUST be added or mapped before we can continue using the instructions on https://nkdagility.com/learn/azure-devops-migration-tools/Reference/v1/Processors/WorkItemMigrationContext/#iteration-maps-and-area-maps
2024-02-13 19:58:35.081 -05:00 [WRN] MAPPED Area: sourcePath=Test1\ProductName\offshore team, mapper=^Test1\ProductName(.)
2024-02-13 19:58:35.081 -05:00 [WRN] MAPPED Area: sourcePath=Test1\ProductName\pn team, mapper=^Test1\ProductName(.)
2024-02-13 19:58:35.082 -05:00 [WRN] MAPPED Iteration: sourcePath=Test1\ProductName\sprint 11, mapper=^Test1\ProductName(.)
2024-02-13 19:58:35.082 -05:00 [WRN] MAPPED Iteration: sourcePath=Test1\ProductName\sprint 10, mapper=^Test1\ProductName(.)
2024-02-13 19:58:35.083 -05:00 [INF] DONE in 00:00:13.7173606
2024-02-13 19:58:35.122 -05:00 [FTL] Error while running WorkItemMigration
System.Exception: Missing Iterations in Target preventing progress, check log for list. To continue you MUST configure IterationMaps or AreaMaps that matches the missing paths..
at VstsSyncMigrator.Engine.WorkItemMigrationContext.InternalExecute() in D:\a\1\s\src\VstsSyncMigrator.Core\Execution\MigrationContext\WorkItemMigrationContext.cs:line 204
at MigrationTools._EngineV1.Processors.MigrationProcessorBase.Execute() in D:\a\1\s\src\MigrationTools_EngineV1\Processors\MigrationProcessorBase.cs:line 47
2024-02-13 19:58:35.136 -05:00 [ERR] WorkItemMigration The Processor MigrationEngine entered the failed state...stopping run

[MrHinsh]

Remove the TfsAreaAndIterationProcessorOptions.

Area and Iteration migration is built into the WorkItemMigrationConfig, which I can see is running in your log above.

Note: This looks like an order version of the tool as all new versions have the version number on each line.

[djbyrd]

This is the latest from when I downloaded it last week, MigrationTools-15.0.0-Preview.99. The version number shows on the console lines but not in the log file, which I copied above to get the verbose output.

Thank you for the reply. Removing that did get past the issue. I thought I tried that before but probably had some other issue.