0004936: Invitations from user, which is in the same SOGo instance are synced as accepted to Outlook 365 - SOGo

ID	Project	Category	View Status	Date Submitted	Last Update

0004936	SOGo	ActiveSync	public	2020-01-14 08:05	2020-02-05 09:44

Reporter	ondrej.kolin	Assigned To
Priority	normal	Severity	minor	Reproducibility	have not tried
Status	new	Resolution	open
Platform	Server	OS	Ubuntu	OS Version	18.04
Product Version	4.2.0

Summary	0004936: Invitations from user, which is in the same SOGo instance are synced as accepted to Outlook 365
Description	Invitations made from a local user (Ada) to another user (Bob) are displayed in Outlook twice. First as "accepted" and second the outlook's "autogenerated invitation". When is outlook off while the invitation is delivered to Bob, it appears only once and as "accepted". This results in confusion and we think it is responsible for some events lost in our instance. Everything is fine in the webcalendar
Steps To Reproduce	Bob uses outlook with EAS 1) Ada@benocs.com sends invitation to Bob@benocs.com 2) Bob checks his calendar 3) Bob sees 2 events
Additional Information	Please notice, that picture showing 2 events (Invitation test 0000002) was reproduced with outlook running, but the Invitation Test is the result of starting outlook and syncing pending invitation from Sogo Webmail (another picture).
Tags	No tags attached.

ondrej.kolin 2020-01-14 08:05 reporter	Screenshot from 2020-01-14 08-56-09.png (3,699 bytes) Screenshot from 2020-01-14 08-56-09.png (3,699 bytes) Screenshot from 2020-01-14 08-54-14.png (1,604 bytes) Screenshot from 2020-01-14 08-54-14.png (1,604 bytes) Screenshot from 2020-01-14 08-53-09.png (2,816 bytes) Screenshot from 2020-01-14 08-53-09.png (2,816 bytes) Screenshot from 2020-01-14 08-52-56.png (81,265 bytes) Screenshot from 2020-01-14 08-52-56.png (81,265 bytes)

tfu 2020-01-14 20:15 reporter ~0014058	Is bob doing anything with the invitation in OL other then synching? Please reproduce with SOGoEASDebugEnabled = YES and upload your sogo.log.

ondrej.kolin 2020-01-16 09:25 reporter ~0014062	We were reproducing this issue and it can be reproduced only on our setup where we have shared DB, but webmail and activesync are two different SOGo instances. On single instance setup (SOGo + EAS) everything works as expected. We will probably move away from our weird setup to normal setup. @tfu Are you interested in our sogo.log even in that case? Is that setup such bad idea, as it seems now?

tfu 2020-01-16 16:44 reporter ~0014064	You mean your env is similar to what is described here: https://sogo.nu/support/faq/dedicated-separate-sogo-instance-for-activesync.html If you'd like to continue upload me your sogo.log.

ondrej.kolin 2020-01-20 12:05 reporter ~0014071	I have separated the related log file. The event shows now "accepted" event in Outlook, that changed probably because of some upgrade we did in the meantime sogo.trunc (50,577 bytes) Jan 20 12:37:04 sogod [6060]: <0x0x55cd9c1b04d0[SOGoActiveSyncDispatcher]> EAS - response for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <Class>Email</Class> <SyncKey>44546-63675</SyncKey> <CollectionId>mail%2Fee293c324575615baa51000048ac2651</CollectionId> <Status>1</Status> <Commands> <Add> <ServerId>44546</ServerId> <ApplicationData> <To xmlns="Email:">"Ondrej Kolin" <okolin@benocs.com></To> <From xmlns="Email:">"Test testuser" <ttestuser@benocs.com></From> <Subject xmlns="Email:">Termineinladung: "Doppelganger"</Subject> <ThreadTopic xmlns="Email:">"Doppelganger"</ThreadTopic> <DateReceived xmlns="Email:">2020-01-20T11:36:54.000Z</DateReceived> <DisplayTo xmlns="Email:">okolin</DisplayTo> <Importance xmlns="Email:">1</Importance> <Read xmlns="Email:">0</Read> <MessageClass xmlns="Email:">IPM.Schedule.Meeting.Request</MessageClass> <MeetingRequest xmlns="Email:"> <AllDayEvent>0</AllDayEvent> <StartTime>2020-01-22T11:30:00.000Z</StartTime> <DTStamp>2020-01-20T11:36:16.000Z</DTStamp> <EndTime>2020-01-22T12:30:00.000Z</EndTime> <InstanceType>0</InstanceType> <Organizer>Test testuser <ttestuser@benocs.com></Organizer> <ResponseRequested>0</ResponseRequested> <Sensitivity>0</Sensitivity> <BusyStatus>2</BusyStatus> <TimeZone>xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone> <DisallowNewTimeProposal>1</DisallowNewTimeProposal> <GlobalObjId>BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAAJQAAAHZDYWwtVWlkAQAAADZDOEUtNUUyNTkwODAtMTctMzE0MjNEMDA=</GlobalObjId> <MeetingMessageType xmlns="Email2:">1</MeetingMessageType> </MeetingRequest> <ContentClass xmlns="Email:">urn:content-classes:calendarmessage</ContentClass> <InternetCPID xmlns="Email:">65001</InternetCPID> <Body xmlns="AirSyncBase:"> <Type>1</Type> <Truncated>0</Truncated> <Preview/> <Data>Termineinladung: "Doppelganger" Test testuser Ihre Teilnahme an diesem Termin ist notwendig Beginn Mittwoch, Januar 22, 2020 - 12:30 Europe/Berlin Ende Mittwoch, Januar 22, 2020 - 13:30 Europe/Berlin</Data> <EstimatedDataSize>343</EstimatedDataSize> </Body> <Flag xmlns="Email:"> <FlagStatus>0</FlagStatus> </Flag> <Categories xmlns="Email:"/> <ConversationId xmlns="Email2:">PDZjOGUtNWUyNTkxMDAtMWQtMzE0MjNkMDBAMjQyNTEwNDk0Pg==</ConversationId> <LastVerbExecuted xmlns="Email2:">0</LastVerbExecuted> <NativeBodyType xmlns="AirSyncBase:">2</NativeBodyType> </ApplicationData> </Add> </Commands> </Collection> </Collections> </Sync> Jan 20 12:37:04 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 1253/99 0.749 - - 0 Jan 20 12:37:04 sogod [6060]: <0x0x55cd9c3794c0[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <ItemOperations xmlns="ItemOperations:"> <Fetch> <Store>Mailbox</Store> <CollectionId xmlns="AirSync:">mail%2Fee293c324575615baa51000048ac2651</CollectionId> <ServerId xmlns="AirSync:">44546</ServerId> <Options> <MIMESupport xmlns="AirSync:">2</MIMESupport> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> </BodyPreference> </Options> </Fetch> </ItemOperations> Jan 20 12:37:05 sogod [6060]: <0x0x55cd9c3794c0[SOGoActiveSyncDispatcher]> EAS - response for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <ItemOperations xmlns="ItemOperations:"> <Status>1</Status> <Response> <Fetch> <Status>1</Status> <CollectionId xmlns="AirSync:">mail%2Fee293c324575615baa51000048ac2651</CollectionId> <ServerId xmlns="AirSync:">44546</ServerId> <Properties> <To xmlns="Email:">"Ondrej Kolin" <okolin@benocs.com></To> <From xmlns="Email:">"Test testuser" <ttestuser@benocs.com></From> <Subject xmlns="Email:">Termineinladung: "Doppelganger"</Subject> <ThreadTopic xmlns="Email:">"Doppelganger"</ThreadTopic> <DateReceived xmlns="Email:">2020-01-20T11:36:54.000Z</DateReceived> <DisplayTo xmlns="Email:">okolin</DisplayTo> <Importance xmlns="Email:">1</Importance> <Read xmlns="Email:">0</Read> <MessageClass xmlns="Email:">IPM.Schedule.Meeting.Request</MessageClass> <MeetingRequest xmlns="Email:"> <AllDayEvent>0</AllDayEvent> <StartTime>2020-01-22T11:30:00.000Z</StartTime> <DTStamp>2020-01-20T11:36:16.000Z</DTStamp> <EndTime>2020-01-22T12:30:00.000Z</EndTime> <InstanceType>0</InstanceType> <Organizer>Test testuser <ttestuser@benocs.com></Organizer> <ResponseRequested>0</ResponseRequested> <Sensitivity>0</Sensitivity> <BusyStatus>2</BusyStatus> <TimeZone>xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone> <DisallowNewTimeProposal>1</DisallowNewTimeProposal> <GlobalObjId>BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAAJQAAAHZDYWwtVWlkAQAAADZDOEUtNUUyNTkwODAtMTctMzE0MjNEMDA=</GlobalObjId> <MeetingMessageType xmlns="Email2:">1</MeetingMessageType> </MeetingRequest> <ContentClass xmlns="Email:">urn:content-classes:calendarmessage</ContentClass> <InternetCPID xmlns="Email:">65001</InternetCPID> <Body xmlns="AirSyncBase:"> <Type>4</Type> <Truncated>0</Truncated> <Preview/> <Data>Return-Path: <ttestuser@benocs.com> Delivered-To: okolin@benocs.com Received: from localhost (localhost [127.0.0.1]) by mail.benocs.com (Postfix) with ESMTP id 895B6650ADC; Mon, 20 Jan 2020 12:31:44 +0100 (CET) X-Virus-Scanned: Debian amavisd-new at benocs.com Received: from mail.benocs.com ([127.0.0.1]) by localhost (mail.benocs.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id mraHU6RrQNAr; Mon, 20 Jan 2020 12:31:42 +0100 (CET) Received: from webmail.benocs.com (webmail.benocs.com [172.16.0.210]) by mail.benocs.com (Postfix) with ESMTP id A098365036A for <okolin@benocs.com>; Mon, 20 Jan 2020 12:31:42 +0100 (CET) From: "Test testuser" <ttestuser@benocs.com> Content-Type: multipart/mixed; boundary="----=_=-_OpenGroupware_org_NGMime-27790-1579520214.955912-6------" MIME-Version: 1.0 Date: Mon, 20 Jan 2020 12:36:54 +0100 Subject: =?utf-8?q?Termineinladung=3A?==?utf-8?q?_=22Doppelganger=22?= Message-ID: <6c8e-5e259100-1d-31423d00@242510494> X-Sogo-Message-Type: calendar:invitation To: "Ondrej Kolin" <okolin@benocs.com> ------=_=-_OpenGroupware_org_NGMime-27790-1579520214.955912-6------ Content-Type: text/html; charset=utf-8 <html> <head> <style type="text/css"> th, td { font-family: Lucida Grande, Bitstream VeraSans, Tahoma, sans-serif; font-size: 12px; line-height: 18px; } </style> </head> <body> <table style="width: 100%; max-width: 600px;" border="0" cellspacing="2" cellpadding="2"> <tr> <th /> <td><h1 class="ng-hide" style="font-size: 18px; font-weight: normal; padding-bottom: 9px; border-bottom: 1px solid #ccc;">Termineinladung: "Doppelganger" <small style="font-size: 12px; color: #999;">Test testuser</small></h1></td> </tr> <tr> <th /> <td><h1 style="font-size: 14px; font-weight: normal; padding-bottom: 9px; border-bottom: 1px solid #ccc;">Ihre Teilnahme an diesem Termin ist notwendig</h1></td> </tr> <tr> <th align="right" style="font-weight: bold;">Beginn</th> <td>Mittwoch, Januar 22, 2020 - 12:30 Europe/Berlin </td> </tr> <tr> <th align="right" style="font-weight: bold;">Ende</th> <td>Mittwoch, Januar 22, 2020 - 13:30 Europe/Berlin </td> </tr> </table> </body> </html> ------=_=-_OpenGroupware_org_NGMime-27790-1579520214.955912-6------ Content-Class: urn:content-classes:calendarmessage Content-Type: text/calendar; method=REQUEST; charset="UTF-8" Content-Transfer-Encoding: quoted-printable BEGIN:VCALENDAR PRODID:-//Inverse inc./SOGo 4.2.0//EN VERSION:2.0 METHOD:REQUEST BEGIN:VTIMEZONE TZID:Europe/Berlin LAST-MODIFIED:20191023T183904Z X-LIC-LOCATION:Europe/Berlin BEGIN:DAYLIGHT TZNAME:CEST TZOFFSETFROM:+0100 TZOFFSETTO:+0200 DTSTART:19700329T020000 RRULE:FREQ=3DYEARLY;BYMONTH=3D3;BYDAY=3D-1SU END:DAYLIGHT BEGIN:STANDARD TZNAME:CET TZOFFSETFROM:+0200 TZOFFSETTO:+0100 DTSTART:19701025T030000 RRULE:FREQ=3DYEARLY;BYMONTH=3D10;BYDAY=3D-1SU END:STANDARD END:VTIMEZONE BEGIN:VEVENT UID:6C8E-5E259080-17-31423D00 SUMMARY:Doppelganger CLASS:PUBLIC TRANSP:OPAQUE DTSTART;TZID=3DEurope/Berlin:20200122T123000 DTEND;TZID=3DEurope/Berlin:20200122T133000 CREATED:20200120T113616Z DTSTAMP:20200120T113616Z LAST-MODIFIED:20200120T113654Z ATTENDEE;ROLE=3DREQ-PARTICIPANT;PARTSTAT=3DNEEDS-ACTION;CN=3DOndrej Kol= in;RSVP=3DTR UE:mailto:okolin@benocs.com ORGANIZER;CN=3DTest testuser:mailto:ttestuser@benocs.com SEQUENCE:1 END:VEVENT END:VCALENDAR ------=_=-_OpenGroupware_org_NGMime-27790-1579520214.955912-6--------</Data> <EstimatedDataSize>4560</EstimatedDataSize> </Body> <Flag xmlns="Email:"> <FlagStatus>0</FlagStatus> </Flag> <Categories xmlns="Email:"/> <ConversationId xmlns="Email2:">PDZjOGUtNWUyNTkxMDAtMWQtMzE0MjNkMDBAMjQyNTEwNDk0Pg==</ConversationId> <LastVerbExecuted xmlns="Email2:">0</LastVerbExecuted> <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType> </Properties> </Fetch> </Response> </ItemOperations> Jan 20 12:37:06 sogod [6060]: <0x0x55cd9d2d2810[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <SyncKey>1-1</SyncKey> <CollectionId>mail%2F4049e917b272255eab73000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>100</WindowSize> <Options> <Conflict>0</Conflict> <FilterType>5</FilterType> </Options> </Collection> </Collections> <WindowSize>100</WindowSize> </Sync> Jan 20 12:37:06 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 0/91 0.308 - - 0 Jan 20 12:37:06 sogod [6060]: <0x0x55cd9d6ff430[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <SyncKey>1579516346</SyncKey> <CollectionId>vevent%2Fpersonal</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>100</WindowSize> <Options> <BodyPreference xmlns="AirSyncBase:"> <Type>1</Type> <TruncationSize>35000000</TruncationSize> <AllOrNone>1</AllOrNone> </BodyPreference> <Conflict>0</Conflict> <FilterType>0</FilterType> </Options> </Collection> </Collections> <WindowSize>100</WindowSize> </Sync> Jan 20 12:37:06 sogod [6060]: <0x0x55cd9d6ff430[SOGoActiveSyncDispatcher]> EAS - Use original serverId: 6C8E-5E259080-17-31423D00 (null) Jan 20 12:37:06 sogod [6060]: <0x0x55cd9d6ff430[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 20 12:37:06 sogod [6060]: <0x0x55cd9d6ff430[SOGoActiveSyncDispatcher]> EAS - response for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <Class>Calendar</Class> <SyncKey>1579520214</SyncKey> <CollectionId>vevent%2Fpersonal</CollectionId> <Status>1</Status> <Commands> <Add> <ServerId>6C8E-5E259080-17-31423D00</ServerId> <ApplicationData> <AllDayEvent xmlns="Calendar:">0</AllDayEvent> <DTStamp xmlns="Calendar:">20200120T113616Z</DTStamp> <StartTime xmlns="Calendar:">20200122T113000Z</StartTime> <EndTime xmlns="Calendar:">20200122T123000Z</EndTime> <TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone> <Organizer_Email xmlns="Calendar:">ttestuser@benocs.com</Organizer_Email> <Organizer_Name xmlns="Calendar:">Test testuser</Organizer_Name> <Attendees xmlns="Calendar:"> <Attendee> <Attendee_Email>okolin@benocs.com</Attendee_Email> <Attendee_Name>Ondrej Kolin</Attendee_Name> <Attendee_Status>5</Attendee_Status> <Attendee_Type>1</Attendee_Type> </Attendee> </Attendees> <ResponseRequested xmlns="Calendar:">1</ResponseRequested> <ResponseType xmlns="Calendar:">5</ResponseType> <DisallowNewTimeProposal xmlns="Calendar:">1</DisallowNewTimeProposal> <BusyStatus xmlns="Calendar:">2</BusyStatus> <MeetingStatus xmlns="Calendar:">3</MeetingStatus> <Subject xmlns="Calendar:">Doppelganger</Subject> <UID xmlns="Calendar:">6C8E-5E259080-17-31423D00</UID> <Sensitivity xmlns="Calendar:">0</Sensitivity> <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType> </ApplicationData> </Add> </Commands> </Collection> </Collections> </Sync> Jan 20 12:37:06 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 574/104 0.034 - - 0 Jan 20 12:37:06 sogod [6060]: <0x0x55cd9c532ed0[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <ItemOperations xmlns="ItemOperations:"> <Fetch> <Store>Mailbox</Store> <CollectionId xmlns="AirSync:">vevent%2Fpersonal</CollectionId> <ServerId xmlns="AirSync:">6C8E-5E259080-17-31423D00</ServerId> <Options> <MIMESupport xmlns="AirSync:">0</MIMESupport> <BodyPreference xmlns="AirSyncBase:"> <Type>1</Type> <TruncationSize>10000</TruncationSize> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Fetch> </ItemOperations> Jan 20 12:37:06 sogod [6060]: <0x0x55cd9c532ed0[SOGoActiveSyncDispatcher]> EAS - response for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <ItemOperations xmlns="ItemOperations:"> <Status>1</Status> <Response> <Fetch> <Status>1</Status> <CollectionId xmlns="AirSync:">vevent%2Fpersonal</CollectionId> <ServerId xmlns="AirSync:">6C8E-5E259080-17-31423D00</ServerId> <Properties> <AllDayEvent xmlns="Calendar:">0</AllDayEvent> <DTStamp xmlns="Calendar:">20200120T113616Z</DTStamp> <StartTime xmlns="Calendar:">20200122T113000Z</StartTime> <EndTime xmlns="Calendar:">20200122T123000Z</EndTime> <TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone> <Organizer_Email xmlns="Calendar:">ttestuser@benocs.com</Organizer_Email> <Organizer_Name xmlns="Calendar:">Test testuser</Organizer_Name> <Attendees xmlns="Calendar:"> <Attendee> <Attendee_Email>okolin@benocs.com</Attendee_Email> <Attendee_Name>Ondrej Kolin</Attendee_Name> <Attendee_Status>5</Attendee_Status> <Attendee_Type>1</Attendee_Type> </Attendee> </Attendees> <ResponseRequested xmlns="Calendar:">1</ResponseRequested> <ResponseType xmlns="Calendar:">5</ResponseType> <DisallowNewTimeProposal xmlns="Calendar:">1</DisallowNewTimeProposal> <BusyStatus xmlns="Calendar:">2</BusyStatus> <MeetingStatus xmlns="Calendar:">3</MeetingStatus> <Subject xmlns="Calendar:">Doppelganger</Subject> <UID xmlns="Calendar:">6C8E-5E259080-17-31423D00</UID> <Sensitivity xmlns="Calendar:">0</Sensitivity> <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType> </Properties> </Fetch> </Response> </ItemOperations> Jan 20 12:37:06 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=ItemOperations&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 555/107 0.016 - - 0 Jan 20 12:37:06 sogod [6060]: <0x0x55cd9d2eacb0[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <SyncKey>17-54</SyncKey> <CollectionId>mail%2F1cf3541367143f5c8163000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>100</WindowSize> <Options> <Conflict>0</Conflict> <FilterType>5</FilterType> </Options> </Collection> </Collections> <WindowSize>100</WindowSize> </Sync> Jan 20 12:37:07 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 0/93 0.311 - - 0 Jan 20 12:37:07 sogod [6060]: <0x0x55cd9c63a1c0[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <SyncKey>123-154</SyncKey> <CollectionId>mail%2F71b26138cfad625bd975000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>100</WindowSize> <Options> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> <TruncationSize>10000</TruncationSize> </BodyPreference> <Conflict>0</Conflict> <FilterType>5</FilterType> <MIMESupport>2</MIMESupport> </Options> </Collection> </Collections> <WindowSize>100</WindowSize> </Sync> Jan 20 12:37:07 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 0/120 0.311 - - 0 Jan 20 12:37:07 sogod [6060]: <0x0x55cd9d524490[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Ping xmlns="Ping:"> <HeartbeatInterval>180</HeartbeatInterval> <Folders> <Folder> <Id>mail%2Fee293c324575615baa51000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>mail%2F71b26138cfad625bd975000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>mail%2F4049e917b272255eab73000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>vevent%2Fpersonal</Id> <Class>Calendar</Class> </Folder> </Folders> </Ping> Jan 20 12:37:08 sogod [6060]: <0x0x55cd9d524490[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync. Jan 20 12:37:08 sogod [6060]: <0x0x55cd9d524490[SOGoActiveSyncDispatcher]> EAS - response for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Ping xmlns="Ping:"> <Status>2</Status> <Folders> <Folder>mail%2Fee293c324575615baa51000048ac2651</Folder> </Folders> </Ping> Jan 20 12:37:08 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Ping&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 58/214 0.544 - - 0 Jan 20 12:37:08 sogod [6060]: 40.101.90.77, 172.16.0.210 "OPTIONS /SOGo/Microsoft-Server-ActiveSync?Cmd=Options&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 0/0 0.004 - - 0 Jan 20 12:37:08 sogod [6060]: <0x0x55cd9c8c1520[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <FolderSync xmlns="FolderHierarchy:"> <SyncKey>activesync_benocs_com_17a9_23d5ab6a_0</SyncKey> </FolderSync> Jan 20 12:37:08 sogod [6060]: <0x0x55cd9c8c1520[SOGoActiveSyncDispatcher]> EAS - response for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <FolderSync xmlns="FolderHierarchy:"> <Status>1</Status> <SyncKey>activesync_benocs_com_17a9_23d5ab6a_0</SyncKey> <Changes> <Count>0</Count> </Changes> </FolderSync> Jan 20 12:37:08 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=FolderSync&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 61/49 0.319 - - 0 Jan 20 12:37:08 sogod [6060]: <0x0x55cd9c70e4c0[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <SyncKey>44546-63675</SyncKey> <CollectionId>mail%2Fee293c324575615baa51000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>100</WindowSize> <Options> <Conflict>0</Conflict> <FilterType>5</FilterType> </Options> </Collection> </Collections> <WindowSize>100</WindowSize> </Sync> Jan 20 12:37:09 sogod [6060]: <0x0x55cd9de24550[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 20 12:37:09 sogod [6060]: <0x0x55cd9c70e4c0[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (5) - lastObject: 44546-63675 Jan 20 12:37:09 sogod [6060]: <0x0x55cd9c70e4c0[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 44546-63675 Jan 20 12:37:09 sogod [6060]: <0x0x55cd9c70e4c0[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jan 20 12:37:09 sogod [6060]: <0x0x55cd9c70e4c0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 44546-63675 Jan 20 12:37:09 sogod [6060]: <0x0x55cd9c70e4c0[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 20 12:37:09 sogod [6060]: <0x0x55cd9c70e4c0[SOGoActiveSyncDispatcher]> EAS - response for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <Class>Email</Class> <SyncKey>44547-63675</SyncKey> <CollectionId>mail%2Fee293c324575615baa51000048ac2651</CollectionId> <Status>1</Status> </Collection> </Collections> </Sync> Jan 20 12:37:09 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 82/99 0.521 - - 0 Jan 20 12:37:09 sogod [6060]: <0x0x55cd9c0769b0[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <SyncKey>1-1</SyncKey> <CollectionId>mail%2F4049e917b272255eab73000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>100</WindowSize> <Options> <Conflict>0</Conflict> <FilterType>5</FilterType> </Options> </Collection> </Collections> <WindowSize>100</WindowSize> </Sync> Jan 20 12:37:09 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 0/91 0.309 - - 0 Jan 20 12:37:09 sogod [6060]: <0x0x55cd9bfabd60[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <SyncKey>1579520214</SyncKey> <CollectionId>vevent%2Fpersonal</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>100</WindowSize> <Options> <BodyPreference xmlns="AirSyncBase:"> <Type>1</Type> <TruncationSize>35000000</TruncationSize> <AllOrNone>1</AllOrNone> </BodyPreference> <Conflict>0</Conflict> <FilterType>0</FilterType> </Options> </Collection> </Collections> <WindowSize>100</WindowSize> </Sync> Jan 20 12:37:09 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 0/104 0.022 - - 0 Jan 20 12:37:09 sogod [6060]: <0x0x55cd9dd47310[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <SyncKey>17-54</SyncKey> <CollectionId>mail%2F1cf3541367143f5c8163000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>100</WindowSize> <Options> <Conflict>0</Conflict> <FilterType>5</FilterType> </Options> </Collection> </Collections> <WindowSize>100</WindowSize> </Sync> Jan 20 12:37:09 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 0/93 0.308 - - 0 Jan 20 12:37:10 sogod [6060]: <0x0x55cd9f111070[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <SyncKey>123-154</SyncKey> <CollectionId>mail%2F71b26138cfad625bd975000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>100</WindowSize> <Options> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> <TruncationSize>10000</TruncationSize> </BodyPreference> <Conflict>0</Conflict> <FilterType>5</FilterType> <MIMESupport>2</MIMESupport> </Options> </Collection> </Collections> <WindowSize>100</WindowSize> </Sync> Jan 20 12:37:10 sogod [6060]: 40.101.90.77, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=okolin&DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook HTTP/1.1" 200 0/120 0.304 - - 0 Jan 20 12:37:10 sogod [6060]: <0x0x55cda06f1ab0[SOGoActiveSyncDispatcher]> EAS - request for device ce6943af1c80475c969c82da57483ee2: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Ping xmlns="Ping:"> <HeartbeatInterval>180</HeartbeatInterval> <Folders> <Folder> <Id>mail%2Fee293c324575615baa51000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>mail%2F71b26138cfad625bd975000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>mail%2F4049e917b272255eab73000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>vevent%2Fpersonal</Id> <Class>Calendar</Class> </Folder> </Folders> </Ping> Jan 20 12:37:10 sogod [5955]: [WARN] <0x0x55cd9b94c390[WOWatchDogChild]> pid 6057 has been hanging in the same request for 2 minutes Jan 20 12:37:10 sogod [6060]: <0x0x55cda06f1ab0[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user okolin in Ping... Jan 20 12:37:14 sogod [6059]: <0x0x55cd9c8f1400[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user sschroeder in Ping... Jan 20 12:37:15 sogod [6057]: <0x0x55cd9cbfd850[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync. Jan 20 12:37:15 sogod [6057]: <0x0x55cd9cbfd850[SOGoActiveSyncDispatcher]> EAS - response for device E039D15ED76F4B659E5A73074A6A0C32: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Ping xmlns="Ping:"> <Status>2</Status> <Folders> <Folder>mail%2Fee293c324575615baa51000048ac2651</Folder> <Folder>vevent%2Fpersonal</Folder> <Folder>vtodo%2Fpersonal</Folder> </Folders> </Ping> Jan 20 12:37:15 sogod [6057]: 95.85.215.127, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Ping&User=okolin&DeviceId=E039D15ED76F4B659E5A73074A6A0C32&DeviceType=WindowsOutlook15 HTTP/1.1" 200 99/645 124.925 - - 0 Jan 20 12:37:15 sogod [6057]: <0x0x55cd9c6dc540[SOGoActiveSyncDispatcher]> EAS - request for device E039D15ED76F4B659E5A73074A6A0C32: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Ping xmlns="Ping:"> <HeartbeatInterval>354</HeartbeatInterval> <Folders> <Folder> <Id>mail%2Fee293c324575615baa51000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>vevent%2Fpersonal</Id> <Class>Calendar</Class> </Folder> <Folder> <Id>vcard%2Fpersonal</Id> <Class>Contacts</Class> </Folder> <Folder> <Id>mail%2F90a38300157b1b5ced1a000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>mail%2F71b26138cfad625bd975000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>vtodo%2Fpersonal</Id> <Class>Tasks</Class> </Folder> <Folder> <Id>mail%2F19c1910991673c5cbd57000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>mail%2F4049e917b272255eab73000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>mail%2F727273071f911b5c3c12000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>mail%2FJunk</Id> <Class>Email</Class> </Folder> <Folder> <Id>mail%2Fb5ff021736673c5c6557000048ac2651</Id> <Class>Email</Class> </Folder> <Folder> <Id>mail%2Fshared</Id> <Class>Email</Class> </Folder> <Folder> <Id>vevent%2Fstaff-calendar_personal</Id> <Class>Calendar</Class> </Folder> <Folder> <Id>vtodo%2Fstaff-calendar_personal</Id> <Class>Tasks</Class> </Folder> </Folders> </Ping> Jan 20 12:37:15 sogod [6054]: <0x0x55cd9c73be10[SOGoActiveSyncDispatcher]> EAS - request for device E039D15ED76F4B659E5A73074A6A0C32: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <SyncKey>44546-63674</SyncKey> <CollectionId>mail%2Fee293c324575615baa51000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>5</FilterType> <MIMESupport>2</MIMESupport> <MIMETruncation>8</MIMETruncation> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>1579516346</SyncKey> <CollectionId>vevent%2Fpersonal</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>0</FilterType> <BodyPreference xmlns="AirSyncBase:"> <Type>1</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>1576083467</SyncKey> <CollectionId>vcard%2Fpersonal</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>0</FilterType> <BodyPreference xmlns="AirSyncBase:"> <Type>1</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>116723-7331</SyncKey> <CollectionId>mail%2F90a38300157b1b5ced1a000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>5</FilterType> <MIMESupport>2</MIMESupport> <MIMETruncation>8</MIMETruncation> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>123-154</SyncKey> <CollectionId>mail%2F71b26138cfad625bd975000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>5</FilterType> <MIMESupport>2</MIMESupport> <MIMETruncation>8</MIMETruncation> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>1579516346</SyncKey> <CollectionId>vtodo%2Fpersonal</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>0</FilterType> <BodyPreference xmlns="AirSyncBase:"> <Type>1</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>1-1</SyncKey> <CollectionId>mail%2F19c1910991673c5cbd57000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>5</FilterType> <MIMESupport>2</MIMESupport> <MIMETruncation>8</MIMETruncation> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>1-1</SyncKey> <CollectionId>mail%2F4049e917b272255eab73000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>5</FilterType> <MIMESupport>2</MIMESupport> <MIMETruncation>8</MIMETruncation> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>64426-34089</SyncKey> <CollectionId>mail%2F727273071f911b5c3c12000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>5</FilterType> <MIMESupport>2</MIMESupport> <MIMETruncation>8</MIMETruncation> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>0</SyncKey> <CollectionId>mail%2FJunk</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <GetChanges>0</GetChanges> <WindowSize>512</WindowSize> <Options> <FilterType>5</FilterType> <MIMESupport>2</MIMESupport> <MIMETruncation>8</MIMETruncation> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>1-1</SyncKey> <CollectionId>mail%2Fb5ff021736673c5c6557000048ac2651</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>5</FilterType> <MIMESupport>2</MIMESupport> <MIMETruncation>8</MIMETruncation> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>0</SyncKey> <CollectionId>mail%2Fshared</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <GetChanges>0</GetChanges> <WindowSize>512</WindowSize> <Options> <FilterType>5</FilterType> <MIMESupport>2</MIMESupport> <MIMETruncation>8</MIMETruncation> <BodyPreference xmlns="AirSyncBase:"> <Type>4</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>1579513006</SyncKey> <CollectionId>vevent%2Fstaff-calendar_personal</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>0</FilterType> <BodyPreference xmlns="AirSyncBase:"> <Type>1</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> <Collection> <SyncKey>1579513006</SyncKey> <CollectionId>vtodo%2Fstaff-calendar_personal</CollectionId> <DeletesAsMoves>0</DeletesAsMoves> <WindowSize>512</WindowSize> <Options> <FilterType>0</FilterType> <BodyPreference xmlns="AirSyncBase:"> <Type>1</Type> <AllOrNone>1</AllOrNone> </BodyPreference> </Options> </Collection> </Collections> <WindowSize>512</WindowSize> </Sync> Jan 20 12:37:16 sogod [6054]: <0x0x55cd9d0856f0[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null) Jan 20 12:37:16 sogod [6054]: <0x0x55cd9c73be10[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (103) - lastObject: 44545-63674 Jan 20 12:37:16 sogod [6054]: <0x0x55cd9c73be10[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (2) - lastObject: 44546-63675 Jan 20 12:37:16 sogod [6054]: <0x0x55cd9c73be10[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0 Jan 20 12:37:16 sogod [6054]: <0x0x55cd9c73be10[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 44545-63674 Jan 20 12:37:16 sogod [6054]: <0x0x55cd9c73be10[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 44546-63675 Jan 20 12:37:16 sogod [6054]: <0x0x55cd9c73be10[SOGoActiveSyncDispatcher]> EAS - ADD! Jan 20 12:37:16 sogod [6054]: <0x0x55cd9c73be10[SOGoActiveSyncDispatcher]> EAS - first uid added 44546 Jan 20 12:37:16 sogod [6054]: <0x0x55cd9c73be10[SOGoActiveSyncDispatcher]> EAS - Use original serverId: 6C8E-5E259080-17-31423D00 (null) Jan 20 12:37:16 sogod [6057]: <0x0x55cd9c6dc540[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync. Jan 20 12:37:16 sogod [6057]: <0x0x55cd9c6dc540[SOGoActiveSyncDispatcher]> EAS - response for device E039D15ED76F4B659E5A73074A6A0C32: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Ping xmlns="Ping:"> <Status>2</Status> <Folders> <Folder>mail%2Fee293c324575615baa51000048ac2651</Folder> <Folder>vevent%2Fpersonal</Folder> <Folder>vtodo%2Fpersonal</Folder> </Folders> </Ping> Jan 20 12:37:16 sogod [6057]: 95.85.215.127, 172.16.0.210 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Ping&User=okolin&DeviceId=E039D15ED76F4B659E5A73074A6A0C32&DeviceType=WindowsOutlook15 HTTP/1.1" 200 99/645 1.119 - - 0 Jan 20 12:37:17 sogod [6054]: <0x0x55cd9c73be10[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content. Jan 20 12:37:17 sogod [6054]: <0x0x55cd9c73be10[SOGoActiveSyncDispatcher]> EAS - response for device E039D15ED76F4B659E5A73074A6A0C32: <?xml version="1.0"?> <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <Class>Email</Class> <SyncKey>44546-63675</SyncKey> <CollectionId>mail%2Fee293c324575615baa51000048ac2651</CollectionId> <Status>1</Status> <Commands> <Add> <ServerId>44546</ServerId> <ApplicationData> <To xmlns="Email:">"Ondrej Kolin" <okolin@benocs.com></To> <From xmlns="Email:">"Test testuser" <ttestuser@benocs.com></From> <Subject xmlns="Email:">Termineinladung: "Doppelganger"</Subject> <ThreadTopic xmlns="Email:">"Doppelganger"</ThreadTopic> <DateReceived xmlns="Email:">2020-01-20T11:36:54.000Z</DateReceived> <DisplayTo xmlns="Email:">okolin</DisplayTo> <Importance xmlns="Email:">1</Importance> <Read xmlns="Email:">0</Read> <MessageClass xmlns="Email:">IPM.Schedule.Meeting.Request</MessageClass> <MeetingRequest xmlns="Email:"> <AllDayEvent>0</AllDayEvent> <StartTime>2020-01-22T11:30:00.000Z</StartTime> <DTStamp>2020-01-20T11:36:16.000Z</DTStamp> <EndTime>2020-01-22T12:30:00.000Z</EndTime> <InstanceType>0</InstanceType> <Organizer>Test testuser <ttestuser@benocs.com></Organizer> <ResponseRequested>0</ResponseRequested> <Sensitivity>0</Sensitivity> <BusyStatus>2</BusyStatus> <TimeZone>xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone> <DisallowNewTimeProposal>1</DisallowNewTimeProposal> <GlobalObjId>BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAAJQAAAHZDYWwtVWlkAQAAADZDOEUtNUUyNTkwODAtMTctMzE0MjNEMDA=</GlobalObjId> </MeetingRequest> <ContentClass xmlns="Email:">urn:content-classes:calendarmessage</ContentClass> <InternetCPID xmlns="Email:">65001</InternetCPID> <Body xmlns="AirSyncBase:"> <Type>4</Type> <Truncated>0</Truncated> <Preview/> <Data>Return-Path: <ttestuser@benocs.com> Delivered-To: okolin@benocs.com Received: from localhost (localhost [127.0.0.1]) by mail.benocs.com (Postfix) with ESMTP id 895B6650ADC; Mon, 20 Jan 2020 12:31:44 +0100 (CET) X-Virus-Scanned: Debian amavisd-new at benocs.com Received: from mail.benocs.com ([127.0.0.1]) by localhost (mail.benocs.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id mraHU6RrQNAr; Mon, 20 Jan 2020 12:31:42 +0100 (CET) Received: from webmail.benocs.com (webmail.benocs.com [172.16.0.210]) by mail.benocs.com (Postfix) with ESMTP id A098365036A for <okolin@benocs.com>; Mon, 20 Jan 2020 12:31:42 +0100 (CET) From: "Test testuser" <ttestuser@benocs.com> Content-Type: multipart/mixed; boundary="----=_=-_OpenGroupware_org_NGMime-27790-1579520214.955912-6------" MIME-Version: 1.0 Date: Mon, 20 Jan 2020 12:36:54 +0100 Subject: =?utf-8?q?Termineinladung=3A?==?utf-8?q?_=22Doppelganger=22?= Message-ID: <6c8e-5e259100-1d-31423d00@242510494> X-Sogo-Message-Type: calendar:invitation To: "Ondrej Kolin" <okolin@benocs.com> ------=_=-_OpenGroupware_org_NGMime-27790-1579520214.955912-6------ Content-Type: text/html; charset=utf-8 <html> <head> <style type="text/css"> th, td { font-family: Lucida Grande, Bitstream VeraSans, Tahoma, sans-serif; font-size: 12px; line-height: 18px; } </style> </head> <body> <table style="width: 100%; max-width: 600px;" border="0" cellspacing="2" cellpadding="2"> <tr> <th /> <td><h1 class="ng-hide" style="font-size: 18px; font-weight: normal; padding-bottom: 9px; border-bottom: 1px solid #ccc;">Termineinladung: "Doppelganger" <small style="font-size: 12px; color: #999;">Test testuser</small></h1></td> </tr> <tr> <th /> <td><h1 style="font-size: 14px; font-weight: normal; padding-bottom: 9px; border-bottom: 1px solid #ccc;">Ihre Teilnahme an diesem Termin ist notwendig</h1></td> </tr> <tr> <th align="right" style="font-weight: bold;">Beginn</th> <td>Mittwoch, Januar 22, 2020 - 12:30 Europe/Berlin </td> </tr> <tr> <th align="right" style="font-weight: bold;">Ende</th> <td>Mittwoch, Januar 22, 2020 - 13:30 Europe/Berlin </td> </tr> </table> </body> </html> ------=_=-_OpenGroupware_org_NGMime-27790-1579520214.955912-6------ Content-Class: urn:content-classes:calendarmessage Content-Type: text/calendar; method=REQUEST; charset="UTF-8" Content-Transfer-Encoding: quoted-printable BEGIN:VCALENDAR PRODID:-//Inverse inc./SOGo 4.2.0//EN VERSION:2.0 METHOD:REQUEST BEGIN:VTIMEZONE TZID:Europe/Berlin LAST-MODIFIED:20191023T183904Z X-LIC-LOCATION:Europe/Berlin BEGIN:DAYLIGHT TZNAME:CEST TZOFFSETFROM:+0100 TZOFFSETTO:+0200 DTSTART:19700329T020000 RRULE:FREQ=3DYEARLY;BYMONTH=3D3;BYDAY=3D-1SU END:DAYLIGHT BEGIN:STANDARD TZNAME:CET TZOFFSETFROM:+0200 TZOFFSETTO:+0100 DTSTART:19701025T030000 RRULE:FREQ=3DYEARLY;BYMONTH=3D10;BYDAY=3D-1SU END:STANDARD END:VTIMEZONE BEGIN:VEVENT UID:6C8E-5E259080-17-31423D00 SUMMARY:Doppelganger CLASS:PUBLIC TRANSP:OPAQUE DTSTART;TZID=3DEurope/Berlin:20200122T123000 DTEND;TZID=3DEurope/Berlin:20200122T133000 CREATED:20200120T113616Z DTSTAMP:20200120T113616Z LAST-MODIFIED:20200120T113654Z ATTENDEE;ROLE=3DREQ-PARTICIPANT;PARTSTAT=3DNEEDS-ACTION;CN=3DOndrej Kol= in;RSVP=3DTR UE:mailto:okolin@benocs.com ORGANIZER;CN=3DTest testuser:mailto:ttestuser@benocs.com SEQUENCE:1 END:VEVENT END:VCALENDAR ------=_=-_OpenGroupware_org_NGMime-27790-1579520214.955912-6--------</Data> <EstimatedDataSize>4560</EstimatedDataSize> </Body> <Flag xmlns="Email:"> <FlagStatus>0</FlagStatus> </Flag> <Categories xmlns="Email:"/> <ConversationId xmlns="Email2:">PDZjOGUtNWUyNTkxMDAtMWQtMzE0MjNkMDBAMjQyNTEwNDk0Pg==</ConversationId> <LastVerbExecuted xmlns="Email2:">0</LastVerbExecuted> <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType> </ApplicationData> </Add> </Commands> </Collection> <Collection> <Class>Calendar</Class> <SyncKey>1579520214</SyncKey> <CollectionId>vevent%2Fpersonal</CollectionId> <Status>1</Status> <Commands> <Add> <ServerId>6C8E-5E259080-17-31423D00</ServerId> <ApplicationData> <AllDayEvent xmlns="Calendar:">0</AllDayEvent> <DTStamp xmlns="Calendar:">20200120T113616Z</DTStamp> <StartTime xmlns="Calendar:">20200122T113000Z</StartTime> <EndTime xmlns="Calendar:">20200122T123000Z</EndTime> <TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone> <Organizer_Email xmlns="Calendar:">ttestuser@benocs.com</Organizer_Email> <Organizer_Name xmlns="Calendar:">Test testuser</Organizer_Name> <Attendees xmlns="Calendar:"> <Attendee> <Attendee_Email>okolin@benocs.com</Attendee_Email> <Attendee_Name>Ondrej Kolin</Attendee_Name> <Attendee_Status>5</Attendee_Status> <Attendee_Type>1</Attendee_Type> </Attendee> </Attendees> <ResponseRequested xmlns="Calendar:">1</ResponseRequested> <ResponseType xmlns="Calendar:">5</ResponseType> <DisallowNewTimeProposal xmlns="Calendar:">1</DisallowNewTimeProposal> <BusyStatus xmlns="Calendar:">2</BusyStatus> <MeetingStatus xmlns="Calendar:">3</MeetingStatus> <Subject xmlns="Calendar:">Doppelganger</Subject> <UID xmlns="Calendar:">6C8E-5E259080-17-31423D00</UID> <Sensitivity xmlns="Calendar:">0</Sensitivity> <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType> </ApplicationData> </Add> </Commands> </Collection> <Collection> <Class>Contacts</Class> <SyncKey>1576083467</SyncKey> <CollectionId>vcard%2Fpersonal</CollectionId> <Status>1</Status> </Collection> <Collection> <Class>Email</Class> <SyncKey>116723-7331</SyncKey> <CollectionId>mail%2F90a38300157b1b5ced1a000048ac2651</CollectionId> <Status>1</Status> </Collection> <Collection> <Class>Email</Class> <SyncKey>123-154</SyncKey> <CollectionId>mail%2F71b26138cfad625bd975000048ac2651</CollectionId> <Status>1</Status> </Collection> <Collection> <Class>Tasks</Class> <SyncKey>1579520214</SyncKey> <CollectionId>vtodo%2Fpersonal</CollectionId> <Status>1</Status> </Collection> <Collection> <Class>Email</Class> <SyncKey>1-1</SyncKey> <CollectionId>mail%2F19c1910991673c5cbd57000048ac2651</CollectionId> <Status>1</Status> </Collection> <Collection> <Class>Email</Class> <SyncKey>1-1</SyncKey> <CollectionId>mail%2F4049e917b272255eab73000048ac2651</CollectionId> <Status>1</Status> </Collection> <Collection> <Class>Email</Class> <SyncKey>64426-34089</SyncKey> <CollectionId>mail%2F727273071f911b5c3c12000048ac2651</CollectionId> <Status>1</Status> </Collection> <Collection> <Class>Email</Class> <SyncKey>1-1</SyncKey> <CollectionId>mail%2Fb5ff021736673c5c6557000048ac2651</CollectionId> <Status>1</Status> </Collection> <Collection> <Class>Calendar</Class> <SyncKey>1579513006</SyncKey> <CollectionId>vevent%2Fstaff-calendar_personal</CollectionId> <Status>1</Status> </Collection> <Collection> <Class>Tasks</Class> <SyncKey>1579513006</SyncKey> <CollectionId>vtodo%2Fstaff-calendar_personal</CollectionId> <Status>1</Status> </Collection> </Collections> </Sync> sogo.trunc (50,577 bytes) Screenshot from 2020-01-20 13-04-51.png (894 bytes) Screenshot from 2020-01-20 13-04-51.png (894 bytes)

tfu 2020-01-20 17:41 reporter ~0014073	I don't understand your update. Is the problem still there after the "upgrade"? sogo.trunc contains traffic for 2 clients (DeviceId=E039D15ED76F4B659E5A73074A6A0C32&DeviceType=WindowsOutlook15 and DeviceId=ce6943af1c80475c969c82da57483ee2&DeviceType=Outlook). As far as I see each client receives the event (ServerId>6C8E-5E259080-17-31423D00<) only once....

ondrej.kolin 2020-01-29 08:12 reporter ~0014117	The problem is still there, the event is shown as "accepted" in outlook (solid background), but it is shown with transparent background in sogo webmail. The issue changed a bit, now I can not see the one extra event pending invitation.

tfu 2020-01-29 19:41 reporter ~0014118	Do both sogo instances (web and activesync) share the same memcached instance?

ondrej.kolin 2020-01-30 08:14 reporter ~0014121	No they don't. That could possibly cause the problem, as mentioned in the other bug.

ondrej.kolin 2020-01-30 08:16 reporter ~0014122	We don't have memcached configured in sogo.conf, we do only have the cache folder OCSCacheFolderURL = "mysql://sogo:password@<db_ip>:3306/sogo/sogo_cache_folder";

tfu 2020-02-05 09:44 reporter ~0014137	Still cannot reproduce the issue. Can you provide a sogo.log with SOGoEASDebugEnabled = YES and a detailed description about the timing (to match earch activity to a log-entry in sogo.log).

Date Modified	Username	Field
2020-01-14 08:05	ondrej.kolin	New Issue
2020-01-14 08:05	ondrej.kolin	File Added: Screenshot from 2020-01-14 08-56-09.png
2020-01-14 08:05	ondrej.kolin	File Added: Screenshot from 2020-01-14 08-54-14.png
2020-01-14 08:05	ondrej.kolin	File Added: Screenshot from 2020-01-14 08-53-09.png
2020-01-14 08:05	ondrej.kolin	File Added: Screenshot from 2020-01-14 08-52-56.png
2020-01-14 20:15	tfu	Note Added: 0014058
2020-01-16 09:25	ondrej.kolin	Note Added: 0014062
2020-01-16 16:44	tfu	Note Added: 0014064
2020-01-20 12:05	ondrej.kolin	File Added: sogo.trunc
2020-01-20 12:05	ondrej.kolin	File Added: Screenshot from 2020-01-20 13-04-51.png
2020-01-20 12:05	ondrej.kolin	Note Added: 0014071
2020-01-20 17:41	tfu	Note Added: 0014073
2020-01-29 08:12	ondrej.kolin	Note Added: 0014117
2020-01-29 19:41	tfu	Note Added: 0014118
2020-01-30 08:14	ondrej.kolin	Note Added: 0014121
2020-01-30 08:16	ondrej.kolin	Note Added: 0014122
2020-02-05 09:44	tfu	Note Added: 0014137

View Issue Details

Activities

Issue History