Start of log - Synthesis SyncML Engine 3.4.0.27

• [-- collapse all --][++ expand all ++]
• +
  –
  [2011-08-07 12:50:41.502] 'loadSystemZoneDefinitions' - Linux system time zones [--][++] [->end]
  –[2011-08-07 12:50:41.781] End of 'loadSystemZoneDefinitions' [->top]
• [2011-08-07 12:50:41.781] SyncML server account: roman
• [2011-08-07 12:50:41.782] client: SyncEvolution 1.1.99.5a+20110716+SE+4131b1b for Maemo
• [2011-08-07 12:50:41.782] device ID: sc-pim-n950-roman
• [2011-08-07 12:50:41.782] Scanning backend libraries in /usr/lib/syncevolution/backends/
  Loading backend library syncxmlrpc.so
  Loading backend library syncaddressbook.so
  Loading backend library synckcalextended.so
  Loading backend library syncfile.so
  Loading backend library syncqtcontacts.so
  Loading backend library syncmaemocal.so
  Loading backend library syncakonadi.so
  Loading backend library syncecal.so
  Loading backend library syncebook.so
  Loading backend library syncdav.so
  Loading backend library syncsqlite.so
• [2011-08-07 12:50:41.793] calendar: inactive
• [2011-08-07 12:50:41.798] todo: inactive
• [2011-08-07 12:50:41.800] memo: inactive
• [2011-08-07 12:50:42.145] QCoreApplication::applicationDirPath: Please instantiate the QApplication object first
• [2011-08-07 12:50:42.146] QCoreApplication::applicationFilePath: Please instantiate the QApplication object first
• [2011-08-07 12:50:42.146] libqtcontacts-tracker: initializing libqtcontacts-tracker 4.14.10-2+0m6 for [2547]
• [2011-08-07 12:50:42.146] available managers (default one first): ("tracker", "memory", "invalid", "simcard", "social")
• [2011-08-07 12:50:42.198] Contacts plugin "/usr/lib/qt4/plugins/contacts/libqtcontacts-simcard.so" has the same name as currently loaded plugin "simcard" ; ignored
• [2011-08-07 12:50:42.199] Contacts plugin "/usr/lib/qt4/plugins/contacts/libqtcontacts-social.so" has the same name as currently loaded plugin "social" ; ignored
• [2011-08-07 12:50:42.199] Contacts plugin "/usr/lib/qt4/plugins/contacts/libqtcontacts_tracker.so" has the same name as currently loaded plugin "tracker" ; ignored
• [2011-08-07 12:50:42.199] "qtcontacts:tracker:" manager supports contact types: ("Contact", "Group") and data types: (QVariant::QUrl , QVariant::int , QVariant::double , QVariant::QString , QVariant::QStringList , QVariant::QDateTime )
• [2011-08-07 12:50:42.364] CreateContext SyncEvolution//addressbook => 0
• [2011-08-07 12:50:42.365] Module_Version = 01080000
• [2011-08-07 12:50:42.365] Module_Capabilities:
  PLATFORM:Linux
  DLL:true
  MINVERSION:V1.0.6.0
  MANUFACTURER:SyncEvolution
  DESCRIPTION:SyncEvolution Synthesis DB Plugin
  plugin_datastore_str:no
  plugin_datastore_key:yes
  ITEM_AS_KEY:yes
  plugin_datablob:no
• [2011-08-07 12:50:42.369] Module_PluginParams
  Engine=01080000
  
• [2011-08-07 12:50:42.370] Module_Capabilities:
  PLATFORM:Linux
  DLL:true
  MINVERSION:V1.0.6.0
  MANUFACTURER:SyncEvolution
  DESCRIPTION:SyncEvolution Synthesis DB Plugin
  plugin_datastore_str:no
  plugin_datastore_key:yes
  ITEM_AS_KEY:yes
  plugin_datablob:no
• [2011-08-07 12:50:42.386] --------- START of embedded log for session ID '212179517442385' ---------
• [2011-08-07 12:50:42.387] Executing Script 'sessioninitscript'
• [2011-08-07 12:50:42.387] ==== Client Session started with SyncML (Server+Client) Engine Version 3.4.0.27
• [2011-08-07 12:50:42.387] ---- Hardcoded Product name: SySync SyncML Library OpenSource Linux
• [2011-08-07 12:50:42.387] ---- Configured Model/Manufacturer: SyncEvolution / Patrick Ohly
• [2011-08-07 12:50:42.388] ---- Running on Linux, URI/deviceID='linux:RM680'
• [2011-08-07 12:50:42.388] ---- Platform Hardware Name/Version = 'Linux PC', Firmware/OS Version = 'unknown'
• [2011-08-07 12:50:42.388] ---- Configured Hardware Version = 'unknown', Firmware Version = '1.1.99.5a+20110716+SE+4131b1b'
• [2011-08-07 12:50:42.389] ---- System local time : 2011-08-07 12:50:42.389 (time zone 'CET/CEST', offset 2:00 hours east of UTC)
• [2011-08-07 12:50:42.389] ---- System time in UTC : 2011-08-07 10:50:42.389
• [2011-08-07 12:50:42.389] ==== Config file='', Last Change=2011-08-07 10:50:42.000
• [2011-08-07 12:50:42.389] ==== Config ID string='SyncEvolution client config'
• [2011-08-07 12:50:42.390] Executing Script 'sessioninitscript'
• +
  –
  [2011-08-07 12:50:42.391] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=idle, newstate=client_initialized [--][++] [->end]
  –[2011-08-07 12:50:42.391] End of 'DSStateChange' [->top]
• [2011-08-07 12:50:42.393] 
  [2011-08-07 12:50:42.392] =================> Starting new client session
• +
  –
  [2011-08-07 12:50:42.393] 'SyncML_Outgoing' - start of new outgoing message [--][++] [->end]
  • [2011-08-07 12:50:42.393] =================> Started new outgoing message
  • [2011-08-07 12:50:42.393] Created command 'SyncHdr' (outgoing)
  • [2011-08-07 12:50:42.394] Target (Remote URI) = 'http://epia.morawek.at:8080/funambol/ds'
  • +
    –
    [2011-08-07 12:50:42.394] 'SyncHdr' - SyncHdr generation, SyncMLVers=1.2, OutgoingMsgID=1 [--][++] [->end] [->enclosing]
    • [2011-08-07 12:50:42.394] SyncHdr: issued in MsgID=1, now queueing for status
    • [2011-08-07 12:50:42.394] Outgoing Message size is now 609 bytes

    –[2011-08-07 12:50:42.395] End of 'SyncHdr' [->top] [->enclosing]
  • +
    –
    [2011-08-07 12:50:42.395] 'MakeAdminReady' - Making Admin Data ready to check sync anchors, localDB=addressbook, remoteDB=card [--][++] [->end] [->enclosing]
    • +
      –
      [2011-08-07 12:50:42.395] 'implMakeAdminReady' - Loading target info and pending maps [--][++] [->end] [->enclosing]
      –[2011-08-07 12:50:42.396] End of 'implMakeAdminReady' [->top] [->enclosing]
    • [2011-08-07 12:50:42.396] First time sync or config changed since last sync -> remote should see our devinf

    –[2011-08-07 12:50:42.396] End of 'MakeAdminReady' [->top] [->enclosing]
  • +
    –
    [2011-08-07 12:50:42.396] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=client_initialized, newstate=admin_ready [--][++] [->end] [->enclosing]
    –[2011-08-07 12:50:42.397] End of 'DSStateChange' [->top] [->enclosing]
  • [2011-08-07 12:50:42.397] Created command 'Put' (outgoing)
  • +
    –
    [2011-08-07 12:50:42.399] 'issue' - issuing command, Cmd=Put [--][++] [->end] [->enclosing]
    • [2011-08-07 12:50:42.402] Put: issued as (outgoing MsgID=1, CmdID=1), now queueing for status
    • [2011-08-07 12:50:42.402] Outgoing Message size is now 13482 bytes

    –[2011-08-07 12:50:42.402] End of 'issue' [->top] [->enclosing]
  • [2011-08-07 12:50:42.403] Nothing known about server, request DevInf using GET command
  • [2011-08-07 12:50:42.403] Created command 'Get' (outgoing)
  • +
    –
    [2011-08-07 12:50:42.403] 'issue' - issuing command, Cmd=Get [--][++] [->end] [->enclosing]
    • [2011-08-07 12:50:42.403] Get: issued as (outgoing MsgID=1, CmdID=2), now queueing for status
    • [2011-08-07 12:50:42.404] Outgoing Message size is now 13647 bytes

    –[2011-08-07 12:50:42.404] End of 'issue' [->top] [->enclosing]
  • [2011-08-07 12:50:42.404] (Saved) Last Local Client Anchor='', (generated) Next Local Client Anchor='20110807T105042Z' (sent to server as <last>/<next> in <alert>)
  • [2011-08-07 12:50:42.404] Created command 'Alert' (outgoing)
  • [2011-08-07 12:50:42.404] addressbook: ALERTING server for slow first time Sync
  • +
    –
    [2011-08-07 12:50:42.404] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=admin_ready, newstate=client_sent_alert [--][++] [->end] [->enclosing]
    –[2011-08-07 12:50:42.405] End of 'DSStateChange' [->top] [->enclosing]
  • +
    –
    [2011-08-07 12:50:42.405] 'issue' - issuing command, Cmd=Alert [--][++] [->end] [->enclosing]
    • [2011-08-07 12:50:42.405] Alert Code 201 sent
    • [2011-08-07 12:50:42.405] - Alert Item: Source='./addressbook', Target='card'
    • [2011-08-07 12:50:42.405] Alert: issued as (outgoing MsgID=1, CmdID=3), now queueing for status
    • [2011-08-07 12:50:42.406] Outgoing Message size is now 13927 bytes

    –[2011-08-07 12:50:42.406] End of 'issue' [->top] [->enclosing]
  • [2011-08-07 12:50:42.406] Ending message with 0 next-message/0 next-package commands: FINAL (final allowed by caller)
  • [2011-08-07 12:50:42.406] Entire message size is now 13955 Bytes

  –[2011-08-07 12:50:42.406] End of 'SyncML_Outgoing' [->top]
• +
  –
  [2011-08-07 12:50:45.170] 'SyncML_Incoming' - Starting to analyze incoming message, RequestNo=0, SySyncVers=3.4.0.27 [--][++] [->end]
  • [2011-08-07 12:50:45.172] =================> Starting to analyze incoming message, SySync V3.4.0.27, RequestNo=0
  • [2011-08-07 12:50:45.172] Created command 'SyncHdr' (incoming)
  • +
    –
    [2011-08-07 12:50:45.173] 'processHdr' - Processing incoming SyncHdr [--][++] [->end] [->enclosing]
    • +
      –
      [2011-08-07 12:50:45.174] 'SyncHdr' - Processing incoming SyncHdr, IncomingMsgID=1 [--][++] [->end] [->enclosing]
      • [2011-08-07 12:50:45.174] Started Processing of message #1 (SyncML/1.2)
      • [2011-08-07 12:50:45.175] Created command 'Status' (outgoing)
      • [2011-08-07 12:50:45.176] RespURI set to = 'http://epia:8080/funambol/ds;jsessionid=89E4ED2FA9B4109AE045CA852AE613F0'
      • +
        –
        [2011-08-07 12:50:45.176] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
        –[2011-08-07 12:50:45.177] End of 'issue' [->top] [->enclosing]
      • [2011-08-07 12:50:45.178] Incoming SyncHdr processed, incomingMsgID=1, SyncMLVers=1.2
      • [2011-08-07 12:50:45.178] - Session ID='40'
      • [2011-08-07 12:50:45.179] - Source (Remote party): URI='http://epia.morawek.at:8080/funambol/ds' DisplayName=''
      • [2011-08-07 12:50:45.179] - Response to be sent to URI='http://epia:8080/funambol/ds;jsessionid=89E4ED2FA9B4109AE045CA852AE613F0'
      • [2011-08-07 12:50:45.180] - Target (Local party) : URI='sc-pim-n950-roman' DisplayName='Linux PC'
      • [2011-08-07 12:50:45.180] Deleted command 'SyncHdr' (incoming MsgID=1, CmdID=0)

      –[2011-08-07 12:50:45.181] End of 'SyncHdr' [->top] [->enclosing]

    –[2011-08-07 12:50:45.181] End of 'processHdr' [->top] [->enclosing]
  • [2011-08-07 12:50:45.182] Created command 'Status' (incoming)
  • +
    –
    [2011-08-07 12:50:45.183] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
    • [2011-08-07 12:50:45.183] Started processing Command 'Status' (incoming MsgID=1, CmdID=1)
    • [2011-08-07 12:50:45.184] RECEIVED STATUS 212 for for command 'SyncHdr' (outgoing MsgID=1, CmdID=0)
    • [2011-08-07 12:50:45.184] - TargetRef (remoteID) = 'http://epia.morawek.at:8080/funambol/ds'
    • [2011-08-07 12:50:45.185] - SourceRef (localID) = 'sc-pim-n950-roman'
    • [2011-08-07 12:50:45.185] Found matching command 'SyncHdr' for Status
    • [2011-08-07 12:50:45.186] Remote accepted authentication for entire session
    • [2011-08-07 12:50:45.186] Authenticated successfully with remote server

    –[2011-08-07 12:50:45.187] End of 'processStatus' [->top] [->enclosing]
  • +
    –
    [2011-08-07 12:50:45.187] 'SyncML_Outgoing' - start of new outgoing message [--][++] [->end] [->enclosing]
    • [2011-08-07 12:50:45.188] =================> Started new outgoing message
    • [2011-08-07 12:50:45.188] Created command 'SyncHdr' (outgoing)
    • [2011-08-07 12:50:45.189] Target (Remote URI) = 'http://epia:8080/funambol/ds;jsessionid=89E4ED2FA9B4109AE045CA852AE613F0'
    • +
      –
      [2011-08-07 12:50:45.189] 'SyncHdr' - SyncHdr generation, SyncMLVers=1.2, OutgoingMsgID=2 [--][++] [->end] [->enclosing]
      • [2011-08-07 12:50:45.190] SyncHdr: issued in MsgID=2, now queueing for status
      • [2011-08-07 12:50:45.191] Outgoing Message size is now 493 bytes
      • +
        –
        [2011-08-07 12:50:45.191] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
        • [2011-08-07 12:50:45.192] Status Code 200 issued for Cmd=SyncHdr, (incoming MsgID=1, CmdID=0)
        • [2011-08-07 12:50:45.193] - TargetRef (localID) = 'sc-pim-n950-roman'
        • [2011-08-07 12:50:45.193] - SourceRef (remoteID) = 'http://epia.morawek.at:8080/funambol/ds'
        • [2011-08-07 12:50:45.194] Status: issued as (outgoing MsgID=2, CmdID=1), not waiting for status
        • [2011-08-07 12:50:45.194] Deleted command 'Status' (outgoing MsgID=2, CmdID=1)
        • [2011-08-07 12:50:45.195] Outgoing Message size is now 698 bytes

        –[2011-08-07 12:50:45.195] End of 'issue' [->top] [->enclosing]

      –[2011-08-07 12:50:45.196] End of 'SyncHdr' [->top] [->enclosing]
    • +
      –
      [2011-08-07 12:50:45.196] 'processStatus' - finishing processing incoming SyncHdr Status [--][++] [->end] [->enclosing]
      • [2011-08-07 12:50:45.197] Deleted command 'SyncHdr' (outgoing MsgID=1, CmdID=0)
      • [2011-08-07 12:50:45.197] Deleted command 'Status' (incoming MsgID=1, CmdID=1)

      –[2011-08-07 12:50:45.198] End of 'processStatus' [->top] [->enclosing]
    • [2011-08-07 12:50:45.200] Created command 'Status' (incoming)
    • +
      –
      [2011-08-07 12:50:45.200] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
      • [2011-08-07 12:50:45.201] Started processing Command 'Status' (incoming MsgID=1, CmdID=2)
      • [2011-08-07 12:50:45.201] RECEIVED STATUS 200 for for command 'Put' (outgoing MsgID=1, CmdID=1)
      • [2011-08-07 12:50:45.202] - SourceRef (localID) = './devinf12'
      • [2011-08-07 12:50:45.202] Found matching command 'Put' for Status
      • [2011-08-07 12:50:45.203] Status: 200: successful --> accept as ok
      • [2011-08-07 12:50:45.203] Deleted command 'Put' (outgoing MsgID=1, CmdID=1)
      • [2011-08-07 12:50:45.204] Deleted command 'Status' (incoming MsgID=1, CmdID=2)

      –[2011-08-07 12:50:45.204] End of 'processStatus' [->top] [->enclosing]
    • [2011-08-07 12:50:45.206] Created command 'Status' (incoming)
    • +
      –
      [2011-08-07 12:50:45.207] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
      • [2011-08-07 12:50:45.207] Started processing Command 'Status' (incoming MsgID=1, CmdID=3)
      • [2011-08-07 12:50:45.208] RECEIVED STATUS 200 for for command 'Get' (outgoing MsgID=1, CmdID=2)
      • [2011-08-07 12:50:45.208] - TargetRef (remoteID) = './devinf12'
      • [2011-08-07 12:50:45.209] Found matching command 'Get' for Status
      • [2011-08-07 12:50:45.209] Status: 200: successful --> accept as ok
      • [2011-08-07 12:50:45.210] Deleted command 'Get' (outgoing MsgID=1, CmdID=2)
      • [2011-08-07 12:50:45.210] Deleted command 'Status' (incoming MsgID=1, CmdID=3)

      –[2011-08-07 12:50:45.211] End of 'processStatus' [->top] [->enclosing]
    • [2011-08-07 12:50:45.213] Created command 'Status' (incoming)
    • +
      –
      [2011-08-07 12:50:45.213] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
      • [2011-08-07 12:50:45.214] Started processing Command 'Status' (incoming MsgID=1, CmdID=4)
      • [2011-08-07 12:50:45.214] RECEIVED STATUS 200 for for command 'Alert' (outgoing MsgID=1, CmdID=3)
      • [2011-08-07 12:50:45.215] - TargetRef (remoteID) = 'card'
      • [2011-08-07 12:50:45.215] - SourceRef (localID) = './addressbook'
      • [2011-08-07 12:50:45.216] - Item data =
      • [2011-08-07 12:50:45.216] Found matching command 'Alert' for Status
      • +
        –
        [2011-08-07 12:50:45.217] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=client_sent_alert, newstate=client_alert_statused [--][++] [->end] [->enclosing]
        –[2011-08-07 12:50:45.217] End of 'DSStateChange' [->top] [->enclosing]
      • [2011-08-07 12:50:45.218] Deleted command 'Alert' (outgoing MsgID=1, CmdID=3)
      • [2011-08-07 12:50:45.218] Deleted command 'Status' (incoming MsgID=1, CmdID=4)

      –[2011-08-07 12:50:45.219] End of 'processStatus' [->top] [->enclosing]
    • [2011-08-07 12:50:45.222] Created command 'Results' (incoming)
    • [2011-08-07 12:50:45.222] Started processing Command 'Results' (incoming MsgID=1, CmdID=5)
    • +
      –
      [2011-08-07 12:50:45.223] 'processCmd' - Processing incoming command, Cmd=Results, IncomingMsgID=1, CmdID=5 [--][++] [->end] [->enclosing]
      • [2011-08-07 12:50:45.224] processing item with locURI=./devinf12
      • [2011-08-07 12:50:45.224] Created command 'Status' (outgoing)
      • +
        –
        [2011-08-07 12:50:45.225] 'DevInf_Analyze' - Analyzing remote devInf [--][++] [->end] [->enclosing]
        • [2011-08-07 12:50:45.226] Device ID='funambol', Type='server', Model='DS Server'
        • [2011-08-07 12:50:45.226] Manufacturer='Funambol', OEM='-'
        • [2011-08-07 12:50:45.227] Softwarevers='8.0.1', Firmwarevers='-', Hardwarevers='-'
        • [2011-08-07 12:50:45.227] SyncML Version: SyncML/1.2
        • [2011-08-07 12:50:45.228] SyncML capability flags: wantsNOC=Yes, canHandleUTC=Yes, supportsLargeObjs=Yes
        • +
          –
          [2011-08-07 12:50:45.228] 'RemoteRules' - Checking for remote rules [--][++] [->end] [->enclosing]
          –[2011-08-07 12:50:45.229] End of 'RemoteRules' [->top] [->enclosing]
        • [2011-08-07 12:50:45.229] Summary of all behaviour options (possibly modified by remote rule(s))
        • [2011-08-07 12:50:45.230] - Remote Description : Funambol DS Server
        • [2011-08-07 12:50:45.230] - Legacy mode : No
        • [2011-08-07 12:50:45.231] - Lenient mode : No
        • [2011-08-07 12:50:45.231] - Limited Field Lengths : No
        • [2011-08-07 12:50:45.232] - Do not send empty props : No
        • [2011-08-07 12:50:45.232] - Quote 8bit content : No
        • [2011-08-07 12:50:45.233] - Prevent Content Folding : Yes
        • [2011-08-07 12:50:45.233] - No replace in slowsync : No
        • [2011-08-07 12:50:45.233] - Treat remote TZ as local : No
        • [2011-08-07 12:50:45.234] - Treat remote TZ as UTC : No
        • [2011-08-07 12:50:45.234] - Use 23:59:59 end dates : Yes
        • [2011-08-07 12:50:45.235] - Ignore field maxSize : No
        • [2011-08-07 12:50:45.235] - Ignore CTCap : No
        • [2011-08-07 12:50:45.236] - send DS path in devInf : Yes
        • [2011-08-07 12:50:45.236] - send DS CGI in devInf : Yes
        • [2011-08-07 12:50:45.237] - Update Client in slowsync : No
        • [2011-08-07 12:50:45.237] - Update Server in slowsync : No
        • [2011-08-07 12:50:45.238] - Allow message retries : Yes
        • [2011-08-07 12:50:45.238] - Strict SyncML exec order : Yes
        • [2011-08-07 12:50:45.239] - Treat copy like add : No
        • [2011-08-07 12:50:45.239] - Complete From-Client-Only : No
        • [2011-08-07 12:50:45.240] - Remote can handle UTC : Yes
        • [2011-08-07 12:50:45.240] - Max Request time [sec] : 0
        • [2011-08-07 12:50:45.241] - Content output charset : UTF-8
        • [2011-08-07 12:50:45.241] - Content input charset : UTF-8
        • +
          –
          [2011-08-07 12:50:45.242] 'RemoteDatastores' - Analyzing remote datastores [--][++] [->end] [->enclosing]
          • +
            –
            [2011-08-07 12:50:45.242] 'RemoteDSDevInf' - Registering remote Datastore from devInf [--][++] [->end] [->enclosing]
            • [2011-08-07 12:50:45.243] Remote Datastore Name='card', DisplayName='card', MaxGUIDSize=32
            • [2011-08-07 12:50:45.243] Preferred Rx='text/x-vcard' version '2.1', preferred Tx='text/x-vcard' version '2.1'
            • +
              –
              [2011-08-07 12:50:45.244] 'RemoteTypes' - Analyzing remote types listed in datastore level CTCap [--][++] [->end] [->enclosing]
              –[2011-08-07 12:50:45.245] End of 'RemoteTypes' [->top] [->enclosing]
            • [2011-08-07 12:50:45.246] Registered Type 'text/x-vcard' Version='2.1', implemented by local type vCard21, related to remote datastore 'card'

            –[2011-08-07 12:50:45.246] End of 'RemoteDSDevInf' [->top] [->enclosing]

          –[2011-08-07 12:50:45.247] End of 'RemoteDatastores' [->top] [->enclosing]

        –[2011-08-07 12:50:45.247] End of 'DevInf_Analyze' [->top] [->enclosing]
      • +
        –
        [2011-08-07 12:50:45.248] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
        • [2011-08-07 12:50:45.249] Status Code 200 issued for Cmd=Results, (incoming MsgID=1, CmdID=5)
        • [2011-08-07 12:50:45.249] - SourceRef (remoteID) = './devinf12'
        • [2011-08-07 12:50:45.250] Status: issued as (outgoing MsgID=2, CmdID=2), not waiting for status
        • [2011-08-07 12:50:45.250] Deleted command 'Status' (outgoing MsgID=2, CmdID=2)
        • [2011-08-07 12:50:45.251] Outgoing Message size is now 834 bytes

        –[2011-08-07 12:50:45.251] End of 'issue' [->top] [->enclosing]
      • [2011-08-07 12:50:45.252] Deleted command 'Results' (incoming MsgID=1, CmdID=5)

      –[2011-08-07 12:50:45.252] End of 'processCmd' [->top] [->enclosing]
    • [2011-08-07 12:50:45.254] Created command 'Alert' (incoming)
    • [2011-08-07 12:50:45.255] Started processing Command 'Alert' (incoming MsgID=1, CmdID=6)
    • +
      –
      [2011-08-07 12:50:45.255] 'processCmd' - Processing incoming command, Cmd=Alert, IncomingMsgID=1, CmdID=6 [--][++] [->end] [->enclosing]
      • [2011-08-07 12:50:45.256] Code=201. No Cred. Analyzing Items
      • [2011-08-07 12:50:45.257] MaxObjSize found in Alert command: 4000000
      • [2011-08-07 12:50:45.257] Created command 'Status' (outgoing)
      • [2011-08-07 12:50:45.258] - Processing Alert Item (code=201), Source='card', Target='./addressbook'
      • [2011-08-07 12:50:45.258] Saved Last Remote Server Anchor='', received <last> Remote Server Anchor='1312714233290' (must match for normal sync)
      • [2011-08-07 12:50:45.259] Received <next> Remote Server Anchor='1312714233290' (to be compared with <last> in NEXT session)
      • [2011-08-07 12:50:45.259] (Saved) fResumeAlertCode = 0 (valid for >DS 1.2 only)
      • +
        –
        [2011-08-07 12:50:45.260] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=client_alert_statused, newstate=client_alerted [--][++] [->end] [->enclosing]
        –[2011-08-07 12:50:45.261] End of 'DSStateChange' [->top] [->enclosing]
      • [2011-08-07 12:50:45.261] Alerted (code=201) for from server only Refresh (Refreshonly)
      • +
        –
        [2011-08-07 12:50:45.262] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=client_alerted, newstate=sync_mode_stable [--][++] [->end] [->enclosing]
        • [2011-08-07 12:50:45.262] executing slow first time Sync from server

        –[2011-08-07 12:50:45.263] End of 'DSStateChange' [->top] [->enclosing]
      • [2011-08-07 12:50:45.263] ALERTED from server for slow first time Sync
      • +
        –
        [2011-08-07 12:50:45.264] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
        • [2011-08-07 12:50:45.265] Status Code 200 issued for Cmd=Alert, (incoming MsgID=1, CmdID=6)
        • [2011-08-07 12:50:45.265] - TargetRef (localID) = './addressbook'
        • [2011-08-07 12:50:45.266] - SourceRef (remoteID) = 'card'
        • [2011-08-07 12:50:45.266] Status: issued as (outgoing MsgID=2, CmdID=3), not waiting for status
        • [2011-08-07 12:50:45.267] Deleted command 'Status' (outgoing MsgID=2, CmdID=3)
        • [2011-08-07 12:50:45.268] Outgoing Message size is now 1089 bytes

        –[2011-08-07 12:50:45.269] End of 'issue' [->top] [->enclosing]
      • [2011-08-07 12:50:45.269] Deleted command 'Alert' (incoming MsgID=1, CmdID=6)

      –[2011-08-07 12:50:45.271] End of 'processCmd' [->top] [->enclosing]
    • [2011-08-07 12:50:45.273] addressbook: starting first time sync from server
    • [2011-08-07 12:50:45.277] =================> Finished processing incoming message #1 (final), request=0
    • [2011-08-07 12:50:45.278] MessageEnded finishes : new outgoing state='sync', new incoming state='sync', NeedToAnswer
    • [2011-08-07 12:50:45.279] Local Datastore 'addressbook': State=sync_mode_stable, SLOW sync, from server only
    • [2011-08-07 12:50:45.281] Local Datastore 'addressbook' - Types: tx to remote: 'vCard21': text/x-vcard (2.1), rx from remote: 'vCard21': text/x-vcard (2.1)
    • +
      –
      [2011-08-07 12:50:45.281] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=sync_mode_stable, newstate=data_access_started [--][++] [->end] [->enclosing]
      • [2011-08-07 12:50:45.284] TStdLogicDS::startDataAccessForClient
      • +
        –
        [2011-08-07 12:50:45.285] 'ReadSyncSet' - Reading Sync Set from Database, datastore=addressbook [--][++] [->end] [->enclosing]
        • [2011-08-07 12:50:45.286] 'addressbook' dev='anydevice' usr='singleuser' err=0
        • [2011-08-07 12:50:45.287] FilterSupport staticfilter:
          dynamicfilter:
          invisiblefilter:
        • [2011-08-07 12:50:45.288] ContextSupport ReadNextItem:allfields
        • [2011-08-07 12:50:45.288] Executing Script 'initscript'
        • [2011-08-07 12:50:45.289] Now reading local sync set: report changes since reference1 at <no time>, and since reference2 at <no time>
        • [2011-08-07 12:50:45.296] creating /home/user/.cache/syncevolution/epia-2011-08-07-12-50-a/addressbook.before