[sync] EAS "unmatched end tag"

[Michael J Rubinsky]

Quoting lst_hoe02 at kwsoft.de:

> Hello,
>
> today we noticed a problem with a EAS (Samsung Device)
>
> 2014-11-07T14:06:28+01:00 INFO: [3469] Sleeping for 29 seconds.
> 2014-11-07T14:06:50+01:00 INFO: [3673]  
> Horde_Core_ActiveSync_Driver::authenticate() attempt for xxx
> 2014-11-07T14:06:50+01:00 INFO: [3673] SYNC request received for user xxx
> 2014-11-07T14:06:50+01:00 INFO: [3673] Device entry exists for  
> SEC198748EC975FF, updating userAgent and version.
> 2014-11-07T14:06:50+01:00 INFO: [3673] Request being handled for  
> device: SEC198748EC975FF, Supporting protocol version: 14.1, Using  
> Horde_ActiveSync v2.20.0
> 2014-11-07T14:06:50+01:00 INFO: [3673] GET VARIABLES: Array
> (
>     [Cmd] => Sync
>     [User] => xxx
>     [DeviceId] => SEC198748EC975FF
>     [DeviceType] => SAMSUNGGTI8190N
> )
>
> 2014-11-07T14:06:50+01:00 INFO: [3673] Handling SYNC command.
> 2014-11-07T14:06:50+01:00 INFO: [3673] Checking policykey for  
> device: SEC198748EC975FF user: xxx
> 2014-11-07T14:06:50+01:00 INFO: [3673] Policykey: 0 verified.
> 2014-11-07T14:06:50+01:00 DEBUG: [3673] I  <Synchronize>
> 2014-11-07T14:06:50+01:00 DEBUG: [3673] I   <Folders>
> 2014-11-07T14:06:50+01:00 DEBUG: [3673] I    <Folder>
> 2014-11-07T14:06:50+01:00 DEBUG: [3673] I     <SyncKey>
> 2014-11-07T14:06:50+01:00 DEBUG: [3673] I       0
> 2014-11-07T14:06:50+01:00 DEBUG: [3673] I     </SyncKey>
> 2014-11-07T14:06:50+01:00 DEBUG: [3673] I     <FolderId>
> 2014-11-07T14:06:50+01:00 DEBUG: [3673] I       




> 2014-11-07T14:06:50+01:00 ERR: [3673] Unmatched end tag:
> 2014-11-07T14:06:50+01:00 ERR:
> 2014-11-07T14:06:50+01:00 ERR: Protocol error
> 2014-11-07T14:06:57+01:00 INFO: [3469] SyncCache collections refreshed.
> 2014-11-07T14:06:57+01:00 INFO: [3469] Refreshing Aaf37e20f from the cache.
> 2014-11-07T14:06:57+01:00 INFO: [3469] Refreshing Ca12d33c8 from the cache.
> 2014-11-07T14:06:57+01:00 INFO: [3469] Refreshing T12e4932e from the cache.
>
> and
>
> Nov  7 14:06:40 h2118808 HORDE: Allowed memory size of 134217728  
> bytes exhausted (tried to allocate 31457280 bytes) [pid 3744 on line  
> 348 of "/usr/share/php/Horde/ActiveSync/Wbxml/Decoder.php"]
> Nov  7 14:06:50 h2118808 HORDE: Returning HTTP 500 while handling  
> Sync command. [pid 3673 on line 159 of "/var/www/horde/rpc.php"]
> Nov  7 14:06:50 h2118808 HORDE: Error in communicating with  
> ActiveSync server: Protocol error [pid 3673 on line 162 of  
> "/usr/share/php/Horde/Rpc/ActiveSync.php"]
> Nov  7 14:06:50 h2118808 HORDE: #0  
> /usr/share/php/Horde/ActiveSync/Request/Sync.php(209):  
> Horde_ActiveSync_Request_Sync->_parseSyncFolders()
> #1 /usr/share/php/Horde/ActiveSync/Request/Base.php(253):  
> Horde_ActiveSync_Request_Sync->_handle()
> #2 /usr/share/php/Horde/ActiveSync.php(882):  
> Horde_ActiveSync_Request_Base->handle()
> #3 /usr/share/php/Horde/Rpc/ActiveSync.php(143):  
> Horde_ActiveSync->handleRequest('Sync', 'SEC198748EC975F...')
> #4 /var/www/horde/rpc.php(159): Horde_Rpc_ActiveSync->getResponse(NULL)
> #5 {main} [pid 3673 on line 162 of "/usr/share/php/Horde/Rpc/ActiveSync.php"]
> Nov  7 14:06:50 h2118808 HORDE: Buffer contents:  [pid 3673 on line  
> 162 of "/usr/share/php/Horde/Rpc/ActiveSync.php"]
>
> So the memeroy limit for PHP is hit which makes me wonder why EAS is  
> using that much memory and how to prevent this beside raising the  
> limit to insane values?


The timestamps on the log seem to indicate the memory exhaustion  
occurred 10 seconds prior to the SYNC request. So, whatever is causing  
that is probably unrelated, or possibly a side effect from whatever is  
causing this error.

 From the log, it looks like maybe the client did not send the  
*required* FOLDERID value. Can you get a wiretrace of the conversation  
to verify exactly what was sent to the server?

-- 
mike
The Horde Project
http://www.horde.org
https://www.facebook.com/hordeproject
https://www.twitter.com/hordeproject
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 5869 bytes
Desc: S/MIME Signature
URL: <http://lists.horde.org/archives/sync/attachments/20141107/d1592e8a/attachment.bin>