Empty folders using z-push, but visible in webapp



  • I have exported to a pst File from Outlook and imported it with the provided kopano-migrate-pst tool.
    I can now see all the Mails and folders. So far so good.

    Now, I tried to add the account over ActiveSync on my iPad. (Tried it with the stock mail app and Outlook)
    I can see all the folders but some of them are empty. I already tried to resync or readd the account without success.

    Z-Push: 2.4.1+0-0
    PHP-MAPI: 8.6.80
    Debian 8.10

    Here is the z-push.log with WBXML enabled. (mail address changed)

    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] -------- Start
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] cmd='Sync' devType='Outlook' devId='338a38fca55e1172' getUser='user@example.com' from='13.74.173.210' version='2.4.1+0-0' method='POST'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] Used timezone 'Europe/Zurich'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] ZPush::GetBackend(): trying autoload backend 'BackendKopano'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] BackendKopano using PHP-MAPI version: 8.6.80 - PHP version: 5.6.33-0+deb8u1
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] Request::ProcessHeaders() ASVersion: 14.1
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] ZPush::CommandNeedsProvisioning(0): true
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] FileStateMachine->GetState() read '3350' bytes from file: '/var/lib/z-push/2/7/338a38fca55e1172-devicedata'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] ASDevice data loaded for user: 'user@example.com'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] TopCollector(): Initialized mutexid Resource id #18 and memid Resource id #19.
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] LoopDetection(): Initialized mutexid Resource id #22 and memid Resource id #23.
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] ZPush::HierarchyCommand(0): false
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] DeviceManager->ProvisioningRequired('421734790') saved device key '421734790': false
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] DeviceManager->getPolicyName(): determined policy name: 'default'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] ZPush::CommandNeedsAuthentication(0): true
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] KopanoBackend->Logon(): Trying to authenticate user 'user@example.com'..
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] KopanoBackend->openMessageStore('user@example.com'): Found 'DEFAULT' store: 'Resource id #34'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] KopanoBackend->Logon(): User 'user@example.com' is authenticated
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] Store supports properties containing Unicode characters.
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] ZPush::CommandNeedsPlainInput(0): false
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I  <Synchronize>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I   <Folders>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I    <Folder>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I     <SyncKey>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I      {7b80d1b9-c582-4b00-8903-74b80b49829e}1
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I     </SyncKey>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I     <FolderId>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I      Uf113f
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I     </FolderId>
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] FileStateMachine->GetState() read '1053' bytes from file: '/var/lib/z-push/2/7/338a38fca55e1172-7b80d1b9-c582-4b00-8903-74b80b49829e-fd'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] SyncParameters->UseCPO('DEFAULT')
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] DeviceManager->GetBackendIdForFolderId(): folderid Uf113f => d5b0152468fa4ce487a001aa0ee1bf4e9f8f01000000
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] SyncCollections->AddCollection(): Folder id 'Uf113f' : ref. PolicyKey '421734790', ref. Lifetime '540', last sync at '1525259180'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] SyncCollections->AddCollection(): Updated reference PolicyKey '421734790', reference Lifetime '540', Last sync at '1525259180'
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I     <DeletesAsMoves>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I      1
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I     </DeletesAsMoves>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I     <GetChanges/>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I     <WindowSize>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I      50
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I     </WindowSize>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I     <Options>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I      <FilterType>
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] SyncParameters->UseCPO('DEFAULT')
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I       5
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I      </FilterType>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I      <AirSyncBase:BodyPreference>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I       <AirSyncBase:Type>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I        2
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I       </AirSyncBase:Type>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I       <AirSyncBase:TruncationSize>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I        1048576
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I       </AirSyncBase:TruncationSize>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I      </AirSyncBase:BodyPreference>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I      <RightsManagement:RightsManagementSupport>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I       1
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I      </RightsManagement:RightsManagementSupport>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I     </Options>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I    </Folder>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I   </Folders>
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] I  </Synchronize>
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] HandleSync(): Start Output
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] ASDevice->GetFolderIdForBackendId(): no valid condition found for determining folderid for backendid '0'. Returning as is!
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] ZPush::GetAdditionalSyncFolderStore('d5b0152468fa4ce487a001aa0ee1bf4e9f8f01000000'): 'false'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] KopanoBackend->GetFolderStat() fetched status information of 33 folders for store 'user@example.com'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] SyncParameters->IsExporterRunRequired(): false - current: 0000000000/159/0/0 - saved: 0000000000/159/0/0 - expiring: 2018-06-02 02:00:27
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] HandleSync: No changes found for Email folder id 'Uf113f'. Omitting output.
    02/05/2018 13:27:50 [ 3014] [WBXML] [user@example.com] WBXML-IN : AwFqAEVcT0sDezdiODBkMWI5LWM1ODItNGIwMC04OTAzLTc0YjgwYjQ5ODI5ZX0xAAFSA1VmMTEzZgABXgMxAAETVQM1MAABV1gDNQABABFFRgMyAAFHAzEwNDg1NzYAAQEAGEUDMQABAQEBAQ==
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] LoopDetection->ProcessLoopDetectionTerminate()
    02/05/2018 13:27:50 [ 3014] [ INFO] [user@example.com] cmd='Sync' memory='2.37 MiB/2.75 MiB' time='0.04s' devType='Outlook' devId='338a38fca55e1172' getUser='user@example.com' from='13.74.173.210' idle='0s' version='2.4.1+0-0' method='POST' httpcode='200'
    02/05/2018 13:27:50 [ 3014] [DEBUG] [user@example.com] -------- End
    

  • Kopano

    Hi @vringe ,

    are the messages you are expecting within the sync window?



  • Hi @fbartels

    Sorry for asking. I’m new into Kopano and z-push.
    What do you exactly mean with “sync window”?


  • Kopano

    Hi @vringe ,

    what I meant is not really about Z-Push or Kopano. When you sync a folder on mobile devices, these by default only sync a certain timeframe (e.g. “the last two weeks”). What I wanted to ask is: what have you specified on the mobile device for the timeframe to sync and could it be that the messages on the server are simply oder than that?



  • Thank you very much, @fbartels

    Ok, I see. That’s really embarrassing. I switched back to the stock mail app and found the setting. Now it works like a charme.
    It seems like the Outlook app (on iOS9) has no setting to set the sync window.


Log in to reply