EAS input doesnt update Calendars

andreaslang

andreaslang What event to which calender exactly did you add? Is this the complete log? I don't see any incoming change from the mobile.

No its not the complete log from grommunio-sync. It was captured with a grep of the accountname.
And i added an event "Test via EAS" to the calendar "Alex".
The part i sent here was happening as i hit the "Sync now"-Button in android calendar.

andreaslang What type of folder is the folder 'Alex'?

Its an calendar named "Alex".

I will capture more... without a grep of the accountname and the situation when i create an "trigger-event" via grommunio-web so the event created on the smartphone pops up in grommunio-web too...

andreaslang

So, here the second (much longer) log.

19:35 i have created an event "EAS-Test" on Android calendar and hit the sync-button" -> The event doesnt showed up in grommunio-web
19:37 i have created an event "TRIGGER" on grommunio-web -> it showes up in grommunio-web and the "EAS-Test" too

05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] -------- Start
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] cmd='Sync' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' version='2.0.130.fa3fdd2' method='POST'
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] Used timezone 'Europe/Vienna'
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] Grommunio using PHP-MAPI version: 2.35.36.x1dcea51 - PHP version: 8.0.30
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] Request::ProcessHeaders() ASVersion: 16.0
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] GSync::CommandNeedsProvisioning(0): true
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] ProvisioningManager->ProvisioningRequired('974652166') saved device key '974652166': false
05/11/2024 19:35:20 [ 1699] [ INFO] [the testing account] Policy 'devencenabled' not supported by the device, ignoring
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] ProvisioningManager->ProvisioningRequired() saved policy hash '431014e4a761ea216e9a35f20aaec61c' matches
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] GSync::CommandNeedsAuthentication(0): true
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] Grommunio->Logon(): Trying to authenticate user 'the testing account'..
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] Grommunio->openMessageStore('the testing account'): Found 'DEFAULT' store: 'Resource id #52'
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] Grommunio->Logon(): User 'the testing account' is authenticated
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] Store supports properties containing Unicode characters.
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] GSync::HierarchyCommand(0): false
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] GSync::CommandNeedsPlainInput(0): false
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I  <Synchronize>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I   <Folders>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I    <Folder>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I     <SyncKey>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I      {1d2051c2-a0c0-4079-95e5-9e22c6ac741b}3
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I     </SyncKey>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I     <FolderId>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I      U2d97e
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I     </FolderId>
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] DeviceManager->GetBackendIdForFolderId(): folderid U2d97e => 0d000000a5187b6fbcdcea1ed03c565700000000000f
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U2d97e' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I     <DeletesAsMoves/>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I     <GetChanges/>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I     <WindowSize>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I      25
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I     </WindowSize>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I     <Options>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I      <FilterType>
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] SyncParameters->UseCPO('DEFAULT')
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I       7
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I      </FilterType>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I      <AirSyncBase:BodyPreference>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I       <AirSyncBase:Type>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I        1
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I       </AirSyncBase:Type>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I       <AirSyncBase:TruncationSize>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I        400000
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I       </AirSyncBase:TruncationSize>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I      </AirSyncBase:BodyPreference>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I     </Options>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I    </Folder>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I   </Folders>
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] I  </Synchronize>
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] HandleSync(): Start Output
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000f'): 'false'
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] Grommunio->GetFolderStat() fetched status information of 77 folders for store 'the testing account'
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730796691/54/9/-1 - saved: 1730796691/54/9/-1 - expiring: 2024-12-06 13:44:20
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] HandleSync: No changes found for Calendar folder id 'U2d97e'. Omitting output.
05/11/2024 19:35:20 [ 1699] [WBXML] [the testing account] WBXML-IN : AwFqAEVcT0sDezFkMjA1MWMyLWEwYzAtNDA3OS05NWU1LTllMjJjNmFjNzQxYn0zAAFSA1UyZDk3ZQABHhNVAzI1AAFXWAM3AAEAEUVGAzEAAUcDNDAwMDAwAAEBAQEBAQ==
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] LoopDetection->ProcessLoopDetectionTerminate()
05/11/2024 19:35:20 [ 1699] [ INFO] [the testing account] cmd='Sync' memory='4.54 MiB/6.00 MiB' time='0.09s' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' idle='0s' version='2.0.130.fa3fdd2' method='POST' httpcode='200'
05/11/2024 19:35:20 [ 1699] [DEBUG] [the testing account] -------- End
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] -------- Start
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] cmd='Sync' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' version='2.0.130.fa3fdd2' method='POST'
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] Used timezone 'Europe/Vienna'
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] Grommunio using PHP-MAPI version: 2.35.36.x1dcea51 - PHP version: 8.0.30
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] Request::ProcessHeaders() ASVersion: 16.0
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] GSync::CommandNeedsProvisioning(0): true
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] ProvisioningManager->ProvisioningRequired('974652166') saved device key '974652166': false
05/11/2024 19:35:20 [ 4831] [ INFO] [the testing account] Policy 'devencenabled' not supported by the device, ignoring
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] ProvisioningManager->ProvisioningRequired() saved policy hash '431014e4a761ea216e9a35f20aaec61c' matches
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] GSync::CommandNeedsAuthentication(0): true
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] Grommunio->Logon(): Trying to authenticate user 'the testing account'..
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] Grommunio->openMessageStore('the testing account'): Found 'DEFAULT' store: 'Resource id #52'
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] Grommunio->Logon(): User 'the testing account' is authenticated
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] Store supports properties containing Unicode characters.
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] GSync::HierarchyCommand(0): false
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] GSync::CommandNeedsPlainInput(0): false
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I  <Synchronize>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I   <Folders>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I    <Folder>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I     <SyncKey>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I      {e84c2f57-3116-4f8a-9ea0-c9d8b4912925}1
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I     </SyncKey>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I     <FolderId>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I      U588a4
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I     </FolderId>
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] DeviceManager->GetBackendIdForFolderId(): folderid U588a4 => 0d000000a5187b6fbcdcea1ed03c5657000000000012
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U588a4' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I     <DeletesAsMoves/>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I     <GetChanges/>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I     <WindowSize>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I      25
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I     </WindowSize>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I     <Options>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I      <AirSyncBase:BodyPreference>
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] SyncParameters->UseCPO('DEFAULT')
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I       <AirSyncBase:Type>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I        1
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I       </AirSyncBase:Type>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I       <AirSyncBase:TruncationSize>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I        400000
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I       </AirSyncBase:TruncationSize>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I      </AirSyncBase:BodyPreference>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I     </Options>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I    </Folder>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I   </Folders>
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] I  </Synchronize>
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] HandleSync(): Start Output
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000012'): 'false'
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] Grommunio->GetFolderStat() fetched status information of 77 folders for store 'the testing account'
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1723446274/0/0/-1 - saved: 1723446274/0/0/-1 - expiring: 2024-12-06 09:57:25
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] HandleSync: No changes found for Tasks folder id 'U588a4'. Omitting output.
05/11/2024 19:35:20 [ 4831] [WBXML] [the testing account] WBXML-IN : AwFqAEVcT0sDe2U4NGMyZjU3LTMxMTYtNGY4YS05ZWEwLWM5ZDhiNDkxMjkyNX0xAAFSA1U1ODhhNAABHhNVAzI1AAFXABFFRgMxAAFHAzQwMDAwMAABAQEBAQE=
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] LoopDetection->ProcessLoopDetectionTerminate()
05/11/2024 19:35:20 [ 4831] [ INFO] [the testing account] cmd='Sync' memory='4.54 MiB/6.00 MiB' time='0.08s' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' idle='0s' version='2.0.130.fa3fdd2' method='POST' httpcode='200'
05/11/2024 19:35:20 [ 4831] [DEBUG] [the testing account] -------- End
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] -------- Start
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] cmd='Ping' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' version='2.0.130.fa3fdd2' method='POST'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] Used timezone 'Europe/Vienna'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] Grommunio using PHP-MAPI version: 2.35.36.x1dcea51 - PHP version: 8.0.30
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] Request::ProcessHeaders() ASVersion: 16.0
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::CommandNeedsProvisioning(18): false
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::CommandNeedsAuthentication(18): true
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] Grommunio->Logon(): Trying to authenticate user 'the testing account'..
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] Grommunio->openMessageStore('the testing account'): Found 'DEFAULT' store: 'Resource id #43'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] Grommunio->Logon(): User 'the testing account' is authenticated
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] Store supports properties containing Unicode characters.
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::HierarchyCommand(18): false
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::CommandNeedsPlainInput(18): false
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I  <Ping:Ping>
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000d'): 'false'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'Uc3998' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{5fcbc712-a4e8-48b5-a271-e14384b1bd98}1'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000e'): 'false'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'Ub49eb' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{3b9f6f68-eeec-4dae-a3dd-f4e9a5873201}1'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000f'): 'false'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U2d97e' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{1d2051c2-a0c0-4079-95e5-9e22c6ac741b}3'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000012'): 'false'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U588a4' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{e84c2f57-3116-4f8a-9ea0-c9d8b4912925}1'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000013'): 'false'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U2f8d7' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{3e59111b-2c70-4d18-b409-e79c227d2798}1'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d0001'): 'false'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'Ude579' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{787f0d03-8250-4005-ad3f-7bf764e69138}31'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d2711'): 'false'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U680a7' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{4f4a5168-ca31-4230-bb99-fabdb6a90641}28'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d4e21'): 'false'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U9748b' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{c40b50e0-e306-4f34-808f-eb1f9fea0d87}18'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d7531'): 'false'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U6e663' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{98b88122-d3b5-4e67-b520-1203df4f3c71}27'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id '' : ref. Lifetime '880', last sync at '1730823594'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->AddCollection(): Updated reference PolicyKey '-1', reference Lifetime '880', Last sync at '1730823594'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{08851729-fd2d-48c1-8807-e36e6ba01924}1'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] StateManager->loadHierarchyCache(): 'sec11421eb558349-08851729-fd2d-48c1-8807-e36e6ba01924-hc-1'
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] HandlePing(): reference PolicyKey for PING: -1
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] HandlePing(): initialization data received
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I   <Ping:LifeTime>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    880
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I   </Ping:LifeTime>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I   <Ping:Folders>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      U680a7
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      Calendar
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U680a7'
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      U9748b
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      Calendar
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U9748b'
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      Uc3998
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      Email
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Email' id 'Uc3998'
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      Ub49eb
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      Email
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Email' id 'Ub49eb'
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      U2f8d7
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      Contacts
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Contacts' id 'U2f8d7'
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      U2d97e
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      Calendar
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U2d97e'
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      Ude579
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      Calendar
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'Ude579'
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      U6e663
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I      Calendar
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U6e663'
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I   </Ping:Folders>
05/11/2024 19:35:20 [ 4832] [WBXML] [the testing account] I  </Ping:Ping>
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'Uc3998' changed
05/11/2024 19:35:20 [ 4832] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'Ub49eb' changed
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U2d97e' changed
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U588a4' changed
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U2f8d7' changed
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'Ude579' changed
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U680a7' changed
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U9748b' changed
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U6e663' changed
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder '' changed
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] DeviceManager->DoAutomaticASDeviceSaving(): save automatically: false
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Announce process as PUSH connection
05/11/2024 19:35:21 [ 4832] [ INFO] [the testing account] SyncCollections->CheckForChanges(): Waiting for store changes... (lifetime 880 seconds)
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] -------- Start
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] cmd='Ping' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' version='2.0.130.fa3fdd2' method='POST'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Used timezone 'Europe/Vienna'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Grommunio->HasChangesSink(): created - HierarchyHash: a8f59b0837e156a094810521794d39c5
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio using PHP-MAPI version: 2.35.36.x1dcea51 - PHP version: 8.0.30
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Request::ProcessHeaders() ASVersion: 16.0
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::CommandNeedsProvisioning(18): false
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::CommandNeedsAuthentication(18): true
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->Logon(): Trying to authenticate user 'the testing account'..
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Grommunio->adviseStoreToSink(): advised store 'Resource id #43'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000d'): 'false'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c565700000000000d'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Grommunio->GetFolderStat() fetched status information of 77 folders for store 'the testing account'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1727890738/1/0/-1 - saved: 1727890738/1/0/-1 - expiring: 2024-11-08 16:11:08
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000e'): 'false'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c565700000000000e'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1723446274/0/0/-1 - saved: 1723446274/0/0/-1 - expiring: 2024-12-06 13:29:30
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->openMessageStore('the testing account'): Found 'DEFAULT' store: 'Resource id #43'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000f'): 'false'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c565700000000000f'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730796691/54/9/-1 - saved: 1730796691/54/9/-1 - expiring: 2024-12-06 13:44:20
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->Logon(): User 'the testing account' is authenticated
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000013'): 'false'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c5657000000000013'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1723446274/0/0/-1 - saved: 1723446274/0/0/-1 - expiring: 2024-12-06 15:30:39
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d0001'): 'false'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d0001'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730823805/54/8/-1 - saved: 1730823805/54/8/-1 - expiring: 2024-12-06 07:48:13
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d2711'): 'false'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d2711'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730790220/64/16/-1 - saved: 1730790220/64/16/-1 - expiring: 2024-12-06 14:05:46
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d4e21'): 'false'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d4e21'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730124833/17/4/-1 - saved: 1730124833/17/4/-1 - expiring: 2024-12-06 05:46:02
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d7531'): 'false'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d7531'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730742291/32/6/-1 - saved: 1730742291/32/6/-1 - expiring: 2024-12-06 05:40:42
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Store supports properties containing Unicode characters.
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::HierarchyCommand(18): false
05/11/2024 19:35:21 [ 1697] [ INFO] [the testing account] StatusException: SyncCollections->CheckForChanges(): Timeout forced after 92s from 880s due to other process - code: 3 - file: /usr/share/grommunio-sync/lib/core/synccollections.php:592
05/11/2024 19:35:21 [ 1697] [DEBUG] [the testing account] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
05/11/2024 19:35:21 [ 1697] [WBXML] [the testing account] O  <Ping:Ping>
05/11/2024 19:35:21 [ 1697] [WBXML] [the testing account] O   <Ping:Status>
05/11/2024 19:35:21 [ 1697] [WBXML] [the testing account] O   1
05/11/2024 19:35:21 [ 1697] [WBXML] [the testing account] O   </Ping:Status>
05/11/2024 19:35:21 [ 1697] [WBXML] [the testing account] O  </Ping:Ping>
05/11/2024 19:35:21 [ 1697] [DEBUG] [the testing account] WBXMLEncoder->endTag() WBXML output completed
05/11/2024 19:35:21 [ 1697] [WBXML] [the testing account] WBXML-OUT: AwFqAAANRUcDMQABAQ==
05/11/2024 19:35:21 [ 1697] [WBXML] [the testing account] WBXML-IN : AwFqAAANRUgDODgwAAFJSksDVTY4MGE3AAFMA0NhbGVuZGFyAAEBSksDVTU4OGE0AAFMA1Rhc2tzAAEBSksDVTk3NDhiAAFMA0NhbGVuZGFyAAEBSksDVWMzOTk4AAFMA0VtYWlsAAEBSksDVWI0OWViAAFMA0VtYWlsAAEBSksDVTJmOGQ3AAFMA0NvbnRhY3RzAAEBSksDVTJkOTdlAAFMA0NhbGVuZGFyAAEBSksDVWRlNTc5AAFMA0NhbGVuZGFyAAEBSksDVTZlNjYzAAFMA0NhbGVuZGFyAAEBAQE=
05/11/2024 19:35:21 [ 1697] [DEBUG] [the testing account] LoopDetection->ProcessLoopDetectionTerminate()
05/11/2024 19:35:21 [ 1697] [ INFO] [the testing account] cmd='Ping' memory='5.71 MiB/8.00 MiB' time='91.85s' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' idle='92s' version='2.0.130.fa3fdd2' method='POST' httpcode='200'
05/11/2024 19:35:21 [ 1697] [DEBUG] [the testing account] -------- End
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 19
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] ExportChangesICS->Config() initialized with state: 0x0200020117402a00010003000000020000520a175002000052191d50031d0001031d2711031d4e21031d7531031ffbd2500002019667100001005200000000000100000006606a00
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] ExportChangesICS->InitializeExporter() successfully. 1 changes ready to sync for 'hierarchy'.
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] MAPIProvider->GetStoreProps(): Getting store properties.
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::CommandNeedsPlainInput(18): false
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] MAPIProvider->GetInboxProps(): Getting inbox properties.
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] ChangesMemoryWrapper->ImportFolderChange(): Change for folder 'Alex' will not be sent as modification is not relevant.
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I  <Ping:Ping>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000d'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'Uc3998' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{5fcbc712-a4e8-48b5-a271-e14384b1bd98}1'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000e'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'Ub49eb' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{3b9f6f68-eeec-4dae-a3dd-f4e9a5873201}1'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000f'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U2d97e' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{1d2051c2-a0c0-4079-95e5-9e22c6ac741b}3'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000012'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U588a4' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{e84c2f57-3116-4f8a-9ea0-c9d8b4912925}1'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000013'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U2f8d7' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{3e59111b-2c70-4d18-b409-e79c227d2798}1'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d0001'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'Ude579' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{787f0d03-8250-4005-ad3f-7bf764e69138}31'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d2711'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U680a7' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{4f4a5168-ca31-4230-bb99-fabdb6a90641}28'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d4e21'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U9748b' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{c40b50e0-e306-4f34-808f-eb1f9fea0d87}18'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d7531'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U6e663' : ref. Lifetime '880', last sync at ''
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{98b88122-d3b5-4e67-b520-1203df4f3c71}27'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id '' : ref. Lifetime '880', last sync at '1730823594'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->AddCollection(): Updated reference PolicyKey '-1', reference Lifetime '880', Last sync at '1730823594'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{08851729-fd2d-48c1-8807-e36e6ba01924}1'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] StateManager->loadHierarchyCache(): 'sec11421eb558349-08851729-fd2d-48c1-8807-e36e6ba01924-hc-1'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] HandlePing(): reference PolicyKey for PING: -1
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] HandlePing(): initialization data received
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I   <Ping:LifeTime>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    880
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I   </Ping:LifeTime>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I   <Ping:Folders>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      U680a7
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Calendar
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U680a7'
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      U588a4
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Tasks
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Tasks' id 'U588a4'
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      U9748b
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Calendar
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U9748b'
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Uc3998
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Email
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Email' id 'Uc3998'
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Ub49eb
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Email
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Email' id 'Ub49eb'
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      U2f8d7
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Contacts
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Contacts' id 'U2f8d7'
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      U2d97e
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Calendar
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U2d97e'
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Ude579
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Calendar
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'Ude579'
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      U6e663
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I      Calendar
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U6e663'
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I   </Ping:Folders>
05/11/2024 19:35:21 [ 6781] [WBXML] [the testing account] I  </Ping:Ping>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'Uc3998' changed
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'Ub49eb' changed
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U2d97e' changed
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U588a4' changed
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U2f8d7' changed
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'Ude579' changed
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U680a7' changed
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U9748b' changed
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U6e663' changed
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder '' changed
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] DeviceManager->DoAutomaticASDeviceSaving(): save automatically: false
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Announce process as PUSH connection
05/11/2024 19:35:21 [ 6781] [ INFO] [the testing account] SyncCollections->CheckForChanges(): Waiting for store changes... (lifetime 880 seconds)
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->HasChangesSink(): created - HierarchyHash: a8f59b0837e156a094810521794d39c5
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->adviseStoreToSink(): advised store 'Resource id #43'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000d'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c565700000000000d'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->GetFolderStat() fetched status information of 77 folders for store 'the testing account'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1727890738/1/0/-1 - saved: 1727890738/1/0/-1 - expiring: 2024-11-08 16:11:08
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000e'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c565700000000000e'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1723446274/0/0/-1 - saved: 1723446274/0/0/-1 - expiring: 2024-12-06 13:29:30
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000f'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c565700000000000f'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730796691/54/9/-1 - saved: 1730796691/54/9/-1 - expiring: 2024-12-06 13:44:20
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000012'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c5657000000000012'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1723446274/0/0/-1 - saved: 1723446274/0/0/-1 - expiring: 2024-12-06 09:57:25
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000013'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c5657000000000013'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1723446274/0/0/-1 - saved: 1723446274/0/0/-1 - expiring: 2024-12-06 15:30:39
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d0001'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d0001'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730823805/54/8/-1 - saved: 1730823805/54/8/-1 - expiring: 2024-12-06 07:48:13
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d2711'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d2711'
05/11/2024 19:35:21 [ 4832] [ INFO] [the testing account] StatusException: SyncCollections->CheckForChanges(): Timeout forced after 0s from 880s due to other process - code: 3 - file: /usr/share/grommunio-sync/lib/core/synccollections.php:592
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730790220/64/16/-1 - saved: 1730790220/64/16/-1 - expiring: 2024-12-06 14:05:46
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d4e21'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d4e21'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730124833/17/4/-1 - saved: 1730124833/17/4/-1 - expiring: 2024-12-06 05:46:02
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d7531'): 'false'
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d7531'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
05/11/2024 19:35:21 [ 4832] [WBXML] [the testing account] O  <Ping:Ping>
05/11/2024 19:35:21 [ 4832] [WBXML] [the testing account] O   <Ping:Status>
05/11/2024 19:35:21 [ 4832] [WBXML] [the testing account] O   1
05/11/2024 19:35:21 [ 4832] [WBXML] [the testing account] O   </Ping:Status>
05/11/2024 19:35:21 [ 4832] [WBXML] [the testing account] O  </Ping:Ping>
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730742291/32/6/-1 - saved: 1730742291/32/6/-1 - expiring: 2024-12-06 05:40:42
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] WBXMLEncoder->endTag() WBXML output completed
05/11/2024 19:35:21 [ 4832] [WBXML] [the testing account] WBXML-OUT: AwFqAAANRUcDMQABAQ==
05/11/2024 19:35:21 [ 4832] [WBXML] [the testing account] WBXML-IN : AwFqAAANRUgDODgwAAFJSksDVTY4MGE3AAFMA0NhbGVuZGFyAAEBSksDVTk3NDhiAAFMA0NhbGVuZGFyAAEBSksDVWMzOTk4AAFMA0VtYWlsAAEBSksDVWI0OWViAAFMA0VtYWlsAAEBSksDVTJmOGQ3AAFMA0NvbnRhY3RzAAEBSksDVTJkOTdlAAFMA0NhbGVuZGFyAAEBSksDVWRlNTc5AAFMA0NhbGVuZGFyAAEBSksDVTZlNjYzAAFMA0NhbGVuZGFyAAEBAQE=
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] LoopDetection->ProcessLoopDetectionTerminate()
05/11/2024 19:35:21 [ 4832] [ INFO] [the testing account] cmd='Ping' memory='5.70 MiB/8.00 MiB' time='0.66s' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' idle='0s' version='2.0.130.fa3fdd2' method='POST' httpcode='200'
05/11/2024 19:35:21 [ 4832] [DEBUG] [the testing account] -------- End
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 19
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] ExportChangesICS->Config() initialized with state: 0x0200020117402a00010003000000020000520a175002000052191d50031d0001031d2711031d4e21031d7531031ffbd2500002019667100001005200000000000100000006606a00
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] ExportChangesICS->InitializeExporter() successfully. 1 changes ready to sync for 'hierarchy'.
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] MAPIProvider->GetStoreProps(): Getting store properties.
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] MAPIProvider->GetInboxProps(): Getting inbox properties.
05/11/2024 19:35:21 [ 6781] [DEBUG] [the testing account] ChangesMemoryWrapper->ImportFolderChange(): Change for folder 'Alex' will not be sent as modification is not relevant.

Looks like you are right as the event "EAS-Test" shows up on :37 in the sync log when i create the "TRIGGER"-Event...
But i dont understand why

Part 2... had to split it because it was to long for one post

05/11/2024 19:37:07 [ 6781] [DEBUG] [the testing account] DeviceManager->GetBackendIdForFolderId(): folderid Ude579 => 0d000000a5187b6fbcdcea1ed03c56570000001d0001
05/11/2024 19:37:07 [ 6781] [DEBUG] [the testing account] ExportChangesICS->InitializeExporter() successfully. 1 changes ready to sync for '0d000000a5187b6fbcdcea1ed03c56570000001d0001'.
05/11/2024 19:37:07 [ 6781] [DEBUG] [the testing account] SyncCollections->CheckForChanges(): Notification received on folder 'Ude579'
05/11/2024 19:37:07 [ 6781] [DEBUG] [the testing account] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] O  <Ping:Ping>
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] O   <Ping:Status>
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] O   2
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] O   </Ping:Status>
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] O   <Ping:Folders>
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] O    <Ping:Folder>
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] O    Ude579
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] O    </Ping:Folder>
05/11/2024 19:37:07 [ 6781] [DEBUG] [the testing account] LoopDetection->ProcessLoopDetectionAddStatus: 'Ude579' with status 2
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] O   </Ping:Folders>
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] O  </Ping:Ping>
05/11/2024 19:37:07 [ 6781] [DEBUG] [the testing account] WBXMLEncoder->endTag() WBXML output completed
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] WBXML-OUT: AwFqAAANRUcDMgABSUoDVWRlNTc5AAEBAQ==
05/11/2024 19:37:07 [ 6781] [WBXML] [the testing account] WBXML-IN : AwFqAAANRUgDODgwAAFJSksDVTY4MGE3AAFMA0NhbGVuZGFyAAEBSksDVTU4OGE0AAFMA1Rhc2tzAAEBSksDVTk3NDhiAAFMA0NhbGVuZGFyAAEBSksDVWMzOTk4AAFMA0VtYWlsAAEBSksDVWI0OWViAAFMA0VtYWlsAAEBSksDVTJmOGQ3AAFMA0NvbnRhY3RzAAEBSksDVTJkOTdlAAFMA0NhbGVuZGFyAAEBSksDVWRlNTc5AAFMA0NhbGVuZGFyAAEBSksDVTZlNjYzAAFMA0NhbGVuZGFyAAEBAQE=
05/11/2024 19:37:07 [ 6781] [DEBUG] [the testing account] LoopDetection->ProcessLoopDetectionTerminate()
05/11/2024 19:37:07 [ 6781] [ INFO] [the testing account] cmd='Ping' memory='5.71 MiB/8.00 MiB' time='106.09s' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' idle='106s' version='2.0.130.fa3fdd2' method='POST' httpcode='200'
05/11/2024 19:37:07 [ 6781] [DEBUG] [the testing account] -------- End
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] -------- Start
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] cmd='Sync' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' version='2.0.130.fa3fdd2' method='POST'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Used timezone 'Europe/Vienna'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Grommunio using PHP-MAPI version: 2.35.36.x1dcea51 - PHP version: 8.0.30
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Request::ProcessHeaders() ASVersion: 16.0
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] GSync::CommandNeedsProvisioning(0): true
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] ProvisioningManager->ProvisioningRequired('974652166') saved device key '974652166': false
05/11/2024 19:37:08 [ 1697] [ INFO] [the testing account] Policy 'devencenabled' not supported by the device, ignoring
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] ProvisioningManager->ProvisioningRequired() saved policy hash '431014e4a761ea216e9a35f20aaec61c' matches
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] GSync::CommandNeedsAuthentication(0): true
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Grommunio->Logon(): Trying to authenticate user 'the testing account'..
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Grommunio->openMessageStore('the testing account'): Found 'DEFAULT' store: 'Resource id #52'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Grommunio->Logon(): User 'the testing account' is authenticated
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Store supports properties containing Unicode characters.
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] GSync::HierarchyCommand(0): false
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] GSync::CommandNeedsPlainInput(0): false
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I  <Synchronize>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I   <Folders>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I    <Folder>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     <SyncKey>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I      {787f0d03-8250-4005-ad3f-7bf764e69138}31
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     </SyncKey>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     <FolderId>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I      Ude579
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     </FolderId>
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] DeviceManager->GetBackendIdForFolderId(): folderid Ude579 => 0d000000a5187b6fbcdcea1ed03c56570000001d0001
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'Ude579' : ref. Lifetime '880', last sync at ''
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     <DeletesAsMoves/>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     <GetChanges/>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     <WindowSize>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I      25
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     </WindowSize>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     <Options>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I      <FilterType>
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] SyncParameters->UseCPO('DEFAULT')
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I       7
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I      </FilterType>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I      <AirSyncBase:BodyPreference>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I       <AirSyncBase:Type>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        1
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I       </AirSyncBase:Type>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I       <AirSyncBase:TruncationSize>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        400000
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I       </AirSyncBase:TruncationSize>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I      </AirSyncBase:BodyPreference>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     </Options>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     <Perform>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I      <Add>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I       <ClientEntryId>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        d0fe3059-a9f6-409b-aa79-c3ff7e8603b5
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I       </ClientEntryId>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I       <Data>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        <POOMCAL:Timezone>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         xP///wAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAoAAAAEAAMAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMAAAAFAAIAAAAAAAAAxP///w==
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        </POOMCAL:Timezone>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        <POOMCAL:AllDayEvent>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         0
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        </POOMCAL:AllDayEvent>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        <POOMCAL:StartTime>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         20241110T070000Z
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        </POOMCAL:StartTime>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        <POOMCAL:EndTime>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         20241110T080000Z
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        </POOMCAL:EndTime>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        <POOMCAL:Subject>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         EAS-Test
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        </POOMCAL:Subject>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        <POOMCAL:Sensitivity>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         0
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        </POOMCAL:Sensitivity>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        <AirSyncBase:Body>
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] StringStreamWrapper::stream_open(): initialized stream length: 7 - HTML-safe-truncate: false
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         <AirSyncBase:Type>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I          1
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         </AirSyncBase:Type>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         <AirSyncBase:Data>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I          
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] StringStreamWrapper::stream_open(): initialized stream length: 0 - HTML-safe-truncate: false
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         </AirSyncBase:Data>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        </AirSyncBase:Body>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        <POOMCAL:Reminder>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         10
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        </POOMCAL:Reminder>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        <POOMCAL:BusyStatus>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         2
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        </POOMCAL:BusyStatus>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        <POOMCAL:MeetingStatus>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I         0
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I        </POOMCAL:MeetingStatus>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I       </Data>
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Sync->getImporter(): initialize importer
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Sync->loadStates(): loading states for folder 'Ude579'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Grommunio->CleanStates(): Found 1 states to clean (787f0d03-8250-4005-ad3f-7bf764e69138) 31
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Grommunio->CleanStates(): Found 1 states to clean (787f0d03-8250-4005-ad3f-7bf764e69138-fs) 30
05/11/2024 19:37:08 [ 1697] [ INFO] [the testing account] StateNotFoundException: Grommunio->getStateMessage(): Could not locate the state message '787f0d03-8250-4005-ad3f-7bf764e69138-fs' (counter: 31) - code: 0 - file: /usr/share/grommunio-sync/lib/grommunio/grommunio.php:1955
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d0001'): 'false'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Grommunio->GetImporter() folderid: '0d000000a5187b6fbcdcea1ed03c56570000001d0001'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] ImportChangesICS->Config(): initializing importer with state: 0x0400020117403f000100050000001d005202035205060109520b13521718521a1c521e22522728012a522e30523438013b524f520159525e5f526569526c6d52717252757650000201966710000100520000000000010000000661ff000201da6710000100520000000000010000000661ff000201d26710000100520000000000010000000000e100
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] ImportChangesICS->LoadConflicts(): will be loaded later if necessary
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] LoopDetection->SetSyncStateUsage(): uuid: 787f0d03-8250-4005-ad3f-7bf764e69138  counter: 31
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] SyncObject->Check(): Fixed object from type SyncAppointment: parameter 'dtstamp' is set to 0
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Sync->importMessage(): message imported
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I      </Add>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I     </Perform>
05/11/2024 19:37:08 [ 1697] [ INFO] [the testing account] Processed '1' incoming changes
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Grommunio->setStateMessage(): creating new state message '787f0d03-8250-4005-ad3f-7bf764e69138-fs' (counter: 31)
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I    </Folder>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I   </Folders>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] I  </Synchronize>
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] HandleSync(): Start Output
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d0001'): 'false'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Grommunio->GetFolderStat() fetched status information of 77 folders for store 'the testing account'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): true - current: 1730831828/56/8/-1 - saved: 1730823805/54/8/-1 - expiring: 2024-12-06 07:48:13
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d0001'): 'false'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] ExportChangesICS->Config() initialized with state: 0x04000201174041000100050000001d005202035205060109520b13521718521a1c521e22522728012a522e30523438013b524f520159525e5f526569526c6d52717252757601815000020196671300010003000000520000010661ff0306767050000201da6710000100520000000000010000000661ff000201d26710000100520000000000010000000000e100
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] ExportChangesICS->InitializeExporter() successfully. 1 changes ready to sync for '0d000000a5187b6fbcdcea1ed03c56570000001d0001'.
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] LoopDetection->ProcessLoopDetectionAddStatus: 'Ude579' with status 1
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Folder type: Calendar
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O  <Synchronize>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O   <Folders>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O    <Folder>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     <SyncKey>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     {787f0d03-8250-4005-ad3f-7bf764e69138}32
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     </SyncKey>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     <FolderId>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     Ude579
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     </FolderId>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     <Status>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     1
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     </Status>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     <Replies>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O      <Add>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       <ClientEntryId>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       d0fe3059-a9f6-409b-aa79-c3ff7e8603b5
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       </ClientEntryId>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       <ServerEntryId>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       Ude579:0d000000a5187b6fbcdcea1ed03c56570000001d0081
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       </ServerEntryId>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       <Status>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       1
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       </Status>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O      </Add>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     </Replies>
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] LoopDetection->Detect(): folderid:'Ude579' uuid:'787f0d03-8250-4005-ad3f-7bf764e69138' counter:'31' max:'25' queued:'1'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] PHPWrapper->ImportMessageChange(): Getting message from MAPIProvider, sourcekey: '0d000000a5187b6fbcdcea1ed03c56570000001d0080', parentsourcekey: '0d000000a5187b6fbcdcea1ed03c56570000001d0001', entryid: '0000000093d040ac13255749948144332567c41b07000d000000a5187b6fbcdcea1ed03c56570000001d000100000d000000a5187b6fbcdcea1ed03c56570000001d00800000'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] BodyPreference types: 1
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] GetBodyPreferenceBestMatch: 1
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] bpo: truncation size:'400000', allornone:'0', preview:'0'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] StringStreamWrapper::stream_open(): initialized stream length: 7 - HTML-safe-truncate: false
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] MAPIStreamWrapper::stream_open(): initialized mapistream: Resource id #265 - streamlength: 0 - HTML-safe-truncate: false
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] MAPIProvider->getAppointment(): setting ourself as the organizer for an appointment without attendees.
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     <Perform>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O      <Add>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       <ServerEntryId>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       Ude579:0d000000a5187b6fbcdcea1ed03c56570000001d0080
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       </ServerEntryId>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       <Data>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:Timezone>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        xP///yhHTVQrMDE6MDApIEFtc3RlcmRhbSwgQmVybGluLCBCZXJuLCBSb21lLCBTdG9ja2hvbG0sIFZpZW5uYQAAAAAAAAoABQAAAAMAAAAAAAAAAAAAAChHTVQrMDE6MDApIEFtc3RlcmRhbSwgQmVybGluLCBCZXJuLCBSb21lLCBTdG9ja2hvbG0sIFZpZW5uYQAAAAAAAAMABQAAAAIAAAAAAAAAxP///w==
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:Timezone>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:DtStamp>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        20241105T183700Z
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:DtStamp>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:StartTime>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        20241109T230000Z
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:StartTime>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:Subject>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        TRIGGER
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:Subject>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:UID>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        0d000000a5187b6fbcdcea1ed03c56570000001d0080
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:UID>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:OrganizerName>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        Familienkalender
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:OrganizerName>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:OrganizerEmail>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        the testing account
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:OrganizerEmail>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:EndTime>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        20241109T233000Z
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:EndTime>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:Sensitivity>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        0
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:Sensitivity>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:BusyStatus>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        2
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:BusyStatus>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:AllDayEvent>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        0
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:AllDayEvent>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:Reminder>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        15
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:Reminder>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <POOMCAL:MeetingStatus>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        0
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </POOMCAL:MeetingStatus>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <AirSyncBase:Body>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O         <AirSyncBase:Type>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O         1
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O         </AirSyncBase:Type>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O         <AirSyncBase:EstimatedDataSize>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O         0
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O         </AirSyncBase:EstimatedDataSize>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O         <AirSyncBase:Data>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O         <<< written 0 of 0 bytes of plain data >>>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O         </AirSyncBase:Data>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </AirSyncBase:Body>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        <AirSyncBase:NativeBodyType>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        1
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O        </AirSyncBase:NativeBodyType>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O       </Data>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O      </Add>
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] PHPWrapper->ImportMessageChange(): change for: 'Ude579:0d000000a5187b6fbcdcea1ed03c56570000001d0080'
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O     </Perform>
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d0001'): 'false'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Sync()->setFolderStat() on Ude579: 1730831828/56/8/-1 expiring 2024-12-06 11:03:59
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O    </Folder>
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] Grommunio->setStateMessage(): creating new state message '787f0d03-8250-4005-ad3f-7bf764e69138' (counter: 32)
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'Ude579' changed
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O   </Folders>
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] O  </Synchronize>
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] WBXMLEncoder->endTag() WBXML output completed
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] WBXML-OUT: AwFqAEVcT0sDezc4N2YwZDAzLTgyNTAtNDAwNS1hZDNmLTdiZjc2NGU2OTEzOH0zMgABUgNVZGU1NzkAAU4DMQABRkdMA2QwZmUzMDU5LWE5ZjYtNDA5Yi1hYTc5LWMzZmY3ZTg2MDNiNQABTQNVZGU1Nzk6MGQwMDAwMDBhNTE4N2I2ZmJjZGNlYTFlZDAzYzU2NTcwMDAwMDAxZDAwODEAAU4DMQABAQFWR00DVWRlNTc5OjBkMDAwMDAwYTUxODdiNmZiY2RjZWExZWQwM2M1NjU3MDAwMDAwMWQwMDgwAAFdAARFA3hQLy8veWhIVFZRck1ERTZNREFwSUVGdGMzUmxjbVJoYlN3Z1FtVnliR2x1TENCQ1pYSnVMQ0JTYjIxbExDQlRkRzlqYTJodmJHMHNJRlpwWlc1dVlRQUFBQUFBQUFvQUJRQUFBQU1BQUFBQUFBQUFBQUFBQUNoSFRWUXJNREU2TURBcElFRnRjM1JsY21SaGJTd2dRbVZ5YkdsdUxDQkNaWEp1TENCU2IyMWxMQ0JUZEc5amEyaHZiRzBzSUZacFpXNXVZUUFBQUFBQUFBTUFCUUFBQUFJQUFBQUFBQUFBeFAvLy93PT0AAVEDMjAyNDExMDVUMTgzNzAwWgABZwMyMDI0MTEwOVQyMzAwMDBaAAFmA1RSSUdHRVIAAWgDMGQwMDAwMDBhNTE4N2I2ZmJjZGNlYTFlZDAzYzU2NTcwMDAwMDAxZDAwODAAAVoDRmFtaWxpZW5rYWxlbmRlcgABWQNmYW1pbGllbmthbGVuZGVyQHNjaGluZGxlci1wcml2YXQuZGUAAVIDMjAyNDExMDlUMjMzMDAwWgABZQMwAAFNAzIAAUYDMAABZAMxNQABWAMwAAEAEUpGAzEAAUwDMAABSwMAAQFWAzEAAQEBAQEBAQ==
05/11/2024 19:37:08 [ 1697] [WBXML] [the testing account] WBXML-IN : AwFqAEVcT0sDezc4N2YwZDAzLTgyNTAtNDAwNS1hZDNmLTdiZjc2NGU2OTEzOH0zMQABUgNVZGU1NzkAAR4TVQMyNQABV1gDNwABABFFRgMxAAFHAzQwMDAwMAABAQEAAFZHTANkMGZlMzA1OS1hOWY2LTQwOWItYWE3OS1jM2ZmN2U4NjAzYjUAAV0ABEUDeFAvLy93QUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQW9BQUFBRUFBTUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFBQUFNQUFBQUZBQUlBQUFBQUFBQUF4UC8vL3c9PQABRgMwAAFnAzIwMjQxMTEwVDA3MDAwMFoAAVIDMjAyNDExMTBUMDgwMDAwWgABZgNFQVMtVGVzdAABZQMwAAEAEUpGAzEAAUsDAAEBAARkAzEwAAFNAzIAAVgDMAABAQEBAQEB
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] LoopDetection->ProcessLoopDetectionTerminate()
05/11/2024 19:37:08 [ 1697] [ INFO] [the testing account] cmd='Sync' memory='6.03 MiB/8.00 MiB' time='0.29s' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' idle='0s' version='2.0.130.fa3fdd2' method='POST' httpcode='200'
05/11/2024 19:37:08 [ 1697] [DEBUG] [the testing account] -------- End
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] -------- Start
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] cmd='Ping' devType='SamsungDevice' devId='sec11421eb558349' getUser='the testing account' from='109.43.242.167' version='2.0.130.fa3fdd2' method='POST'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Used timezone 'Europe/Vienna'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio using PHP-MAPI version: 2.35.36.x1dcea51 - PHP version: 8.0.30
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Request::ProcessHeaders() ASVersion: 16.0
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::CommandNeedsProvisioning(18): false
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::CommandNeedsAuthentication(18): true
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->Logon(): Trying to authenticate user 'the testing account'..
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->openMessageStore('the testing account'): Found 'DEFAULT' store: 'Resource id #43'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->Logon(): User 'the testing account' is authenticated
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Store supports properties containing Unicode characters.
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::HierarchyCommand(18): false
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::CommandNeedsPlainInput(18): false
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I  <Ping:Ping>
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000d'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'Uc3998' : ref. Lifetime '880', last sync at ''
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{5fcbc712-a4e8-48b5-a271-e14384b1bd98}1'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000e'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'Ub49eb' : ref. Lifetime '880', last sync at ''
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{3b9f6f68-eeec-4dae-a3dd-f4e9a5873201}1'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000f'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U2d97e' : ref. Lifetime '880', last sync at ''
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{1d2051c2-a0c0-4079-95e5-9e22c6ac741b}3'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000012'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U588a4' : ref. Lifetime '880', last sync at ''
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{e84c2f57-3116-4f8a-9ea0-c9d8b4912925}1'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000013'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U2f8d7' : ref. Lifetime '880', last sync at ''
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{3e59111b-2c70-4d18-b409-e79c227d2798}1'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d0001'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'Ude579' : ref. Lifetime '', last sync at ''
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{787f0d03-8250-4005-ad3f-7bf764e69138}32'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d2711'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U680a7' : ref. Lifetime '880', last sync at ''
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{4f4a5168-ca31-4230-bb99-fabdb6a90641}28'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d4e21'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U9748b' : ref. Lifetime '880', last sync at ''
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{c40b50e0-e306-4f34-808f-eb1f9fea0d87}18'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d7531'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id 'U6e663' : ref. Lifetime '880', last sync at ''
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{98b88122-d3b5-4e67-b520-1203df4f3c71}27'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Folder id '' : ref. Lifetime '880', last sync at '1730823594'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->AddCollection(): Updated reference PolicyKey '-1', reference Lifetime '880', Last sync at '1730823594'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->GetLatestSyncKey(): '{08851729-fd2d-48c1-8807-e36e6ba01924}1'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] StateManager->loadHierarchyCache(): 'sec11421eb558349-08851729-fd2d-48c1-8807-e36e6ba01924-hc-1'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] HandlePing(): reference PolicyKey for PING: -1
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] HandlePing(): initialization data received
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I   <Ping:LifeTime>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    880
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I   </Ping:LifeTime>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I   <Ping:Folders>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      U680a7
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Calendar
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U680a7'
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      U588a4
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Tasks
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Tasks' id 'U588a4'
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      U9748b
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Calendar
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U9748b'
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Uc3998
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Email
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Email' id 'Uc3998'
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Ub49eb
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Email
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Email' id 'Ub49eb'
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      U2f8d7
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Contacts
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Contacts' id 'U2f8d7'
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      U2d97e
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Calendar
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U2d97e'
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Ude579
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Calendar
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'Ude579'
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    <Ping:Folder>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      U6e663
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:ServerEntryId>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     <Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I      Calendar
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I     </Ping:FolderType>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I    </Ping:Folder>
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] HandlePing(): using saved sync state for 'Calendar' id 'U6e663'
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I   </Ping:Folders>
05/11/2024 19:37:08 [ 1699] [WBXML] [the testing account] I  </Ping:Ping>
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'Uc3998' changed
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'Ub49eb' changed
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U2d97e' changed
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U588a4' changed
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U2f8d7' changed
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'Ude579' changed
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U680a7' changed
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U9748b' changed
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder 'U6e663' changed
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncCollections->SaveCollection(): Data of folder '' changed
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] DeviceManager->DoAutomaticASDeviceSaving(): save automatically: false
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Announce process as PUSH connection
05/11/2024 19:37:08 [ 1699] [ INFO] [the testing account] SyncCollections->CheckForChanges(): Waiting for store changes... (lifetime 880 seconds)
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->HasChangesSink(): created - HierarchyHash: a8f59b0837e156a094810521794d39c5
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->adviseStoreToSink(): advised store 'Resource id #43'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000d'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c565700000000000d'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->GetFolderStat() fetched status information of 77 folders for store 'the testing account'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1727890738/1/0/-1 - saved: 1727890738/1/0/-1 - expiring: 2024-11-08 16:11:08
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000e'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c565700000000000e'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1723446274/0/0/-1 - saved: 1723446274/0/0/-1 - expiring: 2024-12-06 13:29:30
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c565700000000000f'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c565700000000000f'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730796691/54/9/-1 - saved: 1730796691/54/9/-1 - expiring: 2024-12-06 13:44:20
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000012'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c5657000000000012'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1723446274/0/0/-1 - saved: 1723446274/0/0/-1 - expiring: 2024-12-06 09:57:25
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c5657000000000013'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c5657000000000013'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1723446274/0/0/-1 - saved: 1723446274/0/0/-1 - expiring: 2024-12-06 15:30:39
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d0001'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d0001'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730831828/56/8/-1 - saved: 1730831828/56/8/-1 - expiring: 2024-12-06 11:03:59
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d2711'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d2711'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730790220/64/16/-1 - saved: 1730790220/64/16/-1 - expiring: 2024-12-06 14:05:46
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d4e21'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d4e21'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730124833/17/4/-1 - saved: 1730124833/17/4/-1 - expiring: 2024-12-06 05:46:02
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] GSync::GetAdditionalSyncFolderStore('0d000000a5187b6fbcdcea1ed03c56570000001d7531'): 'false'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] Grommunio->ChangesSinkInitialize(): folderid '0d000000a5187b6fbcdcea1ed03c56570000001d7531'
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] SyncParameters->IsExporterRunRequired(): false - current: 1730742291/32/6/-1 - saved: 1730742291/32/6/-1 - expiring: 2024-12-06 05:40:42
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 19
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] ExportChangesICS->Config() initialized with state: 0x0200020117402a00010003000000020000520a175002000052191d50031d0001031d2711031d4e21031d7531031ffbd2500002019667100001005200000000000100000006606a00
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] ExportChangesICS->InitializeExporter() successfully. 1 changes ready to sync for 'hierarchy'.
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] MAPIProvider->GetStoreProps(): Getting store properties.
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] MAPIProvider->GetInboxProps(): Getting inbox properties.
05/11/2024 19:37:08 [ 1699] [DEBUG] [the testing account] ChangesMemoryWrapper->ImportFolderChange(): Change for folder 'Alex' will not be sent as modification is not relevant.

andreaslang

Yes you are right. it makes a difference. The calendar "Alex" isnt the main calendar. Its one of some other calendars in that account.
If i create an event in the main calendar of the same account (Alle zusammen) its working!

externa1

Thanks for the idea!
How do you connect the "Public Folder Calendars" to the android devices? Do they pop up when i connect an excahnge account via stock android EAS?

And isnt the problem there too when using them in that way with the stock android calendar app?

externa1

ok, so i have tested the nine app alias "OfficeMail Pro". There its working but i get problems with all-day events again as they are shifted like in my last thread:

https://community.grommunio.com/d/1819-ganztagestermine-einen-tag-verschoben/42

OH MAN.

Samsung devices with their stock apps are running into the problem described here, outlook for android cant be used, "OfficeMail Pro" which is the new "Nine-App" has an own EAS-implementation without the problem here, but its an app with subscription and leads me to the old problem of shifted all-day events.

Oh man......

I think it's really time for Grommunio to cooperate with any client app like emClient (Windows) or "OfficeMail Pro" (Android + iOS) or whatever that has been tested and confirmed to work with Grommunio.

The current situation, where it's a gamble whether the applications used will eventually work with Grommunio, is so frustrating.

andreaslang

hm... dont understand why its different on my device... its Samsung and Android 14 too