EAS input doesnt update Calendars

Mmorbificagent · Nov 5, 2024

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 · Nov 5, 2024

morbificagent

Hi,

what exactly is this calendar 'Alex'? Is it a subfolder of the main calendar folder and contains calendar items? Is it some shared or public folder?

Did you test adding an event to the main calendar?

andreaslang · Nov 5, 2024

morbificagent

Well, as I suspected your device sends its own changes for this folder only if there changes on the server. Take a look at the request with the id [ 1697] where it happens. It's not a grommunio-sync issue, your device behaves like that.

Mmorbificagent · Nov 5, 2024

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!

Mmorbificagent · Nov 5, 2024

andreaslang

andreaslang It's not a grommunio-sync issue, your device behaves like that.

i understand... but its the "stock" calendar app on android samsung devices... (galaxy fold 6).
For sure thats not your problem if it doesnt work correct but there are many many users using it i think. And using Outlook on Android isnt a good way with grommunio too, right?
I was using exchange before and i cant remember having this problem there.
So i dont know if Microsoft build a workaround for that or why it was working with exchange and android.

For sure i can create one account per calendar but that would cost many licenses :-(

externa1 · Nov 6, 2024

morbificagent
I created a shared calender in public folders - there you may select "sync to mobile devices" and through the permissions select the users which should have access to this calender

that you don't need additional license

rg
Christian

Mmorbificagent · Nov 6, 2024

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 · Nov 6, 2024

morbificagent
it shows up in google calendar app as soon as the sync in the grommunio admin interface is endabled

rg
Christian

Mmorbificagent · Nov 6, 2024

@externa1

i have tested it and it doesnt work for me too.
The public-folder-calendar is displayed in my EAS profile on my samsung device but using it in the stock calendar app shows the same problem.

I have tried to use your google-calendar-app too but it hasnt implemented a own EAS-connection as it uses the already synced calendars from the mobile.
So in my case the problematic samsung-eas-implementation and so it doesnt work there too.

So at the moment it looks like there isnt a good/working way to use more than one calendar on samsung devices with grommunio. (except of buying many user licenses and create many accounts)

Do you have a samsung device or another?

externa1 · Nov 6, 2024

morbificagent
in general we use apple iphone
but i own one android test device, ist a xiaomi redmi note 10 pro (screen shot from was from this)

on samsung phones my customers are using the nine app

rg
Christian

Mmorbificagent · Nov 6, 2024

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

Mmorbificagent · Nov 6, 2024

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 · Nov 6, 2024

morbificagent

morbificagent For sure thats not your problem if it doesnt work correct but there are many many users using it i think. And using Outlook on Android isnt a good way with grommunio too, right?

We don't have any numbers regarding the usage, but from the feedback and issues it looks like the most use the stock android EAS and Nine. Having said that, I'm not sure how many have various subfolders of the main calendar.
We don't recommend using Outlook app because of data privacy concerns, but at the end of the day it's the user's decision which app to use.

morbificagent 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

So, you waited about 2 minutes before "triggering"? What is the max amount of time you've waited for the appointment in the 'Alex' calendar to appear?
I've just tested this on two of my android devices (android 11 and android 14), both with stock apps and after about two minutes an appointment created in a subfolder of the main calendar appeared in grommunio-web without forcing or triggering a sync.

Mmorbificagent · Nov 6, 2024

andreaslang

andreaslang So, you waited about 2 minutes before "triggering"? What is the max amount of time you've waited for the appointment in the 'Alex' calendar to appear?

2 days... i thought it would be arrive after some time. but it doesnt. Then i was searching for a workaround (restarting services and such things) but i couldnt find something that helped. Even a serverrestart dont helped. Only creating these "trigger-events" let the events appear.

andreaslang I've just tested this on two of my android devices

Are these devices samsung phones? Looked like the xiaomi from @externa1 doesnt have this problem...

andreaslang · Nov 6, 2024

morbificagent

morbificagent Are these devices samsung phones? Looked like the xiaomi from @externa1 doesnt have this problem...

Yes, the android 14 one is Samsung Galaxy S23.

Mmorbificagent · Nov 6, 2024

andreaslang

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