[sync] ActiveSync issues

Mon Aug 12 23:13:23 UTC 2013

> -----Original Message-----
> From: sync-bounces at lists.horde.org [mailto:sync-bounces at lists.horde.org] On
> Behalf Of Michael J Rubinsky
> Sent: 12 August 2013 22:12
> To: sync at lists.horde.org
> Subject: Re: [sync] ActiveSync issues
> 
> 
> Quoting Ivan Kouvykov <ivan at kouvykov.com>:
> 
> > The logs were too big and the messages were rejected.
> >
> >> -----Original Message-----
> >> From: sync-bounces at lists.horde.org
> >> [mailto:sync-bounces at lists.horde.org]
> >> On Behalf Of Michael J Rubinsky
> >> Sent: Thursday, August 08, 2013 10:55 PM
> >> To: sync at lists.horde.org
> >> Subject: Re: [sync] ActiveSync issues
> >>
> >> Please don't top post.
> >>
> >> Quoting Ivan Kouvykov <ivan at kouvykov.com>:
> >>
> >> > Horde main log attached. There are errors there too. Could be
> >> > relevant
> >>
> >> I would need the *sync* log to know for sure, but it looks like
> >> either you or the client is sending a DELETEFOLDER request before the
> >> initial FOLDER_SYNC was done.
> >>
> >
> > Links to sync logs below.
> 
> 
> >> >>> 2.  HTC One X only gets e-mails that were created in IMAP server
> >> >>> after horde was set up (i.e. new emails arrive, but the old ones
> >> >>> are not downloaded)
> >> >>
> >> >> Would need to see the sync log, but might simply be a filter
> >> >> setting on the client.
> >> >>
> >
> > HTC One X log. HTC775b17693f2a82d3f9648b65928a8.zip:
> > https://kouvykov.com:5001/fbsharing/rFT02o6M
> 
> Your synclog clearly shows 4 emails being transferred to the client on the initial
> sync. Your device is asking for emails not any older than
> 2 weeks:
> 
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I  <Synchronize>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I   <Folders>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I    <Folder>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I     <SyncKey>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I
> {5203caaf-4b30-40de-a5c3-7b290afa0afa}1
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I     </SyncKey>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I     <FolderId>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I       fc2c48ea
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I     </FolderId>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I     <DeletesAsMoves>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I       1
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I     </DeletesAsMoves>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I     <GetChanges />
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I     <WindowSize>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I       50
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I     </WindowSize>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I     <Options>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I      <FilterType>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I        1
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I      </FilterType>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I
> <AirSyncBase:BodyPreference>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I       <AirSyncBase:Type>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I         1
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I       </AirSyncBase:Type>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I       <AirSyncBase:TruncationSize>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I         5120
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I
> </AirSyncBase:TruncationSize>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I       <AirSyncBase:AllOrNone>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I         0
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I       </AirSyncBase:AllOrNone>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I
> </AirSyncBase:BodyPreference>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I      <Conflict>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I        1
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I      </Conflict>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I     </Options>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I    </Folder>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I   </Folders>
> 2013-08-08T20:43:32+04:00 DEBUG: [31529] I  </Synchronize>
> 
> 
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O  <Synchronize>
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O   <Status>
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O    1
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O   </Status>
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O   <Folders>
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O    <Folder>
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O     <SyncKey>
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O
> {5203caaf-4b30-40de-a5c3-7b290afa0afa}2
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O     </SyncKey>
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O     <FolderId>
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O      fc2c48ea
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O     </FolderId>
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O     <Status>
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O      1
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O     </Status>
> 2013-08-08T20:43:33+04:00 INFO: [31529]
> Horde_Core_ActiveSync_Driver::getMessage(INBOX, 577)
> 2013-08-08T20:43:33+04:00 INFO: Sending PLAINTEXT Message.
> 2013-08-08T20:43:33+04:00 INFO: Validating UTF-8 data coming from utf-8
> 2013-08-08T20:43:33+04:00 INFO: FETCHING VERB
> 2013-08-08T20:43:33+04:00 INFO: RETURNING VERB
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O     <Commands>
> 2013-08-08T20:43:33+04:00 DEBUG: [31529] O      <Add>
> 
> ... and so on.
> 
> 
> >> >>> 3.  Calendar events created on devices are not synced due to
> >> >>> TimeZone error (ERR: No timezone found for the given offsets)
> >> >>
> >> >> ..and *what* timezone is being sent from the device? Also, would
> >> >> need to see the sync log.
> >
> > Mail RT log. 86aa7f74562fcbd7299ba5865312a35b.zip:
> > https://kouvykov.com:5001/fbsharing/52DfTOgO
> 
> 
> This timezone parses fine for me. Maybe your php timezone db is corrupt or out
> of date? The following should output 'GST':
> <code>
> $blob =
> 'EP///1IAdQBzAHMAaQBhAG4AIABTAHQAYQBuAGQAYQByAGQAIABUAGkAbQBl
> AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAFI
> AdQBzAHMAaQBhAG4AIABEAGEAeQBsAGkAZwBoAHQAIABUAGkAbQBlAAAAAA
> AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA==';
> 
> $offsets = Horde_ActiveSync_Timezone::getOffsetsFromSyncTZ($blob);
> $tz = new Horde_ActiveSync_Timezone();
> var_dump($tz->getTimezone($blob));
> </code>
> 
> If it doesn't, your timezone db doesn't contain the needed timezone to parse it.
> 

It turned out that my box CPU is big-endian while data in blob is little-endian. Once I changed endianness in unpack format calendars started syncing.

> --
> mike
> 
> The Horde Project (www.horde.org)
> mrubinsk at horde.org

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

ivan at kouvykov.com