After update to Kopano 8.6.8 no mail delivery via Outlook with z-push possible
-
Hi everyone,
Iam using Kopano4UCS and recently updated to version 8.6.8.
Since the update no mail delivery is possible anymore using Outlook 2013 connected via Z-Push.
However, mail delivery via WebApp and via Android App (also using Z-Push) works without any problems.The issue is not limited to a certain user and if you are in the error case it does not matter if the mail to be sent is targeted domain internal or external.
UPDATE:
The problem does not appear with Outlook 2016. -> Only Outlook 2013 seems to have an issue…Setting the Kopano log to debug the following can be found:
Nov 25 13:42:37 my-server kopano-spooler[374]: Sun Nov 25 13:42:37 2018: [error ] [ 9405] VMIME exception: Bad value type for field 'Reply-To'. Nov 25 13:42:37 my-server kopano-spooler[374]: Sun Nov 25 13:42:37 2018: [warning] [ 9405] E-mail for user USERNAME could not be sent, notifying user: call failed (80004005)
The user gets a “Undelivered Mail Returned to Sender” message in his mailbox stating:
Unfortunately, kopano-spooler was unable to deliver your mail. The error given was: Conversion error: 0X80004005. No error details specified. Your email is not sent at all and cannot be retried. You may need to contact your e-mail administrator to solve this problem.
Any ideas ?
Thanks for your help. -
Hi lw,
please also post the WBXML log of Z-Push for the SendMail request.
Manfred
-
Here we go :-)
26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] ZPush::CommandNeedsAuthentication(0): true 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] KopanoBackend->Logon(): Trying to authenticate user 'sender'.. 26/11/2018 20:17:57 [24349] [DEBUG] [#unknown] Store supports properties containing Unicode characters. 26/11/2018 20:17:57 [24349] [DEBUG] [#unknown] ZPush::CommandNeedsPlainInput(1): false 26/11/2018 20:17:57 [24349] [WBXML] [#unknown] I <ComposeMail:SendMail> 26/11/2018 20:17:57 [24349] [WBXML] [#unknown] I <ComposeMail:ClientId> 26/11/2018 20:17:57 [24349] [WBXML] [#unknown] I {C894277D-4E9C-46DF-9619-418C14F079E4} 26/11/2018 20:17:57 [24349] [WBXML] [#unknown] I </ComposeMail:ClientId> 26/11/2018 20:17:57 [24349] [WBXML] [#unknown] I <ComposeMail:SaveInSentItems/> 26/11/2018 20:17:57 [24349] [WBXML] [#unknown] I <ComposeMail:MIME> 26/11/2018 20:17:57 [24349] [WBXML] [#unknown] I Reply-To: <sender@domain.com> From: "Test User" <sender@domain.com> To: <recipient@domain.com> Subject: test Date: Mon, 26 Nov 2018 20:17:56 +0100 Message-ID: <000501d485bc$bce83f30$36b8bd90$@waigel.eu> MIME-Version: 1.0 Content-Type: multipart/alternative; boundary="----=_NextPart_000_0006_01D485C5.1EAD1C60" X-Mailer: Microsoft Outlook 15.0 Thread-Index: AdSFvLzPDQmMKCzuSM+UJsxfXBv7HQ== Content-Language: de This is a multipart message in MIME format. ------=_NextPart_000_0006_01D485C5.1EAD1C60 Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: 7bit ------=_NextPart_000_0006_01D485C5.1EAD1C60 Content-Type: text/html; charset="us-ascii" Content-Transfer-Encoding: quoted-printable <html xmlns:v=3D"urn:schemas-microsoft-com:vml" = xmlns:o=3D"urn:schemas-microsoft-com:office:office" = xmlns:w=3D"urn:schemas-microsoft-com:office:word" = xmlns:m=3D"http://schemas.microsoft.com/office/2004/12/omml" = xmlns=3D"http://www.w3.org/TR/REC-html40"><head><META = HTTP-EQUIV=3D"Content-Type" CONTENT=3D"text/html; = charset=3Dus-ascii"><meta name=3DGenerator content=3D"Microsoft Word 15 = (filtered medium)"><style><!-- /* Font Definitions */ @font-face {font-family:"Cambria Math"; panose-1:2 4 5 3 5 4 6 3 2 4;} @font-face {font-family:Calibri; panose-1:2 15 5 2 2 2 4 3 2 4;} /* Style Definitions */ p.MsoNormal, li.MsoNormal, div.MsoNormal {margin:0cm; margin-bottom:.0001pt; font-size:11.0pt; font-family:"Calibri","sans-serif"; mso-fareast-language:EN-US;} a:link, span.MsoHyperlink {mso-style-priority:99; color:#0563C1; text-decoration:underline;} a:visited, span.MsoHyperlinkFollowed {mso-style-priority:99; color:#954F72; text-decoration:underline;} span.E-MailFormatvorlage17 {mso-style-type:personal-compose; font-family:"Calibri","sans-serif"; color:windowtext;} .MsoChpDefault {mso-style-type:export-only; font-family:"Calibri","sans-serif"; mso-fareast-language:EN-US;} @page WordSection1 {size:612.0pt 792.0pt; margin:70.85pt 70.85pt 2.0cm 70.85pt;} div.WordSection1 {page:WordSection1;} --></style><!--[if gte mso 9]><xml> <o:shapedefaults v:ext=3D"edit" spidmax=3D"1026" /> </xml><![endif]--><!--[if gte mso 9]><xml> <o:shapelayout v:ext=3D"edit"> <o:idmap v:ext=3D"edit" data=3D"1" /> </o:shapelayout></xml><![endif]--></head><body lang=3DDE = link=3D"#0563C1" vlink=3D"#954F72"><div class=3DWordSection1><p = class=3DMsoNormal><o:p> </o:p></p></div></body></html> ------=_NextPart_000_0006_01D485C5.1EAD1C60-- 26/11/2018 20:17:57 [24349] [WBXML] [#unknown] I </ComposeMail:MIME> 26/11/2018 20:17:57 [24349] [WBXML] [#unknown] I </ComposeMail:SendMail> 26/11/2018 20:17:57 [24349] [DEBUG] [#unknown] KopanoBackend->SendMail(): RFC822: 2570 bytes forward-id: 'null' reply-id: 'null' parent-id: 'false' SaveInSent: '1' ReplaceMIME: 'false' 26/11/2018 20:17:57 [24349] [DEBUG] [#unknown] Use the mapi_inetmapi_imtomapi function 26/11/2018 20:17:57 [24349] [DEBUG] [#unknown] Sent email cpid is not unicode (20127). Set it to unicode and convert email html body. 26/11/2018 20:17:57 [24349] [DEBUG] [#unknown] KopanoBackend->SendMail(): removing PR_SENT_REPRESENTING_* properties 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] KopanoBackend->openMessageStore('sender'): Found 'DEFAULT' store: 'Resource id #35' 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] KopanoBackend->Logon(): User 'sender' is authenticated 26/11/2018 20:17:57 [24349] [DEBUG] [#unknown] KopanoBackend->SendMail(): email submitted 26/11/2018 20:17:57 [24349] [WBXML] [#unknown] WBXML-IN : AwFqAAAVRVEDe0M4OTQyNzdELTRFOUMtNDZERi05NjE5LTQxOEMxNEYwNzlFNH0AAQhQw5QKUmVwbHktVG86IDxsdWR3aWdAd2FpZ2VsLmV1Pg0KRnJvbTogIkx1ZHdpZyBXYWlnZWwiIDxsdWR3aWdAd2FpZ2VsLmV1Pg0KVG86IDx0am9kb2xmQHdhaWdlbC5ldT4NClN1YmplY3Q6IHRlc3QNCkRhdGU6IE1vbiwgMjYgTm92IDIwMTggMjA6MTc6NTYgKzAxMDANCk1lc3NhZ2UtSUQ6IDwwMDA1MDFkNDg1YmMkYmNlODNmMzAkMzZiOGJkOTAkQHdhaWdlbC5ldT4NCk1JTUUtVmVyc2lvbjogMS4wDQpDb250ZW50LVR5cGU6IG11bHRpcGFydC9hbHRlcm5hdGl2ZTsNCglib3VuZGFyeT0iLS0tLT1fTmV4dFBhcnRfMDAwXzAwMDZfMDFENDg1QzUuMUVBRDFDNjAiDQpYLU1haWxlcjogTWljcm9zb2Z0IE91dGxvb2sgMTUuMA0KVGhyZWFkLUluZGV4OiBBZFNGdkx6UERRbU1LQ3p1U00rVUpzeGZYQnY3SFE9PQ0KQ29udGVudC1MYW5ndWFnZTogZGUNCg0KVGhpcyBpcyBhIG11bHRpcGFydCBtZXNzYWdlIGluIE1JTUUgZm9ybWF0Lg0KDQotLS0tLS09X05leHRQYXJ0XzAwMF8wMDA2XzAxRDQ4NUM1LjFFQUQxQzYwDQpDb250ZW50LVR5cGU6IHRleHQvcGxhaW47DQoJY2hhcnNldD0idXMtYXNjaWkiDQpDb250ZW50LVRyYW5zZmVyLUVuY29kaW5nOiA3Yml0DQoNCiANCg0KDQotLS0tLS09X05leHRQYXJ0XzAwMF8wMDA2XzAxRDQ4NUM1LjFFQUQxQzYwDQpDb250ZW50LVR5cGU6IHRleHQvaHRtbDsNCgljaGFyc2V0PSJ1cy1hc2NpaSINCkNvbnRlbnQtVHJhbnNmZXItRW5jb2Rpbmc6IHF1b3RlZC1wcmludGFibGUNCg0KPGh0bWwgeG1sbnM6dj0zRCJ1cm46c2NoZW1hcy1taWNyb3NvZnQtY29tOnZtbCIgPQ0KeG1sbnM6bz0zRCJ1cm46c2NoZW1hcy1taWNyb3NvZnQtY29tOm9mZmljZTpvZmZpY2UiID0NCnhtbG5zOnc9M0QidXJuOnNjaGVtYXMtbWljcm9zb2Z0LWNvbTpvZmZpY2U6d29yZCIgPQ0KeG1sbnM6bT0zRCJodHRwOi8vc2NoZW1hcy5taWNyb3NvZnQuY29tL29mZmljZS8yMDA0LzEyL29tbWwiID0NCnhtbG5zPTNEImh0dHA6Ly93d3cudzMub3JnL1RSL1JFQy1odG1sNDAiPjxoZWFkPjxNRVRBID0NCkhUVFAtRVFVSVY9M0QiQ29udGVudC1UeXBlIiBDT05URU5UPTNEInRleHQvaHRtbDsgPQ0KY2hhcnNldD0zRHVzLWFzY2lpIj48bWV0YSBuYW1lPTNER2VuZXJhdG9yIGNvbnRlbnQ9M0QiTWljcm9zb2Z0IFdvcmQgMTUgPQ0KKGZpbHRlcmVkIG1lZGl1bSkiPjxzdHlsZT48IS0tDQovKiBGb250IERlZmluaXRpb25zICovDQpAZm9udC1mYWNlDQoJe2ZvbnQtZmFtaWx5OiJDYW1icmlhIE1hdGgiOw0KCXBhbm9zZS0xOjIgNCA1IDMgNSA0IDYgMyAyIDQ7fQ0KQGZvbnQtZmFjZQ0KCXtmb250LWZhbWlseTpDYWxpYnJpOw0KCXBhbm9zZS0xOjIgMTUgNSAyIDIgMiA0IDMgMiA0O30NCi8qIFN0eWxlIERlZmluaXRpb25zICovDQpwLk1zb05vcm1hbCwgbGkuTXNvTm9ybWFsLCBkaXYuTXNvTm9ybWFsDQoJe21hcmdpbjowY207DQoJbWFyZ2luLWJvdHRvbTouMDAwMXB0Ow0KCWZvbnQtc2l6ZToxMS4wcHQ7DQoJZm9udC1mYW1pbHk6IkNhbGlicmkiLCJzYW5zLXNlcmlmIjsNCgltc28tZmFyZWFzdC1sYW5ndWFnZTpFTi1VUzt9DQphOmxpbmssIHNwYW4uTXNvSHlwZXJsaW5rDQoJe21zby1zdHlsZS1wcmlvcml0eTo5OTsNCgljb2xvcjojMDU2M0MxOw0KCXRleHQtZGVjb3JhdGlvbjp1bmRlcmxpbmU7fQ0KYTp2aXNpdGVkLCBzcGFuLk1zb0h5cGVybGlua0ZvbGxvd2VkDQoJe21zby1zdHlsZS1wcmlvcml0eTo5OTsNCgljb2xvcjojOTU0RjcyOw0KCXRleHQtZGVjb3JhdGlvbjp1bmRlcmxpbmU7fQ0Kc3Bhbi5FLU1haWxGb3JtYXR2b3JsYWdlMTcNCgl7bXNvLXN0eWxlLXR5cGU6cGVyc29uYWwtY29tcG9zZTsNCglmb250LWZhbWlseToiQ2FsaWJyaSIsInNhbnMtc2VyaWYiOw0KCWNvbG9yOndpbmRvd3RleHQ7fQ0KLk1zb0NocERlZmF1bHQNCgl7bXNvLXN0eWxlLXR5cGU6ZXhwb3J0LW9ubHk7DQoJZm9udC1mYW1pbHk6IkNhbGlicmkiLCJzYW5zLXNlcmlmIjsNCgltc28tZmFyZWFzdC1sYW5ndWFnZTpFTi1VUzt9DQpAcGFnZSBXb3JkU2VjdGlvbjENCgl7c2l6ZTo2MTIuMHB0IDc5Mi4wcHQ7DQoJbWFyZ2luOjcwLjg1cHQgNzAuODVwdCAyLjBjbSA3MC44NXB0O30NCmRpdi5Xb3JkU2VjdGlvbjENCgl7cGFnZTpXb3JkU2VjdGlvbjE7fQ0KLS0+PC9zdHlsZT48IS0tW2lmIGd0ZSBtc28gOV0+PHhtbD4NCjxvOnNoYXBlZGVmYXVsdHMgdjpleHQ9M0QiZWRpdCIgc3BpZG1heD0zRCIxMDI2IiAvPg0KPC94bWw+PCFbZW5kaWZdLS0+PCEtLVtpZiBndGUgbXNvIDldPjx4bWw+DQo8bzpzaGFwZWxheW91dCB2OmV4dD0zRCJlZGl0Ij4NCjxvOmlkbWFwIHY6ZXh0PTNEImVkaXQiIGRhdGE9M0QiMSIgLz4NCjwvbzpzaGFwZWxheW91dD48L3htbD48IVtlbmRpZl0tLT48L2hlYWQ+PGJvZHkgbGFuZz0zRERFID0NCmxpbms9M0QiIzA1NjNDMSIgdmxpbms9M0QiIzk1NEY3MiI+PGRpdiBjbGFzcz0zRFdvcmRTZWN0aW9uMT48cCA9DQpjbGFzcz0zRE1zb05vcm1hbD48bzpwPiZuYnNwOzwvbzpwPjwvcD48L2Rpdj48L2JvZHk+PC9odG1sPg0KLS0tLS0tPV9OZXh0UGFydF8wMDBfMDAwNl8wMUQ0ODVDNS4xRUFEMUM2MC0tDQoBAQ== 26/11/2018 20:17:57 [24349] [DEBUG] [#unknown] LoopDetection->ProcessLoopDetectionTerminate() 26/11/2018 20:17:57 [24349] [ INFO] [#unknown] cmd='SendMail' memory='1.89 MiB/2.00 MiB' time='0.12s' devType='WindowsOutlook' devId='f15028c6a9b7491d9f051f43893854e2' getUser='sender' from='10.10.10.42' idle='0s' version='2.4.4+0-0' method='POST' httpcode='200' 26/11/2018 20:17:57 [24349] [DEBUG] [#unknown] -------- End 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] -------- Start 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] cmd='Ping' devType='WindowsOutlook' devId='f15028c6a9b7491d9f051f43893854e2' getUser='sender' from='10.10.10.42' version='2.4.4+0-0' method='POST' 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] Used timezone 'Europe/Amsterdam' 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] ZPush::GetBackend(): trying autoload backend 'BackendKopano' 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] BackendKopano using PHP-MAPI version: 8.6.8 - PHP version: 7.0.30-0+deb9u1 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] Request::ProcessHeaders() ASVersion: 14.0 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] ZPush::CommandNeedsProvisioning(18): false 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] ZPush::CommandNeedsAuthentication(18): true 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] KopanoBackend->Logon(): Trying to authenticate user 'sender'.. 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] FileStateMachine->GetState() read '7270' bytes from file: '/var/lib/z-push/2/e/f15028c6a9b7491d9f051f43893854e2-devicedata' 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] ASDevice data loaded for user: 'sender' 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] TopCollector(): Initialized mutexid Resource id #21 and memid Resource id #22. 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20' 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] LoopDetection(): Initialized mutexid Resource id #25 and memid Resource id #26. 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337' 26/11/2018 20:17:57 [24348] [DEBUG] [#unknown] ZPush::HierarchyCommand(18): false 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] PHPWrapper->ImportMessageChange(): Getting message from MAPIProvider, sourcekey: 'a9019a1eb747433ba07b8b4557d47cf1104503000000', parentsourcekey: 'a9019a1eb747433ba07b8b4557d47cf1146a01000000', entryid: '00000000aa86b13db1174f80b440d6941d1c6a3f010000000500000074e4634fc4604bf59426707c04e47ea800000000' 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] BodyPreference types: 4 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] GetBodyPreferenceBestMatch: 4 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] bpo: truncation size:'0', allornone:'1', preview:'0' 26/11/2018 20:17:57 [28161] [ERROR] [#unknown] MAPIProvider->imtoinet(): got no stream or content from mapi_inetmapi_imtoinet() 26/11/2018 20:17:57 [28161] [WARN] [#unknown] SyncObject->Check(): object from type SyncMail: parameter 'from' contains an invalid email address '"" <>'. Address is removed. 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <Perform> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <Add> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <ServerEntryId> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O U34400:a9019a1eb747433ba07b8b4557d47cf1104503000000 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </ServerEntryId> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <Data> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <POOMMAIL:To> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O "Test User 2" <recipient@domain.com> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </POOMMAIL:To> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <POOMMAIL:Subject> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O Undelivered Mail Returned to Sender 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </POOMMAIL:Subject> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <POOMMAIL:DateReceived> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O 2018-11-26T19:17:57.000Z 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </POOMMAIL:DateReceived> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <POOMMAIL:DisplayTo> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O Test User 2 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </POOMMAIL:DisplayTo> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <POOMMAIL:Importance> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O 1 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </POOMMAIL:Importance> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <POOMMAIL:Read> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O 0 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </POOMMAIL:Read> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <POOMMAIL:MessageClass> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O REPORT.IPM.Note.NDR 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </POOMMAIL:MessageClass> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <POOMMAIL:InternetCPID> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O 65001 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </POOMMAIL:InternetCPID> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <AirSyncBase:Attachments> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <AirSyncBase:Attachment> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <AirSyncBase:DisplayName> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O test.eml 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </AirSyncBase:DisplayName> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <AirSyncBase:FileReference> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O 00000000aa86b13db1174f80b440d6941d1c6a3f010000000500000074e4634fc4604bf59426707c04e47ea800000000:0:a9019a1eb747433ba07b8b4557d47cf1146a01000000 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </AirSyncBase:FileReference> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <AirSyncBase:Method> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O 5 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </AirSyncBase:Method> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <AirSyncBase:EstimatedDataSize> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O 3828 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </AirSyncBase:EstimatedDataSize> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </AirSyncBase:Attachment> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </AirSyncBase:Attachments> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <POOMMAIL:ContentClass> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O urn:content-classes:message 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </POOMMAIL:ContentClass> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <POOMMAIL:Flag/> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O <AirSyncBase:NativeBodyType> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O 1 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </AirSyncBase:NativeBodyType> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </Data> 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </Add> 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] PHPWrapper->ImportMessageChange(): change for: 'U34400:a9019a1eb747433ba07b8b4557d47cf1104503000000' 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </Perform> 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] ZPush::GetAdditionalSyncFolderStore('a9019a1eb747433ba07b8b4557d47cf1146a01000000'): 'false' 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] Sync()->setFolderStat() on U34400: 1543259877/42/1/266 expiring 2018-12-27 11:58:47 26/11/2018 20:17:57 [28161] [WBXML] [#unknown] O </Folder> 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] FileStateMachine->SetState() written 1 bytes on file: '/var/lib/z-push/2/e/f15028c6a9b7491d9f051f43893854e2-f55a5e90-2e66-4855-a39d-6620cf8c9d87-65' 26/11/2018 20:17:57 [28161] [DEBUG] [#unknown] SyncCollections->SaveCollection(): Data of folder 'U34400' changed
Thank you very much,
Ludwig -
@lw can you also provide an eml file is such a mail? you can retrieve on of these (at delivery) when setting
log_raw_message
in dagent.cfg. -
Hi @fbartels,
I’m experiencing the same issue. Setting the raw_message parameter doesn’t help, cause mail obviously was never handled by dagent but will be reject on spooler level.
In spooler.log the following corresponding lines appear:
Tue Nov 27 09:35:52 2018: [info ] [ 6291] Sending e-mail for user jan, size: 886609 Tue Nov 27 09:35:52 2018: [info ] [ 6291] * PreSending processing started Tue Nov 27 09:35:52 2018: [info ] [ 6291] * PreSending processing done Tue Nov 27 09:35:52 2018: [error ] [ 6291] VMIME exception: Bad value type for field 'Reply-To'. Tue Nov 27 09:35:52 2018: [warning] [ 6291] E-mail for user jan could not be sent, notifying user: call failed (80004005) Tue Nov 27 09:35:52 2018: [info ] [ 7503] Processed message for user jan
The mail is an empty mail with a spam mail attached as eml file, which I’ve sent to my spam-deamon email-address.
Furthermore the bounce message from kopano reachs Outlook as empty-mail according to my other issue, described in https://forum.kopano.io/topic/1342/mapiprovider-imtoinet-got-no-stream-or-content-from-mapi_inetmapi_imtoinet
Best Jan
-
Ah, yes indeed for sending the dagent is not involved. You are running a master build @deHoeninger, right?
As mentioned by @lw you are using Outlook 2013 as well?
-
Trying to create the raw message by spooler was also not sucessful, cause it seems to break the workflow before handing over mail to postfix.
Debug enabled log:
Tue Nov 27 09:58:09 2018: [debug ] [ 6936] Number of messages in the queue: 1 Tue Nov 27 09:58:09 2018: [debug ] [ 6936] Executing "/usr/sbin/kopano-spooler" "kopano-spooler" "--send-message-entryid" "000000007DDFE52E4EE345879B792A4AE2DE46BA01000000050000008A9BF642D09B410EABB4D96AD905F1F600000000" "--send-username-enc" "jan" "--log-fd" "6" "--host" "default:" "--foreground" "localhost" "--port" "25" "--do-sentmail" Tue Nov 27 09:58:09 2018: [info ] [ 6936] Spooler process started on PID 6959 Tue Nov 27 09:58:09 2018: [debug ] [ 6936] Messages with delayed delivery: 0 Tue Nov 27 09:58:09 2018: [debug ] [ 6959] Initializing provider "Kopano Directory Service" Tue Nov 27 09:58:09 2018: [debug ] [ 6959] Initializing provider "Private Folders" Tue Nov 27 09:58:09 2018: [debug ] [ 6959] Initializing provider "Public Folders" Tue Nov 27 09:58:09 2018: [debug ] [ 6959] Initializing provider "Kopano Directory Service" Tue Nov 27 09:58:09 2018: [debug ] [ 6959] Initializing provider "Private Folders" Tue Nov 27 09:58:09 2018: [debug ] [ 6959] Initializing provider "Public Folders" Tue Nov 27 09:58:09 2018: [debug ] [ 6959] PYTHONPATH = /usr/share/kopano-spooler/python Tue Nov 27 09:58:09 2018: [info ] [ 6959] * Loading plugins started Tue Nov 27 09:58:09 2018: [info ] [ 6959] ** Checking plugins in /var/lib/kopano/spooler/plugins Tue Nov 27 09:58:09 2018: [info ] [ 6959] * Loading plugins done Tue Nov 27 09:58:09 2018: [debug ] [ 6959] Sending e-mail for user jan, subject: "", size: 885456 Tue Nov 27 09:58:09 2018: [info ] [ 6959] * PreSending processing started Tue Nov 27 09:58:09 2018: [info ] [ 6959] * PreSending processing done Tue Nov 27 09:58:09 2018: [error ] [ 6959] VMIME exception: Bad value type for field 'Reply-To'. Tue Nov 27 09:58:09 2018: [error ] [ 6959] IMToINet: call failed (80004005) Tue Nov 27 09:58:09 2018: [error ] [ 6959] VMIME exception: Bad value type for field 'Reply-To'. Tue Nov 27 09:58:09 2018: [warning] [ 6959] E-mail for user jan could not be sent, notifying user: call failed (80004005) Tue Nov 27 09:58:10 2018: [debug ] [ 6936] Received signal 17 Tue Nov 27 09:58:10 2018: [debug ] [ 6936] Cleaning 1 subprocesses from queue Tue Nov 27 09:58:10 2018: [info ] [ 6936] Processed message for user jan
My idea to export the bounce mail via Webapp, which also includes the initial mail sent, is also failing with a MAPI error, other mails can be exported without problems.
Best Jan
-
Hi @fbartels,
yes I’m runnning latest Master build on Ubuntu 16.04, version 8.7.80.169-0+44.1.
I’m using latest Outlook 365, but as mentioned earlier, failure is also happening if I’m sending the mail via WebApp.
Best Jan
-
Hi @fbartels ,
the issue for me is exactly the same as for @deHoeninger. However, I can send mail via Webapp and Android app and do experience the problem only with Outlook 2013.
Ludwig
-
I can trigger this error with Outlook 2019 by setting a reply to within the mail. I have created https://jira.kopano.io/browse/KC-1353 for followup.