Error when creating folder with IMAP backend.

I have setup a z-push server with a backend combined of a imap server (dovecot), caldav and carddav (nextcloud).

When i connect a outlook 2013 client al works fine but when i create a folder in my mailbox outlook creates it but it does not appear on the server. Outlooks however uses this folder and i can put mail in it. Al these actions are not syncronised then anymore. The only way to fix it is to do a full resync using z-push-admin.

When do do this on a iphone I get a message that is was not possible to create a folder and thats it.

In the z-push error log i get the following error:

14/12/2017 10:16:34 [ 9811] [ERROR] [user] ImportChangesCombined->Config() icc not configured
14/12/2017 10:16:34 [ 9811] [FATAL] [user] Fatal error: /usr/share/z-push/backend/combined/importer.php:182 - Call to a member function GetImporter() on a non-object (1)

Is it because this feature is not implemented yet or is there somtehing wrong in my config.

// Defines the server to which we want to connect
define('IMAP_SERVER', '10.10.20.6');

// connecting to default port (143)
define('IMAP_PORT', 143);

// best cross-platform compatibility (see http://php.net/imap_open for options)
define('IMAP_OPTIONS', '/tls/novalidate-cert');

// Mark messages as read when moving to Trash.
//      BE AWARE that you will lose the unread flag, but some mail clients do this so the Trash folder doesn't get boldened
define('IMAP_AUTOSEEN_ON_DELETE', false);


// Since I know you won't configure this, I will raise an error unless you do.
// When configured set this to true to remove the error
define('IMAP_FOLDER_CONFIGURED', true);

// Folder prefix is the common part in your names (3, 4)
define('IMAP_FOLDER_PREFIX', '');

// Inbox will have the preffix preppend (3 & 4 to true)
define('IMAP_FOLDER_PREFIX_IN_INBOX', false);

// Inbox folder name (case doesn't matter) - (empty in 4)
define('IMAP_FOLDER_INBOX', 'INBOX');

// Sent folder name (case doesn't matter)
define('IMAP_FOLDER_SENT', 'SENT');

// Draft folder name (case doesn't matter)
define('IMAP_FOLDER_DRAFT', 'DRAFTS');

// Trash folder name (case doesn't matter)
define('IMAP_FOLDER_TRASH', 'TRASH');

// Spam folder name (case doesn't matter). Only showed as special by iOS devices
define('IMAP_FOLDER_SPAM', 'SPAM');

// Archive folder name (case doesn't matter). Only showed as special by iOS devices
define('IMAP_FOLDER_ARCHIVE', 'ARCHIVE');

// forward messages inline (default true - inlined)
define('IMAP_INLINE_FORWARD', true);

// list of folders we want to exclude from sync. Names, or part of it, separated by |
// example: dovecot.sieve|archive|spam
define('IMAP_EXCLUDED_FOLDERS', 'dovecot.sieve');
class BackendCombinedConfig {

    // *************************
    //  BackendCombined settings
    // *************************
    /**
     * Returns the configuration of the combined backend
     *
     * @access public
     * @return array
     *
     */
    public static function GetBackendCombinedConfig() {
        //use a function for it because php does not allow
        //assigning variables to the class members (expecting T_STRING)
        return array(
            //the order in which the backends are loaded.
            //login only succeeds if all backend return true on login
            //sending mail: the mail is sent with first backend that is able to send the mail
            'backends' => array(
                'i' => array(
                    'name' => 'BackendIMAP',
                ),
                'z' => array(
                    'name' => 'BackendKopano',
                ),
                'm' => array(
                    'name' => 'BackendMaildir',
                ),
                'v' => array(
                    'name' => 'BackendVCardDir',
                ),
                'l' => array(
                    'name' => 'BackendLDAP',
                ),
                'd' => array(
                    'name' => 'BackendCardDAV',
                ),
                'c' => array(
                    'name' => 'BackendCalDAV',
                ),
            ),
            'delimiter' => '/',
            //force one type of folder to one backend
            //it must match one of the above defined backends
            'folderbackend' => array(
                SYNC_FOLDER_TYPE_INBOX => 'i',
                SYNC_FOLDER_TYPE_DRAFTS => 'i',
                SYNC_FOLDER_TYPE_WASTEBASKET => 'i',
                SYNC_FOLDER_TYPE_SENTMAIL => 'i',
                SYNC_FOLDER_TYPE_OUTBOX => 'i',
                SYNC_FOLDER_TYPE_TASK => 'c',
                SYNC_FOLDER_TYPE_APPOINTMENT => 'c',
                SYNC_FOLDER_TYPE_CONTACT => 'd',
                SYNC_FOLDER_TYPE_NOTE => 'c',
                SYNC_FOLDER_TYPE_JOURNAL => 'c',
                SYNC_FOLDER_TYPE_OTHER => 'i',
                SYNC_FOLDER_TYPE_USER_MAIL => 'i',
                SYNC_FOLDER_TYPE_USER_APPOINTMENT => 'c',
                SYNC_FOLDER_TYPE_USER_CONTACT => 'd',
                SYNC_FOLDER_TYPE_USER_TASK => 'c',
                SYNC_FOLDER_TYPE_USER_JOURNAL => 'c',
                SYNC_FOLDER_TYPE_USER_NOTE => 'c',
                SYNC_FOLDER_TYPE_UNKNOWN => 'i',
            ),
            //creating a new folder in the root folder should create a folder in one backend
            'rootcreatefolderbackend' => 'i',
        );
    }
}

Hi insan3,

could you post the whole request log when creating a folder on the iphone at at least DEBUG level?

Are you creating the folders in Outlook directly under the root (where Inbox, Sent Items etc are) or under the inbox? Are you creating a mail type folder?

Manfred

I missed the post, but better late then never. :)

I create the folder on my iphone (using the default app). directly under the root. (if i try it under inbox or another folder the same problem occurs).

This is the z-push log

12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] -------- Start
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] cmd='FolderCreate' devType='iPhone' devId='7v59m2qh0d6o9bkuncc8fj6rp8' getUser='jurgen' from='84.241.181.166' version='2.3.8+0' method='POST'
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] Used timezone 'Europe/Amsterdam'
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/imap/imap.php'
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/caldav/caldav.php'
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/carddav/carddav.php'
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] Combined 3 backends loaded.
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] Request::ProcessHeaders() ASVersion: 14.0
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] ZPush::CommandNeedsProvisioning(10): true
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] FileStateMachine->GetState() read '2465' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-devicedata'
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] ASDevice data loaded for user: 'jurgen'
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] TopCollector(): Initialized mutexid Resource id #62 and memid Resource id #63.
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] LoopDetection(): Initialized mutexid Resource id #67 and memid Resource id #68.
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] ZPush::HierarchyCommand(10): true
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] DeviceManager->ProvisioningRequired('675597418') saved device key '675597418': false
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] DeviceManager->getPolicyName(): determined policy name: 'default'
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] ZPush::CommandNeedsAuthentication(10): true
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] Combined->Logon('jurgen', '',***))
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] BackendIMAP->Logon(): Excluding Folders (dovecot.sieve)
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] BackendIMAP->Logon(): User 'jurgen' is authenticated on '{10.10.20.6:143/imap/tls/novalidate-cert}'
12/01/2018 10:38:56 [27856] [DEBUG] [jurgen] BackendCalDAV->Logon(): User 'jurgen' is authenticated on CalDAV 'http://10.10.20.7:80/nextcloud/remote.php/dav/calendars/jurgen/'
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] BackendCardDAV->Logon(): User 'jurgen' is authenticated on 'http://10.10.20.7:80/nextcloud/remote.php/dav/addressbooks/users/jurgen/contacts/'
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] BackendCardDAV::discoverAddressbooks() Found addressbook 'http://10.10.20.7:80/nextcloud/remote.php/dav/addressbooks/users/jurgen/contacts/'
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] Combined->Logon() success
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] ZPush::CommandNeedsPlainInput(10): false
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '0', returning as is.
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] StateManager->loadHierarchyCache(): '7v59m2qh0d6o9bkuncc8fj6rp8-93fa35ec-1d23-46e8-8442-2763c6478a86-hc-1'
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] FileStateMachine->GetState() read '2880' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-93fa35ec-1d23-46e8-8442-2763c6478a86-hc-1'
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] FileStateMachine->GetState() read '1304' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-93fa35ec-1d23-46e8-8442-2763c6478a86-1'
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] FileStateMachine->GetState() read '379' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-93fa35ec-1d23-46e8-8442-2763c6478a86-fd'
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] SyncParameters->UseCPO('DEFAULT')
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 15
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] Combined->Setup('', 'false', '', 'false')
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] Combined->Setup() success
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] ZPush::GetAdditionalSyncFolderStore(''): 'false'
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] Combined->Setup('', 'false', '', 'false')
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] Combined->Setup() success
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] Combined->GetImporter() -> Hierarchy: ImportChangesCombined()
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] ImportChangesCombined->Config(...)
12/01/2018 10:38:57 [27856] [ERROR] [jurgen] ImportChangesCombined->Config() icc not configured
12/01/2018 10:38:57 [27856] [DEBUG] [jurgen] ImportChangesCombined->ImportFolderChange() id: '', parent: '0'
12/01/2018 10:38:57 [27856] [FATAL] [jurgen] Fatal error: /usr/share/z-push/backend/combined/importer.php:182 - Call to a member function GetImporter() on a non-object (1)
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] -------- Start
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] cmd='FolderCreate' devType='iPhone' devId='7v59m2qh0d6o9bkuncc8fj6rp8' getUser='jurgen' from='84.241.181.166' version='2.3.8+0' method='POST'
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] Used timezone 'Europe/Amsterdam'
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/imap/imap.php'
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/caldav/caldav.php'
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/carddav/carddav.php'
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] Combined 3 backends loaded.
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] Request::ProcessHeaders() ASVersion: 14.0
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] ZPush::CommandNeedsProvisioning(10): true
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] FileStateMachine->GetState() read '2465' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-devicedata'
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] ASDevice data loaded for user: 'jurgen'
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] TopCollector(): Initialized mutexid Resource id #62 and memid Resource id #63.
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] LoopDetection(): Initialized mutexid Resource id #67 and memid Resource id #68.
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] ZPush::HierarchyCommand(10): true
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] DeviceManager->ProvisioningRequired('675597418') saved device key '675597418': false
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] DeviceManager->getPolicyName(): determined policy name: 'default'
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] ZPush::CommandNeedsAuthentication(10): true
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] Combined->Logon('jurgen', '',***))
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] BackendIMAP->Logon(): Excluding Folders (dovecot.sieve)
12/01/2018 10:38:57 [27855] [DEBUG] [jurgen] BackendIMAP->Logon(): User 'jurgen' is authenticated on '{10.10.20.6:143/imap/tls/novalidate-cert}'
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] BackendCalDAV->Logon(): User 'jurgen' is authenticated on CalDAV 'http://10.10.20.7:80/nextcloud/remote.php/dav/calendars/jurgen/'
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] BackendCardDAV->Logon(): User 'jurgen' is authenticated on 'http://10.10.20.7:80/nextcloud/remote.php/dav/addressbooks/users/jurgen/contacts/'
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] BackendCardDAV::discoverAddressbooks() Found addressbook 'http://10.10.20.7:80/nextcloud/remote.php/dav/addressbooks/users/jurgen/contacts/'
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] Combined->Logon() success
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] ZPush::CommandNeedsPlainInput(10): false
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '0', returning as is.
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] StateManager->loadHierarchyCache(): '7v59m2qh0d6o9bkuncc8fj6rp8-93fa35ec-1d23-46e8-8442-2763c6478a86-hc-1'
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] FileStateMachine->GetState() read '2880' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-93fa35ec-1d23-46e8-8442-2763c6478a86-hc-1'
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] FileStateMachine->GetState() read '1304' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-93fa35ec-1d23-46e8-8442-2763c6478a86-1'
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] FileStateMachine->GetState() read '379' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-93fa35ec-1d23-46e8-8442-2763c6478a86-fd'
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] SyncParameters->UseCPO('DEFAULT')
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 15
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] Combined->Setup('', 'false', '', 'false')
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] Combined->Setup() success
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] ZPush::GetAdditionalSyncFolderStore(''): 'false'
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] Combined->Setup('', 'false', '', 'false')
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] Combined->Setup() success
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] Combined->GetImporter() -> Hierarchy: ImportChangesCombined()
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] ImportChangesCombined->Config(...)
12/01/2018 10:38:58 [27855] [ERROR] [jurgen] ImportChangesCombined->Config() icc not configured
12/01/2018 10:38:58 [27855] [DEBUG] [jurgen] ImportChangesCombined->ImportFolderChange() id: '', parent: '0'
12/01/2018 10:38:58 [27855] [FATAL] [jurgen] Fatal error: /usr/share/z-push/backend/combined/importer.php:182 - Call to a member function GetImporter() on a non-object (1)
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] -------- Start
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] cmd='FolderSync' devType='iPhone' devId='7v59m2qh0d6o9bkuncc8fj6rp8' getUser='jurgen' from='84.241.181.166' version='2.3.8+0' method='POST'
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] Used timezone 'Europe/Amsterdam'
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/imap/imap.php'
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/caldav/caldav.php'
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/carddav/carddav.php'
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] Combined 3 backends loaded.
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] Request::ProcessHeaders() ASVersion: 14.0
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] ZPush::CommandNeedsProvisioning(9): true
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] FileStateMachine->GetState() read '2465' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-devicedata'
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] ASDevice data loaded for user: 'jurgen'
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] TopCollector(): Initialized mutexid Resource id #62 and memid Resource id #63.
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] LoopDetection(): Initialized mutexid Resource id #67 and memid Resource id #68.
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] ZPush::HierarchyCommand(9): true
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] DeviceManager->ProvisioningRequired('675597418') saved device key '675597418': false
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] DeviceManager->getPolicyName(): determined policy name: 'default'
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] ZPush::CommandNeedsAuthentication(9): true
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] Combined->Logon('jurgen', '',***))
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] BackendIMAP->Logon(): Excluding Folders (dovecot.sieve)
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] BackendIMAP->Logon(): User 'jurgen' is authenticated on '{10.10.20.6:143/imap/tls/novalidate-cert}'
12/01/2018 10:38:59 [27854] [DEBUG] [jurgen] BackendCalDAV->Logon(): User 'jurgen' is authenticated on CalDAV 'http://10.10.20.7:80/nextcloud/remote.php/dav/calendars/jurgen/'
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendCardDAV->Logon(): User 'jurgen' is authenticated on 'http://10.10.20.7:80/nextcloud/remote.php/dav/addressbooks/users/jurgen/contacts/'
12/01/2018 10:39:00 [27851] [DEBUG] [jurgen] BackendCombined->ChangesSink - Calling in 'BackendCalDAV' with 10
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendCardDAV::discoverAddressbooks() Found addressbook 'http://10.10.20.7:80/nextcloud/remote.php/dav/addressbooks/users/jurgen/contacts/'
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] Combined->Logon() success
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] ZPush::CommandNeedsPlainInput(9): false
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] ZPush::GetSupportedProtocolVersions(): 12.0,12.1,14.0
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] ZPush::GetSupportedCommands(): Sync,SendMail,SmartForward,SmartReply,GetAttachment,GetHierarchy,CreateCollection,DeleteCollection,MoveCollection,FolderSync,FolderCreate,FolderDelete,FolderUpdate,MoveItems,GetItemEstimate,MeetingResponse,ResolveRecipients,ValidateCert,Provision,Search,Ping,Notify,ItemOperations,Settings
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] FileStateMachine->GetState() read '379' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-93fa35ec-1d23-46e8-8442-2763c6478a86-fd'
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] SyncParameters->UseCPO('DEFAULT')
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 0
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] Combined->Setup('', 'false', '', 'false')
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] Combined->Setup() success
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] LoopDetection->ProcessLoopDetectionAddStatus: 'hierarchy' with status 1
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] Combined->GetExporter('')
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] ExportChangesCombined constructed
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] ExportChangesCombined->Config(...)
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] ExportChangesCombined->Config() success
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] ExportChangesCombined->InitializeExporter(...)
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] ImportHierarchyChangesCombinedWrap->__construct('i',...)
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] FileStateMachine->GetState() read '1101' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-bs-1513173770'
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->getFolderIdFromImapId('INBOX') = 36de49e5
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->convertImapId('INBOX') = 36de49e5
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->getFolderIdFromImapId('Archive') = 4bea238f
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->convertImapId('Archive') = 4bea238f
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->getFolderIdFromImapId('Sent Messages') = 3598f4d9
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->convertImapId('Sent Messages') = 3598f4d9
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->getFolderIdFromImapId('Sent') = 96cecdc4
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->convertImapId('Sent') = 96cecdc4
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->getFolderIdFromImapId('Trash') = 97124394
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->convertImapId('Trash') = 97124394
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->getFolderIdFromImapId('Junk') = 16efdefb
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->convertImapId('Junk') = 16efdefb
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->getFolderIdFromImapId('Drafts') = ae1242f3
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->convertImapId('Drafts') = ae1242f3
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->getFolderIdFromImapId('name') = 911dfa32
12/01/2018 10:39:00 [27854] [DEBUG] [jurgen] BackendIMAP->convertImapId('name') = 911dfa32```

This is the z-push error log

12/01/2018 10:38:57 [27856] [ERROR] [jurgen] ImportChangesCombined->Config() icc not configured
12/01/2018 10:38:57 [27856] [FATAL] [jurgen] Fatal error: /usr/share/z-push/backend/combined/importer.php:182 - Call to a member function GetImporter() on a non-object (1)
12/01/2018 10:38:58 [27855] [ERROR] [jurgen] ImportChangesCombined->Config() icc not configured
12/01/2018 10:38:58 [27855] [FATAL] [jurgen] Fatal error: /usr/share/z-push/backend/combined/importer.php:182 - Call to a member function GetImporter() on a non-object (1)

Hi insan3,

could you also post the WBXML log for FolderCreate?

Manfred

Hi

Here it is :)

23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] -------- Start
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] cmd='FolderCreate' devType='iPhone' devId='7v59m2qh0d6o9bkuncc8fj6rp8' getUser='jurgen' from='84.241.181.166' version='2.3.8+0' method='POST'
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] Used timezone 'Europe/Amsterdam'
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/imap/imap.php'
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/caldav/caldav.php'
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/carddav/carddav.php'
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] Combined 3 backends loaded.
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] Request::ProcessHeaders() ASVersion: 14.0
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] ZPush::CommandNeedsProvisioning(10): true
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] FileStateMachine->GetState() read '2602' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-devicedata'
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] ASDevice data loaded for user: 'jurgen'
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] TopCollector(): Initialized mutexid Resource id #62 and memid Resource id #63.
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] LoopDetection(): Initialized mutexid Resource id #67 and memid Resource id #68.
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] ZPush::HierarchyCommand(10): true
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] DeviceManager->ProvisioningRequired('107430239') saved device key '107430239': false
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] DeviceManager->getPolicyName(): determined policy name: 'default'
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] ZPush::CommandNeedsAuthentication(10): true
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] Combined->Logon('jurgen', '',***))
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] BackendIMAP->Logon(): Excluding Folders (dovecot.sieve)
23/01/2018 10:16:35 [ 4142] [DEBUG] [jurgen] BackendIMAP->Logon(): User 'jurgen' is authenticated on '{10.10.20.6:143/imap/tls/novalidate-cert}'
23/01/2018 10:16:36 [ 4131] [DEBUG] [jurgen] BackendCombined->ChangesSink - Calling in 'BackendCardDAV' with 10
23/01/2018 10:16:36 [ 4131] [DEBUG] [jurgen] BackendCardDAV->ChangesSink - Not initialized ChangesSink, sleep and exit
23/01/2018 10:16:36 [ 4142] [DEBUG] [jurgen] BackendCalDAV->Logon(): User 'jurgen' is authenticated on CalDAV 'http://10.10.20.7:80/nextcloud/remote.php/dav/calendars/jurgen/'
23/01/2018 10:16:36 [ 4142] [DEBUG] [jurgen] BackendCardDAV->Logon(): User 'jurgen' is authenticated on 'http://10.10.20.7:80/nextcloud/remote.php/dav/addressbooks/users/jurgen/contacts/'
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] BackendCardDAV::discoverAddressbooks() Found addressbook 'http://10.10.20.7:80/nextcloud/remote.php/dav/addressbooks/users/jurgen/contacts/'
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] Combined->Logon() success
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] ZPush::CommandNeedsPlainInput(10): false
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I  <FolderHierarchy:FolderCreate>
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I   <FolderHierarchy:SyncKey>
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I    {529cebd0-3dea-49a0-87ec-6bb1e5f029f3}1
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I   </FolderHierarchy:SyncKey>
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I   <FolderHierarchy:ParentId>
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I    0
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '0', returning as is.
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I   </FolderHierarchy:ParentId>
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I   <FolderHierarchy:DisplayName>
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I    Testje
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I   </FolderHierarchy:DisplayName>
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I   <FolderHierarchy:Type>
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I    12
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I   </FolderHierarchy:Type>
23/01/2018 10:16:37 [ 4142] [WBXML] [jurgen] I  </FolderHierarchy:FolderCreate>
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] StateManager->loadHierarchyCache(): '7v59m2qh0d6o9bkuncc8fj6rp8-529cebd0-3dea-49a0-87ec-6bb1e5f029f3-hc-1'
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] FileStateMachine->GetState() read '2880' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-529cebd0-3dea-49a0-87ec-6bb1e5f029f3-hc-1'
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] FileStateMachine->GetState() read '1304' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-529cebd0-3dea-49a0-87ec-6bb1e5f029f3-1'
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] FileStateMachine->GetState() read '379' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-529cebd0-3dea-49a0-87ec-6bb1e5f029f3-fd'
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] SyncParameters->UseCPO('DEFAULT')
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 15
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] Combined->Setup('', 'false', '', 'false')
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] Combined->Setup() success
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] ZPush::GetAdditionalSyncFolderStore(''): 'false'
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] Combined->Setup('', 'false', '', 'false')
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] Combined->Setup() success
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] Combined->GetImporter() -> Hierarchy: ImportChangesCombined()
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] ImportChangesCombined->Config(...)
23/01/2018 10:16:37 [ 4142] [ERROR] [jurgen] ImportChangesCombined->Config() icc not configured
23/01/2018 10:16:37 [ 4142] [DEBUG] [jurgen] ImportChangesCombined->ImportFolderChange() id: '', parent: '0'
23/01/2018 10:16:37 [ 4142] [FATAL] [jurgen] Fatal error: /usr/share/z-push/backend/combined/importer.php:182 - Call to a member function GetImporter() on a non-object (1)
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] -------- Start
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] cmd='FolderCreate' devType='iPhone' devId='7v59m2qh0d6o9bkuncc8fj6rp8' getUser='jurgen' from='84.241.181.166' version='2.3.8+0' method='POST'
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] Used timezone 'Europe/Amsterdam'
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/imap/imap.php'
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/caldav/caldav.php'
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/carddav/carddav.php'
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] Combined 3 backends loaded.
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] Request::ProcessHeaders() ASVersion: 14.0
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] ZPush::CommandNeedsProvisioning(10): true
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] FileStateMachine->GetState() read '2602' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-devicedata'
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] ASDevice data loaded for user: 'jurgen'
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] TopCollector(): Initialized mutexid Resource id #62 and memid Resource id #63.
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] LoopDetection(): Initialized mutexid Resource id #67 and memid Resource id #68.
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] ZPush::HierarchyCommand(10): true
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] DeviceManager->ProvisioningRequired('107430239') saved device key '107430239': false
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] DeviceManager->getPolicyName(): determined policy name: 'default'
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] ZPush::CommandNeedsAuthentication(10): true
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] Combined->Logon('jurgen', '',***))
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] BackendIMAP->Logon(): Excluding Folders (dovecot.sieve)
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] BackendIMAP->Logon(): User 'jurgen' is authenticated on '{10.10.20.6:143/imap/tls/novalidate-cert}'
23/01/2018 10:16:37 [ 4143] [DEBUG] [jurgen] BackendCalDAV->Logon(): User 'jurgen' is authenticated on CalDAV 'http://10.10.20.7:80/nextcloud/remote.php/dav/calendars/jurgen/'
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] BackendCardDAV->Logon(): User 'jurgen' is authenticated on 'http://10.10.20.7:80/nextcloud/remote.php/dav/addressbooks/users/jurgen/contacts/'
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] BackendCardDAV::discoverAddressbooks() Found addressbook 'http://10.10.20.7:80/nextcloud/remote.php/dav/addressbooks/users/jurgen/contacts/'
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] Combined->Logon() success
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] ZPush::CommandNeedsPlainInput(10): false
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I  <FolderHierarchy:FolderCreate>
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I   <FolderHierarchy:SyncKey>
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I    {529cebd0-3dea-49a0-87ec-6bb1e5f029f3}1
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I   </FolderHierarchy:SyncKey>
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I   <FolderHierarchy:ParentId>
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I    0
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] DeviceManager->GetBackendIdForFolderId(): no backend-folderid available for '0', returning as is.
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I   </FolderHierarchy:ParentId>
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I   <FolderHierarchy:DisplayName>
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I    Testje
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I   </FolderHierarchy:DisplayName>
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I   <FolderHierarchy:Type>
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I    12
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I   </FolderHierarchy:Type>
23/01/2018 10:16:38 [ 4143] [WBXML] [jurgen] I  </FolderHierarchy:FolderCreate>
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] StateManager->loadHierarchyCache(): '7v59m2qh0d6o9bkuncc8fj6rp8-529cebd0-3dea-49a0-87ec-6bb1e5f029f3-hc-1'
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] FileStateMachine->GetState() read '2880' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-529cebd0-3dea-49a0-87ec-6bb1e5f029f3-hc-1'
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] FileStateMachine->GetState() read '1304' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-529cebd0-3dea-49a0-87ec-6bb1e5f029f3-1'
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] FileStateMachine->GetState() read '379' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-529cebd0-3dea-49a0-87ec-6bb1e5f029f3-fd'
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] SyncParameters->UseCPO('DEFAULT')
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 15
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] Combined->Setup('', 'false', '', 'false')
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] Combined->Setup() success
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] ZPush::GetAdditionalSyncFolderStore(''): 'false'
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] Combined->Setup('', 'false', '', 'false')
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] Combined->Setup() success
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] Combined->GetImporter() -> Hierarchy: ImportChangesCombined()
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] ImportChangesCombined->Config(...)
23/01/2018 10:16:38 [ 4143] [ERROR] [jurgen] ImportChangesCombined->Config() icc not configured
23/01/2018 10:16:38 [ 4143] [DEBUG] [jurgen] ImportChangesCombined->ImportFolderChange() id: '', parent: '0'
23/01/2018 10:16:38 [ 4143] [FATAL] [jurgen] Fatal error: /usr/share/z-push/backend/combined/importer.php:182 - Call to a member function GetImporter() on a non-object (1)
23/01/2018 10:16:38 [ 4127] [DEBUG] [jurgen] BackendCombined->ChangesSink - Calling in 'BackendCalDAV' with 10
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] -------- Start
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] cmd='FolderSync' devType='iPhone' devId='7v59m2qh0d6o9bkuncc8fj6rp8' getUser='jurgen' from='84.241.181.166' version='2.3.8+0' method='POST'
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] Used timezone 'Europe/Amsterdam'
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/imap/imap.php'
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/caldav/caldav.php'
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] Including backend file: '/usr/share/z-push/backend/carddav/carddav.php'
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] Combined 3 backends loaded.
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] Request::ProcessHeaders() ASVersion: 14.0
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] ZPush::CommandNeedsProvisioning(9): true
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] FileStateMachine->GetState() read '2602' bytes from file: '/var/lib/z-push/8/p/7v59m2qh0d6o9bkuncc8fj6rp8-devicedata'
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] ASDevice data loaded for user: 'jurgen'
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] TopCollector(): Initialized mutexid Resource id #62 and memid Resource id #63.
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] TopCollector initialised with IPC provider 'IpcSharedMemoryProvider' with type '20'
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] LoopDetection(): Initialized mutexid Resource id #67 and memid Resource id #68.
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] LoopDetection initialised with IPC provider 'IpcSharedMemoryProvider' with type '1337'
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] ZPush::HierarchyCommand(9): true
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] DeviceManager->ProvisioningRequired('107430239') saved device key '107430239': false
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] DeviceManager->getPolicyName(): determined policy name: 'default'
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] DeviceManager->getProvisioningPolicies(): loaded 'default' policy.
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] ZPush::CommandNeedsAuthentication(9): true
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] Combined->Logon('jurgen', '',***))
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] BackendIMAP->Logon(): Excluding Folders (dovecot.sieve)
23/01/2018 10:16:38 [ 4130] [DEBUG] [jurgen] BackendIMAP->Logon(): User 'jurgen' is authenticated on '{10.10.20.6:143/imap/tls/novalidate-cert}'
23/01/2018 10:16:39 [ 4130] [DEBUG] [jurgen] BackendCalDAV->Logon(): User 'jurgen' is authenticated on CalDAV 'http://10.10.20.7:80/nextcloud/remote.php/dav/calendars/jurgen/'
23/01/2018 10:16:40 [ 4130] [DEBUG] [jurgen] BackendCardDAV->Logon(): User 'jurgen' is authenticated on 'http://10.10.20.7:80/nextcloud/remote.php/dav/addressbooks/users/jurgen/contacts/'

Hi insan3,

I was able to reproduce it and created a JIRA issue: https://jira.z-hub.io/browse/ZP-1347.

Manfred