View Issue Details

Jump to NotesJump to History
IDProjectCategoryView StatusDate SubmittedLast Update
0005930SOGoActiveSyncpublic2024-02-25 19:362024-06-18 19:49
Reporterleecher Assigned To 
PrioritynormalSeveritymajorReproducibilityalways
Status newResolutionopen 
Platform[Client] MicrosoftOSWindowsOS Version7
Product Version5.8.4 
Summary0005930: SOGO Activesync does not populate Calendar Delete events
Description

I have a SOGO installation where I have multiple Outlook clients (Outlook 2016 and Outlook 2021, but version doesn't matter) that are accessing the Mailbox "office".
The "office" Account has a personal calendar.
Every Outlook client syncs to this calendar and mails via EAS protocol.
sogo.conf:

SOGoLanguage = German;
SOGoTimeZone = Europe/Vienna;
SxVMemLimit = 512;

/ Activesync /
SOGoMaximumSyncWindowSize = 100;
SOGoMaximumSyncResponseSize = 10240;
WOWatchDogRequestTimeout = 60;
WOWorkersCount = 100;
SOGoEASDebugEnabled = YES;

When adding a calendar event, the event populates nicely to all clients (so Change detected and <Add> is pushed to the clients).
However, if I delete a calendar event, the event just gets deleted on the client that issues the Delete-Request and the deletion isn't propagated to the other devices.
As the change simply isn't detected, it is hard for me to find the cause for it, but it is always reproducable on my setup, that's why I speciefed Reproducible: Always, even though, that may only be on my setup.

Steps To Reproduce

1) Add a calendar event on one machine monitoring the Mailbox.
It gets assigned an ID.
Device D2372A7F4004475F8C11CC4CE7A981E9 created the entry with UID 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD and
EAS - Generated new easId: 62A3-65DB7580-4B-45D47D00 for serverId:
040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD

2) The calendar entry gets populated and gets assigned an individual ID per receiving client, I guess, that is supposed to be like that:
Feb 25 18:15:08 sogod [20985]: <0x0x5587c8011540[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 51F9-65DB7580-791-76D69100 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD
Feb 25 18:15:08 sogod [7282]: <0x0x5587c5058500[SOGoActiveSyncDispatcher]> EAS - Reuse easId: 51F9-65DB7580-791-76D69100 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD
Feb 25 18:15:12 sogod [25251]: <0x0x5587c92db0f0[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 62A3-65DB7580-4B-45D47D00 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD
<UID xmlns="Calendar:">040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD</UID>
Feb 25 18:15:15 sogod [16530]: <0x0x5587ca3856e0[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 4092-65DB7580-65B-7352AC00 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD
<UID xmlns="Calendar:">040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD</UID>
Feb 25 18:15:18 sogod [23527]: <0x0x5587c2bab690[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 5BE7-65DB7580-3-20275600 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD
<UID xmlns="Calendar:">040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD</UID>
Feb 25 18:15:21 sogod [30522]: <0x0x5587c5aebaa0[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 773A-65DB7580-1-4BABB700 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD
<UID xmlns="Calendar:">040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD</UID>
Feb 25 18:16:56 sogod [10499]: <0x0x5587c9664500[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 2903-65DB7600-5-16B3B6C0 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD

3) Delete the calendar entry on one of the clients that just received the new event:
Device A7E7D7D2F6C741778DCAFDBACE3C4419 issues delete of 2903-65DB7600-5-16B3B6C0
The device gets the confirmation for deletion and the EAS server can also look it up to the correct UID:
Feb 25 18:17:20 sogod [25251]: <0x0x5587c7e3d5f0[SOGoActiveSyncDispatcher]> EAS - Found serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD for easId: 2903-65DB7600-5
-16B3B6C0

All the other clients should receive a DELETE for their ID for the given UID as well, but it is not being synced and therefore the deleted item sticks with the other clients, even though it was correctly deleted in the backend (as can be verified via Caldav, for instance).

Additional Information

I attached a log with the excerpt of the various steps that can be seen in EAS log.
Even if you cannot see from it, why there is no sync, maybe you can guide me on how to find out why deletion is not propagated correctly.
No real errors can be found in log so far, which would point to some issue why sync isn't carried out correctly.
So I'm out of clue what is happening here.

Tagsactive sync, calendar, delete, sync

Activities

leecher

leecher

2024-02-25 19:36

reporter  

eas-log-excerpt.txt (16,579 bytes)    
Creation
--------

Feb 25 18:15:07 sogod [20985]: <0x0x5587c8011540[SOGoActiveSyncDispatcher]> EAS - request for device D2372A7F4004475F8C11CC4CE7A981E9: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <SyncKey>1708876178</SyncKey>
   <CollectionId>vevent%2Fpersonal</CollectionId>
   <DeletesAsMoves>0</DeletesAsMoves>
   <GetChanges>0</GetChanges>
   <WindowSize>512</WindowSize>
   <Options>
    <FilterType>0</FilterType>
    <BodyPreference xmlns="AirSyncBase:">
     <Type>1</Type>
     <AllOrNone>1</AllOrNone>
    </BodyPreference>
   </Options>
   <Commands>
    <Add>
     <Class>Calendar</Class>
     <ClientId>{E7361F5A-2091-4781-BA10-E7BB019B5DE4}</ClientId>
     <ApplicationData>
      <TimeZone xmlns="Calendar:">xP///00AaQB0AHQAZQBsAGUAdQByAG8AcADkAGkAcwBjAGgAZQAgAFoAZQBpAHQAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAE0AaQB0AHQAZQBsAGUAdQByAG8AcADkAGkAcwBjAGgAZQAgAFMAbwBtAG0AZQByAHoAZQBpAHQAAAAAAAAAAAA
      <DTStamp xmlns="Calendar:">20240225T171434Z</DTStamp>
      <StartTime xmlns="Calendar:">20240621T063000Z</StartTime>
      <Subject xmlns="Calendar:">Testtermin</Subject>
      <UID xmlns="Calendar:">040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD</UID>
      <EndTime xmlns="Calendar:">20240621T070000Z</EndTime>
      <Body xmlns="AirSyncBase:">
       <Type>1</Type>
       <Data>Partyyyyy</Data>
      </Body>
      <Sensitivity xmlns="Calendar:">0</Sensitivity>
      <BusyStatus xmlns="Calendar:">2</BusyStatus>
      <AllDayEvent xmlns="Calendar:">0</AllDayEvent>
      <Reminder xmlns="Calendar:">15</Reminder>
      <MeetingStatus xmlns="Calendar:">0</MeetingStatus>
     </ApplicationData>
    </Add>
   </Commands>
  </Collection>
 </Collections>
</Sync>
Feb 25 18:15:07 sogod [20985]: [WARN] <0x0x7f6aa54d2a80[WOxElemBuilder]> could not locate builders: WOxExtElemBuilder,WOxExtElemBuilder
Feb 25 18:15:08 sogod [20985]: <0x0x5587c8011540[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 51F9-65DB7580-791-76D69100 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D
140BC8262C6B27B62FD
Feb 25 18:15:08 sogod [20985]: <0x0x5587c8011540[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Feb 25 18:15:08 sogod [20985]: <0x0x5587c8011540[SOGoActiveSyncDispatcher]> EAS - response for device D2372A7F4004475F8C11CC4CE7A981E9: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Calendar</Class>
   <SyncKey>1708876178</SyncKey>
   <CollectionId>vevent%2Fpersonal</CollectionId>
   <Status>1</Status>
   <Responses>
    <Add>
     <ClientId>{E7361F5A-2091-4781-BA10-E7BB019B5DE4}</ClientId>
     <ServerId>51F9-65DB7580-791-76D69100</ServerId>
     <Status>1</Status>
    </Add>
   </Responses>
  </Collection>
 </Collections>
</Sync>


Synchronization to other devices
--------------------------------
Feb 25 18:15:11 sogod [16530]: 192.168.2.56 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Ping&User=office&DeviceId=E429277DBAF04AE1A5AC0E1F9E9D8B0A&DeviceType=WindowsOutlook15 HTTP/1.1" 200 13/2060 11.781 - - 0 - 15
Feb 25 18:15:11 sogod [16530]: <0x0x5587ce49b580[SOGoActiveSyncDispatcher]> EAS - request for device E429277DBAF04AE1A5AC0E1F9E9D8B0A: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Ping xmlns="Ping:">
 <HeartbeatInterval>10</HeartbeatInterval>
 <Folders>
  <Folder>
   <Id>mail%2F2dff4e04c6265358eb110000a63bd99d</Id>
   <Class>Email</Class>
  </Folder>
  <Folder>
   <Id>vevent%2Fpersonal</Id>
   <Class>Calendar</Class>
  </Folder>
  <Folder>
   <Id>vcard%2Fpersonal</Id>
   <Class>Contacts</Class>
  </Folder>
  <Folder>
...
Feb 25 18:15:12 sogod [25251]: <0x0x5587c92db0f0[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 62A3-65DB7580-4B-45D47D00 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD

Feb 25 18:15:14 sogod [25251]: <0x0x5587c92db0f0[SOGoActiveSyncDispatcher]> EAS - response for device E429277DBAF04AE1A5AC0E1F9E9D8B0A: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Email</Class>
   <SyncKey>35409-138663</SyncKey>
   <CollectionId>mail%2F2dff4e04c6265358eb110000a63bd99d</CollectionId>
   <Status>1</Status>
  </Collection>
  <Collection>
   <Class>Calendar</Class>
   <SyncKey>1708881307</SyncKey>
   <CollectionId>vevent%2Fpersonal</CollectionId>
   <Status>1</Status>
   <Commands>
    <Add>
     <ServerId>62A3-65DB7580-4B-45D47D00</ServerId>
     <ApplicationData>
      <AllDayEvent xmlns="Calendar:">0</AllDayEvent>
      <StartTime xmlns="Calendar:">20240621T063000Z</StartTime>
      <EndTime xmlns="Calendar:">20240621T070000Z</EndTime>
      <TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
      <BusyStatus xmlns="Calendar:">2</BusyStatus>
      <MeetingStatus xmlns="Calendar:">0</MeetingStatus>
      <Subject xmlns="Calendar:">Testtermin</Subject>
      <UID xmlns="Calendar:">040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD</UID>
      <Body xmlns="AirSyncBase:">
       <Type>1</Type>
       <EstimatedDataSize>9</EstimatedDataSize>
       <Data>Partyyyyy</Data>
      </Body>
      <Sensitivity xmlns="Calendar:">0</Sensitivity>
      <Reminder xmlns="Calendar:">15</Reminder>
      <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
     </ApplicationData>
    </Add>
   </Commands>
  </Collection>
  <Collection>
   <Class>Contacts</Class>
   <SyncKey>1706701529</SyncKey>
   <CollectionId>vcard%2Fpersonal</CollectionId>
   <Status>1</Status>
  </Collection>
...

Feb 25 18:15:15 sogod [16530]: <0x0x5587ca3856e0[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 4092-65DB7580-65B-7352AC00 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD
Feb 25 18:15:17 sogod [16530]: <0x0x5587ca3856e0[SOGoActiveSyncDispatcher]> EAS - response for device B7D1DD7A305641AFA18499E3883B615F: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Email</Class>
   <SyncKey>35409-138663</SyncKey>
   <CollectionId>mail%2F2dff4e04c6265358eb110000a63bd99d</CollectionId>
   <Status>1</Status>
  </Collection>
  <Collection>
   <Class>Calendar</Class>
   <SyncKey>1708881307</SyncKey>
   <CollectionId>vevent%2Fpersonal</CollectionId>
   <Status>1</Status>
   <Commands>
    <Add>
     <ServerId>4092-65DB7580-65B-7352AC00</ServerId>
     <ApplicationData>
      <AllDayEvent xmlns="Calendar:">0</AllDayEvent>
      <StartTime xmlns="Calendar:">20240621T063000Z</StartTime>
      <EndTime xmlns="Calendar:">20240621T070000Z</EndTime>
      <TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
AAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
      <BusyStatus xmlns="Calendar:">2</BusyStatus>
      <MeetingStatus xmlns="Calendar:">0</MeetingStatus>
      <Subject xmlns="Calendar:">Testtermin</Subject>
      <UID xmlns="Calendar:">040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD</UID>
      <Body xmlns="AirSyncBase:">
       <Type>1</Type>
       <EstimatedDataSize>9</EstimatedDataSize>
       <Data>Partyyyyy</Data>
      </Body>
      <Sensitivity xmlns="Calendar:">0</Sensitivity>
      <Reminder xmlns="Calendar:">15</Reminder>
      <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
     </ApplicationData>
    </Add>
   </Commands>
  </Collection>
...

Feb 25 18:15:18 sogod [23527]: <0x0x5587c2bab690[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 5BE7-65DB7580-3-20275600 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD

Feb 25 18:15:20 sogod [23527]: <0x0x5587c2bab690[SOGoActiveSyncDispatcher]> EAS - response for device 71BDA8D4837B4A638352B40AEDC5B1D7: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Email</Class>
   <SyncKey>35409-138663</SyncKey>
   <CollectionId>mail%2F2dff4e04c6265358eb110000a63bd99d</CollectionId>
   <Status>1</Status>
  </Collection>
  <Collection>
   <Class>Calendar</Class>
   <SyncKey>1708881307</SyncKey>
   <CollectionId>vevent%2Fpersonal</CollectionId>
   <Status>1</Status>
   <Commands>
    <Add>
     <ServerId>5BE7-65DB7580-3-20275600</ServerId>
     <ApplicationData>
      <AllDayEvent xmlns="Calendar:">0</AllDayEvent>
      <StartTime xmlns="Calendar:">20240621T063000Z</StartTime>
      <EndTime xmlns="Calendar:">20240621T070000Z</EndTime>
      <TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
AAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
      <BusyStatus xmlns="Calendar:">2</BusyStatus>
      <MeetingStatus xmlns="Calendar:">0</MeetingStatus>
      <Subject xmlns="Calendar:">Testtermin</Subject>
      <UID xmlns="Calendar:">040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD</UID>
      <Body xmlns="AirSyncBase:">
       <Type>1</Type>
       <EstimatedDataSize>9</EstimatedDataSize>
       <Data>Partyyyyy</Data>
      </Body>
      <Sensitivity xmlns="Calendar:">0</Sensitivity>
      <Reminder xmlns="Calendar:">15</Reminder>
      <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
     </ApplicationData>
    </Add>
   </Commands>
  </Collection>
...

Feb 25 18:15:21 sogod [30522]: <0x0x5587c5aebaa0[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 773A-65DB7580-1-4BABB700 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD
Feb 25 18:15:21 sogod [30522]: <0x0x5587c5aebaa0[SOGoActiveSyncDispatcher]> EAS - response for device 92P3DKPO0514DDA17RH9JSMUGG: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Calendar</Class>
   <SyncKey>1708881307</SyncKey>
   <CollectionId>vevent%2Fpersonal</CollectionId>
   <Status>1</Status>
   <Commands>
    <Add>
     <ServerId>773A-65DB7580-1-4BABB700</ServerId>
     <ApplicationData>
      <AllDayEvent xmlns="Calendar:">0</AllDayEvent>
      <StartTime xmlns="Calendar:">20240621T063000Z</StartTime>
      <EndTime xmlns="Calendar:">20240621T070000Z</EndTime>
      <TimeZone xmlns="Calendar:">xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAFAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
AAAMAAAAFAAIAAAAAAAAAxP///w==</TimeZone>
      <BusyStatus xmlns="Calendar:">2</BusyStatus>
      <MeetingStatus xmlns="Calendar:">0</MeetingStatus>
      <Subject xmlns="Calendar:">Testtermin</Subject>
      <UID xmlns="Calendar:">040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD</UID>
      <Body xmlns="AirSyncBase:">
       <Type>1</Type>
       <EstimatedDataSize>9</EstimatedDataSize>
       <Data>Partyyyyy</Data>
      </Body>
      <Sensitivity xmlns="Calendar:">0</Sensitivity>
      <Reminder xmlns="Calendar:">15</Reminder>
      <NativeBodyType xmlns="AirSyncBase:">1</NativeBodyType>
     </ApplicationData>
    </Add>
   </Commands>
  </Collection>
 </Collections>
</Sync>

Feb 25 18:16:56 sogod [10499]: <0x0x5587c9664500[SOGoActiveSyncDispatcher]> EAS - Generated new easId: 2903-65DB7600-5-16B3B6C0 for serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D14
0BC8262C6B27B62FD


Deletion
--------
Feb 25 18:17:19 sogod [25251]: <0x0x5587c7e3d5f0[SOGoActiveSyncDispatcher]> EAS - request for device A7E7D7D2F6C741778DCAFDBACE3C4419: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <SyncKey>1708881307</SyncKey>
   <CollectionId>vevent%2Fpersonal</CollectionId>
   <DeletesAsMoves>0</DeletesAsMoves>
   <GetChanges>0</GetChanges>
   <WindowSize>512</WindowSize>
   <Options>
    <FilterType>0</FilterType>
    <BodyPreference xmlns="AirSyncBase:">
     <Type>1</Type>
     <AllOrNone>1</AllOrNone>
    </BodyPreference>
   </Options>
   <Commands>
    <Delete>
     <ServerId>2903-65DB7600-5-16B3B6C0</ServerId>
    </Delete>
   </Commands>
  </Collection>
 </Collections>
</Sync>

Feb 25 18:17:20 sogod [25251]: <0x0x5587c7e3d5f0[SOGoActiveSyncDispatcher]> EAS - Delete - Process delete for folder personal easId 2903-65DB7600-5-16B3B6C0
Feb 25 18:17:20 sogod [25251]: <0x0x5587c7e3d5f0[SOGoActiveSyncDispatcher]> EAS - Found serverId: 040000008200E00074C5B7101A82E00800000000D01B2B8E1668DA01000000000000000010000000850CB1CD3562D140BC8262C6B27B62FD for easId: 2903-65DB7600-5
-16B3B6C0
Feb 25 18:17:20 sogod [25251]: [WARN] <0x0x7f6aa54d2a80[WOxElemBuilder]> could not locate builders: WOxExtElemBuilder,WOxExtElemBuilder
Feb 25 18:17:20 sogod [25251]: <0x0x5587c7e3d5f0[SOGoActiveSyncDispatcher]> Change detected during Sync, we push the content.
Feb 25 18:17:20 sogod [25251]: <0x0x5587c7e3d5f0[SOGoActiveSyncDispatcher]> EAS - response for device A7E7D7D2F6C741778DCAFDBACE3C4419: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <Class>Calendar</Class>
   <SyncKey>1708881307</SyncKey>
   <CollectionId>vevent%2Fpersonal</CollectionId>
   <Status>1</Status>
   <Responses>
    <Delete>
     <ServerId>2903-65DB7600-5-16B3B6C0</ServerId>
     <Status>1</Status>
    </Delete>
   </Responses>
  </Collection>
 </Collections>
</Sync>

Feb 25 18:17:24 sogod [8375]: 192.168.2.50 "POST /SOGo/Microsoft-Server-ActiveSync?Cmd=Sync&User=office&DeviceId=B7D1DD7A305641AFA18499E3883B615F&DeviceType=WindowsOutlook15 HTTP/1.1" 200 2657/3836 2.314 - - 37M - 15


Not synced after deletion
-------------------------
Feb 25 18:17:25 sogod [8375]: <0x0x5587d0ca8fd0[SOGoActiveSyncDispatcher]> EAS - request for device D31F49C7C89647F6B29609AFD23282CA: <?xml version="1.0"?>
<!DOCTYPE ActiveSync PUBLIC "-//MICROSOFT//DTD ActiveSync//EN" "http://www.microsoft.com/">
<Sync xmlns="AirSync:">
 <Collections>
  <Collection>
   <SyncKey>6055-27119</SyncKey>
   <CollectionId>mail%2Fc2b3eb0890e0426268490000ae8557e3</CollectionId>
   <DeletesAsMoves>0</DeletesAsMoves>
   <WindowSize>512</WindowSize>
   <Options>
    <FilterType>0</FilterType>
    <MIMESupport>2</MIMESupport>
    <MIMETruncation>8</MIMETruncation>
    <BodyPreference xmlns="AirSyncBase:">
     <Type>4</Type>
     <AllOrNone>1</AllOrNone>
    </BodyPreference>
   </Options>
  </Collection>
  <Collection>
   <SyncKey>1707823491</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>
...

No changes! i.e. EASID 4092-65DB7580-65B-7352AC00 not looked up and not deleted.
eas-log-excerpt.txt (16,579 bytes)   
AndreyEver

AndreyEver

2024-03-14 14:12

reporter   ~0017655

Any chance support team to investigate this?
We r facing many issues with EAS work starting from no events sync from Outlook to Sogo for creator (but visible in participants calendars) and ending the issue described in this thread
leecher

leecher

2024-06-18 19:49

reporter   ~0017751

I updated to 5.10.0 and the problem seems to have disappeared, even though there was nothing in the changelog that indicated this bug. As I'm unsure if it is related to the version or due to the runtime of the daemon (update forced a restart), I'll report back in 60 days if the problem has reappeared.

Issue History

Date Modified Username Field Change
2024-02-25 19:36 leecher New Issue
2024-02-25 19:36 leecher Tag Attached: active sync
2024-02-25 19:36 leecher Tag Attached: calendar
2024-02-25 19:36 leecher Tag Attached: delete
2024-02-25 19:36 leecher Tag Attached: sync
2024-02-25 19:36 leecher File Added: eas-log-excerpt.txt
2024-03-14 14:12 AndreyEver Note Added: 0017655
2024-06-18 19:49 leecher Note Added: 0017751