View Issue Details

IDProjectCategoryView StatusLast Update
0005439SOGoBackend Generalpublic2023-09-25 06:42
Reportermksogoadmin Assigned Tosebastien  
PriorityurgentSeveritymajorReproducibilityalways
Status closedResolutionfixed 
PlatformiPhoneOSiOSOS Version-5.1
Product Version5.3.0 
Summary0005439: Endless invitation emails sent
Description

Multiple invitation emails sent to invitee for one invitation, being resent endlessly multiple times a day

Steps To Reproduce
  • user 1 has access to User 2‘s calendar
  • User 1 is connected to SoGO via iOS activeSync
  • User 1 invites User 2 for a normal event

  • User 2 gets 4 invitation emails at once
  • User 1 ALSO gets invitation emails sent

User 2 endlessly gets invitation emails multiple times a day (seems when User 1 accesses just calendar)

By the User 2 is connected via Caldav to Sogo

TagsNo tags attached.

Activities

tfu

tfu

2021-12-05 07:43

reporter   ~0015672

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?

akimov

akimov

2021-12-24 05:41

reporter   ~0015714

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.

mksogoadmin

mksogoadmin

2022-01-11 21:19

reporter   ~0015749

Last edited: 2022-01-11 21:56

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
mksogoadmin

mksogoadmin

2022-01-13 04:42

reporter   ~0015762

Not sure if relevant but the entry is a full day event which is already correctly shown in invitee‘s calendar.

mksogoadmin

mksogoadmin

2022-02-20 21:57

reporter   ~0015890

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!!

tfu

tfu

2022-02-23 10:42

reporter   ~0015910

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.

akimov

akimov

2022-02-24 04:54

reporter   ~0015912

In my case all clients uses caldav only ( via iCalendar in macOS )

mksogoadmin

mksogoadmin

2022-02-24 07:55

reporter   ~0015913

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.

mksogoadmin

mksogoadmin

2022-05-30 11:37

reporter   ~0016079

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.

mksogoadmin

mksogoadmin

2022-05-30 11:37

reporter   ~0016080

P.S. tested also on 5.6.0

mksogoadmin

mksogoadmin

2022-05-31 04:51

reporter   ~0016081

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.

mksogoadmin

mksogoadmin

2022-06-01 09:42

reporter   ~0016082

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.

mksogoadmin

mksogoadmin

2023-09-23 05:47

reporter   ~0017279

Seems fixed in the more recent releases as this issue did not occur again for some time now.

sebastien

sebastien

2023-09-25 06:42

administrator   ~0017282

Thank you for the feedback I close this one

Issue History

Date Modified Username Field Change
2021-12-04 19:46 mksogoadmin New Issue
2021-12-05 07:43 tfu Note Added: 0015672
2021-12-24 05:41 akimov Note Added: 0015714
2022-01-11 21:19 mksogoadmin Note Added: 0015749
2022-01-11 21:56 francis Note Edited: 0015749
2022-01-13 04:42 mksogoadmin Note Added: 0015762
2022-02-20 21:57 mksogoadmin Note Added: 0015890
2022-02-23 10:42 tfu Note Added: 0015910
2022-02-24 04:54 akimov Note Added: 0015912
2022-02-24 07:55 mksogoadmin Note Added: 0015913
2022-05-30 11:37 mksogoadmin Note Added: 0016079
2022-05-30 11:37 mksogoadmin Note Added: 0016080
2022-05-31 04:51 mksogoadmin Note Added: 0016081
2022-06-01 09:42 mksogoadmin Note Added: 0016082
2022-06-01 12:07 francis Steps to Reproduce Updated
2022-06-01 12:07 francis Steps to Reproduce Updated
2022-06-01 12:07 francis Steps to Reproduce Updated
2022-06-01 12:07 francis Steps to Reproduce Updated
2022-06-01 12:08 francis Steps to Reproduce Updated
2023-09-23 05:47 mksogoadmin Note Added: 0017279
2023-09-25 06:42 sebastien Note Added: 0017282
2023-09-25 06:42 sebastien Assigned To => sebastien
2023-09-25 06:42 sebastien Status new => closed
2023-09-25 06:42 sebastien Resolution open => fixed