View Issue Details

Jump to NotesJump to History
IDProjectCategoryView StatusDate SubmittedLast Update
0005759SOGoBackend Calendarpublic2023-04-28 15:152023-07-23 14:45
ReporterS1ash Assigned To 
PrioritynormalSeveritymajorReproducibilityalways
Status newResolutionopen 
OSiOSOS Version16.3.1 
Product Version5.8.2 
Summary0005759: Notifications are not processed / not received to the ios calendar
Description

We have a number of clients connected via eas - all types - Outlook iPhones and Android.
In the course of work, it turned out that periodically, clients on iPhone do not receive notifications from the calendar about a created or updated event.
Apparently, after receiving a letter containing ics, it is processed and a notification is displayed. However, this does not always happen.

In an attempt to find out, it was found that the iPhone incorrectly processes calendars coming from sogo, but for example, from Google mail, it is always successful.
In the attachment there is an example of a letter created from an iPhone, from sogo and the last one, a letter sent manually with the same ics, however, if the letter has both an attachment in base64 and with a calendar type in a quoted-printable, then the letter is successfully parsed, although two attachments and in mail on iPhone and in the web interface.
It also happens that two letters arrive - both generated by an iPhone and sogo.

Therefore, is it possible to send emails about events in both formats, as well as ignore emails from all clients (I saw a hack only for Outlook in the code), and send them exclusively using sogo?

TagsNo tags attached.

Activities

S1ash

S1ash

2023-04-28 15:15

reporter  

created_in_ios_mail.eml (2,954 bytes)
crafted_mail.eml (3,575 bytes)
created_in_sogo_calendar.eml (4,716 bytes)
tfu

tfu

2023-07-19 20:02

reporter   ~0017118

I updated the dates in created_in_sogo_calendar.eml to be in future and injected it into my mail system.
After syncing the mail via EAS to iphone I get a notification as expected.

Sounds like a character set problem as it works for you when the ics is base64 encoded.
Can you set SOGoEASDebugEnabled = YES and provide sogo.log after reproducing the problem with a new invitation?
S1ash

S1ash

2023-07-21 22:16

reporter   ~0017124

Okay, I took a clean 5.8.4 from the master branch
The first letter was sent by testuser01 to the second mailbox from the web interface. There is no push, the meeting is displayed in the calendar (Siri pulled it out of the letter)

The second letter I took the event generated by SOGO, copied it, changing one character in the form and the start and end dates of the meeting (by the way, the question is what's wrong with the time zone and why it became +1, but now it doesn't matter). Sent by a script that simulates sending a gmail - two ics, one of which is in base64 and voila, the push came instantly.

I would be very grateful for help. I already rummaged through the sources in an attempt to add a second attachment, but there is a suspicion that the assignment of the boundary is incorrect

01.eml (4,598 bytes)
02.eml (3,519 bytes)
S1ash

S1ash

2023-07-21 22:17

reporter   ~0017125

sogo_01.log (18,134 bytes)    
Jul 22 00:51:05 sogod [77844]: version 5.8.4 (build root@web-01 202307212357) -- starting
Jul 22 00:51:05 sogod [77844]: vmem size check enabled: shutting down app when vmem > 1000 MB. Currently at 432 MB
Jul 22 00:51:05 sogod [77844]: <0x0x5606edf97fa0[SOGoProductLoader]> SOGo products loaded from '/usr/local/lib64/GNUstep/SOGo':
Jul 22 00:51:05 sogod [77844]: <0x0x5606edf97fa0[SOGoProductLoader]>   Appointments.SOGo, Contacts.SOGo, Mailer.SOGo, CommonUI.SOGo, ContactsUI.SOGo, MailPartViewers.SOGo, MailerUI.SOGo, MainUI.SOGo, PreferencesUI.SOGo, SchedulerUI.SOGo, AdministrationUI.SOGo, ActiveSync.SOGo
Jul 22 00:51:05 sogod [77844]: All products loaded - current memory usage at 484 MB
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> listening on 0.0.0.0:20000
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> watchdog process pid: 77844
Jul 22 00:51:05 sogod [77844]: <0x0x7fd0f3ef4fe0[WOWatchDogChild]> watchdog request timeout set to 6 minutes
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> preparing 20 children
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77846
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77847
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77848
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77849
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77850
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77851
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77852
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77853
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77854
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77855
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77856
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77857
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77858
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77859
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77860
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77861
Jul 22 00:51:05 sogod [77847]: <0x0x5606ee1f0400[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77846]: <0x0x5606ee1f14d0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77862
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77863
Jul 22 00:51:05 sogod [77849]: <0x0x5606ee1e4fb0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77848]: <0x0x5606ee1ef690[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77864
Jul 22 00:51:05 sogod [77844]: <0x0x5606edfc9fd0[WOWatchDog]> child spawned with pid 77865
Jul 22 00:51:05 sogod [77851]: <0x0x5606ee1eae60[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77850]: <0x0x5606ee1ec4b0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77854]: <0x0x5606ee1e4fb0[WOHttpAdaptor]> notified the watchdog that we are readyJul 22 00:51:05 sogod [77853]: <0x0x5606ee1e7520[WOHttpAdaptor]> notified the watchdog that we are ready

Jul 22 00:51:05 sogod [77855]: <0x0x5606ee1e31f0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77852]: <0x0x5606ee1e9cf0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77858]: <0x0x5606ee19db20[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77857]: <0x0x5606ee1c2d40[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77860]: <0x0x5606ede1c8d0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77859]: <0x0x5606ee19cec0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77862]: <0x0x5606ee18ea50[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77856]: <0x0x5606ee1c2d40[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77864]: <0x0x5606ee18ba80[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77865]: <0x0x5606ee16c730[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77863]: <0x0x5606ee18b770[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:05 sogod [77861]: <0x0x5606ee195ae0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:51:13 sogod [77861]: <0x0x5606edde4db0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Jul 22 00:51:13 sogod [77861]: <0x0x5606edde4db0[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Jul 22 00:51:13 sogod [77861]: <0x0x5606eddea5d0[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Jul 22 00:51:13 sogod [77861]: <0x0x5606eddea5d0[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Jul 22 00:51:13 sogod [77861]: <0x0x5606eddea5d0[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
Jul 22 00:51:13 sogod [77861]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Calendar/personal/newguid HTTP/1.1" 200 55/0 0.027 - - 1M - 13
Jul 22 00:51:14 sogod [77861]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Calendar/UIxAppointmentEditorTemplate HTTP/1.1" 200 39114/0 0.114 - - 2M - 13
Jul 22 00:51:26 sogod [77861]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Contacts/allContactSearch?search=te HTTP/1.1" 200 2230/0 0.072 - - 932K - 14
Jul 22 00:51:27 sogod [77861]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Contacts/allContactSearch?search=test HTTP/1.1" 200 881/0 0.021 - - 0 - 15
Jul 22 00:51:27 sogod [77861]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Contacts/allContactSearch?search=test0 HTTP/1.1" 200 36/0 0.018 - - 0 - 15
Jul 22 00:51:28 sogod [77861]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Contacts/allContactSearch?search=test HTTP/1.1" 200 881/0 0.021 - - 0 - 15
Jul 22 00:51:31 sogod [77861]: <0x0x5606ee120c50[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354)
Jul 22 00:51:31 sogod [77861]: <0x0x5606ee120c50[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("vevent%2Fpersonal", "mail%2F40b59d35cefcba64cfc53a003abbbae4", "vcard%2Fpersonal", "mail%2F41b59d35cefcba64cfc53a003abbbae4", "vtodo%2Fpersonal")
Jul 22 00:51:31 sogod [77861]: <0x0x5606ee120c50[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user testuser02@desplite.ru in Ping...
Jul 22 00:51:32 sogod [77863]: <0x0x5606edda7220[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Jul 22 00:51:32 sogod [77863]: <0x0x5606edda7220[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Jul 22 00:51:32 sogod [77865]: <0x0x5606edda7410[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Jul 22 00:51:32 sogod [77865]: <0x0x5606edda7410[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Jul 22 00:51:32 sogod [77864]: <0x0x5606edda7220[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Jul 22 00:51:32 sogod [77864]: <0x0x5606edda7220[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Jul 22 00:51:32 sogod [77865]: <0x0x5606ee0d93e0[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Jul 22 00:51:32 sogod [77865]: <0x0x5606ee0d93e0[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Jul 22 00:51:32 sogod [77865]: <0x0x5606ee0d93e0[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
Jul 22 00:51:32 sogod [77864]: <0x0x5606eddd0080[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Jul 22 00:51:32 sogod [77864]: <0x0x5606eddd0080[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Jul 22 00:51:32 sogod [77864]: <0x0x5606eddd0080[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
Jul 22 00:51:32 sogod [77863]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Contacts/allContactSearch?search=test HTTP/1.1" 200 881/0 0.094 - - 4M - 14
Jul 22 00:51:32 sogod [77865]: 89.20.1.36 "GET /SOGo/so/testuser02@desplite.ru/freebusy.ifb/ajaxRead?eday=20230728&sday=20230715 HTTP/1.1" 200 2/0 0.063 - - 3M - 13
Jul 22 00:51:32 sogod [77864]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/freebusy.ifb/ajaxRead?eday=20230728&sday=20230715 HTTP/1.1" 200 2/0 0.062 - - 3M - 13
Jul 22 00:51:42 sogod [77864]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Calendar/personal/13025-64BAFE00-1-16AC4E20.ics/saveAsAppointment HTTP/1.1" 200 20/11139 0.521 - - 5M - 14
Jul 22 00:51:42 sogod [77864]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Calendar/eventslist?asc=1&day=20230722&filterpopup=view_next7&search=title_Category_Location&sort=start&value= HTTP/1.1" 200 863/0 0.023 - - 0 - 14
Jul 22 00:51:42 sogod [77865]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Calendar/alarmslist?browserTime=1689976302 HTTP/1.1" 200 60/0 0.019 - - 0 - 14
Jul 22 00:51:42 sogod [77864]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Calendar/eventsblocks?ed=20230723&sd=20230717&view=weekview HTTP/1.1" 200 1066/0 0.020 - - 0 - 14
Jul 22 00:51:58 sogod [77864]: <0x0x5606ede04c80[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354)
Jul 22 00:51:58 sogod [77864]: <0x0x5606ede04c80[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("mail%2F784fc63af739436486952f003abbbae4", "mail%2Fb8eb4307143a436422962f003abbbae4", "vcard%2Fpersonal")
Jul 22 00:52:01 sogod [77865]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 19/144 0.123 - - 1M - 15
Jul 22 00:52:01 sogod [77861]: <0x0x5606ee120c50[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user testuser02@desplite.ru in Ping...
Jul 22 00:52:31 sogod [77844]: [WARN] <0x0x5606ee0930d0[WOWatchDogChild]> pid 77861 has been hanging in the same request for 1 minutes
Jul 22 00:52:32 sogod [77861]: <0x0x5606ee120c50[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user testuser02@desplite.ru in Ping...
Jul 22 00:52:40 sogod [77865]: <0x0x5606ee4385b0[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<FolderSync xmlns="FolderHierarchy:">
 <SyncKey>web-01_12edf_2a6b344f_0</SyncKey>
</FolderSync>

Jul 22 00:52:40 sogod [77865]: <0x0x5606ee4385b0[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<FolderSync xmlns="FolderHierarchy:">
 <Status>1</Status>
 <SyncKey>web-01_12edf_2a6b344f_0</SyncKey>
 <Changes>
  <Count>0</Count>
 </Changes>
</FolderSync>

Jul 22 00:52:40 sogod [77865]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=FolderSync HTTP/1.1" 200 47/35 0.187 - - 1M - 15
Jul 22 00:52:40 sogod [77865]: <0x0x5606ee441020[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <SyncKey>-1</SyncKey>
   <CollectionId>vevent%2Fpersonal</CollectionId>
   <GetChanges/>
   <WindowSize>25</WindowSize>
   <Options>
    <BodyPreference xmlns="AirSyncBase:">
     <Type>1</Type>
     <TruncationSize>32768</TruncationSize>
    </BodyPreference>
   </Options>
  </Collection>
 </Collections>
</Sync>

Jul 22 00:52:40 sogod [77865]: <0x0x5606ee441020[SOGoActiveSyncDispatcher]> EAS - Use original serverId: 13025-64BAFE00-1-16AC4E20 (null)
Jul 22 00:52:40 sogod [77865]: <0x0x5606ee441020[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jul 22 00:52:40 sogod [77865]: <0x0x5606ee441020[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Calendar</Class>
   <SyncKey>1689976302</SyncKey>
   <CollectionId>vevent%2Fpersonal</CollectionId>
   <Status>1</Status>
   <Commands>
    <Add>
     <ServerId>13025-64BAFE00-1-16AC4E20</ServerId>
     <ApplicationData>
      <AllDayEvent xmlns="Calendar:">0</AllDayEvent>
      <DTStamp xmlns="Calendar:">20230721T215142Z</DTStamp>
      <StartTime xmlns="Calendar:">20230722T070000Z</StartTime>
      <EndTime xmlns="Calendar:">20230722T080000Z</EndTime>
      <TimeZone xmlns="Calendar:">TP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAABQ2gAA0H8AANAvUu4GVgAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA==</TimeZone>
      <Organizer_Email xmlns="Calendar:">testuser01@desplite.ru</Organizer_Email>
      <Organizer_Name xmlns="Calendar:">Тестовый Юзер1</Organizer_Name>
      <Attendees xmlns="Calendar:">
       <Attendee>
        <Attendee_Email>testuser02@desplite.ru</Attendee_Email>
        <Attendee_Name>Тестовый Юзер2</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:">test meeting</Subject>
      <Location xmlns="Calendar:">qwerty</Location>
      <UID xmlns="Calendar:">13025-64BAFE00-1-16AC4E20</UID>
      <Sensitivity xmlns="Calendar:">3</Sensitivity>
      <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
     </ApplicationData>
    </Add>
   </Commands>
  </Collection>
 </Collections>
</Sync>

Jul 22 00:52:40 sogod [77865]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 618/64 0.041 - - 516K - 15
Jul 22 00:52:42 sogod [77865]: <0x0x5606edf6b730[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354)
Jul 22 00:52:42 sogod [77865]: <0x0x5606edf6b730[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("vevent%2Fpersonal", "mail%2F40b59d35cefcba64cfc53a003abbbae4", "vcard%2Fpersonal", "mail%2F41b59d35cefcba64cfc53a003abbbae4", "vtodo%2Fpersonal")
Jul 22 00:52:43 sogod [77856]: <0x0x5606edda70a0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Jul 22 00:52:43 sogod [77856]: <0x0x5606edda70a0[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Jul 22 00:52:43 sogod [77863]: <0x0x5606ee197090[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Jul 22 00:52:43 sogod [77863]: <0x0x5606ee197090[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Jul 22 00:52:43 sogod [77863]: <0x0x5606ee197090[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
Jul 22 00:52:43 sogod [77856]: <0x0x5606ede03870[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Jul 22 00:52:43 sogod [77856]: <0x0x5606ede03870[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Jul 22 00:52:43 sogod [77856]: <0x0x5606ede03870[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
Jul 22 00:52:43 sogod [77865]: <0x0x5606edf6b730[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user testuser02@desplite.ru in Ping...
Jul 22 00:52:43 sogod [77863]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Calendar/eventslist?asc=1&day=20230722&filterpopup=view_next7&search=title_Category_Location&sort=start&value= HTTP/1.1" 200 863/0 0.049 - - 1M - 15
Jul 22 00:52:43 sogod [77856]: 89.20.1.36 "GET /SOGo/so/testuser01@desplite.ru/Calendar/eventsblocks?ed=20230723&sd=20230717&view=weekview HTTP/1.1" 200 1066/0 0.043 - - 2M - 14
Jul 22 00:52:58 sogod [77844]: [WARN] <0x0x5606ee0baba0[WOWatchDogChild]> pid 77864 has been hanging in the same request for 1 minutes
Jul 22 00:53:02 sogod [77861]: <0x0x5606ee120c50[SOGoActiveSyncDispatcher]> EAS - Ping request canceled (77865)
Jul 22 00:53:02 sogod [77861]: <0x0x5606ee120c50[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Ping xmlns="Ping:">
 <Status>1</Status>
</Ping>

Jul 22 00:53:02 sogod [77861]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Ping HTTP/1.1" 200 13/0 90.480 - - 2M - 15
Jul 22 00:53:02 sogod [77856]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 19/144 0.119 - - 1M - 15
Jul 22 00:53:13 sogod [77865]: <0x0x5606edf6b730[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user testuser02@desplite.ru in Ping...
sogo_01.log (18,134 bytes)   
sogo_02.log (40,185 bytes)    
Jul 22 00:59:22 sogod [77926]: version 5.8.4 (build root@web-01 202307212357) -- starting
Jul 22 00:59:22 sogod [77926]: vmem size check enabled: shutting down app when vmem > 1000 MB. Currently at 432 MB
Jul 22 00:59:22 sogod [77926]: <0x0x563ecb41efa0[SOGoProductLoader]> SOGo products loaded from '/usr/local/lib64/GNUstep/SOGo':
Jul 22 00:59:22 sogod [77926]: <0x0x563ecb41efa0[SOGoProductLoader]>   Appointments.SOGo, Contacts.SOGo, Mailer.SOGo, CommonUI.SOGo, ContactsUI.SOGo, MailPartViewers.SOGo, MailerUI.SOGo, MainUI.SOGo, PreferencesUI.SOGo, SchedulerUI.SOGo, AdministrationUI.SOGo, ActiveSync.SOGo
Jul 22 00:59:23 sogod [77926]: All products loaded - current memory usage at 484 MB
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> listening on 0.0.0.0:20000
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> watchdog process pid: 77926
Jul 22 00:59:23 sogod [77926]: <0x0x7f95e8b5cfe0[WOWatchDogChild]> watchdog request timeout set to 6 minutes
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> preparing 20 children
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77927
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77928
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77929
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77930
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77931
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77932
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77933
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77934
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77935
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77936
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77937
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77938
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77939
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77940
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77941
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77942
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77943
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77944
Jul 22 00:59:23 sogod [77931]: <0x0x563ecb6734b0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77945
Jul 22 00:59:23 sogod [77926]: <0x0x563ecb450fd0[WOWatchDog]> child spawned with pid 77946
Jul 22 00:59:23 sogod [77927]: <0x0x563ecb6784d0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77929]: <0x0x563ecb676690[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77933]: <0x0x563ecb670cf0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77936]: <0x0x563ecb66a1f0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77928]: <0x0x563ecb677400[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77932]: <0x0x563ecb671e60[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77941]: <0x0x563ecb2a38d0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77937]: <0x0x563ecb649d40[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77934]: <0x0x563ecb66e520[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77935]: <0x0x563ecb66bfb0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77939]: <0x0x563ecb624b20[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77930]: <0x0x563ecb66bfb0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77945]: <0x0x563ecb612a80[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77938]: <0x0x563ecb649d40[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77944]: <0x0x563ecb612770[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77946]: <0x0x563ecb5f3730[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77942]: <0x0x563ecb61cae0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77943]: <0x0x563ecb615a50[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:23 sogod [77940]: <0x0x563ecb623ec0[WOHttpAdaptor]> notified the watchdog that we are ready
Jul 22 00:59:53 sogod [77940]: <0x0x563ecb285be0[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Jul 22 00:59:53 sogod [77940]: <0x0x563ecb285be0[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Jul 22 00:59:53 sogod [77943]: <0x0x563ecb27af90[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Jul 22 00:59:53 sogod [77943]: <0x0x563ecb27af90[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Jul 22 00:59:53 sogod [77940]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 19/66 0.128 - - 2M - 13
Jul 22 00:59:53 sogod [77943]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/unseenCount HTTP/1.1" 200 19/31 0.130 - - 2M - 12
Jul 22 00:59:54 sogod [77943]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 19/144 0.109 - - 408K - 13
Jul 22 01:00:00 sogod [77943]: <0x0x563ecb26ac20[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <SyncKey>-1</SyncKey>
   <CollectionId>mail%2F40b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <GetChanges/>
   <WindowSize>25</WindowSize>
   <Options>
    <FilterType>5</FilterType>
    <MIMETruncation>1</MIMETruncation>
    <Conflict>0</Conflict>
    <MIMESupport>0</MIMESupport>
    <BodyPreference xmlns="AirSyncBase:">
     <Type>1</Type>
     <TruncationSize>500</TruncationSize>
    </BodyPreference>
   </Options>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:00 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 0/104 0.183 - - 1M - 13
Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <SyncKey>-1</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <GetChanges/>
   <WindowSize>25</WindowSize>
   <Options>
    <FilterType>5</FilterType>
    <MIMETruncation>1</MIMETruncation>
    <Conflict>0</Conflict>
    <MIMESupport>0</MIMESupport>
    <BodyPreference xmlns="AirSyncBase:">
     <Type>1</Type>
     <TruncationSize>500</TruncationSize>
    </BodyPreference>
   </Options>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:00 sogod [77943]: <0x0x563ecb8e93a0[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null)
Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (0) - lastObject: (null)
Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 1-3
Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - found in cache: 0  k = 0
Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 1-3
Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - ADD!
Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - first uid added 1
Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jul 22 01:00:00 sogod [77943]: <0x0x563ecb3febe0[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Email</Class>
   <SyncKey>1-3</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <Status>1</Status>
   <Commands>
    <Add>
     <ServerId>1</ServerId>
     <ApplicationData>
      <To xmlns="Email:">&quot;Тестовый Юзер2&quot; &lt;testuser02@desplite.ru&gt;</To>
      <DisplayTo xmlns="Email:">Тестовый Юзер2</DisplayTo>
      <From xmlns="Email:">&quot;Тестовый Юзер1&quot; &lt;testuser01@desplite.ru&gt;</From>
      <Subject xmlns="Email:">Приглашение на мероприятие: &quot;test meeting&quot;</Subject>
      <ThreadTopic xmlns="Email:">&quot;test meeting&quot;</ThreadTopic>
      <DateReceived xmlns="Email:">2023-07-21T21:51:42.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>2023-07-22T07:00:00.000Z</StartTime>
       <DTStamp>2023-07-21T21:51:42.000Z</DTStamp>
       <EndTime>2023-07-22T08:00:00.000Z</EndTime>
       <InstanceType>0</InstanceType>
       <Location>qwerty</Location>
       <Organizer>Тестовый Юзер1 &lt;testuser01@desplite.ru&gt;</Organizer>
       <ResponseRequested>0</ResponseRequested>
       <Sensitivity>3</Sensitivity>
       <BusyStatus>2</BusyStatus>
       <TimeZone>TP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAADQsAAAPlYAAMD6a4L8fwAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA==</TimeZone>
       <DisallowNewTimeProposal>1</DisallowNewTimeProposal>
       <GlobalObjId>BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAAJQAAAHZDYWwtVWlkAQAAADEzMDI1LTY0QkFGRTAwLTEtMTZBQzRFMjA=</GlobalObjId>
       <MeetingMessageType xmlns="Email2:">1</MeetingMessageType>
      </MeetingRequest>
      <ContentClass xmlns="Email:">urn:content-classes:calendarmessage</ContentClass>
      <Reply-To xmlns="Email:">Тестовый Юзер1 &lt;testuser01@desplite.ru&gt;</Reply-To>
      <InternetCPID xmlns="Email:">65001</InternetCPID>
      <Body xmlns="AirSyncBase:">
       <Type>1</Type>
       <Truncated>0</Truncated>
       <Preview/>
       <Data>Приглашение на мероприятие: &quot;test meeting&quot; Тестовый Юзер1
      
      
        
        Ваше участие необходимо для этого события
        
        Местонахождение
        qwerty
      

      
      
      
	Начало
        Суббота, Июль 22, 2023 - 10:00 Europe/Moscow
           
      
      
        Конец
        Суббота, Июль 22, 2023 - 11:00 Europe/Moscow</Data>
       <EstimatedDataSize>1334</EstimatedDataSize>
      </Body>
      <Flag xmlns="Email:">
       <FlagStatus>0</FlagStatus>
      </Flag>
      <Categories xmlns="Email:"/>
      <ConversationId xmlns="Email2:">PDEzMDI4LTY0YmFmZTAwLTMtMjA1YjMyNDBAMjMxNTk1MjU+</ConversationId>
      <LastVerbExecuted xmlns="Email2:">0</LastVerbExecuted>
      <NativeBodyType xmlns="AirSyncBase:">2</NativeBodyType>
     </ApplicationData>
    </Add>
   </Commands>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:00 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 1709/104 0.247 - - 1M - 13
Jul 22 01:00:01 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <SyncKey>1-3</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <GetChanges>0</GetChanges>
   <Options>
    <FilterType>5</FilterType>
    <Conflict>0</Conflict>
    <MIMESupport>0</MIMESupport>
    <BodyPreference xmlns="AirSyncBase:">
     <Type>1</Type>
    </BodyPreference>
   </Options>
   <Commands>
    <Fetch>
     <ServerId>1</ServerId>
    </Fetch>
   </Commands>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:01 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jul 22 01:00:01 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Email</Class>
   <SyncKey>1-3</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <Status>1</Status>
   <Responses>
    <Fetch>
     <ServerId>1</ServerId>
     <Status>1</Status>
     <ApplicationData>
      <To xmlns="Email:">&quot;Тестовый Юзер2&quot; &lt;testuser02@desplite.ru&gt;</To>
      <DisplayTo xmlns="Email:">Тестовый Юзер2</DisplayTo>
      <From xmlns="Email:">&quot;Тестовый Юзер1&quot; &lt;testuser01@desplite.ru&gt;</From>
      <Subject xmlns="Email:">Приглашение на мероприятие: &quot;test meeting&quot;</Subject>
      <ThreadTopic xmlns="Email:">&quot;test meeting&quot;</ThreadTopic>
      <DateReceived xmlns="Email:">2023-07-21T21:51:42.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>2023-07-22T07:00:00.000Z</StartTime>
       <DTStamp>2023-07-21T21:51:42.000Z</DTStamp>
       <EndTime>2023-07-22T08:00:00.000Z</EndTime>
       <InstanceType>0</InstanceType>
       <Location>qwerty</Location>
       <Organizer>Тестовый Юзер1 &lt;testuser01@desplite.ru&gt;</Organizer>
       <ResponseRequested>0</ResponseRequested>
       <Sensitivity>3</Sensitivity>
       <BusyStatus>2</BusyStatus>
       <TimeZone>TP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAADA+AAAPlYAAND7a4L8fwAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA==</TimeZone>
       <DisallowNewTimeProposal>1</DisallowNewTimeProposal>
       <GlobalObjId>BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAAJQAAAHZDYWwtVWlkAQAAADEzMDI1LTY0QkFGRTAwLTEtMTZBQzRFMjA=</GlobalObjId>
       <MeetingMessageType xmlns="Email2:">1</MeetingMessageType>
      </MeetingRequest>
      <ContentClass xmlns="Email:">urn:content-classes:calendarmessage</ContentClass>
      <Reply-To xmlns="Email:">Тестовый Юзер1 &lt;testuser01@desplite.ru&gt;</Reply-To>
      <InternetCPID xmlns="Email:">65001</InternetCPID>
      <Body xmlns="AirSyncBase:">
       <Type>1</Type>
       <Truncated>0</Truncated>
       <Preview/>
       <Data>Приглашение на мероприятие: &quot;test meeting&quot; Тестовый Юзер1
      
      
        
        Ваше участие необходимо для этого события
        
        Местонахождение
        qwerty
      

      
      
      
	Начало
        Суббота, Июль 22, 2023 - 10:00 Europe/Moscow
           
      
      
        Конец
        Суббота, Июль 22, 2023 - 11:00 Europe/Moscow</Data>
       <EstimatedDataSize>1334</EstimatedDataSize>
      </Body>
      <Flag xmlns="Email:">
       <FlagStatus>0</FlagStatus>
      </Flag>
      <Categories xmlns="Email:"/>
      <ConversationId xmlns="Email2:">PDEzMDI4LTY0YmFmZTAwLTMtMjA1YjMyNDBAMjMxNTk1MjU+</ConversationId>
      <LastVerbExecuted xmlns="Email2:">0</LastVerbExecuted>
      <NativeBodyType xmlns="AirSyncBase:">2</NativeBodyType>
     </ApplicationData>
    </Fetch>
   </Responses>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:01 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 1714/102 0.200 - - 312K - 13
Jul 22 01:00:02 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354)
Jul 22 01:00:02 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("vevent%2Fpersonal", "mail%2F40b59d35cefcba64cfc53a003abbbae4", "vcard%2Fpersonal", "mail%2F41b59d35cefcba64cfc53a003abbbae4", "vtodo%2Fpersonal")
Jul 22 01:00:02 sogod [77943]: <0x0x563ecb91b400[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Jul 22 01:00:02 sogod [77943]: <0x0x563ecb91b400[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Jul 22 01:00:02 sogod [77943]: <0x0x563ecb91b400[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
Jul 22 01:00:02 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync.
Jul 22 01:00:02 sogod [77943]: <0x0x563ecb987b20[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Ping xmlns="Ping:">
 <Status>2</Status>
 <Folders>
  <Folder>mail%2F41b59d35cefcba64cfc53a003abbbae4</Folder>
 </Folders>
</Ping>

Jul 22 01:00:02 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Ping HTTP/1.1" 200 58/0 0.207 - - 0 - 14
Jul 22 01:00:02 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <SyncKey>1-3</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <GetChanges/>
   <WindowSize>25</WindowSize>
   <Options>
    <FilterType>5</FilterType>
    <MIMETruncation>1</MIMETruncation>
    <Conflict>0</Conflict>
    <MIMESupport>0</MIMESupport>
    <BodyPreference xmlns="AirSyncBase:">
     <Type>1</Type>
     <TruncationSize>500</TruncationSize>
    </BodyPreference>
   </Options>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:03 sogod [77943]: <0x0x563ecba28780[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null)
Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (1) - lastObject: 1-3
Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 1-3
Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - found in cache: 0  k = 0
Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 1-3
Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jul 22 01:00:03 sogod [77943]: <0x0x563ecb989520[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Email</Class>
   <SyncKey>2-3</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <Status>1</Status>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:03 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 74/105 0.201 - - 0 - 14
Jul 22 01:00:05 sogod [77943]: <0x0x563ecb6422c0[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354)
Jul 22 01:00:05 sogod [77943]: <0x0x563ecb6422c0[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("vevent%2Fpersonal", "mail%2F40b59d35cefcba64cfc53a003abbbae4", "vcard%2Fpersonal", "mail%2F41b59d35cefcba64cfc53a003abbbae4", "vtodo%2Fpersonal")
Jul 22 01:00:05 sogod [77940]: <0x0x563ecb2158a0[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354)
Jul 22 01:00:05 sogod [77940]: <0x0x563ecb2158a0[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("mail%2F784fc63af739436486952f003abbbae4", "mail%2Fb8eb4307143a436422962f003abbbae4", "vcard%2Fpersonal")
Jul 22 01:00:05 sogod [77943]: <0x0x563ecb6422c0[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user testuser02@desplite.ru in Ping...
Jul 22 01:00:18 sogod [77942]: <0x0x563ecb218610[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Jul 22 01:00:18 sogod [77942]: <0x0x563ecb218610[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Jul 22 01:00:18 sogod [77946]: <0x0x563ecb286c00[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Jul 22 01:00:18 sogod [77946]: <0x0x563ecb286c00[SOGoCache]> Using host(s) '127.0.0.1' as server(s)
Jul 22 01:00:18 sogod [77946]: 89.20.1.36 "POST /SOGo/so/testuser02@desplite.ru/Mail/unseenCount HTTP/1.1" 200 19/31 0.141 - - 3M - 13
Jul 22 01:00:18 sogod [77942]: 89.20.1.36 "POST /SOGo/so/testuser02@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 709/66 0.196 - - 5M - 14
Jul 22 01:00:19 sogod [77942]: 89.20.1.36 "POST /SOGo/so/testuser02@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 709/144 0.165 - - 80K - 14
Jul 22 01:00:35 sogod [77943]: <0x0x563ecb6422c0[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync.
Jul 22 01:00:35 sogod [77943]: <0x0x563ecb6422c0[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Ping xmlns="Ping:">
 <Status>2</Status>
 <Folders>
  <Folder>mail%2F41b59d35cefcba64cfc53a003abbbae4</Folder>
 </Folders>
</Ping>

Jul 22 01:00:35 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Ping HTTP/1.1" 200 58/0 30.307 - - 0 - 14
Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <SyncKey>2-3</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <GetChanges/>
   <WindowSize>25</WindowSize>
   <Options>
    <FilterType>5</FilterType>
    <MIMETruncation>1</MIMETruncation>
    <Conflict>0</Conflict>
    <MIMESupport>0</MIMESupport>
    <BodyPreference xmlns="AirSyncBase:">
     <Type>1</Type>
     <TruncationSize>500</TruncationSize>
    </BodyPreference>
   </Options>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:35 sogod [77943]: <0x0x563ecba7b7a0[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null)
Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (1) - lastObject: 1-3
Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (2) - lastObject: 2-4
Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - found in cache: 0  k = 0
Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 1-3
Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 2-4
Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - ADD!
Jul 22 01:00:35 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - first uid added 2
Jul 22 01:00:36 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jul 22 01:00:36 sogod [77943]: <0x0x563ecb2660c0[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Email</Class>
   <SyncKey>2-4</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <Status>1</Status>
   <Commands>
    <Add>
     <ServerId>2</ServerId>
     <ApplicationData>
      <To xmlns="Email:">&quot;testuser02@desplite.ru&quot; &lt;testuser02@desplite.ru&gt;</To>
      <DisplayTo xmlns="Email:">testuser02@desplite.ru</DisplayTo>
      <From xmlns="Email:">&quot;testuser01@desplite.ru&quot; &lt;testuser01@desplite.ru&gt;</From>
      <Subject xmlns="Email:">Subject of my email</Subject>
      <ThreadTopic xmlns="Email:">Subject of my email</ThreadTopic>
      <DateReceived xmlns="Email:">2023-07-21T21:59:35.000Z</DateReceived>
      <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>2023-07-22T11:00:00.000Z</StartTime>
       <DTStamp>2023-07-21T21:51:42.000Z</DTStamp>
       <EndTime>2023-07-22T12:00:00.000Z</EndTime>
       <InstanceType>0</InstanceType>
       <Location>qwerty</Location>
       <Organizer/>
       <ResponseRequested>0</ResponseRequested>
       <Sensitivity>3</Sensitivity>
       <BusyStatus>2</BusyStatus>
       <TimeZone>AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAIAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w==</TimeZone>
       <DisallowNewTimeProposal>1</DisallowNewTimeProposal>
       <GlobalObjId>BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAAJQAAAHZDYWwtVWlkAQAAADEzMDI1LTY0QkFGRTAwLTEtMTZBQzRFMjE=</GlobalObjId>
       <MeetingMessageType xmlns="Email2:">1</MeetingMessageType>
      </MeetingRequest>
      <ContentClass xmlns="Email:">urn:content-classes:calendarmessage</ContentClass>
      <Reply-To xmlns="Email:">testuser01@desplite.ru</Reply-To>
      <InternetCPID xmlns="Email:">65001</InternetCPID>
      <Body xmlns="AirSyncBase:">
       <Type>1</Type>
       <Truncated>0</Truncated>
       <Preview/>
       <Data>Я перестал понимать. Пуш есть?</Data>
       <EstimatedDataSize>180</EstimatedDataSize>
      </Body>
      <Attachments xmlns="AirSyncBase:">
       <Attachment>
        <DisplayName>calendar.ics</DisplayName>
        <FileReference>mail/INBOX/2/2</FileReference>
        <Method>1</Method>
        <EstimatedDataSize>1236</EstimatedDataSize>
       </Attachment>
      </Attachments>
      <Flag xmlns="Email:">
       <FlagStatus>0</FlagStatus>
      </Flag>
      <Categories xmlns="Email:"/>
      <ConversationId xmlns="Email2:">PDIwMjMwNzIxMjE1OTM2Ljg1Q0QyMTM4NzhFMjE3QG1haWwtMDEuZnN0LnNoPg==</ConversationId>
      <LastVerbExecuted xmlns="Email2:">0</LastVerbExecuted>
      <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
     </ApplicationData>
    </Add>
   </Commands>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:36 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 1096/105 0.242 - - 700K - 14
Jul 22 01:00:36 sogod [77943]: <0x0x563ecb90c460[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <SyncKey>2-4</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <GetChanges>0</GetChanges>
   <Options>
    <FilterType>5</FilterType>
    <Conflict>0</Conflict>
    <MIMESupport>0</MIMESupport>
    <BodyPreference xmlns="AirSyncBase:">
     <Type>1</Type>
    </BodyPreference>
   </Options>
   <Commands>
    <Fetch>
     <ServerId>2</ServerId>
    </Fetch>
   </Commands>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:36 sogod [77943]: <0x0x563ecb90c460[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jul 22 01:00:36 sogod [77943]: <0x0x563ecb90c460[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Email</Class>
   <SyncKey>2-4</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <Status>1</Status>
   <Responses>
    <Fetch>
     <ServerId>2</ServerId>
     <Status>1</Status>
     <ApplicationData>
      <To xmlns="Email:">&quot;testuser02@desplite.ru&quot; &lt;testuser02@desplite.ru&gt;</To>
      <DisplayTo xmlns="Email:">testuser02@desplite.ru</DisplayTo>
      <From xmlns="Email:">&quot;testuser01@desplite.ru&quot; &lt;testuser01@desplite.ru&gt;</From>
      <Subject xmlns="Email:">Subject of my email</Subject>
      <ThreadTopic xmlns="Email:">Subject of my email</ThreadTopic>
      <DateReceived xmlns="Email:">2023-07-21T21:59:35.000Z</DateReceived>
      <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>2023-07-22T11:00:00.000Z</StartTime>
       <DTStamp>2023-07-21T21:51:42.000Z</DTStamp>
       <EndTime>2023-07-22T12:00:00.000Z</EndTime>
       <InstanceType>0</InstanceType>
       <Location>qwerty</Location>
       <Organizer/>
       <ResponseRequested>0</ResponseRequested>
       <Sensitivity>3</Sensitivity>
       <BusyStatus>2</BusyStatus>
       <TimeZone>AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAIAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAEAAAAAAAAAxP///w==</TimeZone>
       <DisallowNewTimeProposal>1</DisallowNewTimeProposal>
       <GlobalObjId>BAAAAIIA4AB0xbcQGoLgCAAAAAAAAAAAAAAAAAAAAAAAAAAAJQAAAHZDYWwtVWlkAQAAADEzMDI1LTY0QkFGRTAwLTEtMTZBQzRFMjE=</GlobalObjId>
       <MeetingMessageType xmlns="Email2:">1</MeetingMessageType>
      </MeetingRequest>
      <ContentClass xmlns="Email:">urn:content-classes:calendarmessage</ContentClass>
      <Reply-To xmlns="Email:">testuser01@desplite.ru</Reply-To>
      <InternetCPID xmlns="Email:">65001</InternetCPID>
      <Body xmlns="AirSyncBase:">
       <Type>1</Type>
       <Truncated>0</Truncated>
       <Preview/>
       <Data>Я перестал понимать. Пуш есть?</Data>
       <EstimatedDataSize>180</EstimatedDataSize>
      </Body>
      <Attachments xmlns="AirSyncBase:">
       <Attachment>
        <DisplayName>calendar.ics</DisplayName>
        <FileReference>mail/INBOX/2/2</FileReference>
        <Method>1</Method>
        <EstimatedDataSize>1236</EstimatedDataSize>
       </Attachment>
      </Attachments>
      <Flag xmlns="Email:">
       <FlagStatus>0</FlagStatus>
      </Flag>
      <Categories xmlns="Email:"/>
      <ConversationId xmlns="Email2:">PDIwMjMwNzIxMjE1OTM2Ljg1Q0QyMTM4NzhFMjE3QG1haWwtMDEuZnN0LnNoPg==</ConversationId>
      <LastVerbExecuted xmlns="Email2:">0</LastVerbExecuted>
      <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
     </ApplicationData>
    </Fetch>
   </Responses>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:36 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 1101/102 0.185 - - 0 - 14
Jul 22 01:00:37 sogod [77943]: <0x0x563ecb9ba010[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354)
Jul 22 01:00:37 sogod [77943]: <0x0x563ecb9ba010[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("vevent%2Fpersonal", "mail%2F40b59d35cefcba64cfc53a003abbbae4", "vcard%2Fpersonal", "mail%2F41b59d35cefcba64cfc53a003abbbae4", "vtodo%2Fpersonal")
Jul 22 01:00:38 sogod [77943]: <0x0x563ecb9ba010[SOGoActiveSyncDispatcher]> Change detected using Ping, we let the EAS client know to send a Sync.
Jul 22 01:00:38 sogod [77943]: <0x0x563ecb9ba010[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Ping xmlns="Ping:">
 <Status>2</Status>
 <Folders>
  <Folder>mail%2F41b59d35cefcba64cfc53a003abbbae4</Folder>
 </Folders>
</Ping>

Jul 22 01:00:38 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Ping HTTP/1.1" 200 58/0 0.205 - - 0 - 14
Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <SyncKey>2-4</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <GetChanges/>
   <WindowSize>25</WindowSize>
   <Options>
    <FilterType>5</FilterType>
    <MIMETruncation>1</MIMETruncation>
    <Conflict>0</Conflict>
    <MIMESupport>0</MIMESupport>
    <BodyPreference xmlns="AirSyncBase:">
     <Type>1</Type>
     <TruncationSize>500</TruncationSize>
    </BodyPreference>
   </Options>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:38 sogod [77943]: <0x0x563ecb3fd7a0[NGImap4Client]> Note: no key found for sorting, using 'DATE': (null)
Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - sortedBySequence (2) - lastObject: 2-4
Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - allCacheObjects (1) - lastObject: 2-4
Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - found in cache: 0  k = 0
Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - Dealing with cacheObject: 2-4
Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Jul 22 01:00:38 sogod [77943]: <0x0x563ecb131ac0[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Email</Class>
   <SyncKey>3-4</SyncKey>
   <CollectionId>mail%2F41b59d35cefcba64cfc53a003abbbae4</CollectionId>
   <Status>1</Status>
  </Collection>
 </Collections>
</Sync>

Jul 22 01:00:38 sogod [77943]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=Sync HTTP/1.1" 200 74/105 0.195 - - 0 - 14
Jul 22 01:00:40 sogod [77943]: <0x0x563ecb90c500[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached HeatbeatInterval (354)
Jul 22 01:00:40 sogod [77943]: <0x0x563ecb90c500[SOGoActiveSyncDispatcher]> EAS - Empty Ping request - using cached folders ("vevent%2Fpersonal", "mail%2F40b59d35cefcba64cfc53a003abbbae4", "vcard%2Fpersonal", "mail%2F41b59d35cefcba64cfc53a003abbbae4", "vtodo%2Fpersonal")
Jul 22 01:00:40 sogod [77943]: <0x0x563ecb90c500[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user testuser02@desplite.ru in Ping...
Jul 22 01:00:41 sogod [77942]: <0x0x563ecb9417f0[SOGoActiveSyncDispatcher]> EAS - request for device KKN5M03JQ97UR7RDM6IU028FB8: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<ItemOperations xmlns="ItemOperations:">
 <Fetch>
  <Store>Mailbox</Store>
  <FileReference xmlns="AirSyncBase:">mail/INBOX/2/2</FileReference>
 </Fetch>
</ItemOperations>

Jul 22 01:00:41 sogod [77942]: <0x0x563ecb9417f0[SOGoActiveSyncDispatcher]> EAS - response for device KKN5M03JQ97UR7RDM6IU028FB8: <?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>
   <FileReference xmlns="AirSyncBase:">mail/INBOX/2/2</FileReference>
   <Properties>
    <ContentType xmlns="AirSyncBase:">text/calendar</ContentType>
    <Data>QkVHSU46VkNBTEVOREFSClBST0RJRDotLy9JbnZlcnNlIGluYy4vU09HbyA1LjguNC8vRU4KVkVSU0lPTjoyLjAKTUVUSE9EOlJFUVVFU1QKQkVHSU46VlRJTUVaT05FClRaSUQ6RXVyb3BlL01vc2NvdwpMQVNULU1PRElGSUVEOjIwMjMwNTIzVDA5MjE1N1oKWC1MSUMtTE9DQVRJT046RXVyb3BlL01vc2NvdwpCRUdJTjpTVEFOREFSRApUWk5BTUU6TVNLClRaT0ZGU0VURlJPTTorMDMwMApUWk9GRlNFVFRPOiswMzAwCkRUU1RBUlQ6MTk3MDAxMDFUMDAwMDAwCkVORDpTVEFOREFSRApFTkQ6VlRJTUVaT05FCkJFR0lOOlZFVkVOVApVSUQ6MTMwMjUtNjRCQUZFMDAtMS0xNkFDNEUyMQpTVU1NQVJZOnRlc3QgbWVldGluZwpMT0NBVElPTjpxd2VydHkKQ0xBU1M6Q09ORklERU5USUFMCkFUVEVOREVFO1JPTEU9M0RSRVEtUEFSVElDSVBBTlQ7UEFSVFNUQVQ9M0RORUVEUy1BQ1RJT047Q049M0Q9RDA9QTI9RDA9QjU9Cj1EMT04MT1EMT04Mj1EMD1CRT1EMD1CMj1EMT04Qj1EMD1COSA9RDA9QUU9RDA9Qjc9RDA9QjU9RDE9ODAyO1JTVlA9M0QKIFRSVUU6bWFpbHRvOnRlc3R1c2VyMDJAZGVzcGxpdGUucnUKVFJBTlNQOk9QQVFVRQpEVFNUQVJUO1RaSUQ9M0RFdXJvcGUvTW9zY293OjIwMjMwNzIyVDExMDAwMApEVEVORDtUWklEPTNERXVyb3BlL01vc2NvdzoyMDIzMDcyMlQxMjAwMDAKT1JHQU5JWkVSO0NOPTNEPUQwPUEyPUQwPUI1PUQxPTgxPUQxPTgyPUQwPUJFPUQwPUIyPUQxPThCPUQwPUI5ID1EMD1BRT1EMD0KPUI3PUQwPUI1PUQxPTgwMTptYWlsdG86dGVzdHVzZXIwMUBkZXNwbGl0ZS5ydQpDUkVBVEVEOjIwMjMwNzIxVDIxNTE0MloKRFRTVEFNUDoyMDIzMDcyMVQyMTUxNDJaCkxBU1QtTU9ESUZJRUQ6MjAyMzA3MjFUMjE1MTQyWgpFTkQ6VkVWRU5UCkVORDpWQ0FMRU5EQVI=</Data>
   </Properties>
  </Fetch>
 </Response>
</ItemOperations>

Jul 22 01:00:41 sogod [77942]: 95.217.221.171 "POST /SOGo/Microsoft-Server-ActiveSync?User=testuser02@desplite.ru&DeviceId=KKN5M03JQ97UR7RDM6IU028FB8&DeviceType=iPhone&Cmd=ItemOperations HTTP/1.1" 200 1271/41 0.128 - - 384K - 13
Jul 22 01:00:53 sogod [77942]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 19/66 0.587 - - 0 - 14
Jul 22 01:00:56 sogod [77942]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/unseenCount HTTP/1.1" 200 19/31 2.472 - - 0 - 13
Jul 22 01:00:56 sogod [77946]: 89.20.1.36 "POST /SOGo/so/testuser01@desplite.ru/Mail/0/folderINBOX/changes HTTP/1.1" 200 19/144 1.667 - - 0 - 14
Jul 22 01:01:05 sogod [77926]: [WARN] <0x0x563ecb51a860[WOWatchDogChild]> pid 77940 has been hanging in the same request for 1 minutes
Jul 22 01:01:10 sogod [77943]: <0x0x563ecb90c500[SOGoActiveSyncDispatcher]> Sleeping 30 seconds while detecting changes for user testuser02@desplite.ru in Ping...
sogo_02.log (40,185 bytes)   
photo_2023-07-22_01-08-01 (2).jpg (35,710 bytes)   
photo_2023-07-22_01-08-01 (2).jpg (35,710 bytes)   
photo_2023-07-22_01-08-01.jpg (120,974 bytes)   
photo_2023-07-22_01-08-01.jpg (120,974 bytes)   
tfu

tfu

2023-07-22 16:10

reporter   ~0017126

Injecting 01.eml into my env:

modified DSTART/DEND to be in future
removed headers: Received: and X-Spam-Score:

telnet localhost 25
mail from:mail@example.com
rcpt to:mail@example.com
data
<past modified 01.eml>>
<crlf>
<crlf>
.

Still cannot reproduce. I got the notification as expected. Tested with iOS 16.5.1 / SOGO 5.8.4 on "Debian GNU/Linux 11".
S1ash

S1ash

2023-07-23 14:45

reporter   ~0017129

If I'm not mistaken, I even checked this case on iredmail
I build from source on rocky linux (rhel)
if you are also compiling, could you please provide the parameters and version list of your library, I assume there might be some problem with that. for example gnustep or some ytnef - nightly builds have rpm version 2.0.0-1, although the guys already have 2.1.2, including with additional patches

further I will assume that there may be a problem in the mailer, but I think that the problem is still somewhere in the operation of the eas protocol between the client and the server

Issue History

Date Modified Username Field Change
2023-04-28 15:15 S1ash New Issue
2023-04-28 15:15 S1ash File Added: created_in_ios_mail.eml
2023-04-28 15:15 S1ash File Added: crafted_mail.eml
2023-04-28 15:15 S1ash File Added: created_in_sogo_calendar.eml
2023-07-19 20:02 tfu Note Added: 0017118
2023-07-21 22:16 S1ash Note Added: 0017124
2023-07-21 22:16 S1ash File Added: 01.eml
2023-07-21 22:16 S1ash File Added: 02.eml
2023-07-21 22:17 S1ash Note Added: 0017125
2023-07-21 22:17 S1ash File Added: sogo_01.log
2023-07-21 22:17 S1ash File Added: sogo_02.log
2023-07-21 22:17 S1ash File Added: photo_2023-07-22_01-08-01 (2).jpg
2023-07-21 22:17 S1ash File Added: photo_2023-07-22_01-08-01.jpg
2023-07-22 16:10 tfu Note Added: 0017126
2023-07-23 14:45 S1ash Note Added: 0017129