|
It would help if you could provide sogo.log with SOGoEASDebugEnabled = YES.
Check sogo.log for the result of "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=SendMail...".
Do you see a stuck mail in the outbox of the eas-client? |
|
|
Same problem here. I have 2 caldav (macOS) users with the same calendar accesses.
If user 1 have access to user 2's calendar, macOS of user 1 rewrites appointment data, besause it not receive appointment approval.
Detected between macOS 12.1 and 11.5 by changing caldav headers. |
|
|
Happening again - the email is being sent again if the person who invites opens email/calendar (so whenever it triggers an ActiveSync update) -
here is the log entry at that point - hope this helps to resolve the issue.
11.01.2022, 22:16:03 notice [70]: 78.94.164.238, 78.94.164.238 "POST /SOGo/Microsoft-Server-ActiveSync?User=inviter@ANONYMOUS.net&DeviceId=VMBB3S2LH11OH0CLKPJFOM2REG&DeviceType=iPhone&Cmd=Sync HTTP/1.0" 200 80/111 0.324 - - 0 - 13
11.01.2022, 22:16:03 err Error processing log message: </Sync> ype> <MIMETruncation>1</MIMETruncation> <Conflict>0</Conflict> <MIMESupport>0</MIMESupport> <BodyPreference xmlns="AirSyncBase:"> <Type>1</Type> <TruncationSize>500</TruncationSize> </BodyPreference> </Options> </Collection> </Collections> </Sync> rom> <Subject xmlns="Email:">Treffen mit Lindemann & Familie</Subject> <ThreadTopic xmlns="Email:">Treffen mit Lindemann & Familie</ThreadTopic> <DateReceived xmlns="Email:">2022-01-11T21:15:55.000Z</DateReceived> <Importance xmlns="Email:">1</Importance> <Read xmlns="Email:">1</Read> <MessageClass xmlns="Email:">IPM.Schedule.Meeting.Request</MessageClass> <MeetingRequest xmlns="Email:"> <AllDayEvent>0</AllDayEvent> <StartTime>2022-02-04T23:00:00.000Z</StartTime> <DTStamp>2022-01-10T20:30:43.000Z</DTStamp> <EndTime>2022-02-05T23:00:00.000Z</EndTime> <InstanceType>0</InstanceType> <Organizer>inviter ANONYMOUS <inviter@ANONYMOUS.net></Organizer> <ResponseRequested>0</ResponseRequested> <Sensitivity>0</Sensitivity> <BusyStatus>2</BusyStatus> <TimeZone>xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone> <DisallowNewTimeProposal>1</DisallowNewTimeProposal> <GlobalObjId>BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAALQAAAHZDYWwtVWlkAQAAADI2NDgzMkUwMDUwQTQwRDVCNEJFMDQ5RThGNkY3MzE0MA==</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></Data> <EstimatedDataSize>1</Estimate
11.01.2022, 22:16:03 notice
11.01.2022, 22:16:03 notice
11.01.2022, 22:16:03 notice
11.01.2022, 22:16:03 notice
11.01.2022, 22:16:03 notice
11.01.2022, 22:16:03 notice
11.01.2022, 22:16:03 notice
11.01.2022, 22:16:03 notice
11.01.2022, 22:16:03 err Error processing log message: <Sync xmlns="AirSync:"> <Collections> <Collection> <Class>Email</Class> <SyncKey>3126-5278</SyncKey> <CollectionId>mail%2Fc77fa7168cebd060f22a0e00efa86a2f</CollectionId> <Status>1</Status> </Collection> </Collections> </Sync> ype> <MIMETruncation>1</MIMETruncation> <Conflict>0</Conflict> <MIMESupport>0</MIMESupport> <BodyPreference xmlns="AirSyncBase:"> <Type>1</Type> <TruncationSize>500</TruncationSize> </BodyPreference> </Options> </Collection> </Collections> </Sync> rom> <Subject xmlns="Email:">Treffen mit Lindemann & Familie</Subject> <ThreadTopic xmlns="Email:">Treffen mit Lindemann & Familie</ThreadTopic> <DateReceived xmlns="Email:">2022-01-11T21:15:55.000Z</DateReceived> <Importance xmlns="Email:">1</Importance> <Read xmlns="Email:">1</Read> <MessageClass xmlns="Email:">IPM.Schedule.Meeting.Request</MessageClass> <MeetingRequest xmlns="Email:"> <AllDayEvent>0</AllDayEvent> <StartTime>2022-02-04T23:00:00.000Z</StartTime> <DTStamp>2022-01-10T20:30:43.000Z</DTStamp> <EndTime>2022-02-05T23:00:00.000Z</EndTime> <InstanceType>0</InstanceType> <Organizer>inviter ANONYMOUS <inviter@ANONYMOUS.net></Organizer> <ResponseRequested>0</ResponseRequested> <Sensitivity>0</Sensitivity> <BusyStatus>2</BusyStatus> <TimeZone>xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone> <DisallowNewTimeProposal>1</DisallowNewTimeProposal> <GlobalObjId>BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAALQAAAHZDYWwtVWlkAQAAADI2NDgzMkUwMDUwQTQwRDVCNEJFMDQ5RThGNkY3MzE0MA==</GlobalObjId> <MeetingMessageType xmlns="Email2:">1</MeetingMessageType> </MeetingRequest> <ContentClass xmlns="Email:">urn:content-classes:calendarmessage
11.01.2022, 22:16:03 err Error processing log message: <!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/"> <Sync xmlns="AirSync:"> <Collections> <Collection> <Class>Email</Class> <SyncKey>3126-5278</SyncKey> <CollectionId>mail%2Fc77fa7168cebd060f22a0e00efa86a2f</CollectionId> <Status>1</Status> </Collection> </Collections> </Sync> ype> <MIMETruncation>1</MIMETruncation> <Conflict>0</Conflict> <MIMESupport>0</MIMESupport> <BodyPreference xmlns="AirSyncBase:"> <Type>1</Type> <TruncationSize>500</TruncationSize> </BodyPreference> </Options> </Collection> </Collections> </Sync> rom> <Subject xmlns="Email:">Treffen mit Lindemann & Familie</Subject> <ThreadTopic xmlns="Email:">Treffen mit Lindemann & Familie</ThreadTopic> <DateReceived xmlns="Email:">2022-01-11T21:15:55.000Z</DateReceived> <Importance xmlns="Email:">1</Importance> <Read xmlns="Email:">1</Read> <MessageClass xmlns="Email:">IPM.Schedule.Meeting.Request</MessageClass> <MeetingRequest xmlns="Email:"> <AllDayEvent>0</AllDayEvent> <StartTime>2022-02-04T23:00:00.000Z</StartTime> <DTStamp>2022-01-10T20:30:43.000Z</DTStamp> <EndTime>2022-02-05T23:00:00.000Z</EndTime> <InstanceType>0</InstanceType> <Organizer>inviter ANONYMOUS <inviter@ANONYMOUS.net></Organizer> <ResponseRequested>0</ResponseRequested> <Sensitivity>0</Sensitivity> <BusyStatus>2</BusyStatus> <TimeZone>xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone> <DisallowNewTimeProposal>1</DisallowNewTimeProposal> <GlobalObjId>BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAALQAAAHZDYWwtVWlkAQAAADI2NDgzMkUwMDUwQTQwRDVCNEJFMDQ5RThGNkY3MzE0MA==</GlobalObjId> <MeetingMessageType xmlns="Email2:">1</MeetingMessageType>
11.01.2022, 22:16:03 notice [70]: <0x0x55fade1ce120[SOGoActiveSyncDispatcher]> EAS - response for device VMBB3S2LH11OH0CLKPJFOM2REG: <?xml version="1.0"?>
11.01.2022, 22:16:03 notice [70]: <0x0x55fade1ce120[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
11.01.2022, 22:16:03 notice [70]: <0x0x55fade1ce120[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 3125-5278
11.01.2022, 22:16:03 notice [70]: <0x0x55fade1ce120[SOGoActiveSyncDispatcher]> EAS - found in cache: 0 k = 0
11.01.2022, 22:16:03 notice [70]: <0x0x55fade1ce120[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 3125-5278
11.01.2022, 22:16:03 notice [70]: <0x0x55fade1ce120[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (98) - lastObject: 3125-5278
11.01.2022, 22:16:03 notice [70]: <0x0x55faddfd9230[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null)
11.01.2022, 22:16:03 err Error processing log message: </Sync> rom> <Subject xmlns="Email:">Treffen mit Lindemann & Familie</Subject> <ThreadTopic xmlns="Email:">Treffen mit Lindemann & Familie</ThreadTopic> <DateReceived xmlns="Email:">2022-01-11T21:15:55.000Z</DateReceived> <Importance xmlns="Email:">1</Importance> <Read xmlns="Email:">1</Read> <MessageClass xmlns="Email:">IPM.Schedule.Meeting.Request</MessageClass> <MeetingRequest xmlns="Email:"> <AllDayEvent>0</AllDayEvent> <StartTime>2022-02-04T23:00:00.000Z</StartTime> <DTStamp>2022-01-10T20:30:43.000Z</DTStamp> <EndTime>2022-02-05T23:00:00.000Z</EndTime> <InstanceType>0</InstanceType> <Organizer>inviter ANONYMOUS <inviter@ANONYMOUS.net></Organizer> <ResponseRequested>0</ResponseRequested> <Sensitivity>0</Sensitivity> <BusyStatus>2</BusyStatus> <TimeZone>xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone> <DisallowNewTimeProposal>1</DisallowNewTimeProposal> <GlobalObjId>BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAALQAAAHZDYWwtVWlkAQAAADI2NDgzMkUwMDUwQTQwRDVCNEJFMDQ5RThGNkY3MzE0MA==</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></Data> <EstimatedDataSize>1</EstimatedDataSize> </Body> <Attachments xmlns="AirSyncBase:"> <Attachment> <DisplayName>meeting.ics</DisplayName> <FileReference>mail/Sent/3125/2</FileReference> <Method>1</Method> <EstimatedDataSize>1354</EstimatedDataSize> </Attachm
11.01.2022, 22:16:03 notice |
|
|
Not sure if relevant but the entry is a full day event which is already correctly shown in invitee‘s calendar. |
|
|
Reconfirmed this bug is also present in 5.5.0 - happens frequently with all-day events. With every EAS sync, a new „Meeting cancelled“ email is sent. Only chance is to delete the event completely from the invitee‘s calendar also. So severe bug - it spams mailboxes, so renders Soago useless for this setup with iPhones/EAS for calendar invites.
Can someone please check? If more info needed happy to debug!! |
|
|
In the initial problem description you say that you get repeated " invitation mails". In your last update it is mentioned every EAS sync causes a "Meeting cancelled" email.
Is the involvement of a caldav client relevant to reproduce the problem? i.e. can you reproduce the problem when you disable the caldav client?
Can try to identify the sender of the mail by reviewing the raw-content? i.e. is it sent by the sogo-server or directly from the iphone. |
|
|
In my case all clients uses caldav only ( via iCalendar in macOS ) |
|
|
I checked the situation with different set-ups (EAS to EAS, EAS to CalDAV, CalDAV to CalDAV) - it happens again and again, so no pattern can be seen involving EAS and/or CalDAV. All Mac/iOS-based. Let me know if we can do more debug logging that would help you. |
|
|
Hi there, this issue still persists, even though it cannot easily be reproduced - what is clear is that. For some cancelled/changed appointments where I get invited to from another SoGo user (using iPhone Active Sync), I get the invitiation change email upon each and every use ActiveSync update, so endlessly... Big hassle. |
|
|
P.S. tested also on 5.6.0 |
|
|
P.P.S. If it helps another observation: it seems that after a restart of the server, the sending of the stuck/endlessly being transmitted invitation emails stops… until the next situation arises. |
|
|
Additional info - the emails are definitely sent by Sogo, and not by the user's iPhone. Currently happening again. If you require additional info, please let me know, so we get this fixed. |
|
|
Seems fixed in the more recent releases as this issue did not occur again for some time now. |
|
|
Thank you for the feedback I close this one |
|