viewed status synchronisation with iPhone

Thu Apr 4 16:27:10 CEST 2013

> From: kolab-users-bounces at kolab.org [mailto:kolab-users-bounces at kolab.org]
On Behalf Of Torsten Grote
> Sent: Friday, April 05, 2013 1:07 AM
>
> On Friday 05 April 2013 00:57:51 Rob Arends wrote:
>> Thanks for the tip, however surprisingly a Google search for "Syncrotron
>> debug" (and similar) does not return any documents that explain how to
>> enable debugging in synchrotron.
>
> You can set $rcmail_config['activesync_debug'] in main.inc.php to true,
and 
> examine /var/log/kolab-syncroton/console
>
> Regards,
> Torsten

Thank you Torsten,

After adding a new line to the main.inc.php file as you describe, I had to
create the file for Apache to write to it, and restart httpd.

This is the log file with some attempts the mark the email read/unread in
both directions and to flag/unflag in both directions.
If there are sync requests and nothing to sync, then that would be me
changing status on RoundCube then seeing if that would sync to the iPhone.
I noticed that when I opened the email on the iPhone, immediately an
ActiveSync log entry would appear. I would then force a sync too.
So at this point I cannot advise exactly what I did for each log entry
below.

If you need this let me know.  (off to bed now).

# touch /var/log/kolab-syncroton/console  
# chown apache:apache  /var/log/kolab-syncroton/console 
# service httpd restart
Stopping httpd:                                            [  OK  ]
Starting httpd:                                            [  OK  ]
# tail -f /var/log/kolab-syncroton/console

[05-Apr-2013 01:13:56,000000 +1100]: [DEBUG] Syncroton_Server::handle::56
REQUEST METHOD: POST
[05-Apr-2013 01:13:56,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::89 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => ApplC34K1ATADTWD
    [deviceType] => iPhone
    [policyKey] => 0
    [saveInSent] => 
    [collectionId] => 
    [itemId] => 
    [attachmentName] => 
    [userAgent] => Apple-iPhone5C2/1002.146
    [contentType] => application/vnd.ms-sync.wbxml
)

[05-Apr-2013 01:13:56,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::112 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:AirSyncBase="uri:AirSyncBase">
  <Collections>
    <Collection>
      <SyncKey>50</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <GetChanges/>
      <WindowSize>25</WindowSize>
      <Options>
        <FilterType>2</FilterType>
        <MIMETruncation>1</MIMETruncation>
        <MIMESupport>0</MIMESupport>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>1</Type>
          <TruncationSize>500</TruncationSize>
        </BodyPreference>
      </Options>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:13:56,000000 +1100]: [DEBUG]
Syncroton_Command_Wbxml::__construct::146 sync timestamp: 2013-04-04
14:13:56
[05-Apr-2013 01:13:56,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::156 SyncKey is 50 Class: Email CollectionId:
38b950ebd62cd9a66929c89615d0fc04
[05-Apr-2013 01:13:57,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::527 found (added/changed/deleted) 0/0/0
entries for sync from server to client
[05-Apr-2013 01:13:57,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::751 new synckey is 50
[05-Apr-2013 01:13:57,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::163 xml response:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:Syncroton="uri:Syncroton">
  <Collections>
    <Collection>
      <Class>Email</Class>
      <SyncKey>50</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <Status>1</Status>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:14:03,000000 +1100]: [DEBUG] Syncroton_Server::handle::56
REQUEST METHOD: POST
[05-Apr-2013 01:14:03,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::89 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => ApplC34K1ATADTWD
    [deviceType] => iPhone
    [policyKey] => 0
    [saveInSent] => 
    [collectionId] => 
    [itemId] => 
    [attachmentName] => 
    [userAgent] => Apple-iPhone5C2/1002.146
    [contentType] => application/vnd.ms-sync.wbxml
)

[05-Apr-2013 01:14:03,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::112 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:AirSyncBase="uri:AirSyncBase">
  <Collections>
    <Collection>
      <SyncKey>50</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <GetChanges/>
      <WindowSize>25</WindowSize>
      <Options>
        <FilterType>2</FilterType>
        <MIMETruncation>1</MIMETruncation>
        <MIMESupport>0</MIMESupport>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>1</Type>
          <TruncationSize>500</TruncationSize>
        </BodyPreference>
      </Options>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:14:03,000000 +1100]: [DEBUG]
Syncroton_Command_Wbxml::__construct::146 sync timestamp: 2013-04-04
14:14:03
[05-Apr-2013 01:14:03,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::156 SyncKey is 50 Class: Email CollectionId:
38b950ebd62cd9a66929c89615d0fc04
[05-Apr-2013 01:14:03,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::527 found (added/changed/deleted) 0/0/0
entries for sync from server to client
[05-Apr-2013 01:14:03,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::751 new synckey is 50
[05-Apr-2013 01:14:03,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::163 xml response:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:Syncroton="uri:Syncroton">
  <Collections>
    <Collection>
      <Class>Email</Class>
      <SyncKey>50</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <Status>1</Status>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:14:20,000000 +1100]: [DEBUG] Syncroton_Server::handle::56
REQUEST METHOD: POST
[05-Apr-2013 01:14:20,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::89 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => ApplC34K1ATADTWD
    [deviceType] => iPhone
    [policyKey] => 0
    [saveInSent] => 
    [collectionId] => 
    [itemId] => 
    [attachmentName] => 
    [userAgent] => Apple-iPhone5C2/1002.146
    [contentType] => application/vnd.ms-sync.wbxml
)

[05-Apr-2013 01:14:20,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::112 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:AirSyncBase="uri:AirSyncBase">
  <Collections>
    <Collection>
      <SyncKey>50</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <GetChanges/>
      <WindowSize>25</WindowSize>
      <Options>
        <FilterType>2</FilterType>
        <MIMETruncation>1</MIMETruncation>
        <MIMESupport>0</MIMESupport>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>1</Type>
          <TruncationSize>500</TruncationSize>
        </BodyPreference>
      </Options>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:14:20,000000 +1100]: [DEBUG]
Syncroton_Command_Wbxml::__construct::146 sync timestamp: 2013-04-04
14:14:20
[05-Apr-2013 01:14:20,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::156 SyncKey is 50 Class: Email CollectionId:
38b950ebd62cd9a66929c89615d0fc04
[05-Apr-2013 01:14:20,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::527 found (added/changed/deleted) 0/0/0
entries for sync from server to client
[05-Apr-2013 01:14:20,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::751 new synckey is 50
[05-Apr-2013 01:14:20,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::163 xml response:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:Syncroton="uri:Syncroton">
  <Collections>
    <Collection>
      <Class>Email</Class>
      <SyncKey>50</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <Status>1</Status>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:14:28,000000 +1100]: [DEBUG] Syncroton_Server::handle::56
REQUEST METHOD: POST
[05-Apr-2013 01:14:28,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::89 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => ApplC34K1ATADTWD
    [deviceType] => iPhone
    [policyKey] => 0
    [saveInSent] => 
    [collectionId] => 
    [itemId] => 
    [attachmentName] => 
    [userAgent] => Apple-iPhone5C2/1002.146
    [contentType] => application/vnd.ms-sync.wbxml
)

[05-Apr-2013 01:14:28,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::112 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:AirSyncBase="uri:AirSyncBase">
  <Collections>
    <Collection>
      <SyncKey>50</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <GetChanges/>
      <WindowSize>25</WindowSize>
      <Options>
        <FilterType>2</FilterType>
        <MIMETruncation>1</MIMETruncation>
        <MIMESupport>0</MIMESupport>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>1</Type>
          <TruncationSize>500</TruncationSize>
        </BodyPreference>
      </Options>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:14:28,000000 +1100]: [DEBUG]
Syncroton_Command_Wbxml::__construct::146 sync timestamp: 2013-04-04
14:14:28
[05-Apr-2013 01:14:28,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::156 SyncKey is 50 Class: Email CollectionId:
38b950ebd62cd9a66929c89615d0fc04
[05-Apr-2013 01:14:28,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::527 found (added/changed/deleted) 0/0/0
entries for sync from server to client
[05-Apr-2013 01:14:28,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::751 new synckey is 50
[05-Apr-2013 01:14:28,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::163 xml response:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:Syncroton="uri:Syncroton">
  <Collections>
    <Collection>
      <Class>Email</Class>
      <SyncKey>50</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <Status>1</Status>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:15:03,000000 +1100]: [DEBUG] Syncroton_Server::handle::56
REQUEST METHOD: POST
[05-Apr-2013 01:15:03,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::89 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => ApplC34K1ATADTWD
    [deviceType] => iPhone
    [policyKey] => 0
    [saveInSent] => 
    [collectionId] => 
    [itemId] => 
    [attachmentName] => 
    [userAgent] => Apple-iPhone5C2/1002.146
    [contentType] => application/vnd.ms-sync.wbxml
)

[05-Apr-2013 01:15:03,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::112 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:AirSyncBase="uri:AirSyncBase"
xmlns:Email="uri:Email">
  <Collections>
    <Collection>
      <SyncKey>50</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <GetChanges>0</GetChanges>
      <Options>
        <FilterType>2</FilterType>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>4</Type>
          <TruncationSize>32768</TruncationSize>
        </BodyPreference>
      </Options>
      <Commands>
        <Change>
          <ServerId>38b950ebd62cd9a66929c89615d0fc04::80</ServerId>
          <ApplicationData>
            <Read xmlns="uri:Email">0</Read>
          </ApplicationData>
        </Change>
      </Commands>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:15:03,000000 +1100]: [DEBUG]
Syncroton_Command_Wbxml::__construct::146 sync timestamp: 2013-04-04
14:15:03
[05-Apr-2013 01:15:03,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::156 SyncKey is 50 Class: Email CollectionId:
38b950ebd62cd9a66929c89615d0fc04
[05-Apr-2013 01:15:03,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::277 found 1 entries to be updated on server
[05-Apr-2013 01:15:03,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::751 new synckey is 51
[05-Apr-2013 01:15:03,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::102  startTransaction
request
[05-Apr-2013 01:15:03,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::107  new transactionable.
Starting transaction on this resource
[05-Apr-2013 01:15:03,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::126  queued transaction
with id 3ff8890e5580f42f3f365bb75988336af1f7cda6
[05-Apr-2013 01:15:03,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::commitTransaction::141  commitTransaction
request for 3ff8890e5580f42f3f365bb75988336af1f7cda6
[05-Apr-2013 01:15:03,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::commitTransaction::153  no more open
transactions in queue commiting all transactionables
[05-Apr-2013 01:15:03,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::163 xml response:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:Syncroton="uri:Syncroton">
  <Collections>
    <Collection>
      <Class>Email</Class>
      <SyncKey>51</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <Status>1</Status>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:15:26,000000 +1100]: [DEBUG] Syncroton_Server::handle::56
REQUEST METHOD: POST
[05-Apr-2013 01:15:26,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::89 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => ApplC34K1ATADTWD
    [deviceType] => iPhone
    [policyKey] => 0
    [saveInSent] => 
    [collectionId] => 
    [itemId] => 
    [attachmentName] => 
    [userAgent] => Apple-iPhone5C2/1002.146
    [contentType] => application/vnd.ms-sync.wbxml
)

[05-Apr-2013 01:15:26,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::112 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:AirSyncBase="uri:AirSyncBase"
xmlns:Email="uri:Email">
  <Collections>
    <Collection>
      <SyncKey>51</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <GetChanges>0</GetChanges>
      <Options>
        <FilterType>2</FilterType>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>4</Type>
          <TruncationSize>32768</TruncationSize>
        </BodyPreference>
      </Options>
      <Commands>
        <Change>
          <ServerId>38b950ebd62cd9a66929c89615d0fc04::80</ServerId>
          <ApplicationData>
            <Read xmlns="uri:Email">1</Read>
          </ApplicationData>
        </Change>
      </Commands>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:15:26,000000 +1100]: [DEBUG]
Syncroton_Command_Wbxml::__construct::146 sync timestamp: 2013-04-04
14:15:26
[05-Apr-2013 01:15:26,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::156 SyncKey is 51 Class: Email CollectionId:
38b950ebd62cd9a66929c89615d0fc04
[05-Apr-2013 01:15:26,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::277 found 1 entries to be updated on server
[05-Apr-2013 01:15:26,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::751 new synckey is 52
[05-Apr-2013 01:15:26,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::102  startTransaction
request
[05-Apr-2013 01:15:26,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::107  new transactionable.
Starting transaction on this resource
[05-Apr-2013 01:15:26,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::126  queued transaction
with id 52b0b3d6ab33694001b5dbab599e92abf5340c6f
[05-Apr-2013 01:15:26,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::commitTransaction::141  commitTransaction
request for 52b0b3d6ab33694001b5dbab599e92abf5340c6f
[05-Apr-2013 01:15:26,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::commitTransaction::153  no more open
transactions in queue commiting all transactionables
[05-Apr-2013 01:15:26,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::163 xml response:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:Syncroton="uri:Syncroton">
  <Collections>
    <Collection>
      <Class>Email</Class>
      <SyncKey>52</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <Status>1</Status>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:15:30,000000 +1100]: [DEBUG] Syncroton_Server::handle::56
REQUEST METHOD: POST
[05-Apr-2013 01:15:30,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::89 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => ApplC34K1ATADTWD
    [deviceType] => iPhone
    [policyKey] => 0
    [saveInSent] => 
    [collectionId] => 
    [itemId] => 
    [attachmentName] => 
    [userAgent] => Apple-iPhone5C2/1002.146
    [contentType] => application/vnd.ms-sync.wbxml
)

[05-Apr-2013 01:15:30,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::112 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:AirSyncBase="uri:AirSyncBase"
xmlns:Email="uri:Email">
  <Collections>
    <Collection>
      <SyncKey>52</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <GetChanges>0</GetChanges>
      <Options>
        <FilterType>2</FilterType>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>4</Type>
          <TruncationSize>32768</TruncationSize>
        </BodyPreference>
      </Options>
      <Commands>
        <Change>
          <ServerId>38b950ebd62cd9a66929c89615d0fc04::80</ServerId>
          <ApplicationData>
            <Read xmlns="uri:Email">0</Read>
          </ApplicationData>
        </Change>
      </Commands>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:15:30,000000 +1100]: [DEBUG]
Syncroton_Command_Wbxml::__construct::146 sync timestamp: 2013-04-04
14:15:30
[05-Apr-2013 01:15:30,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::156 SyncKey is 52 Class: Email CollectionId:
38b950ebd62cd9a66929c89615d0fc04
[05-Apr-2013 01:15:30,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::277 found 1 entries to be updated on server
[05-Apr-2013 01:15:30,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::751 new synckey is 53
[05-Apr-2013 01:15:30,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::102  startTransaction
request
[05-Apr-2013 01:15:30,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::107  new transactionable.
Starting transaction on this resource
[05-Apr-2013 01:15:30,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::126  queued transaction
with id fbf1e7aa0a0734de8b36c909c546dffb7a268c47
[05-Apr-2013 01:15:30,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::commitTransaction::141  commitTransaction
request for fbf1e7aa0a0734de8b36c909c546dffb7a268c47
[05-Apr-2013 01:15:30,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::commitTransaction::153  no more open
transactions in queue commiting all transactionables
[05-Apr-2013 01:15:30,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::163 xml response:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:Syncroton="uri:Syncroton">
  <Collections>
    <Collection>
      <Class>Email</Class>
      <SyncKey>53</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <Status>1</Status>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:15:32,000000 +1100]: [DEBUG] Syncroton_Server::handle::56
REQUEST METHOD: POST
[05-Apr-2013 01:15:32,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::89 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => ApplC34K1ATADTWD
    [deviceType] => iPhone
    [policyKey] => 0
    [saveInSent] => 
    [collectionId] => 
    [itemId] => 
    [attachmentName] => 
    [userAgent] => Apple-iPhone5C2/1002.146
    [contentType] => application/vnd.ms-sync.wbxml
)

[05-Apr-2013 01:15:32,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::112 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:AirSyncBase="uri:AirSyncBase"
xmlns:Email="uri:Email">
  <Collections>
    <Collection>
      <SyncKey>53</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <GetChanges>0</GetChanges>
      <Options>
        <FilterType>2</FilterType>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>4</Type>
          <TruncationSize>32768</TruncationSize>
        </BodyPreference>
      </Options>
      <Commands>
        <Change>
          <ServerId>38b950ebd62cd9a66929c89615d0fc04::80</ServerId>
          <ApplicationData>
            <Flag xmlns="uri:Email">
              <Status>2</Status>
              <FlagType>Flag for follow up</FlagType>
            </Flag>
          </ApplicationData>
        </Change>
      </Commands>
    </Collection>
  </Collections>
</Sync>

[05-Apr-2013 01:15:32,000000 +1100]: [DEBUG]
Syncroton_Command_Wbxml::__construct::146 sync timestamp: 2013-04-04
14:15:32
[05-Apr-2013 01:15:32,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::156 SyncKey is 53 Class: Email CollectionId:
38b950ebd62cd9a66929c89615d0fc04
[05-Apr-2013 01:15:32,000000 +1100]: [INFO]
Syncroton_Command_Sync::handle::277 found 1 entries to be updated on server
[05-Apr-2013 01:15:32,000000 +1100]: [INFO]
Syncroton_Command_Sync::getResponse::751 new synckey is 54
[05-Apr-2013 01:15:32,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::102  startTransaction
request
[05-Apr-2013 01:15:32,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::107  new transactionable.
Starting transaction on this resource
[05-Apr-2013 01:15:32,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::startTransaction::126  queued transaction
with id 6549aa22c73c8bb4ff5c14ac1d60f6418305f72f
[05-Apr-2013 01:15:32,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::commitTransaction::141  commitTransaction
request for 6549aa22c73c8bb4ff5c14ac1d60f6418305f72f
[05-Apr-2013 01:15:32,000000 +1100]: [DEBUG]
kolab_sync_transaction_manager::commitTransaction::153  no more open
transactions in queue commiting all transactionables
[05-Apr-2013 01:15:32,000000 +1100]: [DEBUG]
Syncroton_Server::_handlePost::163 xml response:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN"
"http://www.microsoft.com/">
<Sync xmlns="uri:AirSync" xmlns:Syncroton="uri:Syncroton">
  <Collections>
    <Collection>
      <Class>Email</Class>
      <SyncKey>54</SyncKey>
      <CollectionId>38b950ebd62cd9a66929c89615d0fc04</CollectionId>
      <Status>1</Status>
    </Collection>
  </Collections>
</Sync>