App Migration implementation not working when disabling feature flag `com.atlassian.jira.migration.app-migration.feature`

fadli.abdulrahman · June 9, 2021, 8:09am

Summary

JCMA app version 1.5.6
Jira Server version 8.16.1
atlassian-app-cloud-migration-tracker version 1.26

onStartAppMigration stop working when disabling feature flag com.atlassian.jira.migration.app-migration.feature in Jira Server.

I’m implementing CloudMigrationListenerV1 interface in my code which was working before.

Screenshot 2021-06-09 at 3.49.38 PM

Logs

With com.atlassian.jira.migration.app-migration.feature feature flag

2021-06-09 15:18:07,129+0800 pool-49-thread-7 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.m.tracking.polling.StatusPoller] Migration e92f1c57-7163-4d78-9c48-48590c0fba75 completed, Migration successful
2021-06-09 15:18:07,143+0800 pool-47-thread-37 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.m.export.framework.DBGlobalExportContextRepository] Clearing global export context for migration e92f1c57-7163-4d78-9c48-48590c0fba75
2021-06-09 15:18:07,148+0800 pool-47-thread-36 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.m.export.utils.InMemExportingMemoryService] Forget exported all filters for migration e92f1c57-7163-4d78-9c48-48590c0fba75
2021-06-09 15:18:07,148+0800 pool-47-thread-36 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.m.export.utils.InMemExportingMemoryService] Forget exported all boards for migration e92f1c57-7163-4d78-9c48-48590c0fba75
2021-06-09 15:18:07,148+0800 pool-47-thread-38 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.migration.app.CloudMigrationRegistrar] e92f1c57-7163-4d78-9c48-48590c0fba75-appMigration : Triggered app migration
2021-06-09 15:18:09,610+0800 pool-47-thread-38 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.migration.amsclient.DefaultAppMigrationServiceClient] appMigration - received migration details
2021-06-09 15:18:12,584+0800 pool-47-thread-38 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.migration.amsclient.DefaultAppMigrationServiceClient] appMigration - transfer bb34fca4 - registered transfer for app com.servicerocket.jira.salesforce
2021-06-09 15:18:12,584+0800 pool-47-thread-38 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.migration.app.CloudMigrationRegistrar] Queueing job for bb34fca4-dbce-33cf-97e5-af3c4adf4844
2021-06-09 15:18:12,587+0800 Caesium-1-1 INFO ServiceRunner     [c.a.j.migration.app.CloudMigrationRegistrar] Running job for 7fb50226-2751-4c62-b366-404d6cc18804
2021-06-09 15:19:54,465+0800 index-writer-stats-ISSUE-3-0 INFO anonymous     [c.a.jira.index.WriterWithStats] [JIRA-STATS] [index-writer-stats] ISSUE : total stats: {"addDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"deleteDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentConditionallyMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsWithVersionMillis":{"count":24,"min":1,"max":356,"sum":414,"avg":17,"distributionCounter":{"10":23,"100":0,"500":1,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"updateDocumentsWithVersionSize":{"count":24,"min":1,"max":1,"sum":24,"avg":1,"distributionCounter":{"1":24,"10":0,"100":0,"1000":0,"10000":0}},"replaceDocumentsWithVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"10":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"replaceDocumentsWithVersionSize":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"100":0,"1000":0,"10000":0}},"optimizeMillis":{"count":1,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"closeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"commitMillis":{"count":3,"min":8,"max":112,"sum":206,"avg":68,"distributionCounter":{}}}, index writer version cache stats: {"put":24,"get":24,"getFound":12,"clear":0,"clearSizeExceeded":0}, index writer searcher stats: {"createNew":1,"reuse":11}
2021-06-09 15:19:54,465+0800 index-writer-stats-ISSUE-3-0 INFO anonymous     [c.a.jira.index.WriterWithStats] [JIRA-STATS] [index-writer-stats] ISSUE : snapshot stats: {"addDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"deleteDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentConditionallyMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsWithVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"10":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"updateDocumentsWithVersionSize":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"100":0,"1000":0,"10000":0}},"replaceDocumentsWithVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"10":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"replaceDocumentsWithVersionSize":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"100":0,"1000":0,"10000":0}},"optimizeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"closeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"commitMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}}}, index writer version cache stats: {"put":0,"get":0,"getFound":0,"clear":0,"clearSizeExceeded":0}, index writer searcher stats: {"createNew":0,"reuse":0}
2021-06-09 15:19:54,468+0800 index-writer-stats-COMMENT-2-0 INFO anonymous     [c.a.jira.index.WriterWithStats] [JIRA-STATS] [index-writer-stats] COMMENT : total stats: {"addDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"deleteDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsMillis":{"count":22,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentConditionallyMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsWithVersionMillis":{"count":2,"min":8,"max":13,"sum":21,"avg":10,"distributionCounter":{"10":1,"100":1,"500":0,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"updateDocumentsWithVersionSize":{"count":2,"min":1,"max":1,"sum":2,"avg":1,"distributionCounter":{"1":2,"10":0,"100":0,"1000":0,"10000":0}},"replaceDocumentsWithVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"10":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"replaceDocumentsWithVersionSize":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"100":0,"1000":0,"10000":0}},"optimizeMillis":{"count":1,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"closeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"commitMillis":{"count":3,"min":6,"max":436,"sum":574,"avg":191,"distributionCounter":{}}}, index writer version cache stats: {"put":2,"get":2,"getFound":0,"clear":22,"clearSizeExceeded":0}, index writer searcher stats: {"createNew":2,"reuse":0}
2021-06-09 15:19:54,468+0800 index-writer-stats-COMMENT-2-0 INFO anonymous     [c.a.jira.index.WriterWithStats] [JIRA-STATS] [index-writer-stats] COMMENT : snapshot stats: {"addDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"deleteDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentConditionallyMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsWithVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"10":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"updateDocumentsWithVersionSize":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"100":0,"1000":0,"10000":0}},"replaceDocumentsWithVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"10":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"replaceDocumentsWithVersionSize":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"100":0,"1000":0,"10000":0}},"optimizeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"closeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"commitMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}}}, index writer version cache stats: {"put":0,"get":0,"getFound":0,"clear":0,"clearSizeExceeded":0}, index writer searcher stats: {"createNew":0,"reuse":0}
2021-06-09 15:19:54,468+0800 periodic-de-index-fixer-0 INFO      [c.a.j.index.ha.TotalAndSnapshotIndexRepairStats] [JIRA-STATS] [INDEX-FIXER] index repair total stats: {"processedDeindexOperations":{"count":180,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"processedReindexOperations":{"count":180,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"durationIsLongerThanRetentionPeriod":{"count":180,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"indexRecoveryMillis":{"count":180,"min":1,"max":415,"sum":965,"avg":5,"distributionCounter":{}},"errors":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}}}
2021-06-09 15:19:54,468+0800 periodic-de-index-fixer-0 INFO      [c.a.j.index.ha.TotalAndSnapshotIndexRepairStats] [JIRA-STATS] [INDEX-FIXER] index repair snapshot stats: {"processedDeindexOperations":{"count":30,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"processedReindexOperations":{"count":30,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"durationIsLongerThanRetentionPeriod":{"count":30,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"indexRecoveryMillis":{"count":30,"min":1,"max":4,"sum":60,"avg":2,"distributionCounter":{}},"errors":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}}}
2021-06-09 15:19:54,472+0800 index-writer-stats-WORKLOG-4-0 INFO anonymous     [c.a.jira.index.WriterWithStats] [JIRA-STATS] [index-writer-stats] WORKLOG : total stats: {"addDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"deleteDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsMillis":{"count":24,"min":0,"max":1,"sum":1,"avg":0,"distributionCounter":{}},"updateDocumentConditionallyMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsWithVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"10":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"updateDocumentsWithVersionSize":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"100":0,"1000":0,"10000":0}},"replaceDocumentsWithVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"10":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"replaceDocumentsWithVersionSize":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"100":0,"1000":0,"10000":0}},"optimizeMillis":{"count":1,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"closeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"commitMillis":{"count":3,"min":1,"max":13,"sum":15,"avg":5,"distributionCounter":{}}}, index writer version cache stats: {"put":0,"get":0,"getFound":0,"clear":24,"clearSizeExceeded":0}, index writer searcher stats: {"createNew":0,"reuse":0}
2021-06-09 15:19:54,472+0800 index-writer-stats-WORKLOG-4-0 INFO anonymous     [c.a.jira.index.WriterWithStats] [JIRA-STATS] [index-writer-stats] WORKLOG : snapshot stats: {"addDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"deleteDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentConditionallyMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"updateDocumentsWithVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"10":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"updateDocumentsWithVersionSize":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"100":0,"1000":0,"10000":0}},"replaceDocumentsWithVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"10":0,"100":0,"500":0,"1000":0,"5000":0,"10000":0,"30000":0,"60000":0}},"replaceDocumentsWithVersionSize":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"100":0,"1000":0,"10000":0}},"optimizeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"closeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"commitMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}}}, index writer version cache stats: {"put":0,"get":0,"getFound":0,"clear":0,"clearSizeExceeded":0}, index writer searcher stats: {"createNew":0,"reuse":0}
2021-06-09 15:19:57,792+0800 versioning-stats-0 INFO      [c.a.jira.versioning.EntityVersioningManagerWithStats] [JIRA-STATS] [VERSIONING] total stats: {"requestMillis":{"count":2,"min":20,"max":54,"sum":74,"avg":37,"distributionCounter":{"1":0,"5":0,"10":0,"50":1,"100":1,"200":0}},"requestCount":{"count":2,"min":72,"max":72,"sum":144,"avg":72,"distributionCounter":{"1":0,"10":0,"100":2,"1000":0}},"requestPercent":{"count":2,"min":1,"max":1,"sum":2,"avg":1,"distributionCounter":{"1":2,"3":0,"5":0,"10":0,"20":0,"30":0}},"incrementIssueVersionMillis":{"count":24,"min":0,"max":7,"sum":39,"avg":1,"distributionCounter":{}},"incrementCommentVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"incrementWorklogVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"incrementRelatedCommentVersionsMillis":{"count":24,"min":0,"max":4,"sum":19,"avg":0,"distributionCounter":{}},"incrementRelatedWorklogVersionsMillis":{"count":24,"min":0,"max":1,"sum":11,"avg":0,"distributionCounter":{}},"markIssueDeletedAndIncrementVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"markCommentDeletedAndIncrementVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"markWorklogDeletedAndIncrementVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"getIssueVersionMillis":{"count":24,"min":0,"max":1,"sum":2,"avg":0,"distributionCounter":{}},"getCommentVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"getWorklogVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"getRelatedCommentVersionsMillis":{"count":24,"min":0,"max":2,"sum":2,"avg":0,"distributionCounter":{}},"getRelatedWorklogVersionsMillis":{"count":24,"min":0,"max":1,"sum":1,"avg":0,"distributionCounter":{}},"getLocalVersionsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"5":0,"10":0,"50":0,"100":0,"500":0,"1000":0}},"markEntityDeletedAndIncrementVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"cleanAllDeletedEntityVersionsOlderThanMillis":{"count":1,"min":8,"max":8,"sum":8,"avg":8,"distributionCounter":{}},"findEntityVersionsUpdatedInRangeMillis":{"count":540,"min":0,"max":33,"sum":164,"avg":0,"distributionCounter":{}},"getLatestEntityUpdateTimeMillis":{"count":1,"min":22,"max":22,"sum":22,"avg":22,"distributionCounter":{}}}
2021-06-09 15:19:57,792+0800 versioning-stats-0 INFO      [c.a.jira.versioning.EntityVersioningManagerWithStats] [JIRA-STATS] [VERSIONING] snapshot stats: {"requestMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"5":0,"10":0,"50":0,"100":0,"200":0}},"requestCount":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"10":0,"100":0,"1000":0}},"requestPercent":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"3":0,"5":0,"10":0,"20":0,"30":0}},"incrementIssueVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"incrementCommentVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"incrementWorklogVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"incrementRelatedCommentVersionsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"incrementRelatedWorklogVersionsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"markIssueDeletedAndIncrementVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"markCommentDeletedAndIncrementVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"markWorklogDeletedAndIncrementVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"getIssueVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"getCommentVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"getWorklogVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"getRelatedCommentVersionsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"getRelatedWorklogVersionsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"getLocalVersionsMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{"1":0,"5":0,"10":0,"50":0,"100":0,"500":0,"1000":0}},"markEntityDeletedAndIncrementVersionMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"cleanAllDeletedEntityVersionsOlderThanMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}},"findEntityVersionsUpdatedInRangeMillis":{"count":93,"min":0,"max":1,"sum":6,"avg":0,"distributionCounter":{}},"getLatestEntityUpdateTimeMillis":{"count":0,"min":0,"max":0,"sum":0,"avg":0,"distributionCounter":{}}}
2021-06-09 15:19:59,192+0800 Caesium-1-3 DEBUG ServiceRunner     [c.a.activeobjects.osgi.ActiveObjectsServiceFactory] getService bundle [com.atlassian.jira.diagnostics-plugin]
2021-06-09 15:20:01,150+0800 pool-46-thread-2 DEBUG ServiceRunner     [c.a.activeobjects.osgi.ActiveObjectsServiceFactory] getService bundle [com.servicerocket.sfjc-server]
2021-06-09 15:20:02,152+0800 pool-46-thread-8 WARN ServiceRunner     [c.a.j.cache.request.RequestCacheRecorderImpl] Invalid use of RequestCache by thread: pool-46-thread.Incorrect usage of JIRA API. You can only create/use: RequestCacheImpl inside a context (request or Jira-Thread-Local). Check: JiraThreadLocalUtil for details. This message will be logged once per thread.
2021-06-09 15:20:03,307+0800 pool-46-thread-8 INFO ServiceRunner     [c.a.j.migration.amsclient.DefaultAppMigrationServiceClient] appMigration - transfer bb34fca4 - initializing upload
2021-06-09 15:20:04,249+0800 pool-46-thread-8 INFO ServiceRunner     [c.a.j.migration.amsclient.DefaultAppMigrationServiceClient] appMigration - multipart upload URL received
2021-06-09 15:20:06,869+0800 pool-46-thread-8 INFO ServiceRunner     [c.a.j.migration.amsclient.DefaultAppMigrationServiceClient] appMigration - finalizing upload
2021-06-09 15:20:08,252+0800 pool-46-thread-8 INFO ServiceRunner     [c.a.j.migration.amsclient.DefaultAppMigrationServiceClient] appMigration - transfer bb34fca4 - initializing upload
2021-06-09 15:20:09,159+0800 pool-46-thread-8 INFO ServiceRunner     [c.a.j.migration.amsclient.DefaultAppMigrationServiceClient] appMigration - multipart upload URL received
2021-06-09 15:20:10,597+0800 pool-46-thread-8 INFO ServiceRunner     [c.a.j.migration.amsclient.DefaultAppMigrationServiceClient] appMigration - finalizing upload

Without com.atlassian.jira.migration.app-migration.feature feature flag

2021-06-09 15:09:52,177+0800 pool-49-thread-7 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.m.tracking.polling.StatusPoller] Migration bf19c09b-7998-4037-97df-13a63ef27c5f completed, Migration successful
2021-06-09 15:09:52,201+0800 pool-47-thread-12 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.m.export.framework.DBGlobalExportContextRepository] Clearing global export context for migration bf19c09b-7998-4037-97df-13a63ef27c5f
2021-06-09 15:09:52,201+0800 pool-47-thread-13 WARN admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.cache.request.RequestCacheRecorderImpl] Invalid use of RequestCache by thread: pool-47-thread.Incorrect usage of JIRA API. You can only create/use: RequestCacheImpl inside a context (request or Jira-Thread-Local). Check: JiraThreadLocalUtil for details. This message will be logged once per thread.
2021-06-09 15:09:52,202+0800 pool-47-thread-15 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.m.export.utils.InMemExportingMemoryService] Forget exported all filters for migration bf19c09b-7998-4037-97df-13a63ef27c5f
2021-06-09 15:09:52,206+0800 pool-47-thread-15 INFO admin 901x7367x6 4lh7d6 0:0:0:0:0:0:0:1 /rest/plugins/1.0/available/featured [c.a.j.m.export.utils.InMemExportingMemoryService] Forget exported all boards for migration bf19c09b-7998-4037-97df-13a63ef27c5f
2021-06-09 15:10:46,669+0800 http-nio-2990-exec-8 WARN admin 910x17923x1 4lh7d6 0:0:0:0:0:0:0:1 /secure/SiteDarkFeatures!default.jspa [c.a.v.htmlsafe.introspection.AnnotationBoxingUberspect] Velocity template accessing deprecated method com.atlassian.jira.plugin.webfragment.model.JiraHelper#getProjectObject - getEncodedBodyFromContent[line 1, column 10]
2021-06-09 15:10:46,669+0800 http-nio-2990-exec-8 WARN admin 910x17923x1 4lh7d6 0:0:0:0:0:0:0:1 /secure/SiteDarkFeatures!default.jspa [c.a.v.htmlsafe.introspection.AnnotationBoxingUberspect] Velocity template accessing deprecated method com.atlassian.jira.plugin.webfragment.model.JiraHelper#getProjectObject - getEncodedBodyFromContent[line 1, column 40]
2021-06-09 15:11:29,030+0800 http-nio-2990-exec-1 WARN admin 911x18646x1 4lh7d6 0:0:0:0:0:0:0:1 /secure/migration-app-action.jspa [c.a.v.htmlsafe.introspection.AnnotationBoxingUberspect] Velocity template accessing deprecated method com.atlassian.jira.plugin.webfragment.model.JiraHelper#getProjectObject - getEncodedBodyFromContent[line 1, column 10]
2021-06-09 15:11:29,030+0800 http-nio-2990-exec-1 WARN admin 911x18646x1 4lh7d6 0:0:0:0:0:0:0:1 /secure/migration-app-action.jspa [c.a.v.htmlsafe.introspection.AnnotationBoxingUberspect] Velocity template accessing deprecated method com.atlassian.jira.plugin.webfragment.model.JiraHelper#getProjectObject - getEncodedBodyFromContent[line 1, column 40]
2021-06-09 15:11:39,768+0800 plugin-transaction-0 INFO      [c.a.jira.plugin.PluginTransactionListener] [JIRA-STATS] [plugin-transaction] numberStartEvents:558, numberEndEvents:558, numberSendEvents:281, numberEventsInTransactions:11303, numberOfPluginEnableEvents:278
2021-06-09 15:11:46,262+0800 http-nio-2990-exec-9 WARN admin 911x19397x1 4lh7d6 0:0:0:0:0:0:0:1 /secure/SiteDarkFeatures!default.jspa [c.a.v.htmlsafe.introspection.AnnotationBoxingUberspect] Velocity template accessing deprecated method com.atlassian.jira.plugin.webfragment.model.JiraHelper#getProjectObject - getEncodedBodyFromContent[line 1, column 10]
2021-06-09 15:11:46,262+0800 http-nio-2990-exec-9 WARN admin 911x19397x1 4lh7d6 0:0:0:0:0:0:0:1 /secure/SiteDarkFeatures!default.jspa [c.a.v.htmlsafe.introspection.AnnotationBoxingUberspect] Velocity template accessing deprecated method com.atlassian.jira.plugin.webfragment.model.JiraHelper#getProjectObject - getEncodedBodyFromContent[line 1, column 40]
2021-06-09 15:11:51,770+0800 http-nio-2990-exec-9 WARN admin 911x20117x1 4lh7d6 0:0:0:0:0:0:0:1 /secure/migration-app-action.jspa [c.a.v.htmlsafe.introspection.AnnotationBoxingUberspect] Velocity template accessing deprecated method com.atlassian.jira.plugin.webfragment.model.JiraHelper#getProjectObject - getEncodedBodyFromContent[line 1, column 10]
2021-06-09 15:11:51,770+0800 http-nio-2990-exec-9 WARN admin 911x20117x1 4lh7d6 0:0:0:0:0:0:0:1 /secure/migration-app-action.jspa [c.a.v.htmlsafe.introspection.AnnotationBoxingUberspect] Velocity template accessing deprecated method com.atlassian.jira.plugin.webfragment.model.JiraHelper#getProjectObject - getEncodedBodyFromContent[line 1, column 40]
2021-06-09 15:12:02,768+0800 http-nio-2990-exec-9 INFO admin 912x20855x5 4lh7d6 0:0:0:0:0:0:0:1 /rest/migration/latest/stats/server [c.a.j.m.serverdataextractors.usergroup.JiraGroupReader] Group extraction for migration finished successfully.
2021-06-09 15:12:10,835+0800 http-nio-2990-exec-9 WARN admin 912x20880x2 4lh7d6 0:0:0:0:0:0:0:1 /rest/migration/latest/app/consent/update [c.a.j.migration.assessment.AppAssessmentAnalyticsEventService] Unable to generate analytics event for change on property ConsentStatus of application com.servicerocket.jira.salesforce
2021-06-09 15:12:11,768+0800 http-nio-2990-exec-5 INFO admin 912x20885x4 4lh7d6 0:0:0:0:0:0:0:1 /rest/migration/latest/stats/server [c.a.j.m.serverdataextractors.usergroup.JiraGroupReader] Group extraction for migration finished successfully.
2021-06-09 15:12:42,801+0800 http-nio-2990-exec-11 INFO admin 912x20913x3 4lh7d6 0:0:0:0:0:0:0:1 /rest/migration/latest/stats/plan [c.a.j.m.serverdataextractors.usergroup.JiraGroupReader] Group extraction for migration finished successfully.
2021-06-09 15:12:43,890+0800 http-nio-2990-exec-10 INFO admin 912x20919x4 4lh7d6 0:0:0:0:0:0:0:1 /rest/migration/latest/projects/search-metadata [c.a.j.m.serverdataextractors.usergroup.JiraGroupReader] Group extraction for migration finished successfully.
2021-06-09 15:12:46,069+0800 http-nio-2990-exec-5 INFO admin 912x20924x2 4lh7d6 0:0:0:0:0:0:0:1 /rest/migration/latest/stats/plan [c.a.j.m.serverdataextractors.usergroup.JiraGroupReader] Group extraction for migration finished successfully.
2021-06-09 15:12:48,698+0800 http-nio-2990-exec-11 INFO admin 912x20932x2 4lh7d6 0:0:0:0:0:0:0:1 /rest/migration/latest/stats/plan [c.a.j.m.serverdataextractors.usergroup.JiraGroupReader] Group extraction for migration finished successfully.
2021-06-09 15:12:51,167+0800 http-nio-2990-exec-6 INFO admin 912x20940x2 4lh7d6 0:0:0:0:0:0:0:1 /rest/migration/latest/stats/plan [c.a.j.m.serverdataextractors.usergroup.JiraGroupReader] Group extraction for migration finished successfully.

Workaround
Enabling feature flag com.atlassian.jira.migration.app-migration.feature in Jira Server.

jrichards · June 10, 2021, 12:53am

Hi @fadli.abdulrahman,

In short, this is expected behaviour.

App Migration in both CCMA and JCMA is in EAP and so App Migration isn’t available to the general public. As such we’re using the dark feature to allow you to test and for a limited number of customers to help us test during EAP.

You can review the documentation about the dark feature here

How automated migration works | App migration platform

Hope this helps.

Regards,
James.

fadli.abdulrahman · June 10, 2021, 3:03am

Hi @jrichards ,

Thanks for the clarification. Appreciated you helps.

Regards,
Fadli