Migration from Exchange 2003

[goetzi]

Hi,

I want to migrate a few users from Exchange 2003 to Zimbra. I just testet it with one user and it always cancels with one error.

I use the newest Migration Wizzard.

Here are the last lines of the log:

16-11-2005 23:11:01 [5328]: (MI): Got batch response (3631 ms)

16-11-2005 23:11:01 [5328]: (MI): Batch Adding Message d(Fr, 26 Mär 2004 14:30:33 -0000) fid(283)

16-11-2005 23:11:01 [5328]: (MI): Batch Added Message rid(0)

16-11-2005 23:11:01 [5328]: (MI): Batch Adding Message d(Sa, 27 Mär 2004 11:43:13 -0000) fid(283)

16-11-2005 23:11:01 [5328]: (MI): Batch Added Message rid(1)

16-11-2005 23:11:01 [5328]: (MI): Batch Adding Message d(Sa, 27 Mär 2004 12:25:13 -0000) fid(283)

16-11-2005 23:11:01 [5328]: (MI): Batch Added Message rid(2)

16-11-2005 23:11:04 [5328]: (MI): Batch Adding Message d(Sa, 27 Mär 2004 21:32:06 -0000) fid(283)

16-11-2005 23:11:04 [5328]: (MI): Batch Added Message rid(3)

16-11-2005 23:11:04 [5328]: (MI): Executing batch request

16-11-2005 23:11:29 [5328]: (MI): Got batch response (24728 ms)

16-11-2005 23:11:30 [5328]: (MI): Batch Adding Message d(Sa, 27 Mär 2004 21:37:49 -0000) fid(283)

16-11-2005 23:11:30 [5328]: (MI): Batch Added Message rid(0)

16-11-2005 23:11:30 [5328]: (MI): Executing batch request

16-11-2005 23:11:42 [5328]: (MI): Got batch response (12020 ms)

16-11-2005 23:11:43 [5328]: (MI): Batch Adding Message d(So, 28 Mär 2004 19:35:00 -0000) fid(283)

16-11-2005 23:11:43 [5328]: (MI): Batch Added Message rid(0)

16-11-2005 23:11:43 [5328]: (MI): Batch Adding Message d(Mo, 29 Mär 2004 12:57:44 -0000) fid(283)

16-11-2005 23:11:43 [5328]: (MI): Batch Added Message rid(1)

16-11-2005 23:11:43 [5328]: (MI): Batch Adding Message d(Mo, 29 Mär 2004 12:57:44 -0000) fid(278)

16-11-2005 23:11:43 [5328]: (MI): Batch Added Message rid(2)

16-11-2005 23:11:43 [5328]: (MI): Batch Adding Message d(Mo, 29 Mär 2004 17:26:44 -0000) fid(283)

16-11-2005 23:11:43 [5328]: (MI): Batch Added Message rid(3)

16-11-2005 23:11:48 [5328]: (MI): Batch Adding Message d(Di, 30 Mär 2004 14:28:30 -0000) fid(005)

16-11-2005 23:11:48 [5328]: (MI): Batch Added Message rid(4)

16-11-2005 23:11:48 [5328]: (MI): Executing batch request

16-11-2005 23:12:01 [5328]: Clearing internal progress log entries

16-11-2005 23:12:01 [5328]: Entries cleared

16-11-2005 23:12:01 [5328]: RPC Error: The server response could not be parsed.  The server may require a secure channel.

16-11-2005 23:12:01 [5328]: Waiting for work

16-11-2005 23:12:01 [5328]: Worker thread exiting

What could that be?

Edit: After this error, I have to restart all zimbra services because otherwise I can't access the server (admin panel and frontend GUI) anymore.

Edit2: A zmcontrol status says, that "mailbox" is stopped.

[sam]

The HTTP library we use has returned ERROR_WINHTTP_INVALID_SERVER_RESPONSE. In our testing we have found this to happen when the migration wizard thinks it should use HTTP when the port specified is in fact HTTPS.

Heres what you can do:

Downlaoad the WinHttpTraceCfg.exe trace configuration tool (part of windows server 2003 resource kit).

Read the documentation for the tool.

On the machine running the migration wizard, turn on tracing to a file by executing the following:

WinHttpTraceCfg -e 1 -l <prefix> -d 0

(replace prefix with something appropriate)

Restart the migration wizard process and try to import again. This time, a log file will be generated with all the traffic. Remove any sensitive information from the log and post it.

[goetzi]

I don't think the logged data helps you because of the encryption, but I post it anyway:

00:04:19.463 ::*0000009* :: === Warning: Trace file truncated to zero because it exceeded MaxFileSize = 65535 bytes
AD4ffswfDbxD4x+AfiHWrnQ/wDg
00:04:19.463 ::*0000009* :: pD8KvB8fgXVPE8glu/BNpJNBHrGmysjAXJFpdITIhjRcBNr7SW+Nvib8EPFH7Cn7G3gu20qy
00:04:19.463 ::*0000009* :: udQ+IXh79s+ez8A3Og6mzwavZXSKyoskeHid1BCOFSSNhlQoNfc1KFPJ6Sor3lL7R62Jyz2c
00:04:19.463 ::*0000009* :: HKclG58qftA2lj8Lda13wP4E8R6nrfx48caFp+p/ErxbFDDcS/Ce0lcTvoUksZC3FykbMu1s
00:04:19.463 ::*0000009* :: +WuWfLmuij1/4qftjfFvV9D+H/hvw/4k+J9/4M0r4f8AibWX1ZBb+DNH0eLyY/EPn3S+dboE
00:04:19.463 ::*0000009* :: fc6tgMTtQAkCs5YdU4PE1tGvhPBxeJm4cvfqelSy/s/6LrHh74E6PfW/iD4MfCD4f3Nj8ZPj
00:04:19.463 ::*0000009* :: +bCOe4+IfiKPc0gii3B5rcyMYwWJMgGS+0DP6E/Di+8Van8OLHxJ4i0vQrrxt45S08LfDnWd
00:04:19.463 ::*0000009* :: Glja6t/h4iqXmaOMARqsKsoSUAIT9w8181mmEnhF7ast9TwKtW8nHm2KfxR0TUviB4DPwX8L
00:04:19.463 ::*0000009* :: 6P4a8H6JrXhDV/H2i+MPinqBupL/AESBUtYrOF4RiNA27YUEcZZXZmVQM8t8F/2TfFWt/s++
00:04:19.463 ::*0000009* :: IvhJ8cvB0fxD8bfFL4j6N8Q/h58UvCviyx1qb4b/AA40tLX7ZqFhdqWkDLyHUbY3EgznAB6K
00:04:19.463 ::*0000009* :: mI+tYeMYRdurPosPVdKlzp27PuO/aZ+EPwx/b11+ceCfifBZfH74cvp2j/Ajxp4ctpLy7+Kf
00:04:19.463 ::*0000009* :: hG2VVdNQlmXZdy+Wkk3mKMAnoRkj4++OmhfC3TfG3wU8B6/8MfDvjTT/AAnrFrbfHPxfrd4w
00:04:19.463 ::*0000009* :: v/Ewhl2/8I9J5B2WduIc4ZP3u/aQ8eDneVetBxo3vFLQ9ZS9rBSluZP7ScMXwC8R6H8WP2K/
00:04:19.463 ::*0000009* :: iJpHiD9k/wASeLLWx+JXwcgvLe41T4drM6rPo15bwmR5o4Tuminc+awUgu2Fkb6G8VfAHWNI
00:04:19.463 ::*0000009* :: tNX8SP4h0H4o/Arxpap4j1jUfB9w93eeDnlAaMJbtloAsW4m3Utu53EMK5M5q35Yu/Ot2edm
00:04:19.463 ::*0000009* :: +DjGXNS+Nbo5xfEfh7SvClpdeDfANmPB2gabNqHw4+I3wzKafqVhrsPzW2om5cfvII2AaSJi
00:04:19.463 ::*0000009* :: S4JQeWVBr9SfDHxX+Cn/AAUF/Z70bTNV8Onwr+0f40+HsPgnxDp2kmGV/Gc9sVlbWo5Rhdqy
00:04:19.463 ::*0000009* :: RiYwtxHjgkLurklSpSoJr3qp6C9hUoxne1Rbo/N34r/DX4jeLv2k9O1f4t+HNe8ZeNfgR4k8
00:04:19.463 ::*0000009* :: Lx/E+Gx8O3V3B4u0nT0Qacrnl3adUUshb58OQSvNUdO+JPjH4m/tQ/tBfFDxnY3VrdyeMdD8
00:04:19.463 ::*0000009* :: b+B5pWt44LC40uSIr4clYDdaqYEUbvmjwu2T5myPoKeOWKilJe9Y9jEUHVwztpJrczx4n0Px
00:04:19.463 ::*0000009* :: j+0/8b/2m/jZ4R1hPh1rWpWN3f8AhjSbl1az094EsrVdkLJHcCGSP7sBLYJI6mujh+DnhiLw
00:04:19.463 ::*0000009* :: fqvxK+OHxC0nT/hzoGry3eq+KdVQwfa9HLlba1jiI3yuIiqCNQGBTGW6VwZhmVavy06a2Ph8
00:04:19.463 ::*0000009* :: XiHSe93ex8+al8IfDHim+ufjJ4L07U4/hh4W1Ial4Huvh2xuoLS3iUyRTPdoDKbmeFmZozkn
00:04:19.463 ::*0000009* :: OwJgGvqnw/8AtFa9r/8AwTG1/wAe6VbX3hqL4OftI6h4Q+FVldjzriz0+6CdWwPNmdbuX5tg
00:04:19.463 ::*0000009* :: 5PCjFfT5VUpYmrCVR2nHc7MNh3UqpN+p7pcfGrTv25vhJ+zP8PfA3wrg+HPxK+A/w8bwL458
00:04:19.463 ::*0000009* :: SRSW8x8bwwwwQw3EjoEZI4/KLsszZUykK+DXheu+Bfi18afCnjS48N+I/D3i/X/2WPBF74v1
00:04:19.463 ::*0000009* :: Lwje3Eljf6JY2rSKdXtXkDLdNCu9mj/1gV49rtmva4jjhq2PhQw1pT6n1mL/ANipWjq31PCd
00:04:19.463 ::*0000009* :: b8IfDq08Z/Bz46PpGqeGtR8K+H7HWdN0NIpEj8ZaiY/3mrFrkeYqzliQykpuxsIGRXqnxG8H
00:04:19.463 ::*0000009* :: eCfjLcfDv4u/EDwf438a/DSPWL3xtpXhrxlfLYxMgGGZpbZmjuUgnDDkmTCc7A5z8xjPaU5S
00:04:19.463 ::*0000009* :: dRaLY/P6kJyrXvy30PkL4vfH/wCEninwQnimP4WeBtVkl1W58HaHdvqEn2y2hDiK1+zKx8t8
00:04:19.463 ::*0000009* :: syLGJYhGd64ZwCaxLb4XeOtQttE0LVfh+3w6vvB/jHR/Hei+DtXvljHh65jAYRzqW+WWQmN3
00:04:19.463 ::*0000009* :: SNsj/Z4FexlNH6lGKqO7fQ0r5d9TScpXP0N/YO+KWufFHTv2ifhH8XtV0y98S6t8d7vTfh5o
00:04:19.463 ::*0000009* :: NlfjFgnkCS+t0t24tIIwN6467jgHaa/M/wCO3iHVvCqfHf4c+ILSC98R6l8Qrvw18KNC1yzE
00:04:19.463 ::*0000009* :: dxZeHkIP9t2sqYDrG4MLeaDlXG0E8jtwsXUxurtBndh37OX734eh8/fBz9mz4qfE7UrybQvD
00:04:19.463 ::*0000009* :: 3iXWX8M3UH2yLw5pr3tzYuRuRzDHueEMudjOMtglc4zX6zfDu4bwhpWrfDrxxba54U1TTtCu
00:04:19.463 ::*0000009* :: L/w5rPi3RLorrNn5YWXRJnIAkmkBcpIu3DNhs9vN4uqf2vVVKmtI9RzoLNqjcd4rY9C8PfH7
00:04:19.463 ::*0000009* :: 4zr4GuPghqHivV9SmSZdB8CeJNX1BpprDSxFzpEsRHly+WoOyQBSoHzB+AOJ+LPw01zxJf8A
00:04:19.463 ::*0000009* :: wtstA1vwtpej+GNe0y88YfDHUw39oeM7G1RWuLiK5kOQjPw4Uh/3gx8o2n4TF4yWErRp2XMj
00:04:19.463 ::*0000009* :: i+uexsktnqfNPjz9k3w948+KuufCzWH1TwT4cTVYfilod3KLae5sNGuGaYae5X5JI0VCu/PI
00:04:19.463 ::*0000009* :: K7m4bNz43eG7PRfC/gHxULSa18F694wuPBGgXGmzfuL6aCOOOG1d1IDGYfdZRjcPvY4rSvnk
00:04:19.463 ::*0000009* :: 8TOEamkU9ETWpVp14zi9tjn/AILfCWW81rxD+z38VAND8Kat8Xf+Eg8F2Xiy7Mdx4G15Y45l
00:04:19.463 ::*0000009* :: jaWM7ojI6gFsiNzsD4wM/XvjjXvip+0dqHjDw38R18beM/iz4Z0S4+FVhr2ta3Bd32uaTayL
00:04:19.463 ::*0000009* :: ujD4jWYDYCpkDspY5Zh0/RcDxmsJRnQa+LY7sPm9WnGanLSW58W/t8/tC/HbxZ4u+FPwl+JG
00:04:19.463 ::*0000009* :: mw6TpngD4aW2g6Z8MNAuJph4TGwR29xcuFxPfyRLlkjBjVNu3ByK5Hxv4RXTvGXwp+OGrDV9
00:04:19.463 ::*0000009* :: JS3sNA8G+G7a0vhbyC4hjigf5lO5t0h3OpJVgACByBz59mlH6jCVFpOS95HRmGJnCEIQejR9
00:04:19.463 ::*0000009* :: +ftY/D/xb8R/gS1ppOl2kNx8BPDdp4t+Kst5boJlt50ZrUvty2VLEBCM8ZyAK/P74D6jH4Z+
00:04:19.463 ::*0000009* :: BHg/xp8RodSsn+JHjeTQfBEukW1rEfCmhBjG2tSJJzdN5zYBcqmIyo3YwPhMkx0a2Gnh7e82
00:04:19.463 ::*0000009* :: deYU0sNC2rPpjVtK1LSPF+mReJdWt9Tm8EXNpLB4jtIvKTxVbkBoy/8AeB3KR0YcDoOfKf2p
00:04:19.463 ::*0000009* :: 7O58Saf4l1Xw9q6uLe0e0vdMvJYkmsZmQyGM22d+dpAXIxj8a0wzWFxEUvuPEw1Nylpt2PKP
00:04:19.463 ::*0000009* :: hL8QU0O8+FniLxlBqFxH4cm06w0sbzgDesLnIIdW2OyeYpyq5C45r0j4seFl+CGvfGO0PiOK
00:04:19.463 ::*0000009* :: +Xx7djWPB15ptp53/COXBdJBbmIYK7oSdpCkktyDy1bYvCzp433V7szmoqVKbhHVE/7Pt7q9
00:04:19.463 ::*0000009* :: /wDFXwN4k01rZbvw5rVve6vdeYf+JaioXMhVOWcZDHBDAZIPSvUvjH4xh8LXPiXxFK8Gp67p
00:04:19.463 ::*0000009* :: jTC2+ImiaksqX0sjMYoZbV87iXwyFsuPn3dedsdQpuryfZR6GU01y1ZP7z//2Q==
00:04:19.463 ::*0000009* :: 
00:04:19.463 ::*0000009* :: --------------Boundary-00=_0FL24Y7kX93yP9uUPRhg--
00:04:19.463 ::*0000009* :: </content></m></AddMsgRequest></BatchRequest></soap:Body></soap:Envelope>
00:04:19.463 ::*0000009* :: 
00:04:19.463 ::*0000009* :: <<<<-------- End ----------------------------------------------->>>>
00:04:28.352 ::*0000009* :: received data:
00:04:28.352 ::*0000009* :: 0 (0x0) bytes
00:04:28.352 ::*0000009* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
00:04:28.352 ::*0000009* :: 
00:04:28.352 ::*0000009* :: <<<<-------- End ----------------------------------------------->>>>
00:04:28.352 ::*0000009* :: received data:
00:04:28.352 ::*0000009* :: 0 (0x0) bytes
00:04:28.352 ::*0000009* :: <<<<-------- HTTP stream follows below ----------------------------------------------->>>>
00:04:28.352 ::*0000009* :: 
00:04:28.352 ::*0000009* :: <<<<-------- End ----------------------------------------------->>>>
00:04:28.352 ::*0000009* :: WinHttpReceiveResponse: error 12152 [ERROR_HTTP_INVALID_SERVER_RESPONSE]

[sam]

I need the entire log, not just the last request. The trace tool is truncating the log. You can increase the max size by specifying -m 1000000000.

Also, please post the log as an attachment.

[goetzi]

Ok,

here is a better one. It was to big to attach so i put it here

The strange thing is: I migrated exactly the same data with Open Source Edition and there all went fine.

[sam]

Thanks, I got the log. All looks well, but Im suspicious of the 16MB message.

Do you see any errors in the zimbra.log around the same time the large message was sent (06:35:06.625) (the one with the photo attachments) ? Is it a single 16MB message?

[goetzi]

How can I find out whic message is making the problem, then I could delete it...

I did a complete re-install of the OS and Zimbra, still the same problem.

What I found out, always if I ran the Migration Wizzard and it ended with this error this problem comes up.

Please help me, I see now other way to migrate this account from Exchange to Zimbra.

[sam]

if you look at the winhttp trace log you can extract enough information to identify the message, like the date, subject, from, to, etc.

In the log you posted, the subject is 'fotos'.

[goetzi]

There are no errors in zimbra.log

[sam]

you could also try removing any messages in exchange larger than 5mb.