search

fmbiete / Z-Push-contrib

Z-Push fork with changes that I will try to contrib
GNU Affero General Public License v3.0
135 stars 62 forks source link

Unable to fetch imap folder (inbox) contents : looping error : [INFO] ExportChangesDiff->InitializeExporter(): Found '5' changes #130

Open extremeshok opened 9 years ago

extremeshok commented 9 years ago

Hi

This is a new install of Zpush using the latest git on centos 6.5 with php 5.6 with all dependencies, remote imap server is dovecot, all firewalls were disabled.

To the best of my knowledge everything is correctly configured for the imap backend.

I am unable to get any email to display on my devices, ie blank inbox. Sending of emails is working, however there is an error that the email cannot be saved to the sent forlder.

Error /var/www/z-push-contrib/logs/z-push.log The below log messages loop continuously.

11/01/2015 23:00:19 [ 8695] [INFO] [***removed email***] ExportChangesDiff->InitializeExporter(): Found '5' changes
11/01/2015 23:00:20 [ 8686] [INFO] [***removed email***] Version='SVN-trunk-r1931' method='POST' from='196.210.207.241' cmd='FolderSync' getUser='usertwo@nex.co.za' devId='0d767ed8cad94e609166cc143838862e' devType='WindowsOutlook'
11/01/2015 23:00:20 [ 8686] [INFO] [***removed email***] ExportChangesDiff->InitializeExporter(): Found '5' changes

I have run the following with success:

./list_imap_folders.pl -S _removed server address_':993/**removed email**/**removed password**

Archive
Drafts
INBOX
INBOX/CentOS-announce
Junk
Sent
Trash

php -f testing-imap_smtp.php

php -f testing-imap_date.php 

#87 (Wed, 7 Jan 2015 22:45:41 +0000) - From: Johnny Hughes <johnny@centos.org> [CentOS-announce] CESA-2015:0016 Moderate CentOS 6 glibc Security Update
Checking if the messages is withing the cutoffdate 1420411828, 91INSIDE
#88 (Thu, 8 Jan 2015 15:01:08 +0000) - From: Johnny Hughes <johnny@centos.org> [CentOS-announce] CEBA-2015:0017 CentOS 7 libibverbs BugFix Update
Checking if the messages is withing the cutoffdate 1420411828, 92INSIDE
#89 (Thu, 8 Jan 2015 15:01:20 +0000) - From: Johnny Hughes <johnny@centos.org> [CentOS-announce] CEBA-2015:0017 CentOS 7 libmlx4 BugFix Update
Checking if the messages is withing the cutoffdate 1420411828, 93INSIDE
#90 (Thu, 8 Jan 2015 15:02:17 +0000) - From: Johnny Hughes <johnny@centos.org> [CentOS-announce] CEBA-2015:0018 CentOS 6 sblim-sfcb BugFix Update
Checking if the messages is withing the cutoffdate 1420411828, 94INSIDE

config.php

    define('TIMEZONE', 'UTC');
    define('BASE_PATH', dirname($_SERVER['SCRIPT_FILENAME']). '/');
    define('SCRIPT_TIMEOUT', 0);
    define('USE_X_FORWARDED_FOR_HEADER', false);
    define("CERTIFICATE_OWNER_PARAMETER", "SSL_CLIENT_S_DN_CN");
    define('USE_FULLEMAIL_FOR_LOGIN', true);
    define('PRE_AUTHORIZE_USERS', false);
    define('PRE_AUTHORIZE_NEW_USERS', false);
    define('PRE_AUTHORIZE_NEW_DEVICES', false);
    define('PRE_AUTHORIZE_MAX_DEVICES', 5);
    define('STATE_MACHINE', 'FILE');
    define('STATE_DIR', '/var/www/z-push-contrib/private/');
    define('STATE_SQL_DSN', '');
    define('STATE_SQL_USER', '');
    define('STATE_SQL_PASSWORD', '');
    define('STATE_SQL_OPTIONS', serialize(array(PDO::ATTR_PERSISTENT => true)));
    define('LOGFILEDIR', '/var/www/z-push-contrib/logs/');
    define('LOGFILE', LOGFILEDIR . 'z-push.log');
    define('LOGERRORFILE', LOGFILEDIR . 'z-push-error.log');
    define('LOGLEVEL', LOGLEVEL_INFO);
    define('LOGAUTHFAIL', false);
    define('LOGUSERLEVEL', LOGLEVEL_DEVICEID);
    $specialLogUsers = array("****removed email*****");
    define('PROVISIONING', false);
    define('LOOSE_PROVISIONING', false);
    define('SYNC_CONFLICT_DEFAULT', SYNC_CONFLICT_OVERWRITE_PIM);
    define('SYNC_FILTERTIME_MAX', SYNC_FILTERTYPE_ALL);
    define('PING_INTERVAL', 30);
    define('SINK_FORCERECHECK', 300);
    define('FILEAS_ORDER', SYNC_FILEAS_LASTFIRST);
    define('SYNC_MAX_ITEMS', 100);
    define('UNSET_UNDEFINED_PROPERTIES', false);
    define('SYNC_CONTACTS_MAXPICTURESIZE', 49152);
    define('ALLOW_WEBSERVICE_USERS_ACCESS', false);
    define('BACKEND_PROVIDER', 'BackendIMAP');
    define('SEARCH_PROVIDER', '');
    define('SEARCH_WAIT', 10);
    define('SEARCH_MAXRESULTS', 10);
    define('MAX_EMBEDDED_SIZE', 10485760);

backend/imap/config.php

define('IMAP_SERVER', '****removed server address****');
define('IMAP_PORT', '993');
define('IMAP_OPTIONS', '/ssl/norsh');
define('IMAP_DEFAULTFROM', 'username');
define('IMAP_FOLDER_ROOT', 'INBOX');
define('IMAP_FOLDER_SENT', 'Sent');
define('IMAP_FOLDER_DRAFT', 'Drafts');
define('IMAP_FOLDER_TRASH', 'Trash');
define('IMAP_INLINE_FORWARD', true);
define('IMAP_EXCLUDED_FOLDERS', '');
define('IMAP_PREFIX_SHARED_FOLDERS', '');
define('IMAP_SMTP_METHOD', 'smtp');
global $imap_smtp_params;
$imap_smtp_params = array('host' => 'ssl://****removed server address****', 'port' => 465, 'auth' => true, 'username' => 'imap_username', 'password' => 'imap_password');
define('MAIL_MIMEPART_CRLF', "\r\n");
extremeshok commented 9 years ago

The following is seen on the imap (dovecot) server

Jan 11 23:05:18 imap-login: Info: Login: user=<***removed email***>, method=PLAIN, rip=99.99.99.99, lip=77.77.77.77, mpid=17797, TLS, session=<zc1tbGgM3QDAY2MI>
Jan 11 23:05:18 imap(***removed email***): Info: Disconnected: Logged out in=55 out=854
Jan 11 23:05:19 imap-login: Info: Login: user=<***removed email***>, method=PLAIN, rip=99.99.99.99, lip=77.77.77.77, mpid=17805, TLS, session=<EtFzbGgMugDAY2MI>
Jan 11 23:05:19 imap(***removed email***): Info: Disconnected: Logged out in=55 out=854
Jan 11 23:05:19 imap-login: Info: Login: user=<***removed email***>, method=PLAIN, rip=99.99.99.99, lip=77.77.77.77, mpid=17807, TLS, session=<mn96bGgM6QDAY2MI>
Jan 11 23:05:19 imap(***removed email***): Info: Disconnected: Logged out in=55 out=854
Jan 11 23:05:20 imap-login: Info: Login: user=<***removed email***>, method=PLAIN, rip=99.99.99.99, lip=77.77.77.77, mpid=17809, TLS, session=<7HaAbGgMvgDAY2MI>
Jan 11 23:05:20 imap(***removed email***): Info: Disconnected: Logged out in=55 out=854
extremeshok commented 9 years ago

php -q z-push-admin.php -a list -u _removed email_

                 Synchronized devices of user: ***removed email***
-----------------------------------------------------
DeviceId:               0d767ed8cad94e609166cc143838862e
Device type:            WindowsOutlook
UserAgent:              Outlook/15.0 (15.0.4667.1000; MSI; x64)
ActiveSync version:     14.0
First sync:             2015-01-11 22:46
Last sync:              never
Total folders:          5
Synchronized folders:   0
Synchronized data:      None available
Status:                 Not available
WipeRequest on:         not set
WipeRequest by:         not set
Wiped on:               not set
Attention needed:       No errors known
fmbiete commented 9 years ago

@extremeshok put your log in WEBXML level and look into it.

Also try changing this:

define('IMAP_FOLDER_ROOT', '');

Using INBOX there is for courier or compatible mailboxes, where you have something like this:

INBOX/Sent
INBOX/Trash
...

Maybe I should rename the to IMAP_FOLDER_PREFIX

extremeshok commented 9 years ago

z-push-log with WEBXML logging level with define('IMAP_FOLDER_ROOT', '');

12/01/2015 09:19:55 [ 8687] [DEBUG] [****removed email****] -------- Start
12/01/2015 09:19:55 [ 8687] [ INFO] [****removed email****] Version='SVN-trunk-r1931' method='OPTIONS' from='196.210.207.241' cmd='' getUser='****removed email****' devId='' devType=''
12/01/2015 09:19:55 [ 8687] [DEBUG] [****removed email****] NoPostRequestException: Options request - code: 1
12/01/2015 09:19:55 [ 8687] [DEBUG] [****removed email****] Including backend file: '/var/www/z-push-contrib/backend/imap/imap.php'
12/01/2015 09:19:55 [ 8687] [DEBUG] [****removed email****] ZPush::GetSupportedProtocolVersions(): 2.5,12.0,12.1,14.0
12/01/2015 09:19:55 [ 8687] [DEBUG] [****removed email****] 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/2015 09:19:55 [ 8687] [ INFO] [****removed email****] Options request
12/01/2015 09:19:55 [ 8687] [DEBUG] [****removed email****] -------- End
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] -------- Start
12/01/2015 09:19:55 [ 8688] [ INFO] [****removed email****] Version='SVN-trunk-r1931' method='POST' from='196.210.207.241' cmd='FolderSync' getUser='****removed email****' devId='0d767ed8cad94e609166cc143838862e' devType='WindowsOutlook'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] Used timezone 'UTC'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] Including backend file: '/var/www/z-push-contrib/backend/imap/imap.php'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] Request::ProcessHeaders() ASVersion: 14.0
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] ZPush::CommandNeedsAuthentication(9): true
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->Logon(): User '****removed email****' is authenticated on '{****removed serverl****:993/imap/ssl/novalidate-cert/notls/norsh}'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] ASDevice data loaded for user: '****removed email****'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] TopCollector(): Initialized mutexid Resource id #124 and memid Resource id #125.
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] LoopDetection(): Initialized mutexid Resource id #127 and memid Resource id #128.
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] ZPush::HierarchyCommand(9): true
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] ZPush::CommandNeedsPlainInput(9): false
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] I  <FolderHierarchy:FolderSync>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] I   <FolderHierarchy:SyncKey>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] I    0
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] I   </FolderHierarchy:SyncKey>
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] ZPush::GetSupportedProtocolVersions(): 2.5,12.0,12.1,14.0
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] 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/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 0
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] I  </FolderHierarchy:FolderSync>
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] Initializing folder diff engine
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('INBOX') = 18b44911
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->convertImapId('INBOX') = 18b44911
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Junk') = 2ae9cd89
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Junk') = 2ae9cd89
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Sent') = c11daaac
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Sent') = c11daaac
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Trash') = f560ebcc
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Trash') = f560ebcc
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Drafts') = 85c34d63
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Drafts') = 85c34d63
12/01/2015 09:19:55 [ 8688] [ INFO] [****removed email****] ExportChangesDiff->InitializeExporter(): Found '5' changes
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: f560ebcc displayname: Trash
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: c11daaac displayname: Sent
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 85c34d63 displayname: Drafts
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 2ae9cd89 displayname: Junk
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 18b44911 displayname: INBOX
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O  <FolderHierarchy:FolderSync>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O   <FolderHierarchy:Status>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O   1
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O   </FolderHierarchy:Status>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O   <FolderHierarchy:SyncKey>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O   {c47a9e6b-397d-4b9a-ab14-0144b26968eb}1
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O   </FolderHierarchy:SyncKey>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O   <FolderHierarchy:Changes>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    <FolderHierarchy:Count>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    5
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    </FolderHierarchy:Count>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     f560ebcc
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     0
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     Trash
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     4
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     c11daaac
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     0
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     Sent
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     5
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     85c34d63
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     0
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     Drafts
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     3
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     2ae9cd89
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     0
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     Junk
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     12
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     18b44911
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     0
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     INBOX
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     12
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O   </FolderHierarchy:Changes>
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] StateManager::UnLinkState(''): saved state '8d5ede55-e166-40a6-8d31-2c9789dece47' will be deleted.
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-8d5ede55-e166-40a6-8d31-2c9789dece47-1'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-8d5ede55-e166-40a6-8d31-2c9789dece47-hc-1'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] StateManager::linkState(#ASDevice, 'c47a9e6b-397d-4b9a-ab14-0144b26968eb','HierarchyCache'): linked to uuid 'c47a9e6b-397d-4b9a-ab14-0144b26968eb'.
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 5
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] FileStateMachine->SetState() written 929 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-c47a9e6b-397d-4b9a-ab14-0144b26968eb-hc-1'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] FileStateMachine->SetState() written 390 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-c47a9e6b-397d-4b9a-ab14-0144b26968eb-1'
12/01/2015 09:19:55 [ 8688] [WBXML] [****removed email****] O  </FolderHierarchy:FolderSync>
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] WBXMLEncoder->endTag() WBXML output completed
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] BackendIMAP->Logoff(): IMAP connection closed
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] FileStateMachine->SetState() written 381 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] DeviceManager->Save(): Device data changed
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] FileStateMachine->SetState() written 792 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] DeviceManager->Save(): Device data saved
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] LoopDetection->ProcessLoopDetectionTerminate()
12/01/2015 09:19:55 [ 8688] [DEBUG] [****removed email****] -------- End
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] -------- Start
12/01/2015 09:19:56 [ 8684] [ INFO] [****removed email****] Version='SVN-trunk-r1931' method='POST' from='196.210.207.241' cmd='FolderSync' getUser='****removed email****' devId='0d767ed8cad94e609166cc143838862e' devType='WindowsOutlook'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] Used timezone 'UTC'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] Including backend file: '/var/www/z-push-contrib/backend/imap/imap.php'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] Request::ProcessHeaders() ASVersion: 14.0
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] ZPush::CommandNeedsAuthentication(9): true
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->Logon(): User '****removed email****' is authenticated on '{****removed serverl****:993/imap/ssl/novalidate-cert/notls/norsh}'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] ASDevice data loaded for user: '****removed email****'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] TopCollector(): Initialized mutexid Resource id #124 and memid Resource id #125.
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] LoopDetection(): Initialized mutexid Resource id #127 and memid Resource id #128.
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] ZPush::HierarchyCommand(9): true
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] ZPush::CommandNeedsPlainInput(9): false
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] I  <FolderHierarchy:FolderSync>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] I   <FolderHierarchy:SyncKey>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] I    0
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] I   </FolderHierarchy:SyncKey>
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] ZPush::GetSupportedProtocolVersions(): 2.5,12.0,12.1,14.0
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] 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/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 0
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] I  </FolderHierarchy:FolderSync>
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] Initializing folder diff engine
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('INBOX') = 18b44911
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->convertImapId('INBOX') = 18b44911
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Junk') = 2ae9cd89
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Junk') = 2ae9cd89
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Sent') = c11daaac
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Sent') = c11daaac
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Trash') = f560ebcc
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Trash') = f560ebcc
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Drafts') = 85c34d63
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Drafts') = 85c34d63
12/01/2015 09:19:56 [ 8684] [ INFO] [****removed email****] ExportChangesDiff->InitializeExporter(): Found '5' changes
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: f560ebcc displayname: Trash
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: c11daaac displayname: Sent
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 85c34d63 displayname: Drafts
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 2ae9cd89 displayname: Junk
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 18b44911 displayname: INBOX
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O  <FolderHierarchy:FolderSync>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O   <FolderHierarchy:Status>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O   1
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O   </FolderHierarchy:Status>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O   <FolderHierarchy:SyncKey>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O   {1be6e24e-db96-4b66-99fc-735df5b6c1c7}1
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O   </FolderHierarchy:SyncKey>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O   <FolderHierarchy:Changes>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    <FolderHierarchy:Count>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    5
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    </FolderHierarchy:Count>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     f560ebcc
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     0
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     Trash
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     4
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     c11daaac
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     0
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     Sent
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     5
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     85c34d63
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     0
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     Drafts
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     3
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     2ae9cd89
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     0
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     Junk
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     12
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     18b44911
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     0
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     INBOX
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     12
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O   </FolderHierarchy:Changes>
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] StateManager::UnLinkState(''): saved state 'c47a9e6b-397d-4b9a-ab14-0144b26968eb' will be deleted.
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-c47a9e6b-397d-4b9a-ab14-0144b26968eb-1'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-c47a9e6b-397d-4b9a-ab14-0144b26968eb-hc-1'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] StateManager::linkState(#ASDevice, '1be6e24e-db96-4b66-99fc-735df5b6c1c7','HierarchyCache'): linked to uuid '1be6e24e-db96-4b66-99fc-735df5b6c1c7'.
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 5
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] FileStateMachine->SetState() written 929 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-1be6e24e-db96-4b66-99fc-735df5b6c1c7-hc-1'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] FileStateMachine->SetState() written 390 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-1be6e24e-db96-4b66-99fc-735df5b6c1c7-1'
12/01/2015 09:19:56 [ 8684] [WBXML] [****removed email****] O  </FolderHierarchy:FolderSync>
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] WBXMLEncoder->endTag() WBXML output completed
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] BackendIMAP->Logoff(): IMAP connection closed
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] FileStateMachine->SetState() written 381 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] DeviceManager->Save(): Device data changed
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] FileStateMachine->SetState() written 792 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] DeviceManager->Save(): Device data saved
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] LoopDetection->ProcessLoopDetectionTerminate()
12/01/2015 09:19:56 [ 8684] [DEBUG] [****removed email****] -------- End
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] -------- Start
12/01/2015 09:19:56 [ 8685] [ INFO] [****removed email****] Version='SVN-trunk-r1931' method='POST' from='196.210.207.241' cmd='FolderSync' getUser='****removed email****' devId='0d767ed8cad94e609166cc143838862e' devType='WindowsOutlook'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] Used timezone 'UTC'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] Including backend file: '/var/www/z-push-contrib/backend/imap/imap.php'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] Request::ProcessHeaders() ASVersion: 14.0
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] ZPush::CommandNeedsAuthentication(9): true
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->Logon(): User '****removed email****' is authenticated on '{****removed serverl****:993/imap/ssl/novalidate-cert/notls/norsh}'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] ASDevice data loaded for user: '****removed email****'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] TopCollector(): Initialized mutexid Resource id #124 and memid Resource id #125.
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] LoopDetection(): Initialized mutexid Resource id #127 and memid Resource id #128.
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] ZPush::HierarchyCommand(9): true
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] ZPush::CommandNeedsPlainInput(9): false
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] I  <FolderHierarchy:FolderSync>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] I   <FolderHierarchy:SyncKey>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] I    0
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] I   </FolderHierarchy:SyncKey>
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] ZPush::GetSupportedProtocolVersions(): 2.5,12.0,12.1,14.0
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] 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/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 0
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] I  </FolderHierarchy:FolderSync>
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] Initializing folder diff engine
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('INBOX') = 18b44911
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->convertImapId('INBOX') = 18b44911
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Junk') = 2ae9cd89
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Junk') = 2ae9cd89
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Sent') = c11daaac
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Sent') = c11daaac
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Trash') = f560ebcc
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Trash') = f560ebcc
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Drafts') = 85c34d63
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Drafts') = 85c34d63
12/01/2015 09:19:56 [ 8685] [ INFO] [****removed email****] ExportChangesDiff->InitializeExporter(): Found '5' changes
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: f560ebcc displayname: Trash
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: c11daaac displayname: Sent
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 85c34d63 displayname: Drafts
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 2ae9cd89 displayname: Junk
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 18b44911 displayname: INBOX
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O  <FolderHierarchy:FolderSync>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O   <FolderHierarchy:Status>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O   1
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O   </FolderHierarchy:Status>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O   <FolderHierarchy:SyncKey>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O   {d753ff41-6915-4aa1-91e4-4b192f82ef07}1
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O   </FolderHierarchy:SyncKey>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O   <FolderHierarchy:Changes>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    <FolderHierarchy:Count>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    5
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    </FolderHierarchy:Count>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     f560ebcc
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     0
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     Trash
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     4
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     c11daaac
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     0
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     Sent
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     5
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     85c34d63
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     0
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     Drafts
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     3
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     2ae9cd89
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     0
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     Junk
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     12
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     18b44911
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     0
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     INBOX
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     12
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O   </FolderHierarchy:Changes>
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] StateManager::UnLinkState(''): saved state '1be6e24e-db96-4b66-99fc-735df5b6c1c7' will be deleted.
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-1be6e24e-db96-4b66-99fc-735df5b6c1c7-1'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-1be6e24e-db96-4b66-99fc-735df5b6c1c7-hc-1'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] StateManager::linkState(#ASDevice, 'd753ff41-6915-4aa1-91e4-4b192f82ef07','HierarchyCache'): linked to uuid 'd753ff41-6915-4aa1-91e4-4b192f82ef07'.
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 5
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] FileStateMachine->SetState() written 929 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-d753ff41-6915-4aa1-91e4-4b192f82ef07-hc-1'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] FileStateMachine->SetState() written 390 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-d753ff41-6915-4aa1-91e4-4b192f82ef07-1'
12/01/2015 09:19:56 [ 8685] [WBXML] [****removed email****] O  </FolderHierarchy:FolderSync>
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] WBXMLEncoder->endTag() WBXML output completed
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] BackendIMAP->Logoff(): IMAP connection closed
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] FileStateMachine->SetState() written 381 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] DeviceManager->Save(): Device data changed
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] FileStateMachine->SetState() written 792 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] DeviceManager->Save(): Device data saved
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] LoopDetection->ProcessLoopDetectionTerminate()
12/01/2015 09:19:56 [ 8685] [DEBUG] [****removed email****] -------- End
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] -------- Start
12/01/2015 09:19:57 [ 8695] [ INFO] [****removed email****] Version='SVN-trunk-r1931' method='POST' from='196.210.207.241' cmd='FolderSync' getUser='****removed email****' devId='0d767ed8cad94e609166cc143838862e' devType='WindowsOutlook'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] Used timezone 'UTC'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] Including backend file: '/var/www/z-push-contrib/backend/imap/imap.php'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] Request::ProcessHeaders() ASVersion: 14.0
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] ZPush::CommandNeedsAuthentication(9): true
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->Logon(): User '****removed email****' is authenticated on '{****removed serverl****:993/imap/ssl/novalidate-cert/notls/norsh}'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] ASDevice data loaded for user: '****removed email****'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] TopCollector(): Initialized mutexid Resource id #124 and memid Resource id #125.
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] LoopDetection(): Initialized mutexid Resource id #127 and memid Resource id #128.
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] ZPush::HierarchyCommand(9): true
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] ZPush::CommandNeedsPlainInput(9): false
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] I  <FolderHierarchy:FolderSync>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] I   <FolderHierarchy:SyncKey>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] I    0
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] I   </FolderHierarchy:SyncKey>
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] ZPush::GetSupportedProtocolVersions(): 2.5,12.0,12.1,14.0
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] 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/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 0
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] I  </FolderHierarchy:FolderSync>
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] Initializing folder diff engine
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('INBOX') = 18b44911
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->convertImapId('INBOX') = 18b44911
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Junk') = 2ae9cd89
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Junk') = 2ae9cd89
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Sent') = c11daaac
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Sent') = c11daaac
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Trash') = f560ebcc
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Trash') = f560ebcc
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Drafts') = 85c34d63
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Drafts') = 85c34d63
12/01/2015 09:19:57 [ 8695] [ INFO] [****removed email****] ExportChangesDiff->InitializeExporter(): Found '5' changes
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: f560ebcc displayname: Trash
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: c11daaac displayname: Sent
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 85c34d63 displayname: Drafts
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 2ae9cd89 displayname: Junk
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 18b44911 displayname: INBOX
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O  <FolderHierarchy:FolderSync>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O   <FolderHierarchy:Status>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O   1
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O   </FolderHierarchy:Status>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O   <FolderHierarchy:SyncKey>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O   {a77551f1-fac4-4112-b6f0-453883a9f480}1
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O   </FolderHierarchy:SyncKey>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O   <FolderHierarchy:Changes>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    <FolderHierarchy:Count>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    5
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    </FolderHierarchy:Count>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     f560ebcc
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     0
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     Trash
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     4
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     c11daaac
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     0
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     Sent
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     5
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     85c34d63
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     0
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     Drafts
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     3
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     2ae9cd89
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     0
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     Junk
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     12
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     18b44911
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     0
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     INBOX
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     12
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O   </FolderHierarchy:Changes>
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] StateManager::UnLinkState(''): saved state 'd753ff41-6915-4aa1-91e4-4b192f82ef07' will be deleted.
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-d753ff41-6915-4aa1-91e4-4b192f82ef07-1'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-d753ff41-6915-4aa1-91e4-4b192f82ef07-hc-1'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] StateManager::linkState(#ASDevice, 'a77551f1-fac4-4112-b6f0-453883a9f480','HierarchyCache'): linked to uuid 'a77551f1-fac4-4112-b6f0-453883a9f480'.
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 5
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] FileStateMachine->SetState() written 929 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-a77551f1-fac4-4112-b6f0-453883a9f480-hc-1'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] FileStateMachine->SetState() written 390 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-a77551f1-fac4-4112-b6f0-453883a9f480-1'
12/01/2015 09:19:57 [ 8695] [WBXML] [****removed email****] O  </FolderHierarchy:FolderSync>
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] WBXMLEncoder->endTag() WBXML output completed
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] BackendIMAP->Logoff(): IMAP connection closed
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] FileStateMachine->SetState() written 381 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] DeviceManager->Save(): Device data changed
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] FileStateMachine->SetState() written 792 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] DeviceManager->Save(): Device data saved
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] LoopDetection->ProcessLoopDetectionTerminate()
12/01/2015 09:19:57 [ 8695] [DEBUG] [****removed email****] -------- End
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] -------- Start
12/01/2015 09:19:57 [ 8686] [ INFO] [****removed email****] Version='SVN-trunk-r1931' method='POST' from='196.210.207.241' cmd='FolderSync' getUser='****removed email****' devId='0d767ed8cad94e609166cc143838862e' devType='WindowsOutlook'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] Used timezone 'UTC'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] Including backend file: '/var/www/z-push-contrib/backend/imap/imap.php'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] Request::ProcessHeaders() ASVersion: 14.0
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] ZPush::CommandNeedsAuthentication(9): true
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->Logon(): User '****removed email****' is authenticated on '{****removed serverl****:993/imap/ssl/novalidate-cert/notls/norsh}'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] ASDevice data loaded for user: '****removed email****'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] TopCollector(): Initialized mutexid Resource id #124 and memid Resource id #125.
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] LoopDetection(): Initialized mutexid Resource id #127 and memid Resource id #128.
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] ZPush::HierarchyCommand(9): true
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] ZPush::CommandNeedsPlainInput(9): false
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] I  <FolderHierarchy:FolderSync>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] I   <FolderHierarchy:SyncKey>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] I    0
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] I   </FolderHierarchy:SyncKey>
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] ZPush::GetSupportedProtocolVersions(): 2.5,12.0,12.1,14.0
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] 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/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 0
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] I  </FolderHierarchy:FolderSync>
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] Initializing folder diff engine
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('INBOX') = 18b44911
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->convertImapId('INBOX') = 18b44911
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Junk') = 2ae9cd89
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Junk') = 2ae9cd89
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Sent') = c11daaac
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Sent') = c11daaac
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Trash') = f560ebcc
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Trash') = f560ebcc
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Drafts') = 85c34d63
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Drafts') = 85c34d63
12/01/2015 09:19:57 [ 8686] [ INFO] [****removed email****] ExportChangesDiff->InitializeExporter(): Found '5' changes
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: f560ebcc displayname: Trash
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: c11daaac displayname: Sent
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 85c34d63 displayname: Drafts
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 2ae9cd89 displayname: Junk
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 18b44911 displayname: INBOX
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O  <FolderHierarchy:FolderSync>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O   <FolderHierarchy:Status>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O   1
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O   </FolderHierarchy:Status>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O   <FolderHierarchy:SyncKey>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O   {b2a7ae56-39eb-4bdc-81ea-11e6d3402d56}1
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O   </FolderHierarchy:SyncKey>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O   <FolderHierarchy:Changes>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    <FolderHierarchy:Count>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    5
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    </FolderHierarchy:Count>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     f560ebcc
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     0
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     Trash
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     4
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     c11daaac
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     0
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     Sent
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     5
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     85c34d63
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     0
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     Drafts
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     3
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     2ae9cd89
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     0
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     Junk
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     12
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     18b44911
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     0
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     INBOX
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     12
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O   </FolderHierarchy:Changes>
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] StateManager::UnLinkState(''): saved state 'a77551f1-fac4-4112-b6f0-453883a9f480' will be deleted.
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-a77551f1-fac4-4112-b6f0-453883a9f480-1'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-a77551f1-fac4-4112-b6f0-453883a9f480-hc-1'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] StateManager::linkState(#ASDevice, 'b2a7ae56-39eb-4bdc-81ea-11e6d3402d56','HierarchyCache'): linked to uuid 'b2a7ae56-39eb-4bdc-81ea-11e6d3402d56'.
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 5
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] FileStateMachine->SetState() written 929 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-b2a7ae56-39eb-4bdc-81ea-11e6d3402d56-hc-1'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] FileStateMachine->SetState() written 390 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-b2a7ae56-39eb-4bdc-81ea-11e6d3402d56-1'
12/01/2015 09:19:57 [ 8686] [WBXML] [****removed email****] O  </FolderHierarchy:FolderSync>
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] WBXMLEncoder->endTag() WBXML output completed
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] BackendIMAP->Logoff(): IMAP connection closed
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] FileStateMachine->SetState() written 381 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] DeviceManager->Save(): Device data changed
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] FileStateMachine->SetState() written 792 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] DeviceManager->Save(): Device data saved
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] LoopDetection->ProcessLoopDetectionTerminate()
12/01/2015 09:19:57 [ 8686] [DEBUG] [****removed email****] -------- End
12/01/2015 09:19:57 [ 8687] [DEBUG] [****removed email****] -------- Start
12/01/2015 09:19:57 [ 8687] [ INFO] [****removed email****] Version='SVN-trunk-r1931' method='POST' from='196.210.207.241' cmd='FolderSync' getUser='****removed email****' devId='0d767ed8cad94e609166cc143838862e' devType='WindowsOutlook'
12/01/2015 09:19:57 [ 8687] [DEBUG] [****removed email****] Used timezone 'UTC'
12/01/2015 09:19:57 [ 8687] [DEBUG] [****removed email****] Including backend file: '/var/www/z-push-contrib/backend/imap/imap.php'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] Request::ProcessHeaders() ASVersion: 14.0
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] ZPush::CommandNeedsAuthentication(9): true
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->Logon(): User '****removed email****' is authenticated on '{****removed serverl****:993/imap/ssl/novalidate-cert/notls/norsh}'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] ASDevice data loaded for user: '****removed email****'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] TopCollector(): Initialized mutexid Resource id #124 and memid Resource id #125.
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] LoopDetection(): Initialized mutexid Resource id #127 and memid Resource id #128.
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] ZPush::HierarchyCommand(9): true
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] ZPush::CommandNeedsPlainInput(9): false
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] I  <FolderHierarchy:FolderSync>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] I   <FolderHierarchy:SyncKey>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] I    0
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] I   </FolderHierarchy:SyncKey>
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] ZPush::GetSupportedProtocolVersions(): 2.5,12.0,12.1,14.0
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] 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/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 0
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] I  </FolderHierarchy:FolderSync>
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] Initializing folder diff engine
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('INBOX') = 18b44911
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->convertImapId('INBOX') = 18b44911
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Junk') = 2ae9cd89
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Junk') = 2ae9cd89
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Sent') = c11daaac
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Sent') = c11daaac
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Trash') = f560ebcc
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Trash') = f560ebcc
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Drafts') = 85c34d63
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Drafts') = 85c34d63
12/01/2015 09:19:58 [ 8687] [ INFO] [****removed email****] ExportChangesDiff->InitializeExporter(): Found '5' changes
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: f560ebcc displayname: Trash
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: c11daaac displayname: Sent
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 85c34d63 displayname: Drafts
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 2ae9cd89 displayname: Junk
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 18b44911 displayname: INBOX
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O  <FolderHierarchy:FolderSync>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O   <FolderHierarchy:Status>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O   1
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O   </FolderHierarchy:Status>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O   <FolderHierarchy:SyncKey>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O   {eac57626-58a7-4236-ab69-569834e268c4}1
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O   </FolderHierarchy:SyncKey>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O   <FolderHierarchy:Changes>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    <FolderHierarchy:Count>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    5
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    </FolderHierarchy:Count>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     f560ebcc
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     0
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     Trash
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     4
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     c11daaac
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     0
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     Sent
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     5
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     85c34d63
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     0
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     Drafts
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     3
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     2ae9cd89
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     0
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     Junk
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     12
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    <FolderHierarchy:Add>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:ServerEntryId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     18b44911
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:ServerEntryId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:ParentId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     0
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:ParentId>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:DisplayName>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     INBOX
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:DisplayName>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     <FolderHierarchy:Type>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     12
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O     </FolderHierarchy:Type>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O    </FolderHierarchy:Add>
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O   </FolderHierarchy:Changes>
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] StateManager::UnLinkState(''): saved state 'b2a7ae56-39eb-4bdc-81ea-11e6d3402d56' will be deleted.
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-b2a7ae56-39eb-4bdc-81ea-11e6d3402d56-1'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] FileStateMachine->CleanStates(): Deleting file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-b2a7ae56-39eb-4bdc-81ea-11e6d3402d56-hc-1'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] StateManager::linkState(#ASDevice, 'eac57626-58a7-4236-ab69-569834e268c4','HierarchyCache'): linked to uuid 'eac57626-58a7-4236-ab69-569834e268c4'.
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 5
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] FileStateMachine->SetState() written 929 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-eac57626-58a7-4236-ab69-569834e268c4-hc-1'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] FileStateMachine->SetState() written 390 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-eac57626-58a7-4236-ab69-569834e268c4-1'
12/01/2015 09:19:58 [ 8687] [WBXML] [****removed email****] O  </FolderHierarchy:FolderSync>
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] WBXMLEncoder->endTag() WBXML output completed
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] BackendIMAP->Logoff(): IMAP connection closed
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] FileStateMachine->SetState() written 381 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] DeviceManager->Save(): Device data changed
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] FileStateMachine->SetState() written 792 bytes on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] DeviceManager->Save(): Device data saved
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] LoopDetection->ProcessLoopDetectionTerminate()
12/01/2015 09:19:58 [ 8687] [DEBUG] [****removed email****] -------- End
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] -------- Start
12/01/2015 09:19:58 [ 8688] [ INFO] [****removed email****] Version='SVN-trunk-r1931' method='POST' from='196.210.207.241' cmd='FolderSync' getUser='****removed email****' devId='0d767ed8cad94e609166cc143838862e' devType='WindowsOutlook'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] Used timezone 'UTC'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] Including backend file: '/var/www/z-push-contrib/backend/imap/imap.php'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] Request::ProcessHeaders() ASVersion: 14.0
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] ZPush::CommandNeedsAuthentication(9): true
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->Logon(): User '****removed email****' is authenticated on '{****removed serverl****:993/imap/ssl/novalidate-cert/notls/norsh}'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-devicedata'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] ASDevice data loaded for user: '****removed email****'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] TopCollector(): Initialized mutexid Resource id #124 and memid Resource id #125.
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] LoopDetection(): Initialized mutexid Resource id #127 and memid Resource id #128.
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] ZPush::HierarchyCommand(9): true
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] ZPush::CommandNeedsPlainInput(9): false
12/01/2015 09:19:58 [ 8688] [WBXML] [****removed email****] I  <FolderHierarchy:FolderSync>
12/01/2015 09:19:58 [ 8688] [WBXML] [****removed email****] I   <FolderHierarchy:SyncKey>
12/01/2015 09:19:58 [ 8688] [WBXML] [****removed email****] I    0
12/01/2015 09:19:58 [ 8688] [WBXML] [****removed email****] I   </FolderHierarchy:SyncKey>
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] ZPush::GetSupportedProtocolVersions(): 2.5,12.0,12.1,14.0
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] 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/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 0
12/01/2015 09:19:58 [ 8688] [WBXML] [****removed email****] I  </FolderHierarchy:FolderSync>
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] Initializing folder diff engine
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] FileStateMachine->GetState() on file: '/var/www/z-push-contrib/private/e/2/0d767ed8cad94e609166cc143838862e-bs-1421016398'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('INBOX') = 18b44911
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->convertImapId('INBOX') = 18b44911
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Junk') = 2ae9cd89
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Junk') = 2ae9cd89
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Sent') = c11daaac
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Sent') = c11daaac
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Trash') = f560ebcc
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Trash') = f560ebcc
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getFolderIdFromImapId('Drafts') = 85c34d63
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->convertImapId('Drafts') = 85c34d63
12/01/2015 09:19:58 [ 8688] [ INFO] [****removed email****] ExportChangesDiff->InitializeExporter(): Found '5' changes
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('f560ebcc') = Trash
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('f560ebcc'): 'SyncFolder (
    (S) serverid => f560ebcc
    (S) parentid => 0
    (S) displayname => Trash
    (S) type => 4
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: f560ebcc displayname: Trash
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('c11daaac') = Sent
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('c11daaac'): 'SyncFolder (
    (S) serverid => c11daaac
    (S) parentid => 0
    (S) displayname => Sent
    (S) type => 5
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: c11daaac displayname: Sent
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('85c34d63') = Drafts
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('85c34d63'): 'SyncFolder (
    (S) serverid => 85c34d63
    (S) parentid => 0
    (S) displayname => Drafts
    (S) type => 3
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 85c34d63 displayname: Drafts
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('2ae9cd89') = Junk
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('2ae9cd89'): 'SyncFolder (
    (S) serverid => 2ae9cd89
    (S) parentid => 0
    (S) displayname => Junk
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] HierarchyCache: AddFolder() serverid: 2ae9cd89 displayname: Junk
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->getImapIdFromFolderId('18b44911') = INBOX
12/01/2015 09:19:58 [ 8688] [DEBUG] [****removed email****] BackendIMAP->GetFolder('18b44911'): 'SyncFolder (
    (S) serverid => 18b44911
    (S) parentid => 0
    (S) displayname => INBOX
    (S) type => 12
    (S) Store => null
    unsetVars(Array) size: 0
    flags => false
    content => null
)'
extremeshok commented 9 years ago

Are there any issues with the above log ?

If not then there is there bug or compatibility issue with z-push ?

fmbiete commented 9 years ago

Your log doesn't show any error, but the client is sending SyncFolder operation and not acknowledging the response. So maybe it's a compatibility issue. What's your client/mobile?

extremeshok commented 9 years ago

Tried with iOS 8 (ipad) and Outlook 2013 (windows 7)

Compatibility with the imap server ?

fmbiete commented 9 years ago

No, with the client. I have a list of problems for iOS 8, and Outlook 2013. When I have time I will put them in the wiki. If only Apple would offer an emulator for non-mac users...