Sunday, February 23, 2014

Exchange 2007 to 2010 mailbox move fails with: “Fatal error MapiExceptionInvalidParameter has occurred.”

Problem

You attempt to move a mailbox from Exchange 2007 to 2010 but noticed that the Move Request fails at 95%:

image

image

Proceeding to the Details tab reveals the following Suspended comment:

image

Error: MapiExceptionInvalidParameter: Unable to modify table. (hr=0x80070057, ec=-2147024809)

Diagnostic context:

Lid: 55847 EMSMDBPOOL.EcPoolSessionDoRpc called [length=228]

Lid: 43559 EMSMDBPOOL.EcPoolSessionDoRpc returned [ec=0x0][length=652][latency=46]

Lid: 23226 --- ROP Parse Start ---

Lid: 27962 ROP: ropModifyRules [65]

Lid: 17082 ROP Error: 0x80070057

Lid: 27745

Lid: 21921 StoreEc: 0x80070057

Lid: 27962 ROP: ropExtendedError [250]

Lid: 1494 ---- Remote Context Beg ----

Lid: 1238 Remote Context Overflow

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x67870102

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x668F0040

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x67AA000B

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x67870102

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x668F0040

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x67AA000B

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x67870102

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x668F0040

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x67AA000B

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x67870102

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x67F60040

Lid: 48851

Lid: 21970 StoreEc: 0x8004010F PropTag: 0x67F60040

Lid: 65267

Lid: 40691

Lid: 5559 StoreEc: 0x80070057

Lid: 65015

Lid: 65439

Lid: 4302 StoreEc: 0x80070057

Lid: 1750 ---- Remote Context End ----

Lid: 26849

Lid: 21817 ROP Failure: 0x80070057

Lid: 29150

Lid: 20446 StoreEc: 0x80070057

Continuing to the Log tab and clicking the View button displays the following verbose logs:

image

1/30/2014 11:28:54 AM [MailServer01] 'contoso.com/contoso/Bermuda/Users/Terence Luk' created move request.
1/30/2014 11:28:59 AM [MailServer04] The Microsoft Exchange Mailbox Replication service 'MailServer04.contoso.com' (14.3.151.0 caps:07) is examining the request.
1/30/2014 11:28:59 AM [MailServer04] Connected to target mailbox 'Primary (f323d788-25ef-4604-8536-fcec2eb153c3)', database 'Asia Mailbox Database', Mailbox server 'MailServer01.contoso.com' Version 14.3 (Build 123.0).
1/30/2014 11:28:59 AM [MailServer04] Connected to source mailbox 'Primary (f323d788-25ef-4604-8536-fcec2eb153c3)', database 'contosoMS01\Third Storage Group\New Mailbox Database', Mailbox server 'contosoms01.contoso.com' Version 8.3 (Build 245.0).
1/30/2014 11:28:59 AM [MailServer04] Request processing started.
1/30/2014 11:28:59 AM [MailServer04] Mailbox signature will not be preserved for mailbox 'Primary (f323d788-25ef-4604-8536-fcec2eb153c3)'. Outlook clients will need to restart to access the moved mailbox.
1/30/2014 11:29:00 AM [MailServer04] Source mailbox information before the move:
Regular Items: 14861, 6.575 GB (7,059,980,564 bytes)
Regular Deleted Items: 2, 3.867 MB (4,054,770 bytes)
FAI Items: 154, 0 B (0 bytes)
FAI Deleted Items: 0, 8.15 KB (8,346 bytes)
1/30/2014 11:29:01 AM [MailServer04] Initializing folder hierarchy in mailbox 'Primary (f323d788-25ef-4604-8536-fcec2eb153c3)': 145 folders total.
1/30/2014 11:29:02 AM [MailServer04] Folder hierarchy initialized for mailbox 'Primary (f323d788-25ef-4604-8536-fcec2eb153c3)': 145 folders total.
1/30/2014 11:29:02 AM [MailServer04] Stage: CreatingInitialSyncCheckpoint. Percent complete: 15.
1/30/2014 11:29:06 AM [MailServer04] Stage: LoadingMessages. Percent complete: 20.
1/30/2014 11:29:07 AM [MailServer04] Mailbox 'Primary (f323d788-25ef-4604-8536-fcec2eb153c3)' contains 4 soft-deleted items (3.867 MB (4,054,502 bytes)). They won't be migrated.
1/30/2014 11:29:07 AM [MailServer04] Stage: CopyingMessages. Percent complete: 25.
1/30/2014 11:29:07 AM [MailServer04] Copy progress: 0/15011 messages, 0 B (0 bytes)/6.575 GB (7,059,979,292 bytes).
1/30/2014 11:29:08 AM [MailServer04] Messages have been enumerated successfully. 15011 items loaded. Total size: 6.575 GB (7,059,979,292 bytes).
1/30/2014 11:34:08 AM [MailServer04] Stage: CopyingMessages. Percent complete: 32.
1/30/2014 11:34:08 AM [MailServer04] Copy progress: 1819/15011 messages, 757.3 MB (794,036,146 bytes)/6.575 GB (7,059,979,292 bytes).
1/30/2014 11:39:09 AM [MailServer04] Stage: CopyingMessages. Percent complete: 40.
1/30/2014 11:39:09 AM [MailServer04] Copy progress: 2539/15011 messages, 1.488 GB (1,597,760,004 bytes)/6.575 GB (7,059,979,292 bytes).
1/30/2014 11:44:10 AM [MailServer04] Stage: CopyingMessages. Percent complete: 47.
1/30/2014 11:44:10 AM [MailServer04] Copy progress: 3620/15011 messages, 2.107 GB (2,262,009,276 bytes)/6.575 GB (7,059,979,292 bytes).
1/30/2014 11:49:10 AM [MailServer04] Stage: CopyingMessages. Percent complete: 54.
1/30/2014 11:49:10 AM [MailServer04] Copy progress: 4833/15011 messages, 2.801 GB (3,007,770,072 bytes)/6.575 GB (7,059,979,292 bytes).
1/30/2014 11:54:12 AM [MailServer04] Stage: CopyingMessages. Percent complete: 61.
1/30/2014 11:54:12 AM [MailServer04] Copy progress: 7051/15011 messages, 3.433 GB (3,686,175,829 bytes)/6.575 GB (7,059,979,292 bytes).
1/30/2014 11:59:12 AM [MailServer04] Stage: CopyingMessages. Percent complete: 68.
1/30/2014 11:59:12 AM [MailServer04] Copy progress: 9695/15011 messages, 4.117 GB (4,420,836,101 bytes)/6.575 GB (7,059,979,292 bytes).
1/30/2014 12:04:13 PM [MailServer04] Stage: CopyingMessages. Percent complete: 76.
1/30/2014 12:04:13 PM [MailServer04] Copy progress: 11940/15011 messages, 4.805 GB (5,159,692,615 bytes)/6.575 GB (7,059,979,292 bytes).
1/30/2014 12:09:14 PM [MailServer04] Stage: CopyingMessages. Percent complete: 83.
1/30/2014 12:09:14 PM [MailServer04] Copy progress: 12782/15011 messages, 5.51 GB (5,916,507,136 bytes)/6.575 GB (7,059,979,292 bytes).
1/30/2014 12:14:14 PM [MailServer04] Stage: CopyingMessages. Percent complete: 90.
1/30/2014 12:14:14 PM [MailServer04] Copy progress: 13599/15011 messages, 6.141 GB (6,593,692,925 bytes)/6.575 GB (7,059,979,292 bytes).
1/30/2014 12:17:50 PM [MailServer04] Initial seeding completed, 15011 items copied, total size 6.575 GB (7,059,979,292 bytes).
1/30/2014 12:17:50 PM [MailServer04] Changes reported in source 'Primary (f323d788-25ef-4604-8536-fcec2eb153c3)': 0 changed folders, 0 deleted folders, 1 changed messages.
1/30/2014 12:17:50 PM [MailServer04] Incremental Sync 'Primary (f323d788-25ef-4604-8536-fcec2eb153c3)' completed: 1 changed items.
1/30/2014 12:17:50 PM [MailServer04] Stage: IncrementalSync. Percent complete: 95.
1/30/2014 12:17:51 PM [MailServer04] Final sync has started.
1/30/2014 12:18:01 PM [MailServer04] Changes reported in source 'Primary (f323d788-25ef-4604-8536-fcec2eb153c3)': 0 changed folders, 0 deleted folders, 0 changed messages.
1/30/2014 12:18:01 PM [MailServer04] Incremental Sync 'Primary (f323d788-25ef-4604-8536-fcec2eb153c3)' completed: 0 changed items.
1/30/2014 12:18:18 PM [MailServer04] Fatal error MapiExceptionInvalidParameter has occurred.
Error details: MapiExceptionInvalidParameter: Unable to modify table. (hr=0x80070057, ec=-2147024809)
Diagnostic context:
    Lid: 55847   EMSMDBPOOL.EcPoolSessionDoRpc called [length=228]
    Lid: 43559   EMSMDBPOOL.EcPoolSessionDoRpc returned [ec=0x0][length=652][latency=46]
    Lid: 23226   --- ROP Parse Start ---
    Lid: 27962   ROP: ropModifyRules [65]
    Lid: 17082   ROP Error: 0x80070057
    Lid: 27745 
    Lid: 21921   StoreEc: 0x80070057
    Lid: 27962   ROP: ropExtendedError [250]
    Lid: 1494    ---- Remote Context Beg ----
    Lid: 1238    Remote Context Overflow
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x67870102
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x668F0040
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x67AA000B
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x67870102
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x668F0040
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x67AA000B
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x67870102
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x668F0040
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x67AA000B
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x67870102
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x67F60040
    Lid: 48851 
    Lid: 21970   StoreEc: 0x8004010F PropTag: 0x67F60040
    Lid: 65267 
    Lid: 40691 
    Lid: 5559    StoreEc: 0x80070057
    Lid: 65015 
    Lid: 65439 
    Lid: 4302    StoreEc: 0x80070057
    Lid: 1750    ---- Remote Context End ----
    Lid: 26849 
    Lid: 21817   ROP Failure: 0x80070057
    Lid: 29150 
    Lid: 20446   StoreEc: 0x80070057
   at Microsoft.Mapi.MapiExceptionHelper.ThrowIfError(String message, Int32 hresult, SafeExInterfaceHandle iUnknown, Exception innerException)
   at Microsoft.Mapi.MapiModifyTable.ModifyTable(ModifyTableFlags flags, ICollection`1 rowList)
   at Microsoft.Mapi.MapiFolder.AddRules(Rule[] rules)
   at Microsoft.Mapi.MapiFolder.SetRules(Rule[] rules)
   at Microsoft.Exchange.MailboxReplicationService.LocalDestinationFolder.Microsoft.Exchange.MailboxReplicationService.IDestinationFolder.SetRules(RuleData[] rules)
   at Microsoft.Exchange.MailboxReplicationService.DestinationFolderWrapper.<>c__DisplayClass31.<Microsoft.Exchange.MailboxReplicationService.IDestinationFolder.SetRules>b__30()
   at Microsoft.Exchange.MailboxReplicationService.ExecutionContext.Execute(GenericCallDelegate operation)
   at Microsoft.Exchange.MailboxReplicationService.DestinationFolderWrapper.Microsoft.Exchange.MailboxReplicationService.IDestinationFolder.SetRules(RuleData[] rules)
   at Microsoft.Exchange.MailboxReplicationService.FolderRecWrapper.WriteRules(IDestinationFolder targetFolder)
   at Microsoft.Exchange.MailboxReplicationService.MailboxCopierBase.CopyFolderProperties(FolderRecWrapper folderRec, ISourceFolder sourceFolder, IDestinationFolder destFolder, FolderRecDataFlags dataToCopy)
   at Microsoft.Exchange.MailboxReplicationService.MailboxMover.<>c__DisplayClass2.<>c__DisplayClass4.<FinalSyncCopyAllFolders>b__1()
   at Microsoft.Exchange.MailboxReplicationService.ExecutionContext.Execute(GenericCallDelegate operation)
   at Microsoft.Exchange.MailboxReplicationService.MailboxMover.<>c__DisplayClass2.<FinalSyncCopyAllFolders>b__0(FolderRecWrapper folderRec, EnumFolderContext ctx)
   at Microsoft.Exchange.MailboxReplicationService.FolderMap.EnumSingleFolder(FolderRecWrapper folderRec, EnumFolderContext ctx, EnumFolderCallback callback, EnumHierarchyFlags flags)
   at Microsoft.Exchange.MailboxReplicationService.FolderMap.EnumSingleFolder(FolderRecWrapper folderRec, EnumFolderContext ctx, EnumFolderCallback callback, EnumHierarchyFlags flags)
   at Microsoft.Exchange.MailboxReplicationService.FolderMap.EnumSingleFolder(FolderRecWrapper folderRec, EnumFolderContext ctx, EnumFolderCallback callback, EnumHierarchyFlags flags)
   at Microsoft.Exchange.MailboxReplicationService.FolderMap.EnumSingleFolder(FolderRecWrapper folderRec, EnumFolderContext ctx, EnumFolderCallback callback, EnumHierarchyFlags flags)
   at Microsoft.Exchange.MailboxReplicationService.MailboxMover.FinalSyncCopyAllFolders()
   at Microsoft.Exchange.MailboxReplicationService.MoveBaseJob.<FinalSync>b__4d(MailboxMover mbxCtx)
   at Microsoft.Exchange.MailboxReplicationService.MoveBaseJob.ForeachMailboxContext(MailboxMoverDelegate del)
   at Microsoft.Exchange.MailboxReplicationService.MoveBaseJob.FinalSync(Object[] wiParams)
   at Microsoft.Exchange.MailboxReplicationService.CommonUtils.CatchKnownExceptions(GenericCallDelegate del, FailureDelegate failureDelegate)
Error context: --------
Operation: IDestinationFolder.SetRules
OperationSide: Target
Primary (f323d788-25ef-4604-8536-fcec2eb153c3)
Rules: [Rule: Condition: none; Actions: [RuleAction: OOFREPLY TemplateEID:[len=70, data=00000000EF3A814DA7AA3D43BA1745450B92452507001615272E4E643E45967C8A0AA6C68D4400CD5354D99600001615272E4E643E45967C8A0AA6C68D4400CF2B0984B40000], TemplateGuid:cef3470e-3e8e-4680-85bc-763348af8165, Flags:0]; Name 'MSFT:TDX OOF Rules'; Provider: 'MSFT:TDX OOF Rules'; ProviderData: ; ExecutionSequence: 50; Level: 0; StateFlags: 141; UserFlags: 2; IsExtended: False]
--------
Folder: '/Top of Information Store/Archive/Inbox', entryId [len=46, data=00000000EF3A814DA7AA3D43BA1745450B9245250100B6F293EEFC8DAA4DB36D6541DB56FFFB000214CBA6BE0000], parentId [len=46, data=00000000EF3A814DA7AA3D43BA1745450B9245250100B6F293EEFC8DAA4DB36D6541DB56FFFB000214CBA6BD0000]
1/30/2014 12:18:18 PM [MailServer04] Relinquishing job.

All of the other mailboxes are moved successfully so you know this error is specific to this user.

Solution

The line that tipped off what the mailbox move was failing on was the following:

Folder: '/Top of Information Store/Archive/Inbox', entryId [len=46,

Since the user was on vacation, I went ahead and reset her password so I could log into her mailbox to have a look at the folders and as indicated in the line above, I found an Inbox folder located in an Archive folder:

image

With the hunch that the folder may be corrupted, I renamed the Inbox folder to OLD-Inbox:

image

… created a new Inbox folder:

image

… then moved all the OLD-Inbox folder items into the new Inbox folder:

image

I proceeded to delete the OLD-Inbox folder:

image image

… and then permanently deleted it from the Deleted Items folder:

image

**Note that the step above is important because if you don’t remove the corrupted folder from the Deleted Items the next mailbox move would fail.

Once steps above were done, the mailbox move completed successfully.

No comments: