[horde] after Update ActiveSync error Did not initialize the PARTIAL sync.
Steffen
skhorde at smail.inf.fh-bonn-rhein-sieg.de
Fri Feb 27 14:49:38 UTC 2015
I have upgrade Horde from
Horde_ActiveSync 2.16.5 stable
webmail 5.1.4 stable
to
webmail 5.2.5 stable
Horde_ActiveSync 2.26.0 stable
The configuration says that all databases are updated.
One (the only one that connected after the upgrade) Windows Phone produces
the log below once a minute for nearly 6 houres now.
Shall I remove the device on the /horde/admin/activesync.php page or
would this make the problem worse?
syslog:
Feb 27 15:22:57 mailsrv1 HORDE: Returning HTTP 500 while handling Sync command. [pid 8033 on line 158 of "/var/www/horde/rpc.php"]
Feb 27 15:22:57 mailsrv1 HORDE: Error in communicating with ActiveSync server: Did not initialize the PARTIAL sync. [pid 8033 on line 162 of "/usr/share/php/Horde/Rpc/ActiveSync.php"]
Feb 27 15:22:57 mailsrv1 HORDE: #0 /usr/share/php/Horde/ActiveSync/Request/Sync.php(242): Horde_ActiveSync_Collections->getMissingCollectionsFromCache()#012#1 /usr/share/php/Horde/ActiveSync/Request/Base.php(253): Horde_ActiveSync_Request_Sync->_handle()#012#2 /usr/share/php/Horde/ActiveSync.php(882): Horde_ActiveSync_Request_Base->handle()#012#3 /usr/share/php/Horde/Rpc/ActiveSync.php(143): Horde_ActiveSync->handleRequest('Sync', '301087ecd667e65...')#012#4 /var/www/horde/rpc.php(158): Horde_Rpc_ActiveSync->getResponse(NULL)#012#5 {main} [pid 8033 on line 162 of "/usr/share/php/Horde/Rpc/ActiveSync.php"]
Feb 27 15:22:57 mailsrv1 HORDE: Buffer contents: [pid 8033 on line 162 of "/usr/share/php/Horde/Rpc/ActiveSync.php"]
ActiveSync log:
2015-02-27T15:36:53+01:00 INFO: [10145] Maximum memory usage for ActiveSync request: 16018360 bytes.
2015-02-27T15:36:53+01:00 INFO: [10145] Horde_Core_ActiveSync_Driver::authenticate() attempt for THEUSER
2015-02-27T15:36:53+01:00 INFO: [10145] SYNC request received for user THEUSER
2015-02-27T15:36:53+01:00 INFO: [10145] Device entry exists for THEDEVICEID, updating userAgent and version.
2015-02-27T15:36:53+01:00 INFO: [10145] Request being handled for device: THEDEVICEID, Supporting protocol version: 14.1, Using Horde_ActiveSync v2.26.0
2015-02-27T15:36:53+01:00 INFO: [10145] GET VARIABLES: Array
(
[ProtVer] => 14.1
[Cmd] => Sync
[Locale] => 1031
[DeviceId] => THEDEVICEID
[PolicyKey] => 0
[DeviceType] => WindowsPhone
)
2015-02-27T15:36:53+01:00 INFO: [10145] Handling SYNC command.
2015-02-27T15:36:53+01:00 INFO: [10145] Checking policykey for device: THEDEVICEID user: THEUSER
2015-02-27T15:36:53+01:00 INFO: [10145] Policykey: 0 verified.
2015-02-27T15:36:53+01:00 DEBUG: [10145] I <Synchronize>
2015-02-27T15:36:53+01:00 DEBUG: [10145] I <HeartbeatInterval>
2015-02-27T15:36:53+01:00 DEBUG: [10145] I 1500
2015-02-27T15:36:53+01:00 DEBUG: [10145] I </HeartbeatInterval>
2015-02-27T15:36:53+01:00 DEBUG: [10145] I <Partial />
2015-02-27T15:36:53+01:00 DEBUG: [10145] I </Synchronize>
2015-02-27T15:36:53+01:00 INFO: [10145] Executing a PARTIAL SYNC.
2015-02-27T15:36:53+01:00 ERR: No collections in collection handler, loading full collection set from cache.
2015-02-27T15:36:53+01:00 INFO: [10145] Loaded @Contacts@ from the cache.
2015-02-27T15:36:53+01:00 INFO: [10145] Loaded INBOX from the cache.
2015-02-27T15:36:53+01:00 INFO: [10145] Loaded @Calendar@ from the cache.
2015-02-27T15:36:53+01:00 INFO: [10145] Loaded @Tasks@ from the cache.
2015-02-27T15:36:53+01:00 INFO: [10145] Loaded Sent from the cache.
2015-02-27T15:36:53+01:00 INFO: [10145] Loaded MAIL1 from the cache.
2015-02-27T15:36:53+01:00 INFO: [10145] Loaded MAIL2 from the cache.
2015-02-27T15:36:53+01:00 INFO: [10145] Loaded MAIL3 from the cache.
2015-02-27T15:36:53+01:00 ERR: Did not initialize the PARTIAL sync.
--
Steffen
More information about the horde
mailing list