08/08/2018 14:05:55 [21582] [DEBUG] [oliver] -------- Start 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] cmd='Sync' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] Backend implementation of IStateMachine: activesync_statemachine 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(0): true 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] ASDevice data loaded for user: 'oliver' 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] LoopDetection initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] ZPush::HierarchyCommand(0): false 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] DeviceManager->ProvisioningRequired('710930222') saved device key '710930222': false 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] DeviceManager->getPolicyName(): determined policy name: 'default' 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] ZPush::CommandNeedsAuthentication(0): true 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(0): false 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I {0fde4661-cd2f-48a2-8768-e9b5cfcf4939}1 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 101000000000 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'0fde4661-cd2f-48a2-8768-e9b5cfcf4939' counter:'false' 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '101000000000', returning as is. 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000000' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666354' 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666354' 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 1 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 50 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 0 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 2 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 4 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [WBXML] [oliver] I 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] HandleSync(): Start Output 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000000'): 'false' 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:05:55 [21582] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:05:56 [21582] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:05:56 [21582] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:05:56 [21582] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000000') returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:05:56 [21582] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - saved: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - expiring: 2018-09-07 20:31:16 08/08/2018 14:05:56 [21582] [DEBUG] [oliver] HandleSync: No changes found for Email folder id '101000000000'. Omitting output. 08/08/2018 14:05:56 [21582] [WBXML] [oliver] WBXML-IN : AwFqAEVcT0sDezBmZGU0NjYxLWNkMmYtNDhhMi04NzY4LWU5YjVjZmNmNDkzOX0xAAFSAzEwMTAwMDAwMDAwMAABXgMxAAETVQM1MAABV1gDMAABABFFRgMyAAEBRUYDNAABAQEBAQE= 08/08/2018 14:05:56 [21582] [DEBUG] [oliver] LOGOFF 08/08/2018 14:05:56 [21582] [DEBUG] [oliver] LoopDetection->ProcessLoopDetectionTerminate() 08/08/2018 14:05:56 [21582] [ INFO] [oliver] cmd='Sync' memory='3.33 MiB/4.00 MiB' time='0.67s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:05:56 [21582] [DEBUG] [oliver] -------- End 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] -------- Start 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] cmd='Ping' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] Backend implementation of IStateMachine: activesync_statemachine 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(18): false 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::CommandNeedsAuthentication(18): true 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ASDevice data loaded for user: 'oliver' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] LoopDetection initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::HierarchyCommand(18): false 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(18): false 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'0fde4661-cd2f-48a2-8768-e9b5cfcf4939' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000000'): 'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000000' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666354' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666354' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{0fde4661-cd2f-48a2-8768-e9b5cfcf4939}1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'0fde4661-cd2f-48a2-8768-e9b5cfcf4939' counter:'1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'e19af67a-ec28-49b9-accf-4d7536aeff66' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000001'): 'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000001' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666653' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666653' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{e19af67a-ec28-49b9-accf-4d7536aeff66}2' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'e19af67a-ec28-49b9-accf-4d7536aeff66' counter:'2' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'1bac3455-df90-4b8f-8859-259b5546c0e2' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000002'): 'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000002' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666640' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{1bac3455-df90-4b8f-8859-259b5546c0e2}1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'1bac3455-df90-4b8f-8859-259b5546c0e2' counter:'1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'b3052b52-278b-4a14-b574-49c56c905536' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000003'): 'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000003' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666682' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666682' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{b3052b52-278b-4a14-b574-49c56c905536}4' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'b3052b52-278b-4a14-b574-49c56c905536' counter:'4' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'8e53ea26-a866-4545-ac90-4e608ae0ce71' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000004'): 'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000004' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533667509' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533667509' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{8e53ea26-a866-4545-ac90-4e608ae0ce71}2' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'8e53ea26-a866-4545-ac90-4e608ae0ce71' counter:'2' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'85920f33-9300-493b-a428-5b045b361c54' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000005'): 'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000005' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666640' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{85920f33-9300-493b-a428-5b045b361c54}2' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'85920f33-9300-493b-a428-5b045b361c54' counter:'2' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'10c1e3e5-9dad-4ba0-bc57-f2d4f3f0ff94' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000007'): 'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000007' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666649' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{10c1e3e5-9dad-4ba0-bc57-f2d4f3f0ff94}1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'10c1e3e5-9dad-4ba0-bc57-f2d4f3f0ff94' counter:'1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'e67a9d3d-6329-43a0-b79a-1c7446dfcc08' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100100000006'): 'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '100100000006' : ref. PolicyKey '710930222', ref. Lifetime '470', last sync at '1533666834' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{e67a9d3d-6329-43a0-b79a-1c7446dfcc08}72' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'e67a9d3d-6329-43a0-b79a-1c7446dfcc08' counter:'72' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'6aa258a7-ca11-493e-b405-f9f4c70fc413' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100000000006'): 'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '100000000006' : ref. PolicyKey '710930222', ref. Lifetime '470', last sync at '1533666617' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{6aa258a7-ca11-493e-b405-f9f4c70fc413}136' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'6aa258a7-ca11-493e-b405-f9f4c70fc413' counter:'136' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'4b6b804b-d5ee-4f95-8fb6-013ab93f5019' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100200000006'): 'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '100200000006' : ref. PolicyKey '710930222', ref. Lifetime '470', last sync at '1533666357' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{4b6b804b-d5ee-4f95-8fb6-013ab93f5019}1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'4b6b804b-d5ee-4f95-8fb6-013ab93f5019' counter:'1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'23d554f9-450e-4823-97da-be0c98882c7e' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '' : ref. PolicyKey '710930222', ref. Lifetime '10', last sync at '1511648467' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{23d554f9-450e-4823-97da-be0c98882c7e}1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] StateManager->loadHierarchyCache(): 'sec195ebf99e3ada-23d554f9-450e-4823-97da-be0c98882c7e-hc-1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'hc' key:'23d554f9-450e-4823-97da-be0c98882c7e' counter:'1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'23d554f9-450e-4823-97da-be0c98882c7e' counter:'1' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] HandlePing(): reference PolicyKey for PING: 710930222 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] HandlePing(): initialization data received 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 100100000006 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I Calendar 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Calendar' id '100100000006' 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 101000000000 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I Email 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000000' 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 101000000001 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I Email 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000001' 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 100000000006 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I Contacts 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Contacts' id '100000000006' 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 101000000007 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I Email 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000007' 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 100200000006 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I Tasks 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Tasks' id '100200000006' 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 101000000002 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I Email 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000002' 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 101000000003 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I Email 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000003' 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 101000000005 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I Email 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000005' 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [WBXML] [oliver] I 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SyncCollections->SaveCollection(): Data of folder '101000000000' changed 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->SetState(): devid:'sec195ebf99e3ada' type:'fd' key:'0fde4661-cd2f-48a2-8768-e9b5cfcf4939' counter:'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] DeviceManager->DoAutomaticASDeviceSaving(): save automatically: false 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] PingTracking initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-2' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] Announce process as PUSH connection 08/08/2018 14:13:03 [21985] [ INFO] [oliver] SyncCollections->CheckForChanges(): Waiting for store changes... (lifetime 600 seconds) 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000000'): 'false' 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000000) 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:03 [21985] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000000') returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - saved: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - expiring: 2018-09-07 20:31:16 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000001'): 'false' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000001) 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000001') returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:30-R:0-U:0-NUID:1395-UIDV:1314494811 - saved: M:30-R:0-U:0-NUID:1395-UIDV:1314494811 - expiring: 2018-09-07 20:30:53 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000002'): 'false' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000002) 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000002') returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:0-R:0-U:0-NUID:1-UIDV:1342246661 - saved: M:0-R:0-U:0-NUID:1-UIDV:1342246661 - expiring: 2018-09-07 20:30:40 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000003'): 'false' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000003) 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000003') returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:1693-R:0-U:0-NUID:1753-UIDV:1314494811 - saved: M:1693-R:0-U:0-NUID:1753-UIDV:1314494811 - expiring: 2018-09-07 20:32:16 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000005'): 'false' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000005) 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000005') returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:106-R:0-U:0-NUID:138-UIDV:1314553856 - saved: M:106-R:0-U:0-NUID:138-UIDV:1314553856 - expiring: 2018-09-07 20:32:07 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000007'): 'false' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000007) 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000007') returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:64-R:0-U:0-NUID:7237-UIDV:1362921642 - saved: M:64-R:0-U:0-NUID:7237-UIDV:1362921642 - expiring: 2018-09-07 20:30:51 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100100000006'): 'false' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(100100000006) 08/08/2018 14:13:04 [21985] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::read($res_id) should be compatible with EGroupware\Api\Storage::read($keys, $extra_cols = '', $join = '') (2) 08/08/2018 14:13:04 [21985] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::save($resource) should be compatible with EGroupware\Api\Storage::save($keys = NULL, $extra_where = NULL) (2) 08/08/2018 14:13:04 [21985] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::delete($res_id) should be compatible with EGroupware\Api\Storage::delete($keys = NULL, $only_return_ids = false) (2) 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '100100000006') returning '1533118805' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: 1533118805 - saved: 1533118805 - expiring: 2018-09-07 20:36:14 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100000000006'): 'false' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(100000000006) 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '100000000006') returning 1532374947 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: 1532374947 - saved: 1532374947 - expiring: 2018-09-07 20:30:19 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100200000006'): 'false' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(100200000006) 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '100200000006') returning 'EGw-empty-wGE' 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: EGw-empty-wGE - saved: EGw-empty-wGE - expiring: 2018-09-07 20:25:57 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 11 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] Initializing folder diff engine 08/08/2018 14:13:04 [21985] [DEBUG] [oliver] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] calendar_zpush::GetFolderList() returning Array([0] => Array([id] => 100100000006[mod] => Herr Oliver Wingenbach[parent] => 0)) 08/08/2018 14:13:05 [21985] [ INFO] [oliver] ExportChangesDiff->InitializeExporter(): Found '0' changes for 'hierarchy' 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:13:05 [21985] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] -------- Start 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] cmd='FolderSync' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] Backend implementation of IStateMachine: activesync_statemachine 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(9): true 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] ASDevice data loaded for user: 'oliver' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] LoopDetection initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] ZPush::HierarchyCommand(9): true 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] DeviceManager->ProvisioningRequired('710930222') saved device key '710930222': false 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] DeviceManager->getPolicyName(): determined policy name: 'default' 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] -------- Start 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] cmd='Sync' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] ZPush::CommandNeedsAuthentication(9): true 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(9): false 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] Backend implementation of IStateMachine: activesync_statemachine 08/08/2018 14:13:19 [21699] [WBXML] [oliver] I 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:19 [21699] [WBXML] [oliver] I 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 08/08/2018 14:13:19 [21699] [WBXML] [oliver] I {23d554f9-450e-4823-97da-be0c98882c7e}1 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(0): true 08/08/2018 14:13:19 [21699] [WBXML] [oliver] I 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] StateManager->loadHierarchyCache(): 'sec195ebf99e3ada-23d554f9-450e-4823-97da-be0c98882c7e-hc-1' 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] ASDevice data loaded for user: 'oliver' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'hc' key:'23d554f9-450e-4823-97da-be0c98882c7e' counter:'1' 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] SqlStateMachine->CleanStates(): devid:'sec195ebf99e3ada' type:'hc' key:'23d554f9-450e-4823-97da-be0c98882c7e' counter:'1' thisCounterOnly:'false' 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] LoopDetection initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'23d554f9-450e-4823-97da-be0c98882c7e' counter:'1' 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] ZPush::HierarchyCommand(0): false 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] SqlStateMachine->CleanStates(): devid:'sec195ebf99e3ada' type:'' key:'23d554f9-450e-4823-97da-be0c98882c7e' counter:'1' thisCounterOnly:'false' 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] DeviceManager->ProvisioningRequired('710930222') saved device key '710930222': false 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'23d554f9-450e-4823-97da-be0c98882c7e' counter:'false' 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] DeviceManager->getPolicyName(): determined policy name: 'default' 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] ZPush::CommandNeedsAuthentication(0): true 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 11 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS 08/08/2018 14:13:19 [21699] [WBXML] [oliver] I 08/08/2018 14:13:19 [21700] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(0): false 08/08/2018 14:13:19 [21700] [WBXML] [oliver] I rtWBXML() type: vnd.ms-sync.wbxml 08/08/2018 14:13:19 [21700] [WBXML] [oliver] I 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] Initializing folder diff engine 08/08/2018 14:13:19 [21700] [WBXML] [oliver] I 08/08/2018 14:13:19 [21699] [DEBUG] [oliver] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine 08/08/2018 14:13:19 [21700] [WBXML] [oliver] I 08/08/2018 14:13:19 [21700] [WBXML] [oliver] I {0fde4661-cd2f-48a2-8768-e9b5cfcf4939}1 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 101000000000 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'0fde4661-cd2f-48a2-8768-e9b5cfcf4939' counter:'false' 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '101000000000', returning as is. 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000000' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666354' 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666354' 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 1 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 50 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 0 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 2 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 4 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [WBXML] [oliver] I 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] HandleSync(): Start Output 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000000'): 'false' 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:20 [21699] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:20 [21699] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000000') returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - saved: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - expiring: 2018-09-07 20:31:16 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] HandleSync: No changes found for Email folder id '101000000000'. Omitting output. 08/08/2018 14:13:20 [21700] [WBXML] [oliver] WBXML-IN : AwFqAEVcT0sDezBmZGU0NjYxLWNkMmYtNDhhMi04NzY4LWU5YjVjZmNmNDkzOX0xAAFSAzEwMTAwMDAwMDAwMAABXgMxAAETVQM1MAABV1gDMAABABFFRgMyAAEBRUYDNAABAQEBAQE= 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] LOGOFF 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] LoopDetection->ProcessLoopDetectionTerminate() 08/08/2018 14:13:20 [21700] [ INFO] [oliver] cmd='Sync' memory='3.37 MiB/2.00 MiB' time='1.48s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:20 [21700] [DEBUG] [oliver] -------- End 08/08/2018 14:13:21 [21699] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::read($res_id) should be compatible with EGroupware\Api\Storage::read($keys, $extra_cols = '', $join = '') (2) 08/08/2018 14:13:21 [21699] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::save($resource) should be compatible with EGroupware\Api\Storage::save($keys = NULL, $extra_where = NULL) (2) 08/08/2018 14:13:21 [21699] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::delete($res_id) should be compatible with EGroupware\Api\Storage::delete($keys = NULL, $only_return_ids = false) (2) 08/08/2018 14:13:21 [21699] [DEBUG] [oliver] calendar_zpush::GetFolderList() returning Array([0] => Array([id] => 100100000006[mod] => Herr Oliver Wingenbach[parent] => 0)) 08/08/2018 14:13:21 [21699] [ INFO] [oliver] ExportChangesDiff->InitializeExporter(): Found '0' changes for 'hierarchy' 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 1 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O {23d554f9-450e-4823-97da-be0c98882c7e}1 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 0 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 08/08/2018 14:13:21 [21699] [WBXML] [oliver] O 08/08/2018 14:13:21 [21699] [DEBUG] [oliver] WBXMLEncoder->endTag() WBXML output completed 08/08/2018 14:13:21 [21699] [WBXML] [oliver] WBXML-OUT: AwFqAAAHVkwDMQABUgN7MjNkNTU0ZjktNDUwZS00ODIzLTk3ZGEtYmUwYzk4ODgyYzdlfTEAAU5XAzAAAQEB 08/08/2018 14:13:21 [21699] [WBXML] [oliver] WBXML-IN : AwFqAAAHVlIDezIzZDU1NGY5LTQ1MGUtNDgyMy05N2RhLWJlMGM5ODg4MmM3ZX0xAAEB 08/08/2018 14:13:21 [21699] [DEBUG] [oliver] LOGOFF 08/08/2018 14:13:21 [21699] [DEBUG] [oliver] LoopDetection->ProcessLoopDetectionTerminate() 08/08/2018 14:13:21 [21699] [ INFO] [oliver] cmd='FolderSync' memory='4.23 MiB/6.00 MiB' time='2.72s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:21 [21699] [DEBUG] [oliver] -------- End 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] -------- Start 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] cmd='Sync' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] Backend implementation of IStateMachine: activesync_statemachine 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(0): true 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] ASDevice data loaded for user: 'oliver' 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] LoopDetection initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] ZPush::HierarchyCommand(0): false 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] DeviceManager->ProvisioningRequired('710930222') saved device key '710930222': false 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] DeviceManager->getPolicyName(): determined policy name: 'default' 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] ZPush::CommandNeedsAuthentication(0): true 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(0): false 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I {e67a9d3d-6329-43a0-b79a-1c7446dfcc08}72 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 100100000006 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'e67a9d3d-6329-43a0-b79a-1c7446dfcc08' counter:'false' 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '100100000006', returning as is. 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '100100000006' : ref. PolicyKey '710930222', ref. Lifetime '470', last sync at '1533666834' 08/08/2018 14:13:21 [20330] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '470', Last sync at '1533666834' 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 08/08/2018 14:13:21 [20330] [WBXML] [oliver] I 5 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 0 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 1 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 400000 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [WBXML] [oliver] I 08/08/2018 14:13:22 [20330] [DEBUG] [oliver] HandleSync(): Start Output 08/08/2018 14:13:22 [20330] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100100000006'): 'false' 08/08/2018 14:13:22 [20330] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::read($res_id) should be compatible with EGroupware\Api\Storage::read($keys, $extra_cols = '', $join = '') (2) 08/08/2018 14:13:22 [20330] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::save($resource) should be compatible with EGroupware\Api\Storage::save($keys = NULL, $extra_where = NULL) (2) 08/08/2018 14:13:22 [20330] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::delete($res_id) should be compatible with EGroupware\Api\Storage::delete($keys = NULL, $only_return_ids = false) (2) 08/08/2018 14:13:22 [20330] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:13:22 [20330] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] -------- Start 08/08/2018 14:13:22 [20330] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '100100000006') returning '1533118805' 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] cmd='Sync' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:22 [20330] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: 1533118805 - saved: 1533118805 - expiring: 2018-09-07 20:36:14 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:22 [20467] [DEBUG] [oliver] -------- Start 08/08/2018 14:13:22 [20330] [DEBUG] [oliver] HandleSync: No changes found for Calendar folder id '100100000006'. Omitting output. 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] -------- Start (): init 08/08/2018 14:13:22 [20330] [WBXML] [oliver] WBXML-IN : AwFqAEVcT0sDe2U2N2E5ZDNkLTYzMjktNDNhMC1iNzlhLTFjNzQ0NmRmY2MwOH03MgABUgMxMDAxMDAwMDAwMDYAAR4TVQM1AAFXWAMwAAEAEUVGAzEAAUcDNDAwMDAwAAEBAQEBAQ== 08/08/2018 14:13:22 [20330] [DEBUG] [oliver] LOGOFF 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] cmd='Sync' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:22 [20467] [DEBUG] [oliver] SqlStateMachine(): init opDetectionTerminate() 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 08/08/2018 14:13:22 [20330] [ INFO] [oliver] cmd='Sync' memory='3.05 MiB/2.00 MiB' time='0.71s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] SqlStateMachine(): init sioning(0): true 08/08/2018 14:13:22 [20330] [DEBUG] [oliver] -------- End essHeaders() ASVersion: 14.0 ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:22 [20467] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(0): true activesync_statemachine 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] ASDevice data loaded for user: 'oliver' 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' counter:'false' 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] LoopDetection initialised with IPC provi08/08/2018 14:13:22 [21582] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(0): true 08/08/2018 14:13:22 [20467] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:22 [20467] [DEBUG] [oliver] LoopDetection initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] DeviceManager->ProvisioningRequired('710930222') saved device key '710930222': false 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] ASDevice data loaded for user: 'oliver' 08/08/2018 14:13:22 [20467] [DEBUG] [oliver] DeviceManager->ProvisioningRequired('710930222') saved device key '710930222': false 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:22 [20467] [DEBUG] [oliver] DeviceManager->getPolicyName(): determined policy name: 'default' 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. 08/08/2018 14:13:22 [20467] [DEBUG] [oliver] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. ' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] ZPush::HierarchyCommand(0): false 0): true 08/08/2018 14:13:22 [20467] [DEBUG] [oliver] ZPush::CommandNeedsAuthentication(0): true 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS 08/08/2018 14:13:22 [20467] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS vice key '710930222': false 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] DeviceManager->getPolicyName(): determined policy name: 'default' 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(0): false 08/08/2018 14:13:22 [20774] [WBXML] [oliver] I dsPlainInput(0): false 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. 08/08/2018 14:13:22 [20467] [WBXML] [oliver] I dsAuthentication(0): true 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] activesync_bac08/08/2018 14:13:22 [20467] [WBXML] [oliver] I 08/08/2018 14:13:22 [20774] [WBXML] [oliver] I 08/08/2018 14:13:22 [21582] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(0): false 08/08/2018 14:13:22 [21582] [WBXML] [oliver] I 08/08/2018 14:13:22 [20467] [WBXML] [oliver] I 08/08/2018 14:13:22 [21582] [WBXML] [oliver] I a7-ca11-493e-b405-f9f4c70fc413}136 08/08/2018 14:13:22 [20467] [WBXML] [oliver] I 08/08/2018 14:13:22 [21582] [WBXML] [oliver] I y> 08/08/2018 14:13:22 [20467] [WBXML] [oliver] I {4b6b804b-d5ee-4f95-8fb6-013ab93f5019}1 08/08/2018 14:13:22 [21582] [WBXML] [oliver] I 08/08/2018 14:13:22 [20467] [WBXML] [oliver] I 08/08/2018 14:13:22 [21582] [WBXML] [oliver] I {0fde4661-cd2f-48a2-8768-e9b5cfcf4939}1 08/08/2018 14:13:22 [20774] [WBXML] [oliver] I 100000000006 08/08/2018 14:13:22 [21582] [WBXML] [oliver] I 08/08/2018 14:13:22 [20467] [WBXML] [oliver] I 1002000000008/08/2018 14:13:22 [21582] [WBXML] [oliver] I 08/08/2018 14:13:22 [20774] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'6aa258a7-ca11-493e-b405-f9f4c70fc413' counter:'false' 08/08/2018 14:13:22 [21582] [WBXML] [oliver] I 1010000000008/08/2018 14:13:22 [20774] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:22 [20467] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'4b6b804b-d5ee-4f95-8fb6-013ab93f5019' counter:'false' 08/08/2018 14:13:22 [21582] [WBXML] [oliver] I 08/08/2018 14:13:23 [20774] [DEBUG] [oliver] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '100000000006', returning as is. 08/08/2018 14:13:23 [21582] [DEBUG] [oliver] SqlStateMachine->GetState(): devid08/08/2018 14:13:23 [20774] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '100000000006' : ref. PolicyKey '710930222', ref. Lifetime '470', last sync at '1533666617' 08/08/2018 14:13:23 [20774] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '470', Last sync at '1533666617' 08/08/2018 14:13:23 [21582] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I ection(): Folder id '100200000006' : ref. PolicyKey '710930222', ref. Lifetime '470', last sync at '1533666357' 08/08/2018 14:13:23 [21582] [DEBUG] [oliver] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '101000000000', returning as is. 08/08/2018 14:13:23 [21582] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000000' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666354' 08/08/2018 14:13:23 [20467] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '470', Last sync at '1533666357' 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I 08/08/2018 14:13:23 [20467] [WBXML] [oliver] I 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I dCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666354' 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I 5 08/08/2018 14:13:23 [20467] [WBXML] [oliver] I s> 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I 08/08/2018 14:13:23 [20467] [WBXML] [oliver] I 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I 1 08/08/2018 14:13:23 [20467] [WBXML] [oliver] I 5 tions> 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I 08/08/2018 14:13:23 [20467] [WBXML] [oliver] I 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I 08/08/2018 14:13:23 [20467] [WBXML] [oliver] I 08/08/2018 14:13:23 [20774] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I ference> 08/08/2018 14:13:23 [20467] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I 1 08/08/2018 14:13:23 [20467] [WBXML] [oliver] I 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I e:Type> 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] -------- Start 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] cmd='Sync' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I e:Type> 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:23 [21582] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] Backend implementation of IStateMachine: activesync_statemachine 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I evId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:23 [20467] [WBXML] [oliver] I pporting version '2' 08/08/2018 14:13:23 [22018] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:23 [20467] [WBXML] [oliver] I 14.0 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I 2 andNeedsProvisioning(0): true 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I > : init 08/08/2018 14:13:23 [22018] [DEBUG] [oliver] Backend implementation of IStateMachine: activesync_statemachine edata' key:'null' counter:'false' 08/08/2018 14:13:23 [20774] [WBXML] [oliver] I ase:Type> 08/08/2018 14:13:23 [22018] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] ASDevice data loaded for user: 'oliver' 08/08/2018 14:13:23 [20467] [WBXML] [oliver] I tart Output 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I 08/08/2018 14:13:23 [20774] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100000000006'): 'false' 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I ith IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:23 [22018] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(0): true 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] ZPush::HierarchyCommand(0): false 08/08/2018 14:13:23 [20467] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100200000006'): 'false' 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] DeviceManager->ProvisioningRequired('710930222') saved device key '710930222': false unter:'false' 08/08/2018 14:13:23 [22018] [DEBUG] [oliver] ASDevice data loaded for user: 'oliver' 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] DeviceManager->getPolicyName08/08/2018 14:13:23 [22018] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I 08/08/2018 14:13:23 [22018] [DEBUG] [oliver] LoopDetection initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I 08/08/2018 14:13:23 [22018] [DEBUG] [oliver] ZPush::HierarchyCommand(0): false 0): true 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I 08/08/2018 14:13:23 [21582] [WBXML] [oliver] I ProvisioningRequired('710930222') saved device key '710930222': false 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(0): false 0200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:13:23 [22018] [DEBUG] [oliver] DeviceManager->getPolicyName(): determined policy name: 'default' 08/08/2018 14:13:23 [20774] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '100000000006') returning 1532374947 08/08/2018 14:13:23 [22018] [DEBUG] [oliver] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. empty-wGE' 08/08/2018 14:13:23 [20774] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: 1532374947 - saved: 1532374947 - expiring: 2018-09-07 20:30:19 08/08/2018 14:13:23 [20467] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: EGw-empty-wGE - saved: EGw-empty-wGE - expiring: 2018-09-07 20:25:57 08/08/2018 14:13:23 [20774] [DEBUG] [oliver] HandleSync: No changes found for Contacts f08/08/2018 14:13:23 [21582] [DEBUG] [olive08/08/2018 14:13:23 [21698] [WBXML] [oliver] I lse' 08/08/2018 14:13:23 [20774] [WBXML] [oliver] WBXML-IN : AwFqAEVcT0sDezZhYTI1OGE3LWNhMTEtNDkzZS1iNDA1LWY5ZjRjNzBmYzQxM30xMzYAAVIDMTAwMDAwMDAwMDA2AAEeE1UDNQABVwARRUYDMQABRwM0MDAwMDAAAQEBAQEB 08/08/2018 14:13:23 [21582] [DEBUG] [oliver] SqlStateMachine(): init n('oliver','',...) logon SUCCESS 08/08/2018 14:13:23 [20467] [WBXML] [oliver] WBXML-IN : AwFqAEVcT0sDezRiNmI4MDRiLWQ1ZWUtNGY5NS04ZmI2LTAxM2FiOTNmNTAxOX0xAAFSAzEwMDIwMDAwMDAwNgABHhNVAzUAAVcAEUVGAzEAAUcDNDAwMDAwAAEBAQEBAQ== 08/08/2018 14:13:23 [22018] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(0): false 08/08/2018 14:13:23 [20467] [DEBUG] [oliver] LOGOFF eMachine->GetState(): devid:'sec195ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:23 [20774] [DEBUG] [oliver] LoopDetection->ProcessLoopDetectionTerminate() 08/08/2018 14:13:23 [21698] [WBXML] [oliver] I 08/08/2018 14:13:23 [22018] [WBXML] [oliver] I ocessLoopDetectionTerminate() 08/08/2018 14:13:23 [21698] [WBXML] [oliver] I 08/08/2018 14:13:23 [22018] [WBXML] [oliver] I 08/08/2018 14:13:23 [20774] [ INFO] [oliver] cmd='Sync' memory='3.06 MiB/4.00 MiB' time='2.01s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:23 [22018] [WBXML] [oliver] I 08/08/2018 14:13:23 [20467] [ INFO] [oliver] cmd='Sync' memory='2.94 MiB/4.00 MiB' time='1.99s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:23 [22018] [WBXML] [oliver] I 08/08/2018 14:13:23 [20467] [DEBUG] [oliver] -------- End 08/08/2018 14:13:23 [21698] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'e19af67a-ec28-49b9-accf-4d7536aeff66' counter:'false' 08/08/2018 14:13:23 [22018] [WBXML] [oliver] I 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I 08/08/2018 14:13:24 [21698] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:24 [21698] [DEBUG] [oliver] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '101000000001', returning as is. 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I 101000000002 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I 08/08/2018 14:13:24 [21698] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000001' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666653' 08/08/2018 14:13:24 [22018] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'1bac3455-df90-4b8f-8859-259b5546c0e2' counter:'false' 08/08/2018 14:13:24 [21698] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666653' 08/08/2018 14:13:24 [21698] [WBXML] [oliver] I 08/08/2018 14:13:24 [22025] [DEBUG] [oliver] -------- Start >UseCPO('DEFAULT') 08/08/2018 14:13:24 [21698] [WBXML] [oliver] I 1 08/08/2018 14:13:24 [22018] [DEBUG] [oliver] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '101000000002', returning as is. 08/08/2018 14:13:24 [22025] [DEBUG] [oliver] cmd='Sync' devType='Sams08/08/2018 14:13:24 [22018] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000002' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666640' 08/08/2018 14:13:24 [21698] [WBXML] [oliver] I 08/08/2018 14:13:24 [22018] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666640' 08/08/2018 14:13:24 [22024] [DEBUG] [oliver] -------- Start end::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I 08/08/2018 14:13:24 [22024] [DEBUG] [oliver] cmd='Sync' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I 1 08/08/2018 14:13:24 [22024] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:24 [22025] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' eturning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:24 [21698] [WBXML] [oliver] I nit 08/08/2018 14:13:24 [22025] [DEBUG] [oliver] Request::ProcessHead08/08/2018 14:13:24 [22024] [DEBUG] [oliver] Backend implementation of IStateMachine: activesync_statemachine 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I :AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:13:24 [22024] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:24 [21582] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000000') returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:24 [22024] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 08/08/2018 14:13:24 [21698] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') red(): false - current: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - saved: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - expiring: 2018-09-07 20:31:16 08/08/2018 14:13:24 [22024] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(0): true 5ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:24 [21582] [DEBUG] [oliver] HandleSync: No changes found for Email folder id '101000000000'. Omitting output. 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I >GetState(): devid:'sec195ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:24 [22030] [DEBUG] [oliver] -------- Start 08/08/2018 14:13:24 [21698] [WBXML] [oliver] I sDezBmZGU0NjYxLWNkMmYtNDhhMi04NzY4LWU5YjVjZmNmNDkzOX0xAAFSAzEwMTAwMDAwMDAwMAABXgMxAAETVQM1MAABV1gDMAABABFFRgMyAAEBRUYDNAABAQEBAQE= 08/08/2018 14:13:24 [22028] [DEBUG] [oliver] cmd='Sync' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:24 [21582] [DEBUG] [oliver] LOGOFF 08/08/2018 14:13:24 [22018] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:24 [22030] [DEBUG] [oliver] cmd='Sync' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:24 [22025] [DEBUG] [oliver] ZPush::HierarchyCommand(0): false provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I nd(0): false username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:24 [22025] [DEBUG] [oliver] DeviceManag08/08/2018 14:13:24 [22028] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:24 [22025] [DEBUG] [oliver] DeviceManager->getPolicyName(): determined policy name: 'default' gDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I SVersion: 14.0 08/08/2018 14:13:24 [21582] [DEBUG] [oliver] -------- End yncBase:BodyPreference> mined policy name: 'default' 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I 2 andNeedsProvisioning(0): true activesync_statemachine . 08/08/2018 14:13:24 [21698] [WBXML] [oliver] I es(): loaded 'default' policy. 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I ation(0): true 08/08/2018 14:13:24 [22024] [DEBUG] [oliver] ZPush::CommandNeedsAuthentication(0): true bf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:24 [22025] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS ' 08/08/2018 14:13:24 [22024] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS 08/08/2018 14:13:24 [21698] [WBXML] [oliver] I 4 andNeedsPlainInput(0): false 08/08/2018 14:13:24 [22030] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:24 [22028] [DEBUG] [oliver] LoopDetection initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:24 [22025] [WBXML] [oliver] I 08/08/2018 14:13:24 [21698] [WBXML] [oliver] I ' key:'null' counter:'false' 08/08/2018 14:13:24 [22025] [WBXML] [oliver] I 08/08/2018 14:13:24 [22028] [DEBUG] [oliver] DeviceManager->ProvisioningRequired('710930222') saved device key '710930222': false 08/08/2018 14:13:24 [22025] [WBXML] [oliver] I s> ded for user: 'oliver' 08/08/2018 14:13:24 [22030] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:24 [21698] [WBXML] [oliver] I cBase:BodyPreference> 08/08/2018 14:13:24 [22025] [WBXML] [oliver] I etProvisioningPolicies(): loaded 'default' policy. ' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:24 [21698] [WBXML] [oliver] I > 9300-493b-a428-5b045b361c54}2 08/08/2018 14:13:24 [22025] [WBXML] [oliver] I {b3052b52-278b-4a14-b574-49c56c905536}4 08/08/2018 14:13:24 [22024] [WBXML] [oliver] I dsAuthentication(0): true 08/08/2018 14:13:24 [21698] [WBXML] [oliver] I 08/08/2018 14:13:24 [22028] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS vice key '710930222': false 08/08/2018 14:13:24 [22018] [WBXML] [oliver] I tart Output 08/08/2018 14:13:24 [22025] [WBXML] [oliver] I 08/08/2018 14:13:24 [21698] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000001'): 'false' ult' 08/08/2018 14:13:24 [22030] [DEBUG] [oliver] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. 08/08/2018 14:13:24 [21698] [DEBUG] [oliver] SqlStateMachine(): init ut 08/08/2018 14:13:24 [22030] [DEBUG] [oliver] ZPush::CommandNeedsAuthentication(0): true 08/08/2018 14:13:24 [22025] [WBXML] [oliver] I alSyncFolderStore('101000000002'): 'false' d' key:'85920f33-9300-493b-a428-5b045b361c54' counter:'false' 08/08/2018 14:13:24 [22030] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS e:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:24 [22024] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:24 [22028] [WBXML] [oliver] I e(): init e(): devid:'sec195ebf99e3ada' type:'fd' key:'b3052b52-278b-4a14-b574-49c56c905536' counter:'false' 08/08/2018 14:13:24 [22028] [WBXML] [oliver] I 08/08/2018 14:13:24 [22018] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:24 [22028] [WBXML] [oliver] I {10c1e3e5-9dad-4ba0-bc57-f2d4f3f0ff94}1 ckend-folderid available for '101000000005', returning as is. 08/08/2018 14:13:24 [22025] [DEBUG] [oliver] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '101000000003', returning as is. 08/08/2018 14:13:24 [22028] [WBXML] [oliver] I 08/08/2018 14:13:24 [22025] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000003' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666682' 08/08/2018 14:13:24 [22028] [WBXML] [oliver] I 08/08/2018 14:13:24 [22024] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666640' 08/08/2018 14:13:24 [22025] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666682' 08/08/2018 14:13:24 [22024] [WBXML] [oliver] I 08/08/2018 14:13:24 [22025] [WBXML] [oliver] I 08/08/2018 14:13:24 [22024] [WBXML] [oliver] I 1 08/08/2018 14:13:24 [22025] [WBXML] [oliver] I 1 67a9d3d-6329-43a0-b79a-1c7446dfcc08}72 08/08/2018 14:13:24 [22028] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'10c1e3e5-9dad-4ba0-bc57-f2d4f3f0ff94' counter:'false' 08/08/2018 14:13:24 [22030] [WBXML] [oliver] I 08/08/2018 14:13:24 [22030] [WBXML] [oliver] I /> es> 08/08/2018 14:13:24 [22028] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:25 [22024] [WBXML] [oliver] I 08/08/2018 14:13:25 [22028] [DEBUG] [oliver] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '101000000007', returning as is. 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I ingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:13:25 [22028] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000007' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666649' 08/08/2018 14:13:25 [21698] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:13:25 [22028] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666649' 08/08/2018 14:13:25 [22025] [WBXML] [oliver] I 50 08/08/2018 14:13:25 [22025] [WBXML] [oliver] I gChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I 08/08/2018 14:13:25 [22024] [WBXML] [oliver] I porterRunRequired(): false - current: M:30-R:0-U:0-NUID:1395-UIDV:1314494811 - saved: M:30-R:0-U:0-NUID:1395-UIDV:1314494811 - expiring: 2018-09-07 20:30:53 08/08/2018 14:13:25 [22018] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:13:25 [21698] [DEBUG] [oliver] HandleSync: No changes found for Email folder id '101000000001'. Omitting output. 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I DEFAULT') 08/08/2018 14:13:25 [22025] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') , '101000000002') returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' ime '470', last sync at '1533666834' UYDNAABAQEBAQE= 08/08/2018 14:13:25 [21698] [DEBUG] [oliver] LOGOFF 0 08/08/2018 14:13:25 [22025] [WBXML] [oliver] I 0 Changes/> 08/08/2018 14:13:25 [22024] [WBXML] [oliver] I orterRunRequired(): false - current: M:0-R:0-U:0-NUID:1-UIDV:1342246661 - saved: M:0-R:0-U:0-NUID:1-UIDV:1342246661 - expiring: 2018-09-07 20:30:40 08/08/2018 14:13:25 [22025] [WBXML] [oliver] I sLoopDetectionTerminate() 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I es/> Preference> 08/08/2018 14:13:25 [22018] [DEBUG] [oliver] HandleSync: No changes found for Email folder id '101000000002'. Omitting output. 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I :Type> ference> 08/08/2018 14:13:25 [21698] [ INFO] [oliver] cmd='Sync' memory='3.35 MiB/4.00 MiB' time='2.18s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:25 [22025] [WBXML] [oliver] I YWMzNDU1LWRmOTAtNGI4Zi04ODU5LTI1OWI1NTQ2YzBlMn0xAAFSAzEwMTAwMDAwMDAwMgABXgMxAAETVQM1MAABV1gDMAABABFFRgMyAAEBRUYDNAABAQEBAQE= 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I 08/08/2018 14:13:25 [22018] [DEBUG] [oliver] LOGOFF 2 d 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I 5 tions> 08/08/2018 14:13:25 [22024] [WBXML] [oliver] I 08/08/2018 14:13:25 [22018] [DEBUG] [oliver] LoopDetection->ProcessLoopDe08/08/2018 14:13:25 [22028] [WBXML] [oliver] I 08/08/2018 14:13:25 [22024] [WBXML] [oliver] I 08/08/2018 14:13:25 [22024] [WBXML] [oliver] I 08/08/2018 14:13:25 [22018] [ INFO] [oliver] cmd='Sync' memory='3.34 MiB/4.00 MiB' time='2.14s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:25 [22025] [WBXML] [oliver] I 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I 0 irSyncBase:Type> 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I Type> 08/08/2018 14:13:25 [22024] [WBXML] [oliver] I ULT') 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I 0 08/08/2018 14:13:25 [22025] [WBXML] [oliver] I ference> 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I Type> 08/08/2018 14:13:25 [22025] [WBXML] [oliver] I 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I 2 ions> 08/08/2018 14:13:25 [22025] [WBXML] [oliver] I se:BodyPreference> 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I 1 08/08/2018 14:13:25 [22025] [WBXML] [oliver] I 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I 08/08/2018 14:13:25 [22024] [WBXML] [oliver] I ='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I 08/08/2018 14:13:25 [22025] [DEBUG] [oliver] HandleSync(): Start Output ruct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I 08/08/2018 14:13:25 [22025] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000003'): 'false' 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I 400000 08/08/2018 14:13:25 [22031] [DEBUG] [oliver] Backend implementation of IStateMachine: activesync_statemach08/08/2018 14:13:25 [22028] [WBXML] [oliver] I 08/08/2018 14:13:25 [22031] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I 08/08/2018 14:13:25 [22031] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I GetState(): devid:'sec195ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:25 [22031] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(0): true 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I ->GetState(): devid:'sec195ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I oaded for user: 'oliver' 08/08/2018 14:13:25 [22028] [WBXML] [oliver] I 08/08/2018 14:13:25 [22031] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:25 [22030] [WBXML] [oliver] I 08/08/2018 14:13:25 [22031] [DEBUG] [oliver] LoopDetection initialised w08/08/2018 14:13:25 [22030] [DEBUG] [oliver] HandleSync(): Start Output 37' 08/08/2018 14:13:25 [22030] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100100000006'): 'false' 08/08/2018 14:13:25 [22031] [DEBUG] [oliver] DeviceManager->ProvisioningRequired('710930222') saved device key '710930222': false 08/08/2018 14:13:25 [22028] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:25 [22028] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:25 [22030] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::read($res_id) should be compatible with EGroupware\Api\Storage::read($keys, $extra_cols = '', $join = '') (2) 08/08/2018 14:13:25 [22031] [DEBUG] [oliver] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] ZPush::CommandNeedsAuthentication(0): true 08/08/2018 14:13:26 [22030] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::save($resource) should be compatible with EGroupware\Api\Storage::save($keys = NULL, $extra_where = NULL) (2) 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(0): false 08/08/2018 14:13:26 [22024] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' should be compatible with EGroupware\Api\Storage::delete($keys = NULL, $only_return_ids = false) (2) 08/08/2018 14:13:26 [22025] [DEBUG] [oliver] mail_zpush::Alter08/08/2018 14:13:26 [22024] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:13:26 [22030] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:13:26 [22028] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' Array() 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I kend::GetFolderStat(, '101000000005') returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:13:26 [22030] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:13:26 [22028] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 1314553856 - expiring: 2018-09-07 20:32:07 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I nd::GetFolderStat(, '100100000006') returning '1533118805' 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I {0fde4661-cd2f-48a2-8768-e9b5cfcf4939}1 - current: 1533118805 - saved: 1533118805 - expiring: 2018-09-07 20:36:14 0-NUID:1753-UIDV:1314494811 - expiring: 2018-09-07 20:32:16 08/08/2018 14:13:26 [22028] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000007') returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I anges found for Calendar folder id '100100000006'. Omitting output. 08/08/2018 14:13:26 [22024] [WBXML] [oliver] WBXML-IN : AwFqAEVcT0sDezg1OTIwZjMzLTkzMDAtNDkzYi1hNDI4LTViMDQ1YjM2MWM1NH0yAAFSAzE08/08/2018 14:13:26 [22028] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:64-R:0-U:0-NUID:7237-UIDV:1362921642 - saved: M:64-R:0-U:0-NUID:7237-UIDV:1362921642 - expiring: 2018-09-07 20:30:51 08/08/2018 14:13:26 [22030] [WBXML] [oliver] WBXML-IN : AwFqAE08/08/2018 14:13:26 [22025] [WBXML] [oliver] WBXML-IN : AwFqAEVcT0sDe2IzMDUyYjUyLTI3OGItNGExNC1iNTc0LTQ5YzU2YzkwNTUzNn00AAFSAzEwMTAwMDA08/08/2018 14:13:26 [22024] [DEBUG] [oliver] LOGOFF BAQEBAQE= 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 101000000000 08/08/2018 14:13:26 [22025] [DEBUG] [oliver] LOGOFF ync: No changes found for Email folder id '101000000007'. Omitting output. 08/08/2018 14:13:26 [22024] [DEBUG] [oliver] LoopDetection->ProcessLoopDetectionTerminate() 08/08/2018 14:13:26 [22028] [WBXML] [oliver] WBXML-IN : AwFqAEVcT0sDezEwYzFlM2U1LTlkYWQtNGJhMC1iYzU3LWYyZDRmM2YwZmY5NH0xAAFSAzEwMTAwMDAwMDAwNwABXgMxAAETVQM1MAABV1gDMAABABFFRgMyAAEBRUYDNAABAQEBAQE= 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'0fde4661-cd2f-48a2-8768-e9b5cfcf4939' counter:'false' 08/08/2018 14:13:26 [22028] [DEBUG] [oliver] LOGOFF 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:26 [22028] [DEBUG] [oliver] LoopDetection->ProcessLoopDetectionTerminate() 7s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '101000000000', returning as is. oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:26 [22030] [DEBUG] [oliver] -------- End 08/08/2018 14:13:26 [22025] [DEBUG] [oliver] -------- End 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000000' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666354' 08/08/2018 14:13:26 [22028] [ INFO] [oliver] cmd='Sync' memory='3.35 MiB/4.00 MiB' time='2.60s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666354' 08/08/2018 14:13:26 [22028] [DEBUG] [oliver] -------- End 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 1 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 50 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 0 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 2 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 4 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [WBXML] [oliver] I 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] HandleSync(): Start Output 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000000'): 'false' 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:26 [22031] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:27 [22031] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:27 [22031] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:13:27 [22031] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000000') returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:27 [22031] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - saved: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - expiring: 2018-09-07 20:31:16 08/08/2018 14:13:27 [22031] [DEBUG] [oliver] HandleSync: No changes found for Email folder id '101000000000'. Omitting output. 08/08/2018 14:13:27 [22031] [WBXML] [oliver] WBXML-IN : AwFqAEVcT0sDezBmZGU0NjYxLWNkMmYtNDhhMi04NzY4LWU5YjVjZmNmNDkzOX0xAAFSAzEwMTAwMDAwMDAwMAABXgMxAAETVQM1MAABV1gDMAABABFFRgMyAAEBRUYDNAABAQEBAQE= 08/08/2018 14:13:27 [22031] [DEBUG] [oliver] LOGOFF 08/08/2018 14:13:27 [22031] [DEBUG] [oliver] LoopDetection->ProcessLoopDetectionTerminate() 08/08/2018 14:13:27 [22031] [ INFO] [oliver] cmd='Sync' memory='3.37 MiB/4.00 MiB' time='1.85s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:27 [22031] [DEBUG] [oliver] -------- End 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] -------- Start 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] cmd='Sync' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] Backend implementation of IStateMachine: activesync_statemachine 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(0): true 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] ASDevice data loaded for user: 'oliver' 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] LoopDetection initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] ZPush::HierarchyCommand(0): false 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] DeviceManager->ProvisioningRequired('710930222') saved device key '710930222': false 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] DeviceManager->getPolicyName(): determined policy name: 'default' 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] DeviceManager->getProvisioningPolicies(): loaded 'default' policy. 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] ZPush::CommandNeedsAuthentication(0): true 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(0): false 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I {b3052b52-278b-4a14-b574-49c56c905536}4 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 101000000003 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'b3052b52-278b-4a14-b574-49c56c905536' counter:'false' 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '101000000003', returning as is. 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000003' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666682' 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666682' 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 1 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 50 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 0 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 2 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 4 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [WBXML] [oliver] I 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] HandleSync(): Start Output 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000003'): 'false' 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:28 [22044] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:29 [22044] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:13:29 [22044] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:13:29 [22044] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000003') returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:13:29 [22044] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:1693-R:0-U:0-NUID:1753-UIDV:1314494811 - saved: M:1693-R:0-U:0-NUID:1753-UIDV:1314494811 - expiring: 2018-09-07 20:32:16 08/08/2018 14:13:29 [22044] [DEBUG] [oliver] HandleSync: No changes found for Email folder id '101000000003'. Omitting output. 08/08/2018 14:13:29 [22044] [WBXML] [oliver] WBXML-IN : AwFqAEVcT0sDe2IzMDUyYjUyLTI3OGItNGExNC1iNTc0LTQ5YzU2YzkwNTUzNn00AAFSAzEwMTAwMDAwMDAwMwABXgMxAAETVQM1MAABV1gDMAABABFFRgMyAAEBRUYDNAABAQEBAQE= 08/08/2018 14:13:29 [22044] [DEBUG] [oliver] LOGOFF 08/08/2018 14:13:29 [22044] [DEBUG] [oliver] LoopDetection->ProcessLoopDetectionTerminate() 08/08/2018 14:13:29 [22044] [ INFO] [oliver] cmd='Sync' memory='3.35 MiB/4.00 MiB' time='0.55s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:29 [22044] [DEBUG] [oliver] -------- End 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] -------- Start 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] cmd='Ping' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] activesync_backend::__construct() username=oliver, loose_provisioning=FALSE, autheticated=TRUE 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] Backend implementation of IStateMachine: activesync_statemachine 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetStateVersion(): supporting version '2' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] Request::ProcessHeaders() ASVersion: 14.0 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::CommandNeedsProvisioning(18): false 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::CommandNeedsAuthentication(18): true 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] activesync_backend::Logon('oliver','',...) logon SUCCESS 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'devicedata' key:'null' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ASDevice data loaded for user: 'oliver' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] TopCollector initialised with IPC provider 'activesync_ipc_backend' with type '20' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] LoopDetection initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-1337' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::HierarchyCommand(18): false 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::CommandNeedsPlainInput(18): false 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'0fde4661-cd2f-48a2-8768-e9b5cfcf4939' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000000'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000000' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666354' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666354' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{0fde4661-cd2f-48a2-8768-e9b5cfcf4939}1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'0fde4661-cd2f-48a2-8768-e9b5cfcf4939' counter:'1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'e19af67a-ec28-49b9-accf-4d7536aeff66' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000001'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000001' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666653' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666653' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{e19af67a-ec28-49b9-accf-4d7536aeff66}2' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'e19af67a-ec28-49b9-accf-4d7536aeff66' counter:'2' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'1bac3455-df90-4b8f-8859-259b5546c0e2' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000002'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000002' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666640' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{1bac3455-df90-4b8f-8859-259b5546c0e2}1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'1bac3455-df90-4b8f-8859-259b5546c0e2' counter:'1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'b3052b52-278b-4a14-b574-49c56c905536' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000003'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000003' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666682' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533666682' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{b3052b52-278b-4a14-b574-49c56c905536}4' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'b3052b52-278b-4a14-b574-49c56c905536' counter:'4' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'8e53ea26-a866-4545-ac90-4e608ae0ce71' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000004'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000004' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533667509' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Updated reference PolicyKey '710930222', reference Lifetime '', Last sync at '1533667509' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{8e53ea26-a866-4545-ac90-4e608ae0ce71}2' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'8e53ea26-a866-4545-ac90-4e608ae0ce71' counter:'2' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'85920f33-9300-493b-a428-5b045b361c54' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000005'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000005' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666640' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{85920f33-9300-493b-a428-5b045b361c54}2' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'85920f33-9300-493b-a428-5b045b361c54' counter:'2' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'10c1e3e5-9dad-4ba0-bc57-f2d4f3f0ff94' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000007'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '101000000007' : ref. PolicyKey '710930222', ref. Lifetime '', last sync at '1533666649' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{10c1e3e5-9dad-4ba0-bc57-f2d4f3f0ff94}1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'10c1e3e5-9dad-4ba0-bc57-f2d4f3f0ff94' counter:'1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'e67a9d3d-6329-43a0-b79a-1c7446dfcc08' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100100000006'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '100100000006' : ref. PolicyKey '710930222', ref. Lifetime '470', last sync at '1533666834' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{e67a9d3d-6329-43a0-b79a-1c7446dfcc08}72' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'e67a9d3d-6329-43a0-b79a-1c7446dfcc08' counter:'72' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'6aa258a7-ca11-493e-b405-f9f4c70fc413' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100000000006'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '100000000006' : ref. PolicyKey '710930222', ref. Lifetime '470', last sync at '1533666617' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{6aa258a7-ca11-493e-b405-f9f4c70fc413}136' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'6aa258a7-ca11-493e-b405-f9f4c70fc413' counter:'136' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'4b6b804b-d5ee-4f95-8fb6-013ab93f5019' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100200000006'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '100200000006' : ref. PolicyKey '710930222', ref. Lifetime '470', last sync at '1533666357' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{4b6b804b-d5ee-4f95-8fb6-013ab93f5019}1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'4b6b804b-d5ee-4f95-8fb6-013ab93f5019' counter:'1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'fd' key:'23d554f9-450e-4823-97da-be0c98882c7e' counter:'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->UseCPO('DEFAULT') 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncCollections->AddCollection(): Folder id '' : ref. PolicyKey '710930222', ref. Lifetime '10', last sync at '1511648467' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->GetLatestSyncKey(): '{23d554f9-450e-4823-97da-be0c98882c7e}1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] StateManager->loadHierarchyCache(): 'sec195ebf99e3ada-23d554f9-450e-4823-97da-be0c98882c7e-hc-1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'hc' key:'23d554f9-450e-4823-97da-be0c98882c7e' counter:'1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'' key:'23d554f9-450e-4823-97da-be0c98882c7e' counter:'1' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] HandlePing(): reference PolicyKey for PING: 710930222 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] HandlePing(): initialization data received 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 100100000006 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I Calendar 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Calendar' id '100100000006' 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 101000000000 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I Email 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000000' 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 101000000001 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I Email 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000001' 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 100000000006 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I Contacts 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Contacts' id '100000000006' 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 101000000007 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I Email 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000007' 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 100200000006 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I Tasks 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Tasks' id '100200000006' 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 101000000002 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I Email 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000002' 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 101000000003 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I Email 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000003' 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 101000000005 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I Email 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] HandlePing(): using saved sync state for 'Email' id '101000000005' 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [WBXML] [oliver] I 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] DeviceManager->DoAutomaticASDeviceSaving(): save automatically: false 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] PingTracking initialised with IPC provider 'activesync_ipc_backend' with type 'sec195ebf99e3ada-2' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] Announce process as PUSH connection 08/08/2018 14:13:31 [22048] [ INFO] [oliver] SyncCollections->CheckForChanges(): Waiting for store changes... (lifetime 600 seconds) 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000000'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000000) 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine(): init 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SqlStateMachine->GetState(): devid:'sec195ebf99e3ada' type:'folder_hashes' key:'folder_hashes' counter:'null' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000000') returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - saved: M:3896-R:0-U:331-NUID:8064-UIDV:1314484195 - expiring: 2018-09-07 20:31:16 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000001'): 'false' 08/08/2018 14:13:31 [22048] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000001) 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000001') returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:30-R:0-U:0-NUID:1395-UIDV:1314494811 - saved: M:30-R:0-U:0-NUID:1395-UIDV:1314494811 - expiring: 2018-09-07 20:30:53 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000002'): 'false' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000002) 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000002') returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:0-R:0-U:0-NUID:1-UIDV:1342246661 - saved: M:0-R:0-U:0-NUID:1-UIDV:1342246661 - expiring: 2018-09-07 20:30:40 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000003'): 'false' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000003) 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000003') returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:1693-R:0-U:0-NUID:1753-UIDV:1314494811 - saved: M:1693-R:0-U:0-NUID:1753-UIDV:1314494811 - expiring: 2018-09-07 20:32:16 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000005'): 'false' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000005) 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000005') returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:106-R:0-U:0-NUID:138-UIDV:1314553856 - saved: M:106-R:0-U:0-NUID:138-UIDV:1314553856 - expiring: 2018-09-07 20:32:07 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('101000000007'): 'false' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(101000000007) 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '101000000007') returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: M:64-R:0-U:0-NUID:7237-UIDV:1362921642 - saved: M:64-R:0-U:0-NUID:7237-UIDV:1362921642 - expiring: 2018-09-07 20:30:51 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100100000006'): 'false' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(100100000006) 08/08/2018 14:13:32 [22048] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::read($res_id) should be compatible with EGroupware\Api\Storage::read($keys, $extra_cols = '', $join = '') (2) 08/08/2018 14:13:32 [22048] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::save($resource) should be compatible with EGroupware\Api\Storage::save($keys = NULL, $extra_where = NULL) (2) 08/08/2018 14:13:32 [22048] [WARN] [oliver] /usr/share/egroupware/resources/inc/class.resources_so.inc.php:0 Declaration of resources_so::delete($res_id) should be compatible with EGroupware\Api\Storage::delete($keys = NULL, $only_return_ids = false) (2) 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '100100000006') returning '1533118805' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: 1533118805 - saved: 1533118805 - expiring: 2018-09-07 20:36:14 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100000000006'): 'false' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(100000000006) 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '100000000006') returning 1532374947 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: 1532374947 - saved: 1532374947 - expiring: 2018-09-07 20:30:19 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] ZPush::GetAdditionalSyncFolderStore('100200000006'): 'false' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::ChangesSinkInitialize(100200000006) 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::GetFolderStat(, '100200000006') returning 'EGw-empty-wGE' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] SyncParameters->IsExporterRunRequired(): false - current: EGw-empty-wGE - saved: EGw-empty-wGE - expiring: 2018-09-07 20:25:57 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 11 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] Initializing folder diff engine 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] calendar_zpush::GetFolderList() returning Array([0] => Array([id] => 100100000006[mod] => Herr Oliver Wingenbach[parent] => 0)) 08/08/2018 14:13:32 [22048] [ INFO] [oliver] ExportChangesDiff->InitializeExporter(): Found '0' changes for 'hierarchy' 08/08/2018 14:13:32 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:13:33 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:13:35 [21985] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:13:35 [21985] [ INFO] [oliver] StatusException: SyncCollections->CheckForChanges(): Timeout forced after 31s from 600s due to other process - code: 3 - file: /usr/share/egroupware/activesync/vendor/z-push/z-push/src/lib/core/synccollections.php:586 08/08/2018 14:13:35 [21985] [DEBUG] [oliver] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml 08/08/2018 14:13:35 [21985] [WBXML] [oliver] O 08/08/2018 14:13:35 [21985] [WBXML] [oliver] O 08/08/2018 14:13:35 [21985] [WBXML] [oliver] O 1 08/08/2018 14:13:35 [21985] [WBXML] [oliver] O 08/08/2018 14:13:35 [21985] [WBXML] [oliver] O 08/08/2018 14:13:35 [21985] [DEBUG] [oliver] WBXMLEncoder->endTag() WBXML output completed 08/08/2018 14:13:35 [21985] [WBXML] [oliver] WBXML-OUT: AwFqAAANRUcDMQABAQ== 08/08/2018 14:13:35 [21985] [WBXML] [oliver] WBXML-IN : AwFqAAANRUlKSwMxMDAxMDAwMDAwMDYAAUwDQ2FsZW5kYXIAAQFKSwMxMDEwMDAwMDAwMDAAAUwDRW1haWwAAQFKSwMxMDEwMDAwMDAwMDEAAUwDRW1haWwAAQFKSwMxMDAwMDAwMDAwMDYAAUwDQ29udGFjdHMAAQFKSwMxMDEwMDAwMDAwMDcAAUwDRW1haWwAAQFKSwMxMDAyMDAwMDAwMDYAAUwDVGFza3MAAQFKSwMxMDEwMDAwMDAwMDIAAUwDRW1haWwAAQFKSwMxMDEwMDAwMDAwMDMAAUwDRW1haWwAAQFKSwMxMDEwMDAwMDAwMDUAAUwDRW1haWwAAQEBAQ== 08/08/2018 14:13:35 [21985] [DEBUG] [oliver] LOGOFF 08/08/2018 14:13:35 [21985] [DEBUG] [oliver] LoopDetection->ProcessLoopDetectionTerminate() 08/08/2018 14:13:35 [21985] [ INFO] [oliver] cmd='Ping' memory='5.24 MiB/6.00 MiB' time='32.53s' devType='SamsungDevice' devId='sec195ebf99e3ada' getUser='oliver' from='80.187.101.59' version='EGroupware-17.1.20180720' method='POST' httpcode='200' 08/08/2018 14:13:35 [21985] [DEBUG] [oliver] -------- End 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:14:03 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:14:33 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:14:33 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:14:33 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:14:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:14:33 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:14:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:14:33 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:14:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:14:33 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:14:33 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:14:34 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:14:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:14:34 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:14:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:14:34 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:14:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:14:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:14:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:14:34 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:15:04 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:15:34 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:16:04 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:16:04 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:16:05 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:16:35 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:17:06 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:17:36 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:18:06 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:18:06 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:18:06 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:18:06 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:18:06 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:18:06 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:18:07 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:18:37 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:19:07 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:19:37 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:19:37 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:19:38 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30) 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) returning Array() 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000000, ...) INBOX (0) returning 'M:3896-R:0-U:331-NUID:8064-UIDV:1314484195' 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000000',''M:3896-R:0-U:331-NUID:8064-UIDV:1314484195'') type=mail, folder=0 returning Array() 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000001, ...) Drafts (0) returning 'M:30-R:0-U:0-NUID:1395-UIDV:1314494811' 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000001',''M:30-R:0-U:0-NUID:1395-UIDV:1314494811'') type=mail, folder=1 returning Array() 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000002, ...) Templates (0) returning 'M:0-R:0-U:0-NUID:1-UIDV:1342246661' 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000002',''M:0-R:0-U:0-NUID:1-UIDV:1342246661'') type=mail, folder=2 returning Array() 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000003, ...) Sent (0) returning 'M:1693-R:0-U:0-NUID:1753-UIDV:1314494811' 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000003',''M:1693-R:0-U:0-NUID:1753-UIDV:1314494811'') type=mail, folder=3 returning Array() 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000005, ...) Junk (0) returning 'M:106-R:0-U:0-NUID:138-UIDV:1314553856' 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000005',''M:106-R:0-U:0-NUID:138-UIDV:1314553856'') type=mail, folder=5 returning Array() 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] mail_zpush::AlterPingChanges(101000000007, ...) INBOX.Server-Sicherungen (0) returning 'M:64-R:0-U:0-NUID:7237-UIDV:1362921642' 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('101000000007',''M:64-R:0-U:0-NUID:7237-UIDV:1362921642'') type=mail, folder=7 returning Array() 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] calendar_zpush::AlterPingChanges('100100000006', ...) type='calendar', owner=6 --> syncstate='1533118805' 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100100000006',''1533118805'') type=calendar, folder=6 returning NULL 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100000000006','1532374947') type=addressbook, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::AlterPingChanges('100200000006',''EGw-empty-wGE'') type=infolog, folder=6 returning Array([0] => Array([type] => fakeChange)) 08/08/2018 14:20:08 [22048] [DEBUG] [oliver] activesync_backend::ChangesSink(30) no changes, going to sleep(30)