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.


  • Kopano

    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>&nbsp;</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


  • Kopano

    @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


  • Kopano

    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


  • Kopano

    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.


Log in to reply