[sync] ActiveSync error after 5.2 upgrade

[Ivan Kouvykov]

Hello, 

After upgrade to 5.2 all devices stopped syncing via ActiveSync, wit the
follwing error: 

2014-07-16T16:09:01+04:00 ERR: HORDE SQL QUERY FAILED: SQLSTATE[42S22]:
Column not found: 1054 Unknown column 'sync_changed' in 'field list'
 SELECT message_uid, sync_read, sync_flagged, sync_deleted,sync_changed
 FROM horde_activesync_mailmap WHERE sync_folderid = 'INBOX' AND
 sync_devid = 'SEC1EAAE5574652F' AND sync_user = 'mikhail' AND
 message_uid IN (526,527,528,529,530,531,525,486,487,488,489,490,491)
[pid 28746 on line 204 of
"/volume1/@appstore/horde/share/pear/php/Horde/Db/Adapter/Pdo/Base.php"]
2014-07-16T16:09:01+04:00 ERR: HORDE Returning HTTP 500 while handling
Sync command. [pid 28746 on line 159 of "/volume1/web/horde/rpc.php"]
2014-07-16T16:09:01+04:00 ERR: HORDE Error in communicating with
ActiveSync server: SQLSTATE[42S22]: Column not found: 1054 Unknown
column 'sync_changed' in 'field list' [pid 28746 on line 162 of
"/volume1/@appstore/horde/share/pear/php/Horde/Rpc/ActiveSync.php"]
2014-07-16T16:09:01+04:00 ERR: HORDE #0
/volume1/@appstore/horde/share/pear/php/Horde/ActiveSync/State/Base.php(456):
Horde_ActiveSync_State_Sql->_getMailMapChanges(Array)
#1
/volume1/@appstore/horde/share/pear/php/Horde/ActiveSync/Collections.php(1223):
Horde_ActiveSync_State_Base->getChanges(Array)
#2
/volume1/@appstore/horde/share/pear/php/Horde/ActiveSync/Collections.php(1259):
Horde_ActiveSync_Collections->getCollectionChanges(false)
#3
/volume1/@appstore/horde/share/pear/php/Horde/ActiveSync/Request/Sync.php(400):
Horde_ActiveSync_Collections->getCollectionChangeCount()
#4
/volume1/@appstore/horde/share/pear/php/Horde/ActiveSync/Request/Base.php(253):
Horde_ActiveSync_Request_Sync->_handle()
#5 /volume1/@appstore/horde/share/pear/php/Horde/ActiveSync.php(880):
Horde_ActiveSync_Request_Base->handle()
#6
/volume1/@appstore/horde/share/pear/php/Horde/Rpc/ActiveSync.php(143):
Horde_ActiveSync->handleRequest('Sync', 'SEC1EAAE5574652...')
#7 /volume1/web/horde/rpc.php(159):
Horde_Rpc_ActiveSync->getResponse(NULL)
#8 {main} [pid 28746 on line 162 of
"/volume1/@appstore/horde/share/pear/php/Horde/Rpc/ActiveSync.php"]
2014-07-16T16:09:01+04:00 ERR: HORDE Buffer contents: jEN1 [pid 28746 on
line 162 of
"/volume1/@appstore/horde/share/pear/php/Horde/Rpc/ActiveSync.php"]
2014-07-16T16:09:02+04:00 ERR: HORDE SQL QUERY FAILED: SQLSTATE[42S22]:
Column not found: 1054 Unknown column 'sync_changed' in 'field list'
 SELECT message_uid, sync_read, sync_flagged, sync_deleted,sync_changed
 FROM horde_activesync_mailmap WHERE sync_folderid = 'INBOX' AND
 sync_devid = 'E254E6E555E36ABE04BBDCCB20C2B62D' AND sync_user = 'ivan'
 AND message_uid IN
 (2394,2395,2396,2397,2398,2399,2400,2393,2260,2386,2387,2388,2389) [pid
25447 on line 204 of
"/volume1/@appstore/horde/share/pear/php/Horde/Db/Adapter/Pdo/Base.php"]
2014-07-16T16:09:02+04:00 ERR: HORDE Returning HTTP 500 while handling
Sync command. [pid 25447 on line 159 of "/volume1/web/horde/rpc.php"]
2014-07-16T16:09:02+04:00 ERR: HORDE Error in communicating with
ActiveSync server: SQLSTATE[42S22]: Column not found: 1054 Unknown
column 'sync_changed' in 'field list' [pid 25447 on line 162 of
"/volume1/@appstore/horde/share/pear/php/Horde/Rpc/ActiveSync.php"]
2014-07-16T16:09:02+04:00 ERR: HORDE #0
/volume1/@appstore/horde/share/pear/php/Horde/ActiveSync/State/Base.php(456):
Horde_ActiveSync_State_Sql->_getMailMapChanges(Array)
#1
/volume1/@appstore/horde/share/pear/php/Horde/ActiveSync/Collections.php(1223):
Horde_ActiveSync_State_Base->getChanges(Array)
#2
/volume1/@appstore/horde/share/pear/php/Horde/ActiveSync/Collections.php(1259):
Horde_ActiveSync_Collections->getCollectionChanges(false)
#3
/volume1/@appstore/horde/share/pear/php/Horde/ActiveSync/Request/Sync.php(400):
Horde_ActiveSync_Collections->getCollectionChangeCount()
#4
/volume1/@appstore/horde/share/pear/php/Horde/ActiveSync/Request/Base.php(253):
Horde_ActiveSync_Request_Sync->_handle()
#5 /volume1/@appstore/horde/share/pear/php/Horde/ActiveSync.php(880):
Horde_ActiveSync_Request_Base->handle()
#6
/volume1/@appstore/horde/share/pear/php/Horde/Rpc/ActiveSync.php(143):
Horde_ActiveSync->handleRequest('Sync', 'e254e6e555e36ab...')
#7 /volume1/web/horde/rpc.php(159):
Horde_Rpc_ActiveSync->getResponse(NULL)
#8 {main} [pid 25447 on line 162 of
"/volume1/@appstore/horde/share/pear/php/Horde/Rpc/ActiveSync.php"]
2014-07-16T16:09:02+04:00 ERR: HORDE Buffer contents:
jEN1OK{5297200f-81ec-4e06-8edd-43820afa0afa}70R at Contacts@N1 [pid 25447
on line 162 of
"/volume1/@appstore/horde/share/pear/php/Horde/Rpc/ActiveSync.php"] 

Seems that db schema migration was unsuccessful, but Configuration menu
states that all schemas are up to date (but "ActiveSync" not present in
application list, only main Horde applications are listed)

-- 
С уважением, / Kindest regards,
Иван Кувыков / Ivan Kouvykov

ivan at kouvykov.com