[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: OPTIONS
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Provision
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Provision xmlns="uri:Provision" xmlns:Settings="uri:Settings">
  <DeviceInformation xmlns="uri:Settings">
    <Set>
      <Model>Microsoft Corporation Surface Pro 3 Surface_Pro_3</Model>
      <IMEI/>
      <FriendlyName>SP3</FriendlyName>
      <OS>WINDOWS</OS>
      <OSLanguage>English</OSLanguage>
      <PhoneNumber/>
      <UserAgent>WindowsMail/17.5.9600.20605</UserAgent>
      <EnableOutboundSMS>0</EnableOutboundSMS>
    </Set>
  </DeviceInformation>
  <Policies>
    <Policy>
      <PolicyType>MS-EAS-Provisioning-WBXML</PolicyType>
    </Policy>
  </Policies>
</Provision>

[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:08
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Command_Provision::getResponse::96 PolicyType: MS-EAS-Provisioning-WBXML PolicyKey:
[28-Sep-2014 15:30:08,000000 -0500]: [INFO] Syncroton_Command_Provision::_sendPolicy::124 send policy to device
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Provision xmlns="uri:Provision" xmlns:Syncroton="uri:Syncroton" xmlns:Settings="uri:Settings">
  <Status>1</Status>
  <Settings:DeviceInformation>
    <Settings:Status>1</Settings:Status>
  </Settings:DeviceInformation>
  <Policies>
    <Policy>
      <PolicyType>MS-EAS-Provisioning-WBXML</PolicyType>
      <Status>2</Status>
    </Policy>
  </Policies>
</Provision>

[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => FolderSync
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<FolderSync xmlns="uri:FolderHierarchy">
  <SyncKey>0</SyncKey>
</FolderSync>

[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:08
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Command_FolderSync::handle::80 synckey is 0
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<FolderSync xmlns="uri:FolderHierarchy" xmlns:Syncroton="uri:Syncroton" xmlns:Internal="uri:Internal">
  <Status>1</Status>
  <SyncKey>1</SyncKey>
  <Changes>
    <Count>8</Count>
    <Add>
      <ServerId>Calendar::Syncroton</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Calendar</DisplayName>
      <Type>8</Type>
    </Add>
    <Add>
      <ServerId>Contacts::Syncroton</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Contacts</DisplayName>
      <Type>9</Type>
    </Add>
    <Add>
      <ServerId>Inbox</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Inbox</DisplayName>
      <Type>2</Type>
    </Add>
    <Add>
      <ServerId>Drafts</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Drafts</DisplayName>
      <Type>3</Type>
    </Add>
    <Add>
      <ServerId>Sent Items</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Sent Items</DisplayName>
      <Type>5</Type>
    </Add>
    <Add>
      <ServerId>Deleted Items</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Deleted Items</DisplayName>
      <Type>4</Type>
    </Add>
    <Add>
      <ServerId>Notes::Syncroton</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Notes</DisplayName>
      <Type>10</Type>
    </Add>
    <Add>
      <ServerId>Tasks::Syncroton</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Tasks</DisplayName>
      <Type>7</Type>
    </Add>
  </Changes>
</FolderSync>

[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Sync xmlns="uri:AirSync">
  <Collections>
    <Collection>
      <SyncKey>0</SyncKey>
      <CollectionId>Inbox</CollectionId>
    </Collection>
    <Collection>
      <SyncKey>0</SyncKey>
      <CollectionId>Sent Items</CollectionId>
    </Collection>
  </Collections>
  <WindowSize>100</WindowSize>
</Sync>

[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:08
[28-Sep-2014 15:30:08,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 0 Class: Email CollectionId: Inbox
[28-Sep-2014 15:30:08,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::205 initial client synckey 0 provided
[28-Sep-2014 15:30:08,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 0 Class: Email CollectionId: Sent Items
[28-Sep-2014 15:30:08,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::205 initial client synckey 0 provided
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Command_Sync::getResponse::910 update syncState for collection: Inbox
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::102  startTransaction request
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::107  new transactionable. Starting transaction on this resource
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::126  queued transaction with id aaf050dfde4a442e00faddee48df5299bfcca5f4
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::commitTransaction::141  commitTransaction request for aaf050dfde4a442e00faddee48df5299bfcca5f4
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::commitTransaction::153  no more open transactions in queue commiting all transactionables
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Command_Sync::getResponse::910 update syncState for collection: Sent Items
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::102  startTransaction request
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::107  new transactionable. Starting transaction on this resource
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::126  queued transaction with id 18357f1bc6649f884691e0fbe081ae89cd12ae68
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::commitTransaction::141  commitTransaction request for 18357f1bc6649f884691e0fbe081ae89cd12ae68
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::commitTransaction::153  no more open transactions in queue commiting all transactionables
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?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>1</SyncKey>
      <CollectionId>Inbox</CollectionId>
      <Status>1</Status>
    </Collection>
    <Collection>
      <Class>Email</Class>
      <SyncKey>1</SyncKey>
      <CollectionId>Sent Items</CollectionId>
      <Status>1</Status>
    </Collection>
  </Collections>
</Sync>

[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 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:RightsManagement="uri:RightsManagement">
  <Collections>
    <Collection>
      <SyncKey>1</SyncKey>
      <CollectionId>Inbox</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
      <Options>
        <FilterType>5</FilterType>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>1</Type>
          <TruncationSize>102400</TruncationSize>
          <Preview>255</Preview>
        </BodyPreference>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>2</Type>
          <TruncationSize>102400</TruncationSize>
          <Preview>255</Preview>
        </BodyPreference>
        <RightsManagementSupport xmlns="uri:RightsManagement">1</RightsManagementSupport>
      </Options>
    </Collection>
    <Collection>
      <SyncKey>1</SyncKey>
      <CollectionId>Sent Items</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
      <Options>
        <FilterType>5</FilterType>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>1</Type>
          <TruncationSize>102400</TruncationSize>
          <Preview>255</Preview>
        </BodyPreference>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>2</Type>
          <TruncationSize>102400</TruncationSize>
          <Preview>255</Preview>
        </BodyPreference>
        <RightsManagementSupport xmlns="uri:RightsManagement">1</RightsManagementSupport>
      </Options>
    </Collection>
  </Collections>
  <WindowSize>100</WindowSize>
</Sync>

[28-Sep-2014 15:30:08,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:08
[28-Sep-2014 15:30:08,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 1 Class: Email CollectionId: Inbox
[28-Sep-2014 15:30:08,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 1 Class: Email CollectionId: Sent Items
[28-Sep-2014 15:30:08,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:08,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 1
[28-Sep-2014 15:30:08,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:08,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 1
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Settings
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Settings xmlns="uri:Settings">
  <UserInformation>
    <Get/>
  </UserInformation>
  <RightsManagementInformation>
    <Get/>
  </RightsManagementInformation>
  <Oof>
    <Get>
      <BodyType>Text</BodyType>
    </Get>
  </Oof>
</Settings>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:09
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Settings xmlns="uri:Settings" xmlns:Syncroton="uri:Syncroton" xmlns:Internal="uri:Internal">
  <Status>1</Status>
  <UserInformation>
    <Status>1</Status>
    <Get>
      <Accounts>
        <Account>
          <UserDisplayName>C, J</UserDisplayName>
          <EmailAddresses>
            <PrimarySmtpAddress>jc@home.com</PrimarySmtpAddress>
          </EmailAddresses>
        </Account>
      </Accounts>
    </Get>
  </UserInformation>
</Settings>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Provision
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Provision xmlns="uri:Provision" xmlns:Settings="uri:Settings">
  <DeviceInformation xmlns="uri:Settings">
    <Set>
      <Model>Microsoft Corporation Surface Pro 3 Surface_Pro_3</Model>
      <IMEI/>
      <FriendlyName>SP3</FriendlyName>
      <OS>WINDOWS</OS>
      <OSLanguage>English</OSLanguage>
      <PhoneNumber/>
      <UserAgent>WindowsMail/17.5.9600.20605</UserAgent>
      <EnableOutboundSMS>0</EnableOutboundSMS>
    </Set>
  </DeviceInformation>
  <Policies>
    <Policy>
      <PolicyType>MS-EAS-Provisioning-WBXML</PolicyType>
    </Policy>
  </Policies>
</Provision>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:09
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Provision::getResponse::96 PolicyType: MS-EAS-Provisioning-WBXML PolicyKey:
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Provision::_sendPolicy::124 send policy to device
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Provision xmlns="uri:Provision" xmlns:Syncroton="uri:Syncroton" xmlns:Settings="uri:Settings">
  <Status>1</Status>
  <Settings:DeviceInformation>
    <Settings:Status>1</Settings:Status>
  </Settings:DeviceInformation>
  <Policies>
    <Policy>
      <PolicyType>MS-EAS-Provisioning-WBXML</PolicyType>
      <Status>2</Status>
    </Policy>
  </Policies>
</Provision>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Sync xmlns="uri:AirSync">
  <Collections>
    <Collection>
      <SyncKey>1</SyncKey>
      <CollectionId>Inbox</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
    </Collection>
    <Collection>
      <SyncKey>0</SyncKey>
      <CollectionId>Calendar::Syncroton</CollectionId>
    </Collection>
    <Collection>
      <SyncKey>0</SyncKey>
      <CollectionId>Contacts::Syncroton</CollectionId>
    </Collection>
  </Collections>
  <WindowSize>100</WindowSize>
</Sync>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:09
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::handle::163 restored options to Array
(
    [filterType] => 5
    [bodyPreferences] => Array
        (
            [1] => Array
                (
                    [type] => 1
                    [truncationSize] => 102400
                    [preview] => 255
                )

            [2] => Array
                (
                    [type] => 2
                    [truncationSize] => 102400
                    [preview] => 255
                )

        )

    [mimeSupport] => 0
    [mimeTruncation] => 8
)

[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 1 Class: Email CollectionId: Inbox
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 0 Class: Calendar CollectionId: Calendar::Syncroton
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::205 initial client synckey 0 provided
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 0 Class: Contacts CollectionId: Contacts::Syncroton
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::205 initial client synckey 0 provided
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 1
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::getResponse::910 update syncState for collection: Calendar::Syncroton
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::102  startTransaction request
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::107  new transactionable. Starting transaction on this resource
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::126  queued transaction with id 9c3ff26ec15a8be486eb24d920a5618ff35b6646
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::commitTransaction::141  commitTransaction request for 9c3ff26ec15a8be486eb24d920a5618ff35b6646
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::commitTransaction::153  no more open transactions in queue commiting all transactionables
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::getResponse::910 update syncState for collection: Contacts::Syncroton
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::102  startTransaction request
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::107  new transactionable. Starting transaction on this resource
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::126  queued transaction with id e039f5b21091e49d21eeabaab7ddc85b9e76dfec
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::commitTransaction::141  commitTransaction request for e039f5b21091e49d21eeabaab7ddc85b9e76dfec
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::commitTransaction::153  no more open transactions in queue commiting all transactionables
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?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>Calendar</Class>
      <SyncKey>1</SyncKey>
      <CollectionId>Calendar::Syncroton</CollectionId>
      <Status>1</Status>
    </Collection>
    <Collection>
      <Class>Contacts</Class>
      <SyncKey>1</SyncKey>
      <CollectionId>Contacts::Syncroton</CollectionId>
      <Status>1</Status>
    </Collection>
  </Collections>
</Sync>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 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>1</SyncKey>
      <CollectionId>Calendar::Syncroton</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
      <Options>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>1</Type>
        </BodyPreference>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>2</Type>
        </BodyPreference>
        <Conflict>1</Conflict>
        <FilterType>6</FilterType>
      </Options>
    </Collection>
    <Collection>
      <SyncKey>1</SyncKey>
      <CollectionId>Contacts::Syncroton</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
      <Options>
        <BodyPreference xmlns="uri:AirSyncBase">
          <Type>1</Type>
          <Preview>255</Preview>
        </BodyPreference>
      </Options>
    </Collection>
  </Collections>
  <WindowSize>100</WindowSize>
</Sync>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:09
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 1 Class: Calendar CollectionId: Calendar::Syncroton
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 1 Class: Contacts CollectionId: Contacts::Syncroton
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 1
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 1/0/0 entries for sync from server to client
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 2
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::getResponse::910 update syncState for collection: Contacts::Syncroton
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::102  startTransaction request
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::107  new transactionable. Starting transaction on this resource
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::startTransaction::126  queued transaction with id 0c8f30d271704fff959d2883b0dfc8ed42ea1dc9
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::commitTransaction::141  commitTransaction request for 0c8f30d271704fff959d2883b0dfc8ed42ea1dc9
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] kolab_sync_transaction_manager::commitTransaction::153  no more open transactions in queue commiting all transactionables
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?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" xmlns:AirSyncBase="uri:AirSyncBase" xmlns:Contacts="uri:Contacts" xmlns:Contacts2="uri:Contacts2">
  <Collections>
    <Collection xmlns:default="uri:Contacts">
      <Class>Contacts</Class>
      <SyncKey>2</SyncKey>
      <CollectionId>Contacts::Syncroton</CollectionId>
      <Status>1</Status>
      <Commands xmlns:default="uri:Contacts">
        <Add xmlns:default="uri:Contacts">
          <ServerId>20122f84-7b8a-498b-ac72-fe0354c6f675</ServerId>
          <ApplicationData>
            <Contacts:FirstName xmlns="uri:Contacts">rrrr</Contacts:FirstName>
            <Contacts:LastName xmlns="uri:Contacts">vvvv</Contacts:LastName>
          </ApplicationData>
        </Add>
      </Commands>
    </Collection>
  </Collections>
</Sync>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Provision
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Provision xmlns="uri:Provision" xmlns:Settings="uri:Settings">
  <DeviceInformation xmlns="uri:Settings">
    <Set>
      <Model>Microsoft Corporation Surface Pro 3 Surface_Pro_3</Model>
      <IMEI/>
      <FriendlyName>SP3</FriendlyName>
      <OS>WINDOWS</OS>
      <OSLanguage>English</OSLanguage>
      <PhoneNumber/>
      <UserAgent>WindowsMail/17.5.9600.20605</UserAgent>
      <EnableOutboundSMS>0</EnableOutboundSMS>
    </Set>
  </DeviceInformation>
  <Policies>
    <Policy>
      <PolicyType>MS-EAS-Provisioning-WBXML</PolicyType>
    </Policy>
  </Policies>
</Provision>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:09
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Provision::getResponse::96 PolicyType: MS-EAS-Provisioning-WBXML PolicyKey:
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Provision::_sendPolicy::124 send policy to device
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Provision xmlns="uri:Provision" xmlns:Syncroton="uri:Syncroton" xmlns:Settings="uri:Settings">
  <Status>1</Status>
  <Settings:DeviceInformation>
    <Settings:Status>1</Settings:Status>
  </Settings:DeviceInformation>
  <Policies>
    <Policy>
      <PolicyType>MS-EAS-Provisioning-WBXML</PolicyType>
      <Status>2</Status>
    </Policy>
  </Policies>
</Provision>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Sync xmlns="uri:AirSync">
  <Collections>
    <Collection>
      <SyncKey>1</SyncKey>
      <CollectionId>Inbox</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
    </Collection>
    <Collection>
      <SyncKey>1</SyncKey>
      <CollectionId>Calendar::Syncroton</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
    </Collection>
    <Collection>
      <SyncKey>2</SyncKey>
      <CollectionId>Contacts::Syncroton</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
    </Collection>
    <Collection>
      <SyncKey>1</SyncKey>
      <CollectionId>Sent Items</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
    </Collection>
  </Collections>
  <WindowSize>100</WindowSize>
</Sync>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:09
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::handle::163 restored options to Array
(
    [filterType] => 5
    [bodyPreferences] => Array
        (
            [1] => Array
                (
                    [type] => 1
                    [truncationSize] => 102400
                    [preview] => 255
                )

            [2] => Array
                (
                    [type] => 2
                    [truncationSize] => 102400
                    [preview] => 255
                )

        )

    [mimeSupport] => 0
    [mimeTruncation] => 8
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::handle::163 restored options to Array
(
    [filterType] => 6
    [bodyPreferences] => Array
        (
            [1] => Array
                (
                    [type] => 1
                )

            [2] => Array
                (
                    [type] => 2
                )

        )

    [mimeSupport] => 0
    [mimeTruncation] => 8
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::handle::163 restored options to Array
(
    [bodyPreferences] => Array
        (
            [1] => Array
                (
                    [type] => 1
                    [preview] => 255
                )

        )

    [filterType] => 0
    [mimeSupport] => 0
    [mimeTruncation] => 8
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::handle::163 restored options to Array
(
    [filterType] => 5
    [bodyPreferences] => Array
        (
            [1] => Array
                (
                    [type] => 1
                    [truncationSize] => 102400
                    [preview] => 255
                )

            [2] => Array
                (
                    [type] => 2
                    [truncationSize] => 102400
                    [preview] => 255
                )

        )

    [mimeSupport] => 0
    [mimeTruncation] => 8
)

[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 1 Class: Email CollectionId: Inbox
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 1 Class: Calendar CollectionId: Calendar::Syncroton
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 2 Class: Contacts CollectionId: Contacts::Syncroton
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 1 Class: Email CollectionId: Sent Items
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 1
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 1
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 2
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 1
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Provision
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Provision xmlns="uri:Provision" xmlns:Settings="uri:Settings">
  <DeviceInformation xmlns="uri:Settings">
    <Set>
      <Model>Microsoft Corporation Surface Pro 3 Surface_Pro_3</Model>
      <IMEI/>
      <FriendlyName>SP3</FriendlyName>
      <OS>WINDOWS</OS>
      <OSLanguage>English</OSLanguage>
      <PhoneNumber/>
      <UserAgent>WindowsMail/17.5.9600.20605</UserAgent>
      <EnableOutboundSMS>0</EnableOutboundSMS>
    </Set>
  </DeviceInformation>
  <Policies>
    <Policy>
      <PolicyType>MS-EAS-Provisioning-WBXML</PolicyType>
    </Policy>
  </Policies>
</Provision>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:09
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Provision::getResponse::96 PolicyType: MS-EAS-Provisioning-WBXML PolicyKey:
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Provision::_sendPolicy::124 send policy to device
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Provision xmlns="uri:Provision" xmlns:Syncroton="uri:Syncroton" xmlns:Settings="uri:Settings">
  <Status>1</Status>
  <Settings:DeviceInformation>
    <Settings:Status>1</Settings:Status>
  </Settings:DeviceInformation>
  <Policies>
    <Policy>
      <PolicyType>MS-EAS-Provisioning-WBXML</PolicyType>
      <Status>2</Status>
    </Policy>
  </Policies>
</Provision>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Sync
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Sync xmlns="uri:AirSync">
  <Collections>
    <Collection>
      <SyncKey>1</SyncKey>
      <CollectionId>Inbox</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
    </Collection>
    <Collection>
      <SyncKey>1</SyncKey>
      <CollectionId>Calendar::Syncroton</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
    </Collection>
    <Collection>
      <SyncKey>2</SyncKey>
      <CollectionId>Contacts::Syncroton</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
    </Collection>
    <Collection>
      <SyncKey>1</SyncKey>
      <CollectionId>Sent Items</CollectionId>
      <DeletesAsMoves>0</DeletesAsMoves>
      <WindowSize>100</WindowSize>
    </Collection>
  </Collections>
  <WindowSize>100</WindowSize>
</Sync>

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:09
[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::handle::163 restored options to Array
(
    [filterType] => 5
    [bodyPreferences] => Array
        (
            [1] => Array
                (
                    [type] => 1
                    [truncationSize] => 102400
                    [preview] => 255
                )

            [2] => Array
                (
                    [type] => 2
                    [truncationSize] => 102400
                    [preview] => 255
                )

        )

    [mimeSupport] => 0
    [mimeTruncation] => 8
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::handle::163 restored options to Array
(
    [filterType] => 6
    [bodyPreferences] => Array
        (
            [1] => Array
                (
                    [type] => 1
                )

            [2] => Array
                (
                    [type] => 2
                )

        )

    [mimeSupport] => 0
    [mimeTruncation] => 8
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::handle::163 restored options to Array
(
    [bodyPreferences] => Array
        (
            [1] => Array
                (
                    [type] => 1
                    [preview] => 255
                )

        )

    [filterType] => 0
    [mimeSupport] => 0
    [mimeTruncation] => 8
)

[28-Sep-2014 15:30:09,000000 -0500]: [DEBUG] Syncroton_Command_Sync::handle::163 restored options to Array
(
    [filterType] => 5
    [bodyPreferences] => Array
        (
            [1] => Array
                (
                    [type] => 1
                    [truncationSize] => 102400
                    [preview] => 255
                )

            [2] => Array
                (
                    [type] => 2
                    [truncationSize] => 102400
                    [preview] => 255
                )

        )

    [mimeSupport] => 0
    [mimeTruncation] => 8
)

[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 1 Class: Email CollectionId: Inbox
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 1 Class: Calendar CollectionId: Calendar::Syncroton
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 2 Class: Contacts CollectionId: Contacts::Syncroton
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::handle::200 SyncKey is 1 Class: Email CollectionId: Sent Items
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 1
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 1
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 2
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::654 found (added/changed/deleted) 0/0/0 entries for sync from server to client
[28-Sep-2014 15:30:09,000000 -0500]: [INFO] Syncroton_Command_Sync::getResponse::897 current synckey is 1
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Ping
    [deviceId] => 9239A1EB2AFB02B837D164290BE8BEB7
    [deviceType] => WindowsMail
    [policyKey] =>
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync.wbxml
)

[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Ping xmlns="uri:Ping">
  <HeartbeatInterval>420</HeartbeatInterval>
  <Folders>
    <Folder>
      <Id>Inbox</Id>
      <Class>Email</Class>
    </Folder>
    <Folder>
      <Id>Sent Items</Id>
      <Class>Email</Class>
    </Folder>
    <Folder>
      <Id>Calendar::Syncroton</Id>
      <Class>Calendar</Class>
    </Folder>
    <Folder>
      <Id>Contacts::Syncroton</Id>
      <Class>Contacts</Class>
    </Folder>
  </Folders>
</Ping>

[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:10
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Command_Ping::handle::74 Folder not found
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Command_Ping::handle::109 Folders to monitor(420 / 1411936210 / 1411936630 / 7): Array
(
)

[28-Sep-2014 15:30:10,000000 -0500]: [INFO] Syncroton_Command_Ping::handle::210 DeviceId: 9239A1EB2AFB02B837D164290BE8BEB7 Lifetime: 420 SecondsLeft: 1411936630 Status: 7)
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Ping xmlns="uri:Ping" xmlns:Syncroton="uri:Syncroton">
  <Status>7</Status>
</Ping>

[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: OPTIONS
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => Provision
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Provision xmlns="uri:Provision" xmlns:Settings="uri:Settings">
  <DeviceInformation xmlns="uri:Settings">
    <Set>
      <Model>Microsoft Corporation Surface Pro 3 Surface_Pro_3</Model>
      <IMEI/>
      <FriendlyName>SP3</FriendlyName>
      <OS>WINDOWS</OS>
      <OSLanguage>English</OSLanguage>
      <PhoneNumber/>
      <UserAgent>WindowsMail/17.5.9600.20605</UserAgent>
      <EnableOutboundSMS>0</EnableOutboundSMS>
    </Set>
  </DeviceInformation>
  <Policies>
    <Policy>
      <PolicyType>MS-EAS-Provisioning-WBXML</PolicyType>
    </Policy>
  </Policies>
</Provision>

[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:10
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Command_Provision::getResponse::96 PolicyType: MS-EAS-Provisioning-WBXML PolicyKey:
[28-Sep-2014 15:30:10,000000 -0500]: [INFO] Syncroton_Command_Provision::_sendPolicy::124 send policy to device
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<Provision xmlns="uri:Provision" xmlns:Syncroton="uri:Syncroton" xmlns:Settings="uri:Settings">
  <Status>1</Status>
  <Settings:DeviceInformation>
    <Settings:Status>1</Settings:Status>
  </Settings:DeviceInformation>
  <Policies>
    <Policy>
      <PolicyType>MS-EAS-Provisioning-WBXML</PolicyType>
      <Status>2</Status>
    </Policy>
  </Policies>
</Provision>

[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => FolderSync
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<FolderSync xmlns="uri:FolderHierarchy">
  <SyncKey>1</SyncKey>
</FolderSync>

[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:10
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Command_FolderSync::handle::80 synckey is 1
[28-Sep-2014 15:30:10,000000 -0500]: [INFO] Syncroton_Command_FolderSync::getResponse::113 invalid synckey provided. FolderSync 0 needed.
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<FolderSync xmlns="uri:FolderHierarchy" xmlns:Syncroton="uri:Syncroton">
  <Status>9</Status>
</FolderSync>

[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::handle::61 REQUEST METHOD: POST
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::103 REQUEST Array
(
    [protocolVersion] => 14.1
    [command] => FolderSync
    [deviceId] => 9239a1eb2afb02b837d164290be8beb7
    [deviceType] => WindowsMail
    [policyKey] => 0
    [saveInSent] =>
    [collectionId] =>
    [itemId] =>
    [attachmentName] =>
    [acceptMultipart] =>
    [userAgent] => WindowsMail/17.5.9600.20605
    [contentType] => application/vnd.ms-sync
)

[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::126 xml request:
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<FolderSync xmlns="uri:FolderHierarchy">
  <SyncKey>0</SyncKey>
</FolderSync>

[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Command_Wbxml::__construct::163 sync timestamp: 2014-09-28 20:30:10
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Command_FolderSync::handle::80 synckey is 0
[28-Sep-2014 15:30:10,000000 -0500]: [DEBUG] Syncroton_Server::_handlePost::175 xml response(0):
<?xml version="1.0" encoding="utf-8"?>
<!DOCTYPE AirSync PUBLIC "-//AIRSYNC//DTD AirSync//EN" "http://www.microsoft.com/">
<FolderSync xmlns="uri:FolderHierarchy" xmlns:Syncroton="uri:Syncroton" xmlns:Internal="uri:Internal">
  <Status>1</Status>
  <SyncKey>1</SyncKey>
  <Changes>
    <Count>8</Count>
    <Add>
      <ServerId>Calendar::Syncroton</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Calendar</DisplayName>
      <Type>8</Type>
    </Add>
    <Add>
      <ServerId>Contacts::Syncroton</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Contacts</DisplayName>
      <Type>9</Type>
    </Add>
    <Add>
      <DisplayName>Inbox</DisplayName>
      <ServerId>Inbox</ServerId>
      <ParentId>0</ParentId>
      <Type>2</Type>
    </Add>
    <Add>
      <DisplayName>Drafts</DisplayName>
      <ServerId>Drafts</ServerId>
      <ParentId>0</ParentId>
      <Type>3</Type>
    </Add>
    <Add>
      <DisplayName>Sent Items</DisplayName>
      <ServerId>Sent Items</ServerId>
      <ParentId>0</ParentId>
      <Type>5</Type>
    </Add>
    <Add>
      <DisplayName>Deleted Items</DisplayName>
      <ServerId>Deleted Items</ServerId>
      <ParentId>0</ParentId>
      <Type>4</Type>
    </Add>
    <Add>
      <ServerId>Notes::Syncroton</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Notes</DisplayName>
      <Type>10</Type>
    </Add>
    <Add>
      <ServerId>Tasks::Syncroton</ServerId>
      <ParentId>0</ParentId>
      <DisplayName>Tasks</DisplayName>
      <Type>7</Type>
    </Add>
  </Changes>
</FolderSync>