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)