Start of log - Synthesis SyncML Engine 3.4.0.53

• [-- collapse all --][++ expand all ++]
• TSyncSession::TSyncSession: calling InternalResetSession
• TSyncSession::TSyncSession: InternalResetSession called
• ==== Client Session started with SyncML (Client) Engine Version 3.4.0.53
• ---- Hardcoded Product name: SySync Client Library STD ANDROID
• ---- Configured Model/Manufacturer: SySync Client PocketPC PRO / Synthesis AG
• ---- Running on Android, URI/deviceID='1cb094accb4a-359901043542659'
• ---- Platform Hardware Name/Version = 'Android Device', Firmware/OS Version = 'unknown'
• ---- Configured Hardware Version = 'Android HTC One S', Firmware Version = 'Android/4.0.4'
• ---- System local time : 2012-11-21 16:02:19.452 (time zone 'Europe/Zurich', offset 1:00 hours east of UTC)
• Offset in Minutes east of UTC: 60
• ---- System time in UTC : 2012-11-21 15:02:19.452
• ---- System time zone offset per 2012-02-01 = 1:00 (=60 mins)
• ---- System time zone offset per 2012-08-01 = 2:00 (=120 mins)
• ==== Config file='', Last Change=<no time>
• ==== Config ID string='uiapp client'
• ##### DBApi (LNK): Session_CreateContext: desc='JNI bridge to Java', vers=V1.9.3.0
• (1DD7E08) JNI (LNK): Session_CreateContext: n=0 tCB=022CA200 cC=36257368 mC=36257368 sC=36277416
• (1DD7E08) JNI (LNK): Session_CreateContext: > '212220313339451' sContext=36277416
• ##### JNI (LNK): Threads: old=4014F588 => new=01DD7E08 (main=4014F588) chg
• ##### JNI (LNK): JVars: fndElem=0 env=01C4C6D0
• ##### JNI (LNK): AttachCurrentThread: vm=01C4C7F8 env=01C4C6D0
• ##### JNI (LNK): AttachCurrentThread: env=022C0718 (prev=01C4C6D0)
• ##### session_plugin (212220313339451) Session_CreateContext
• ##### session_plugin (212220313339451) Session_CreateContext: cC=36257368 / mC=36257368 / sC=36277416 / flags=ffff / aCB=22ca200 / context=3
• (1DD7E08) JNI (LNK): Session_CreateContext: n=1 tCB=022CA200 cC=36257368 mC=36257368 sC=36277416
• (1DD7E08) JNI (LNK): Session_CreateContext: < '212220313339451' jsContext=3
• ##### uiapp SDK Engine: V1.9.3 'Android'
• ##### uiapp SDK Interface: V1.9.3
• ##### uiapp PRG Version: V2.4.12
• ##### uiapp stat: show=o pro=v flags=ffff
• ##### uiapp pkg: name='com.sysync' / market=o
• ##### uiapp wifi: name='semspace' / status=3 / wifiReq=v / during tsync=o / ignore SSL=v
• ##### uiapp hidden: serverUsr_attr=''
• ##### uiapp screen: width=540 / height=960 / densitiy=1.5
• ##### uiapp force UID: gmail=o / others=o
• ##### uiapp CON: photoMode=1
• ##### uiapp CAL: birthdays=v / tasks=v / astridAllowed=v / astridTasks=o
• ##### uiapp DOC: typestring='application/vnd.omads-file+xml'
• ##### uiapp flags: legacyMode=o / syncAttToCli=o / syncAttToSrv=o / allDayFlag=v / multiProfile=o
• ##### uiapp VERS: custom.vSDK=15 / OLDC=o / COXX=both / CAXX=both / exotic=v
• ##### uiapp more: msg_dump=v / useragent=v / userzone in=v / out=v / javazone out=o / maxmsgsize=50000 / clrtxtPW=o
• ##### uiapp o --- 1001 local='contacts': remote='contacts' / pluginName='[JNI]!/com/sysync/cc_contacts'
• ##### uiapp v --- 1002 local='events': remote='calendar' / pluginName='[JNI]!/com/sysync/ds_calendar' ==> slow to device
• ##### uiapp o crc 1003 local='tasks': remote='tasks' / pluginName='[JNI]!/com/sysync/ds_tasks'
• ##### uiapp o crc 1004 local='sms': remote='sms' / pluginName='[JNI]!/com/sysync/ds_sms'
• ##### uiapp o crc 1005 local='notes': remote='notes' / pluginName='[JNI]!/com/sysync/ds_notes'
• ##### uiapp o crc 1006 local='raw': remote='raw' / pluginName='[JNI]!/com/sysync/ds_raw'
• ##### uiapp o --- 1007 local='files': remote='files' / pluginName='[JNI]!/com/sysync/ds_files'
• ##### uiapp o --- 1008 local='documents': remote='documents' / pluginName='[JNI]!/com/sysync/ds_docs'
• ##### uiapp o crc 1009 local='example': remote='example' / pluginName='[JNI]!/com/sysync/ds_example'
• +
  –
  [2012-11-21 16:02:19.459] 'V_DB_Disp' -  [--][++] [->end]
  • ##### uiapp V_DB_Disp: f cnt: '20596'
  • ##### uiapp V_DB_Disp: f ldc: '0'
  • ##### uiapp V_DB_Disp: f dev: '1cb094accb4a-359901043542659'
  • ##### uiapp V_DB_Disp: f ast: '0'
  • ##### uiapp V_DB_Disp: f prf: '4'
  • ##### uiapp V_DB_Disp: f tto: '1'
  • ##### uiapp V_DB_Disp: f tsb: '2'
  • ##### uiapp V_DB_Disp: f nto: '0'
  • ##### uiapp V_DB_Disp: f ads: '0'
  • ##### uiapp V_DB_Disp: f mpo: '0'
  • ##### uiapp V_DB_Disp: f dne: '0'
  • ##### uiapp V_DB_Disp: f dte: '1'
  • ##### uiapp V_DB_Disp: f uam: '0'
  • ##### uiapp V_DB_Disp: f llc: '10'
  • ##### uiapp V_DB_Disp: f dct: '0'
  • ##### uiapp V_DB_Disp: f wct: '1'
  • ##### uiapp V_DB_Disp: f wcn: '1'
  • ##### uiapp V_DB_Disp: f uid: '1'
  • ##### uiapp V_DB_Disp: f dpa_3: '/sdcard/sync'
  • ##### uiapp V_DB_Disp: f lst: '1353510085531'
  • ##### uiapp V_DB_Disp: f cku: '2456259'
  • ##### uiapp V_DB_Disp: f nxa: '1353513600000'
  • ##### uiapp V_DB_Disp: f lgn: '157'
  • ##### uiapp V_DB_Disp: f fpa_4: '/sdcard/sync'
  • ##### uiapp V_DB_Disp: f dpa_4: '/sdcard/sync'
  • ##### uiapp V_DB_Disp: f events_NID: '22964'
  • ##### uiapp V_DB_Disp: f dpa_2: '/sdcard/sync'
  • ##### uiapp V_DB_Disp: f lat: '1353435017003'
  • ##### uiapp V_DB_Disp: f profile: '2'
  • ##### uiapp V_DB_Disp: f trt: '-'
  • ##### uiapp V_DB_Disp: f brt: '-12.0'
  • ##### uiapp V_DB_Disp: f sme: '0'
  • ##### uiapp V_DB_Disp: f mxm: '50000'
  • ##### uiapp V_DB_Disp: f dft: '0'
  • ##### uiapp V_DB_Disp: f mxm_2: '50000'
  • ##### uiapp V_DB_Disp: f mxm_4: '50000'
  • ##### uiapp V_DB_Disp: f mxm_3: '50000'
  • ##### uiapp V_DB_Disp: r pup: ''
  • ##### uiapp V_DB_Disp: r usr: ''
  • ##### uiapp V_DB_Disp: r tok: ''
  • ##### uiapp V_DB_Disp: r org: ''
  • ##### uiapp V_DB_Disp: r sup: 'tk@ontopica.de'
  • ##### uiapp V_DB_Disp: r flt: ''
  • ##### uiapp V_DB_Disp: r mxm_0: ''
  • ##### uiapp V_DB_Disp: r fpa_0: ''
  • ##### uiapp V_DB_Disp: r fse_0: ''
  • ##### uiapp V_DB_Disp: r dpa_0: ''
  • ##### uiapp V_DB_Disp: r dse_0: ''
  • ##### uiapp V_DB_Disp: w fse_4: ''
  • ##### uiapp V_DB_Disp: w dse_4: ''
  • ##### uiapp V_DB_Disp: r ver: ''
  • ##### uiapp V_DB_Disp: r ida: ''

  –[2012-11-21 16:02:19.469] End of 'V_DB_Disp' [->top]
• TSyncSession::ResetSession: calling InternalResetSession
• TSyncSession::ResetSession: InternalResetSession called
• +
  –
  [2012-11-21 16:02:19.470] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=idle, newstate=client_initialized [--][++] [->end]
  –[2012-11-21 16:02:19.470] End of 'DSStateChange' [->top]
• ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
• ##### uiapp ReportStep: 101 15 0 0 0 0 / err=0
• ##### uiapp ReportStep: 100 15 0 0 0 0 / err=0
• 
  [2012-11-21 16:02:19.471] =================> Starting new client session
• Initializing outgoing XML translation instance
• +
  –
  [2012-11-21 16:02:19.471] 'SyncML_Outgoing' - start of new outgoing message [--][++] [->end]
  • =================> Started new outgoing message
  • Created command 'SyncHdr' (outgoing)
  • Target (Remote URI) = 'https://webmail.your-server.de/rpc.php'
  • Authenticating with server as user 'office@ontopica.de'
  • - using nonce ''
  • +
    –
    [2012-11-21 16:02:19.471] 'SyncHdr' - SyncHdr generation, SyncMLVers=1.2, OutgoingMsgID=1 [--][++] [->end] [->enclosing]
    • SyncHdr: issued in MsgID=1, now queueing for status
    • Outgoing Message size is now 249 bytes

    –[2012-11-21 16:02:19.471] End of 'SyncHdr' [->top] [->enclosing]
  • Rebuilding - alertprepscript, ctx=0x229B218, VarDefs:
  • +
    –
    [2012-11-21 16:02:19.472] 'ScriptExecute' - Start executing Script, name=alertprepscript [--][++] [->end] [->enclosing]
    • Line 1658:
    • Line 1659:         // pass on configured filter expressions
    • Line 1660:         setrecordfilter(targetsetting("remoteFilters"),false); // remote, exclusive
    • - SETRECORDFILTER() built-in function call:
    • PrepareLocals - <name unknown>, ctx=0x229AC70, VarDefs:
    • 0: string
    • 1: integer
    • - TARGETSETTING() built-in function call:
    • PrepareLocals - <name unknown>, ctx=0x229AEC0, VarDefs:
    • 0: string
    • - Parameter #1 (by value) = "remoteFilters" (string)
    • - TARGETSETTING() function result = <empty> (string)
    • - Parameter #1 (by value) = <empty> (string)
    • - Parameter #2 (by value) = 0 (integer)
    • - SETRECORDFILTER() function result = <NO FIELD>
    • Line 1661:         ADDFILTER(targetsetting("localFilters")); // local
    • - ADDFILTER() built-in function call:
    • PrepareLocals - <name unknown>, ctx=0x229AC70, VarDefs:
    • 0: string
    • - TARGETSETTING() built-in function call:
    • PrepareLocals - <name unknown>, ctx=0x229AEC0, VarDefs:
    • 0: string
    • - Parameter #1 (by value) = "localFilters" (string)
    • - TARGETSETTING() function result = <empty> (string)
    • - Parameter #1 (by value) = <empty> (string)
    • - ADDFILTER() function result = <NO FIELD>
    • Line 1662:         // If a day range is selected and enabled in the settigs, apply it for the current sync
    • Line 1663:         if (targetsetting("extras") & 1 == 1) {
    • - TARGETSETTING() built-in function call:
    • PrepareLocals - <name unknown>, ctx=0x229AC70, VarDefs:
    • 0: string
    • - Parameter #1 (by value) = "extras" (string)
    • - TARGETSETTING() function result = 0 (integer)
    • - IF condition is false
    • Line 1664:           setdaysrange(
    • Line 1665:             targetsetting("limit1"),
    • Line 1666:             targetsetting("limit2")
    • Line 1667:           );
    • Line 1668:         }
    • - End of skipped IF

    –[2012-11-21 16:02:19.473] End of 'ScriptExecute' [->top] [->enclosing]
  • +
    –
    [2012-11-21 16:02:19.474] 'MakeAdminReady' - Making Admin Data ready to check sync anchors, localDB=events, remoteDB=calendar [--][++] [->end] [->enclosing]
    • +
      –
      [2012-11-21 16:02:19.474] 'implMakeAdminReady' - Loading target info and pending maps [--][++] [->end] [->enclosing]
      • implMakeAdminReady(binfile): ResumeAlertCode=201, PreviousSuspendModCount=2, PreviousToRemoteModCount=0, LastRemoteAnchor=''
      • - last preflight update (fPreviousToRemoteSyncCmpRef) at 2012-11-21 15:01:21.301
      • - last preflight update (fPreviousToRemoteSyncIdentifier) is ''
      • openPendingMaps: file name='/data/data/com.sysync/syncml_client_sysynclib/events_4_pmap_sysynclib_.bfi'
      • implMakeAdminReady: remotePartyID of pendingmaps=4, current profile's remotepartyID=4
      • implMakeAdminReady: loaded 0 pending add maps
      • opening pending item file: file name='/data/data/com.sysync/syncml_client_sysynclib/events_4_pitm_sysynclib_.bfi'
      • implMakeAdminReady: loaded pending item with 0 data bytes

      –[2012-11-21 16:02:19.474] End of 'implMakeAdminReady' [->top] [->enclosing]
    • First time sync or config changed since last sync -> remote should see our devinf

    –[2012-11-21 16:02:19.475] End of 'MakeAdminReady' [->top] [->enclosing]
  • +
    –
    [2012-11-21 16:02:19.475] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=client_initialized, newstate=admin_ready [--][++] [->end] [->enclosing]
    –[2012-11-21 16:02:19.475] End of 'DSStateChange' [->top] [->enclosing]
  • Found suspended session with Alert Code = 201
  • Created command 'Put' (outgoing)
  • +
    –
    [2012-11-21 16:02:19.475] 'issue' - issuing command, Cmd=Put [--][++] [->end] [->enclosing]
    • Command 'Put': is 0-th counted cmd, cmdsize(+tags needed to end msg)=1732, available=99751 (maxfree=99751, freeaftersend=98019, notUsableBufferBytes()=0)
    • Put: issued as (outgoing MsgID=1, CmdID=1), now queueing for status
    • Outgoing Message size is now 1979 bytes

    –[2012-11-21 16:02:19.477] End of 'issue' [->top] [->enclosing]
  • Nothing known about server, request DevInf using GET command
  • Created command 'Get' (outgoing)
  • +
    –
    [2012-11-21 16:02:19.478] 'issue' - issuing command, Cmd=Get [--][++] [->end] [->enclosing]
    • Command 'Get': is 1-th counted cmd, cmdsize(+tags needed to end msg)=72, available=98021 (maxfree=98021, freeaftersend=97949, notUsableBufferBytes()=0)
    • Get: issued as (outgoing MsgID=1, CmdID=2), now queueing for status
    • Outgoing Message size is now 2049 bytes

    –[2012-11-21 16:02:19.478] End of 'issue' [->top] [->enclosing]
  • (Saved) Last Local Client Anchor='', (generated) Next Local Client Anchor='20121121T150219Z' (sent to server as <last>/<next> in <alert>)
  • Alerting resume of last sync session (original alert code = 201)
  • Created command 'Alert' (outgoing)
  • events: ALERTING server for RESUMED slow first time Sync
  • +
    –
    [2012-11-21 16:02:19.478] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=admin_ready, newstate=client_sent_alert [--][++] [->end] [->enclosing]
    –[2012-11-21 16:02:19.478] End of 'DSStateChange' [->top] [->enclosing]
  • +
    –
    [2012-11-21 16:02:19.478] 'issue' - issuing command, Cmd=Alert [--][++] [->end] [->enclosing]
    • Command 'Alert': is 2-th counted cmd, cmdsize(+tags needed to end msg)=84, available=97951 (maxfree=97951, freeaftersend=97867, notUsableBufferBytes()=0)
    • Alert Code 225 sent
    • - Alert Item: Source='./events', Target='calendar'
    • Alert: issued as (outgoing MsgID=1, CmdID=3), now queueing for status
    • Outgoing Message size is now 2131 bytes

    –[2012-11-21 16:02:19.479] End of 'issue' [->top] [->enclosing]
  • Ending message with 0 next-message/0 next-package commands: FINAL (final allowed by caller)
  • XML translation enabled...
  • - Writing outgoing XML translation
  • Outgoing WBXML message msgID=1 saved as XML translation
  • Entire message size is now 2136 Bytes
  • ---> NextMessage, outgoing state='init', incoming state='idle'
  • Local Datastore 'events': State=client_sent_alert, RESUMED SLOW sync, from server only

  –[2012-11-21 16:02:19.480] End of 'SyncML_Outgoing' [->top]
• ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
• ##### uiapp ReportStep: 101 6 0 0 0 0 / err=0
• ##### uiapp ReportStep: 110 6 0 0 0 0 / err=0
• ##### uiapp DoPost URL: https://webmail.your-server.de/rpc.php (redirect=o withHttp=v n=1)
• ##### uiapp POST 1 =>: size=2136
• ##### uiapp DoPost https: err=0
• ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
• ##### uiapp ReportStep: 101 7 0 0 0 0 / err=0
• ##### uiapp ReportStep: 111 7 0 0 0 0 / err=0
• ##### uiapp POST 1 <=: size=1385
• ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
• ##### uiapp ReportStep: 101 9 0 0 0 0 / err=0
• ##### uiapp ReportStep: 100 9 0 0 0 0 / err=0
• Calling smlProcessData(NEXT_COMMAND)
• +
  –
  [2012-11-21 16:02:20.153] 'SyncML_Incoming' - Starting to analyze incoming message, RequestNo=0, SySyncVers=3.4.0.53 [--][++] [->end]
  • =================> Starting to analyze incoming message, SySync V3.4.0.53, RequestNo=0
  • Initializing incoming XML translation instance
  • Created command 'SyncHdr' (incoming)
  • +
    –
    [2012-11-21 16:02:20.153] 'processHdr' - Processing incoming SyncHdr [--][++] [->end] [->enclosing]
    • +
      –
      [2012-11-21 16:02:20.153] 'SyncHdr' - Processing incoming SyncHdr, IncomingMsgID=1 [--][++] [->end] [->enclosing]
      • Started Processing of message #1 (SyncML/1.2)
      • MaxMsgSize for outgoing msgs set to 1000000000
      • MaxObjSize found in SyncHdr: 1000000000
      • Created command 'Status' (outgoing)
      • RespURI set to = 'https://webmail.your-server.de/rpc.php'
      • +
        –
        [2012-11-21 16:02:20.154] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
        • Outgoing message header not yet generated, command 'Status' queued

        –[2012-11-21 16:02:20.154] End of 'issue' [->top] [->enclosing]
      • Incoming SyncHdr processed, incomingMsgID=1, SyncMLVers=1.2
      • - Session ID='227'
      • - Source (Remote party): URI='https://webmail.your-server.de/rpc.php' DisplayName=''
      • - Response to be sent to URI='https://webmail.your-server.de/rpc.php'
      • - Target (Local party) : URI='1cb094accb4a-359901043542659' DisplayName='Android Device'
      • SyncHdr: finished execution -> deleting
      • Deleted command 'SyncHdr' (incoming MsgID=1, CmdID=0)
      • New message: Executing 0 delayed commands
      • 0 delayed commands could not yet be executed and are left in the queue for next message
      • New package: Sending 0 commands that were generated earlier for this package

      –[2012-11-21 16:02:20.154] End of 'SyncHdr' [->top] [->enclosing]

    –[2012-11-21 16:02:20.154] End of 'processHdr' [->top] [->enclosing]
  • Created command 'Status' (incoming)
  • +
    –
    [2012-11-21 16:02:20.155] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
    • Started processing Command 'Status' (incoming MsgID=1, CmdID=1)
    • RECEIVED STATUS 212 for for command 'SyncHdr' (outgoing MsgID=1, CmdID=0)
    • - TargetRef (remoteID) = 'https://webmail.your-server.de/rpc.php'
    • - SourceRef (localID) = '1cb094accb4a-359901043542659'
    • Found matching command 'SyncHdr' for Status
    • Remote accepted authentication for entire session
    • Authenticated successfully with remote server

    –[2012-11-21 16:02:20.155] End of 'processStatus' [->top] [->enclosing]
  • Initializing outgoing XML translation instance
  • +
    –
    [2012-11-21 16:02:20.155] 'SyncML_Outgoing' - start of new outgoing message [--][++] [->end] [->enclosing]
    • =================> Started new outgoing message
    • Created command 'SyncHdr' (outgoing)
    • Target (Remote URI) = 'https://webmail.your-server.de/rpc.php'
    • +
      –
      [2012-11-21 16:02:20.156] 'SyncHdr' - SyncHdr generation, SyncMLVers=1.2, OutgoingMsgID=2 [--][++] [->end] [->enclosing]
      • SyncHdr: issued in MsgID=2, now queueing for status
      • Outgoing Message size is now 169 bytes
      • Sending 1 commands that were queued because header was not yet generated
      • +
        –
        [2012-11-21 16:02:20.156] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
        • Command 'Status': is 0-th counted cmd, cmdsize(+tags needed to end msg)=111, available=98446 (maxfree=98446, freeaftersend=98335, notUsableBufferBytes()=0)
        • Status Code 200 issued for Cmd=SyncHdr, (incoming MsgID=1, CmdID=0)
        • - TargetRef (localID) = '1cb094accb4a-359901043542659'
        • - SourceRef (remoteID) = 'https://webmail.your-server.de/rpc.php'
        • Status: issued as (outgoing MsgID=2, CmdID=1), not waiting for status
        • Status: issue finished and not waiting for status -> deleting command
        • Deleted command 'Status' (outgoing MsgID=2, CmdID=1)
        • Outgoing Message size is now 278 bytes

        –[2012-11-21 16:02:20.156] End of 'issue' [->top] [->enclosing]

      –[2012-11-21 16:02:20.157] End of 'SyncHdr' [->top] [->enclosing]
    • +
      –
      [2012-11-21 16:02:20.157] 'processStatus' - finishing processing incoming SyncHdr Status [--][++] [->end] [->enclosing]
      • Status: processed, removed command 'SyncHdr' from status wait queue
      • Status: command 'SyncHdr' has handled status and allows to be deleted
      • Deleted command 'SyncHdr' (outgoing MsgID=1, CmdID=0)
      • Deleted command 'Status' (incoming MsgID=1, CmdID=1)

      –[2012-11-21 16:02:20.157] End of 'processStatus' [->top] [->enclosing]
    • ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
    • Calling smlProcessData(NEXT_COMMAND)
    • Created command 'Status' (incoming)
    • +
      –
      [2012-11-21 16:02:20.157] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
      • Started processing Command 'Status' (incoming MsgID=1, CmdID=2)
      • RECEIVED STATUS 200 for for command 'Put' (outgoing MsgID=1, CmdID=1)
      • - SourceRef (localID) = './devinf12'
      • Found matching command 'Put' for Status
      • Status: 200: successful --> accept as ok
      • Status: processed, removed command 'Put' from status wait queue
      • Status: command 'Put' has handled status and allows to be deleted
      • Deleted command 'Put' (outgoing MsgID=1, CmdID=1)
      • Deleted command 'Status' (incoming MsgID=1, CmdID=2)

      –[2012-11-21 16:02:20.158] End of 'processStatus' [->top] [->enclosing]
    • ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
    • Calling smlProcessData(NEXT_COMMAND)
    • Created command 'Status' (incoming)
    • +
      –
      [2012-11-21 16:02:20.158] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
      • Started processing Command 'Status' (incoming MsgID=1, CmdID=3)
      • RECEIVED STATUS 200 for for command 'Get' (outgoing MsgID=1, CmdID=2)
      • - TargetRef (remoteID) = './devinf12'
      • Found matching command 'Get' for Status
      • Status: 200: successful --> accept as ok
      • Status: processed, removed command 'Get' from status wait queue
      • Status: command 'Get' has handled status and allows to be deleted
      • Deleted command 'Get' (outgoing MsgID=1, CmdID=2)
      • Deleted command 'Status' (incoming MsgID=1, CmdID=3)

      –[2012-11-21 16:02:20.159] End of 'processStatus' [->top] [->enclosing]
    • ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
    • Calling smlProcessData(NEXT_COMMAND)
    • Created command 'Results' (incoming)
    • Started processing Command 'Results' (incoming MsgID=1, CmdID=4)
    • +
      –
      [2012-11-21 16:02:20.159] 'processCmd' - Processing incoming command, Cmd=Results, IncomingMsgID=1, CmdID=4 [--][++] [->end] [->enclosing]
      • processing item with locURI=./devinf12
      • Created command 'Status' (outgoing)
      • +
        –
        [2012-11-21 16:02:20.160] 'DevInf_Analyze' - Analyzing remote devInf [--][++] [->end] [->enclosing]
        • Warning: No DevInf found (possible cause: improperly encoded devInf from remote)

        –[2012-11-21 16:02:20.160] End of 'DevInf_Analyze' [->top] [->enclosing]
      • +
        –
        [2012-11-21 16:02:20.160] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
        • Command 'Status': is 0-th counted cmd, cmdsize(+tags needed to end msg)=51, available=98337 (maxfree=98337, freeaftersend=98286, notUsableBufferBytes()=0)
        • WARNING: Non-OK Status 400 returned to remote!
        • Status Code 400 issued for Cmd=Results, (incoming MsgID=1, CmdID=4)
        • - SourceRef (remoteID) = './devinf12'
        • Status: issued as (outgoing MsgID=2, CmdID=2), not waiting for status
        • Status: issue finished and not waiting for status -> deleting command
        • Deleted command 'Status' (outgoing MsgID=2, CmdID=2)
        • Outgoing Message size is now 327 bytes

        –[2012-11-21 16:02:20.161] End of 'issue' [->top] [->enclosing]
      • Results: command finished execution -> deleting
      • Deleted command 'Results' (incoming MsgID=1, CmdID=4)

      –[2012-11-21 16:02:20.161] End of 'processCmd' [->top] [->enclosing]
    • ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
    • Calling smlProcessData(NEXT_COMMAND)
    • Created command 'Status' (incoming)
    • +
      –
      [2012-11-21 16:02:20.161] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
      • Started processing Command 'Status' (incoming MsgID=1, CmdID=5)
      • RECEIVED STATUS 508 for for command 'Alert' (outgoing MsgID=1, CmdID=3)
      • - TargetRef (remoteID) = 'calendar'
      • - SourceRef (localID) = './events'
      • - Item data =
      • Found matching command 'Alert' for Status
      • events: testState=TRUE - expected state=='client_sent_alert', found state=='client_sent_alert'
      • +
        –
        [2012-11-21 16:02:20.162] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=client_sent_alert, newstate=client_alert_statused [--][++] [->end] [->enclosing]
        –[2012-11-21 16:02:20.162] End of 'DSStateChange' [->top] [->enclosing]
      • engHandleAlertStatus: Remote party needs SlowSync, switching to slowsync (AFTER alert, cancelling possible Resume)
      • Status: processed, removed command 'Alert' from status wait queue
      • Status: command 'Alert' has handled status and allows to be deleted
      • Deleted command 'Alert' (outgoing MsgID=1, CmdID=3)
      • Deleted command 'Status' (incoming MsgID=1, CmdID=5)

      –[2012-11-21 16:02:20.162] End of 'processStatus' [->top] [->enclosing]
    • ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
    • Calling smlProcessData(NEXT_COMMAND)
    • Created command 'Alert' (incoming)
    • Started processing Command 'Alert' (incoming MsgID=1, CmdID=6)
    • +
      –
      [2012-11-21 16:02:20.163] 'processCmd' - Processing incoming command, Cmd=Alert, IncomingMsgID=1, CmdID=6 [--][++] [->end] [->enclosing]
      • Code=201. No Cred. Analyzing Items
      • MaxObjSize found in Alert command: 1000000000
      • Created command 'Status' (outgoing)
      • - Processing Alert Item (code=201), Source='calendar', Target='./events'
      • Rebuilding - <name unknown>, ctx=0x229B218, VarDefs:
      • PrepareLocals - <name unknown>, ctx=0x229B218, VarDefs:
      • Saved Last Remote Server Anchor='', received <last> Remote Server Anchor='0' (must match for normal sync)
      • Received <next> Remote Server Anchor='1353510138' (to be compared with <last> in NEXT session)
      • (Saved) fResumeAlertCode = 201 (valid for >DS 1.2 only)
      • +
        –
        [2012-11-21 16:02:20.163] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=client_alert_statused, newstate=client_alerted [--][++] [->end] [->enclosing]
        –[2012-11-21 16:02:20.163] End of 'DSStateChange' [->top] [->enclosing]
      • Alerted (code=201) for from server only Refresh (Refreshonly)
      • +
        –
        [2012-11-21 16:02:20.163] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=client_alerted, newstate=sync_mode_stable [--][++] [->end] [->enclosing]
        • executing slow first time Sync from server

        –[2012-11-21 16:02:20.164] End of 'DSStateChange' [->top] [->enclosing]
      • ALERTED from server for slow first time Sync
      • +
        –
        [2012-11-21 16:02:20.164] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
        • Command 'Status': is 1-th counted cmd, cmdsize(+tags needed to end msg)=81, available=98288 (maxfree=98288, freeaftersend=98207, notUsableBufferBytes()=0)
        • Status Code 200 issued for Cmd=Alert, (incoming MsgID=1, CmdID=6)
        • - TargetRef (localID) = './events'
        • - SourceRef (remoteID) = 'calendar'
        • Status: issued as (outgoing MsgID=2, CmdID=3), not waiting for status
        • Status: issue finished and not waiting for status -> deleting command
        • Deleted command 'Status' (outgoing MsgID=2, CmdID=3)
        • Outgoing Message size is now 406 bytes

        –[2012-11-21 16:02:20.164] End of 'issue' [->top] [->enclosing]
      • Alert: command finished execution -> deleting
      • Deleted command 'Alert' (incoming MsgID=1, CmdID=6)

      –[2012-11-21 16:02:20.165] End of 'processCmd' [->top] [->enclosing]
    • ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
    • ##### uiapp ReportStep: 101 19 1002 2 0 1 / err=0
    • ##### uiapp ReportStep: 100 19 1002 2 0 1 / err=0
    • Calling smlProcessData(NEXT_COMMAND)
    • ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
    • Calling smlProcessData(NEXT_COMMAND)
    • ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
    • Calling smlProcessData(NEXT_COMMAND)
    • XML translation enabled...
    • - Writing incoming XML translation
    • Incoming WBXML message msgID=1 saved as XML translation
    • =================> Finished processing incoming message #1 (final), request=0
    • MessageEnded starts : old outgoing state='init', old incoming state='init', NeedToAnswer
    • MessageEnded finishes : new outgoing state='sync', new incoming state='sync', NeedToAnswer
    • Local Datastore 'events': State=sync_mode_stable, SLOW sync, from server only
    • ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
    • events: testState=FALSE - expected state>='sync_set_ready', found state=='sync_mode_stable'
    • +
      –
      [2012-11-21 16:02:20.187] 'RemoteRules' - Checking for remote rules [--][++] [->end] [->enclosing]
      –[2012-11-21 16:02:20.187] End of 'RemoteRules' [->top] [->enclosing]
    • No remote information available -> applying best-guess workaround behaviour options
    • Summary of all behaviour options (possibly modified by remote rule(s))
    • - Remote Description : [unknown remote] (no devInf)
    • - Legacy mode : Yes
    • - Lenient mode : No
    • - Limited Field Lengths : No
    • - Do not send empty props : No
    • - Quote 8bit content : No
    • - Prevent Content Folding : No
    • - No replace in slowsync : No
    • - Treat remote TZ as local : No
    • - Treat remote TZ as UTC : No
    • - Use 23:59:59 end dates : Yes
    • - Ignore field maxSize : No
    • - Ignore CTCap : No
    • - send DS path in devInf : Yes
    • - send DS CGI in devInf : Yes
    • - Update Client in slowsync : No
    • - Update Server in slowsync : No
    • - Allow message retries : Yes
    • - Strict SyncML exec order : Yes
    • - Treat copy like add : No
    • - Complete From-Client-Only : No
    • - Remote can handle UTC : Yes
    • - Max Request time [sec] : 0
    • - Content output charset : UTF-8
    • - Content input charset : UTF-8
    • Warning: No DevInf for remote datastore, running blind sync attempt
    • Local Datastore 'events' - Types: tx to remote: 'vtodoz10': text/x-vcalendar (1.0), rx from remote: 'vtodoz10': text/x-vcalendar (1.0)
    • Remote Datastore 'calendar' - Types: tx to local: 'vtodoz10': text/x-vcalendar (1.0), rx from local: 'vtodoz10': text/x-vcalendar (1.0)
    • ident=MATCHES, vardefP=0x0, vardefP->fIdx=-128, stored idx=-1 (=vardef idx 0)
    • created new vardef, ident=MATCHES, type=string, vardefP=0x234FE78, vardefs.size()=1
    • ident=CAT, vardefP=0x0, vardefP->fIdx=-128, stored idx=-2 (=vardef idx 1)
    • created new vardef, ident=CAT, type=string, vardefP=0x24400B8, vardefs.size()=2
    • ident=CN, vardefP=0x0, vardefP->fIdx=-128, stored idx=-3 (=vardef idx 2)
    • created new vardef, ident=CN, type=string, vardefP=0x234FF40, vardefs.size()=3
    • ident=EM, vardefP=0x0, vardefP->fIdx=-128, stored idx=-4 (=vardef idx 3)
    • created new vardef, ident=EM, type=string, vardefP=0x234FF88, vardefs.size()=4
    • ident=i, vardefP=0x0, vardefP->fIdx=-128, stored idx=-5 (=vardef idx 4)
    • created new vardef, ident=i, type=integer, vardefP=0x2350168, vardefs.size()=5
    • ident=ts, vardefP=0x0, vardefP->fIdx=-128, stored idx=-6 (=vardef idx 5)
    • created new vardef, ident=ts, type=timestamp, vardefP=0x2350198, vardefs.size()=6
    • Rebuilding - incomingscript, ctx=0x243FE68, VarDefs:
    • 0: string MATCHES[]
    • 1: string CAT
    • 2: string CN
    • 3: string EM
    • 4: integer i
    • 5: timestamp ts
    • ident=ds, vardefP=0x0, vardefP->fIdx=-128, stored idx=-7 (=vardef idx 6)
    • created new vardef, ident=ds, type=timestamp, vardefP=0x23501B8, vardefs.size()=7
    • Rebuilding - outgoingscript, ctx=0x243FE68, VarDefs:
    • 0: string MATCHES[]
    • 1: string CAT
    • 2: string CN
    • 3: string EM
    • 4: integer i
    • 5: timestamp ts
    • 6: timestamp ds
    • Rebuilding - <name unknown>, ctx=0x243FE68, VarDefs:
    • 0: string MATCHES[]
    • 1: string CAT
    • 2: string CN
    • 3: string EM
    • 4: integer i
    • 5: timestamp ts
    • 6: timestamp ds
    • PrepareLocals - <name unknown>, ctx=0x243FE68, VarDefs:
    • 0: string MATCHES[]
    • 1: string CAT
    • 2: string CN
    • 3: string EM
    • 4: integer i
    • 5: timestamp ts
    • 6: timestamp ds
    • +
      –
      [2012-11-21 16:02:20.191] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=sync_mode_stable, newstate=data_access_started [--][++] [->end] [->enclosing]
      • TStdLogicDS::startDataAccessForClient
      • Type-specific postfetch filtering NOT needed
      • Datastore-level postfetch filtering NOT needed
      • +
        –
        [2012-11-21 16:02:20.191] 'ReadSyncSet' - Reading Sync Set from Database, datastore=events [--][++] [->end] [->enclosing]
        • (1DD7E08) JNI (LNK): CreateContext: n=0 tCB=0229A838 cC=37502008 mC=37502008 sC=0
        • (1DD7E08) JNI (LNK): CreateContext: 'events' dev='anydevice' usr='singleuser'
        • ##### JNI (LNK): Threads: old=4014F588 => new=01DD7E08 (main=4014F588) chg
        • ##### JNI (LNK): JVars: fndElem=0 env=022C0718
        • ##### JNI (LNK): AttachCurrentThread: vm=01C4C7F8 env=022C0718
        • ##### JNI (LNK): AttachCurrentThread: env=022C0718 (prev=022C0718)
        • ##### ds_calendar (events) CreateContext
        • ##### ds_calendar (events) CreateContext: cC=37502008 / mC=37502008 / sC=0 / flags=ffff / aCB=229a838 / context=3
        • ##### ds_calendar (events) CreateContext: uri=content://com.android.calendar/events
        • ##### uiapp CalendarAccount.Update: events account
        • ##### uiapp util.GetEntries: url=content://com.android.calendar/calendars / sel=null
        • ##### uiapp CalendarAccount.Update: calID_BDAY=0 / calID_TASK=4
        • ##### ds_calendar (events) CreateContext: ds_calendar: ffffffff / n=10
        • (1DD7E08) JNI (LNK): FilterSupport: n=1 tCB=0229A838 cC=37502008 mC=37502008 sC=0
        • (1DD7E08) JNI (LNK): FilterSupport: 'staticfilter:
          dynamicfilter:
          invisiblefilter:
          '
        • (1DD7E08) JNI (LNK): FilterSupport: n=2 tCB=0229A838 cC=37502008 mC=37502008 sC=0
        • (1DD7E08) JNI (LNK): FilterSupport: j->c->env 022C0718
        • ##### ds_calendar (events) FilterSupport: 'staticfilter:
          dynamicfilter:
          invisiblefilter:
          ' n=0
        • (1DD7E08) JNI (LNK): ContextSupport: n=3 tCB=0229A838 cC=37502008 mC=37502008 sC=0
        • (1DD7E08) JNI (LNK): ContextSupport: 'ReadNextItem:allfields
          '
        • (1DD7E08) JNI (LNK): ContextSupport: n=4 tCB=0229A838 cC=37502008 mC=37502008 sC=0
        • (1DD7E08) JNI (LNK): ContextSupport: j->c->env 022C0718
        • ##### ds_calendar (events) ContextSupport: 'ReadNextItem:allfields
          ' n=0
        • Now reading local sync set: report changes since reference1 at 2012-11-21 15:01:21.301, and since reference2 at 2012-11-21 15:01:21.301
        • (1DD7E08) JNI (LNK): StartDataRead: n=5 tCB=0229A838 cC=37502008 mC=37502008 sC=0
        • (1DD7E08) JNI (LNK): StartDataRead: last='' resume='' / j=02350368
        • (1DD7E08) JNI (LNK): StartDataRead: n=6 tCB=0229A838 cC=37502008 mC=37502008 sC=0
        • (1DD7E08) JNI (LNK): StartDataRead: env=022C0718
        • ##### ds_calendar 'R' (events) StartDataRead
        • ##### ds_calendar 'R' (events) FileContents: =start= /data/data/com.sysync/syncml_client_sysynclib/sysynclib_cals.bfi
        • ##### ds_calendar 'R' (events) FileContents: events (office)
        • ##### ds_calendar 'R' (events) FileContents: office;0;cal1;com.sysync
        • ##### ds_calendar 'R' (events) FileContents: office;0;PC Sync;cal=PC Sync;com.htc.pcsc
        • ##### ds_calendar 'R' (events) FileContents: office;0;MyPhonebook;cal=MyPhonebook;com.htc.android.myphonebook
        • ##### ds_calendar 'R' (events) FileContents: office;0;Kontakte;cal=Kontakte;HTC_BirthdayEvent
        • ##### ds_calendar 'R' (events) FileContents: office;0;Tasks;cal=Tasks;com.google/task
        • ##### ds_calendar 'R' (events) FileContents: office;0;on.thomas.k@googlemail.com;cal=on.thomas.k@googlemail.com;com.google
        • ##### ds_calendar 'R' (events) FileContents: office;0;Deutsche Feiertage;cal=Deutsche Feiertage;com.google
        • ##### ds_calendar 'R' (events) FileContents: office;0;Geburts- und Jahrestage meiner Kontakte;cal=Geburts- und Jahrestage meiner Kontakte;com.google
        • ##### ds_calendar 'R' (events) FileContents: office;0;Wochennummern;cal=Wochennummern;com.google
        • ##### ds_calendar 'R' (events) FileContents: office;0;Tasks;cal=Tasks;com.htc.task/task
        • ##### ds_calendar 'R' (events) FileContents: office;1;cal1;cal=cal1;com.sysync
        • ##### ds_calendar 'R' (events) FileContents: =end=/data/data/com.sysync/syncml_client_sysynclib/sysynclib_cals.bfi
        • ##### ds_calendar 'R' (events) DispEntries: =start=
        • ##### ds_calendar 'R' (events) DispEntries: _id / calendar_displayName / visible / account_type / calendar_access_level / account_name / calendar_color
        • ##### ds_calendar 'R' (events) DispEntries: '1' / 'PC Sync' / '0' / 'com.htc.pcsc' / '700' / 'PC Sync' / '-15894509'
        • ##### ds_calendar 'R' (events) DispEntries: '2' / 'MyPhonebook' / '0' / 'com.htc.android.myphonebook' / '700' / 'MyPhonebook' / '-15894509'
        • ##### ds_calendar 'R' (events) DispEntries: '3' / 'Kontakte' / '0' / 'HTC_BirthdayEvent' / '200' / 'Birthday / Anniversary Sync' / '-8570095'
        • ##### ds_calendar 'R' (events) DispEntries: '4' / 'Tasks' / '0' / 'com.google/task' / '200' / 'on.thomas.k@googlemail.com' / '-13416077'
        • ##### ds_calendar 'R' (events) DispEntries: '5' / 'on.thomas.k@googlemail.com' / '0' / 'com.google' / '700' / 'on.thomas.k@googlemail.com' / '-14069085'
        • ##### ds_calendar 'R' (events) DispEntries: '6' / 'Deutsche Feiertage' / '0' / 'com.google' / '200' / 'on.thomas.k@googlemail.com' / '-14069085'
        • ##### ds_calendar 'R' (events) DispEntries: '7' / 'Geburts- und Jahrestage meiner Kontakte' / '0' / 'com.google' / '200' / 'on.thomas.k@googlemail.com' / '-14069085'
        • ##### ds_calendar 'R' (events) DispEntries: '8' / 'Wochennummern' / '0' / 'com.google' / '200' / 'on.thomas.k@googlemail.com' / '-14069085'
        • ##### ds_calendar 'R' (events) DispEntries: '12' / 'Tasks' / '0' / 'com.htc.task/task' / '200' / 'My task' / '-13199500'
        • ##### ds_calendar 'R' (events) DispEntries: '13' / 'cal1' / '1' / 'com.sysync' / '700' / 'sysync' / '-16743621'
        • ##### ds_calendar 'R' (events) DispEntries: =end=
        • ##### uiapp CalendarAccount.Update: events account
        • ##### uiapp util.GetEntries: url=content://com.android.calendar/calendars / sel=null
        • ##### uiapp CalendarAccount.Update: calID_BDAY=0 / calID_TASK=4
        • ##### ds_calendar 'R' (events) DispActiveCals: noMatchSw=o / noMatchIndex=9 / inUse.length=10
        • ##### uiapp sync_reporting: events: aTot=-1 / cN=-2 / cX=-2
        • ##### ds_calendar 'R' (events) PrepCursors: o n=0 / chg=0 / del=0 / isG=o force=o ACC name='PC Sync' / type='com.htc.pcsc'
        • ##### ds_calendar 'R' (events) PrepCursors: o n=1 / chg=0 / del=0 / isG=o force=o ACC name='MyPhonebook' / type='com.htc.android.myphonebook'
        • ##### ds_calendar 'R' (events) PrepCursors: o n=2 / chg=0 / del=0 / isG=o force=o ACC name='Kontakte' / type='HTC_BirthdayEvent'
        • ##### ds_calendar 'R' (events) PrepCursors: o n=3 / chg=0 / del=0 / isG=o force=o ACC name='Tasks' / type='com.google/task'
        • ##### ds_calendar 'R' (events) PrepCursors: o n=4 / chg=0 / del=0 / isG=v force=o ACC name='on.thomas.k@googlemail.com' / type='com.google'
        • ##### ds_calendar 'R' (events) PrepCursors: o n=5 / chg=0 / del=0 / isG=v force=o ACC name='Deutsche Feiertage' / type='com.google'
        • ##### ds_calendar 'R' (events) PrepCursors: o n=6 / chg=0 / del=0 / isG=v force=o ACC name='Geburts- und Jahrestage meiner Kontakte' / type='com.google'
        • ##### ds_calendar 'R' (events) PrepCursors: o n=7 / chg=0 / del=0 / isG=v force=o ACC name='Wochennummern' / type='com.google'
        • ##### ds_calendar 'R' (events) PrepCursors: o n=8 / chg=0 / del=0 / isG=o force=o ACC name='Tasks' / type='com.htc.task/task'
        • +
          –
          [2012-11-21 16:02:20.324] 'DispDBFields' - calendar_id=13 AND deleted=0 [--][++] [->end] [->enclosing]
          • ##### uiapp DispDBFields: i=0 eventEndTimezone
          • ##### uiapp DispDBFields: i=1 cal_sync9
          • ##### uiapp DispDBFields: i=2 cal_sync8
          • ##### uiapp DispDBFields: i=3 cal_sync7
          • ##### uiapp DispDBFields: i=4 visible
          • ##### uiapp DispDBFields: i=5 cal_sync6
          • ##### uiapp DispDBFields: i=6 cal_sync5
          • ##### uiapp DispDBFields: i=7 rrule
          • ##### uiapp DispDBFields: i=8 cal_sync4
          • ##### uiapp DispDBFields: i=9 cal_sync3
          • ##### uiapp DispDBFields: i=10 cal_sync2
          • ##### uiapp DispDBFields: i=11 cal_sync1
          • ##### uiapp DispDBFields: i=12 hide_declined
          • ##### uiapp DispDBFields: i=13 reminder_type
          • ##### uiapp DispDBFields: i=14 facebook_avatar_large
          • ##### uiapp DispDBFields: i=15 calendar_color_index
          • ##### uiapp DispDBFields: i=16 hasAlarm
          • ##### uiapp DispDBFields: i=17 suggest_text_2
          • ##### uiapp DispDBFields: i=18 rdate
          • ##### uiapp DispDBFields: i=19 dtstart
          • ##### uiapp DispDBFields: i=20 sync_data1
          • ##### uiapp DispDBFields: i=21 sync_data2
          • ##### uiapp DispDBFields: i=22 hasAttendeeData
          • ##### uiapp DispDBFields: i=23 sync_data3
          • ##### uiapp DispDBFields: i=24 calendar_timezone
          • ##### uiapp DispDBFields: i=25 sync_data4
          • ##### uiapp DispDBFields: i=26 description
          • ##### uiapp DispDBFields: i=27 sync_data5
          • ##### uiapp DispDBFields: i=28 reminder_duration
          • ##### uiapp DispDBFields: i=29 sync_data6
          • ##### uiapp DispDBFields: i=30 calendar_access_level
          • ##### uiapp DispDBFields: i=31 sync_data7
          • ##### uiapp DispDBFields: i=32 sync_data8
          • ##### uiapp DispDBFields: i=33 sync_data9
          • ##### uiapp DispDBFields: i=34 account_type
          • ##### uiapp DispDBFields: i=35 hasExtendedProperties
          • ##### uiapp DispDBFields: i=36 last_update_time
          • ##### uiapp DispDBFields: i=37 eventLocation
          • ##### uiapp DispDBFields: i=38 dtend
          • ##### uiapp DispDBFields: i=39 allDay
          • ##### uiapp DispDBFields: i=40 sync_data10
          • ##### uiapp DispDBFields: i=41 organizer
          • ##### uiapp DispDBFields: i=42 lastSynced
          • ##### uiapp DispDBFields: i=43 deleted
          • ##### uiapp DispDBFields: i=44 account_name
          • ##### uiapp DispDBFields: i=45 originalInstanceTime
          • ##### uiapp DispDBFields: i=46 alerts_vibrate
          • ##### uiapp DispDBFields: i=47 importance
          • ##### uiapp DispDBFields: i=48 facebook_source_id
          • ##### uiapp DispDBFields: i=49 selfAttendeeStatus
          • ##### uiapp DispDBFields: i=50 eventColor_index
          • ##### uiapp DispDBFields: i=51 eventTimezone
          • ##### uiapp DispDBFields: i=52 facebook_avatar_small
          • ##### uiapp DispDBFields: i=53 allowedAvailability
          • ##### uiapp DispDBFields: i=54 ownerAccount
          • ##### uiapp DispDBFields: i=55 canOrganizerRespond
          • ##### uiapp DispDBFields: i=56 canModifyTimeZone
          • ##### uiapp DispDBFields: i=57 lastDate
          • ##### uiapp DispDBFields: i=58 guestsCanModify
          • ##### uiapp DispDBFields: i=59 guestsCanSeeGuests
          • ##### uiapp DispDBFields: i=60 exrule
          • ##### uiapp DispDBFields: i=61 title
          • ##### uiapp DispDBFields: i=62 _id
          • ##### uiapp DispDBFields: i=63 _sync_id
          • ##### uiapp DispDBFields: i=64 allowedReminders
          • ##### uiapp DispDBFields: i=65 cal_sync10
          • ##### uiapp DispDBFields: i=66 dirty
          • ##### uiapp DispDBFields: i=67 alerts_ringtone
          • ##### uiapp DispDBFields: i=68 allowedAttendeeTypes
          • ##### uiapp DispDBFields: i=69 facebook_avatar_local
          • ##### uiapp DispDBFields: i=70 MeetingStatus
          • ##### uiapp DispDBFields: i=71 availability
          • ##### uiapp DispDBFields: i=72 calendar_id
          • ##### uiapp DispDBFields: i=73 suggest_text_1
          • ##### uiapp DispDBFields: i=74 original_id
          • ##### uiapp DispDBFields: i=75 originalAllDay
          • ##### uiapp DispDBFields: i=76 maxReminders
          • ##### uiapp DispDBFields: i=77 accessLevel
          • ##### uiapp DispDBFields: i=78 facebook_type
          • ##### uiapp DispDBFields: i=79 calendar_color
          • ##### uiapp DispDBFields: i=80 iCalGUID
          • ##### uiapp DispDBFields: i=81 duration
          • ##### uiapp DispDBFields: i=82 parentID
          • ##### uiapp DispDBFields: i=83 calendar_displayName
          • ##### uiapp DispDBFields: i=84 guestsCanInviteOthers
          • ##### uiapp DispDBFields: i=85 original_sync_id
          • ##### uiapp DispDBFields: i=86 eventColor
          • ##### uiapp DispDBFields: i=87 exdate
          • ##### uiapp DispDBFields: i=88 desc_mime_type
          • ##### uiapp DispDBFields: i=89 eventStatus

          –[2012-11-21 16:02:20.355] End of 'DispDBFields' [->top] [->enclosing]
        • ##### ds_calendar 'R' (events) StartDataRead: CalQuery: n=9 / sel='calendar_id=13 AND deleted=0' / cnt=0
        • ##### uiapp forced_exdates: cID=13 / i=9 isG=o / forced=o
        • ##### ds_calendar 'R' (events) PrepCursors: vv n=9 / chg=0 / del=0 / isG=o force=o ACC name='cal1' / type='com.sysync' / cnt=0
        • ##### ds_calendar 'R' (events) StartDataRead: # of DB entries=0 / withURL=false
        • +
          –
          [2012-11-21 16:02:20.370] 'StartDataRead alarms' -  [--][++] [->end] [->enclosing]
          • ##### uiapp StartDataRead alarms: i=1 '720' '1' '21' '30'
          • ##### uiapp StartDataRead alarms: i=2 '720' '1' '24' '35'
          • ##### uiapp StartDataRead alarms: i=3 '720' '1' '26' '38'
          • ##### uiapp StartDataRead alarms: i=4 '720' '1' '30' '118'
          • ##### uiapp StartDataRead alarms: i=5 '720' '1' '34' '124'
          • ##### uiapp StartDataRead alarms: i=6 '720' '1' '36' '127'
          • ##### uiapp StartDataRead alarms: i=7 '720' '1' '38' '130'
          • ##### uiapp StartDataRead alarms: i=8 '720' '1' '40' '133'
          • ##### uiapp StartDataRead alarms: i=9 '720' '1' '42' '136'
          • ##### uiapp StartDataRead alarms: i=10 '720' '1' '46' '142'
          • ##### uiapp StartDataRead alarms: i=11 '720' '1' '48' '145'
          • ##### uiapp StartDataRead alarms: i=12 '720' '1' '50' '148'
          • ##### uiapp StartDataRead alarms: i=13 '720' '1' '52' '151'
          • ##### uiapp StartDataRead alarms: i=14 '720' '1' '56' '157'
          • ##### uiapp StartDataRead alarms: i=15 '720' '1' '58' '160'
          • ##### uiapp StartDataRead alarms: i=16 '720' '1' '60' '163'
          • ##### uiapp StartDataRead alarms: i=17 '720' '1' '66' '172'
          • ##### uiapp StartDataRead alarms: i=18 '720' '1' '69' '176'
          • ##### uiapp StartDataRead alarms: i=19 '720' '1' '71' '179'
          • ##### uiapp StartDataRead alarms: i=20 '720' '1' '73' '182'
          • ##### uiapp StartDataRead alarms: i=21 '720' '1' '80' '192'
          • ##### uiapp StartDataRead alarms: i=22 '720' '1' '139' '267'
          • ##### uiapp StartDataRead alarms: i=23 '720' '1' '648' '837'
          • ##### uiapp StartDataRead alarms: i=24 '720' '1' '698' '888'
          • ##### uiapp StartDataRead alarms: i=25 '720' '1' '710' '900'
          • ##### uiapp StartDataRead alarms: i=26 '720' '1' '757' '961'
          • ##### uiapp StartDataRead alarms: i=27 '720' '1' '759' '963'
          • ##### uiapp StartDataRead alarms: i=28 '720' '1' '761' '965'
          • ##### uiapp StartDataRead alarms: i=29 '720' '1' '763' '968'
          • ##### uiapp StartDataRead alarms: i=30 '720' '1' '766' '971'
          • ##### uiapp StartDataRead alarms: i=31 '720' '1' '770' '975'
          • ##### uiapp StartDataRead alarms: i=32 '720' '1' '774' '979'
          • ##### uiapp StartDataRead alarms: i=33 '720' '1' '783' '991'
          • ##### uiapp StartDataRead alarms: i=34 '720' '1' '789' '997'
          • ##### uiapp StartDataRead alarms: i=35 '720' '1' '793' '1001'
          • ##### uiapp StartDataRead alarms: i=36 '720' '1' '797' '1005'
          • ##### uiapp StartDataRead alarms: i=37 '720' '1' '801' '1009'
          • ##### uiapp StartDataRead alarms: i=38 '720' '1' '805' '1013'
          • ##### uiapp StartDataRead alarms: i=39 '720' '1' '807' '1018'
          • ##### uiapp StartDataRead alarms: i=40 '720' '1' '816' '1027'
          • ##### uiapp StartDataRead alarms: i=41 '720' '1' '822' '1033'
          • ##### uiapp StartDataRead alarms: i=42 '720' '1' '826' '1037'
          • ##### uiapp StartDataRead alarms: i=43 '720' '1' '832' '1044'
          • ##### uiapp StartDataRead alarms: i=44 '720' '1' '840' '1060'
          • ##### uiapp StartDataRead alarms: i=45 '720' '1' '899' '1167'
          • ##### uiapp StartDataRead alarms: i=46 '720' '1' '1115' '1414'
          • ##### uiapp StartDataRead alarms: i=47 '720' '1' '1379' '2293'
          • ##### uiapp StartDataRead alarms: i=48 '720' '1' '1380' '2295'

          –[2012-11-21 16:02:20.375] End of 'StartDataRead alarms' [->top] [->enclosing]
        • ##### ds_calendar LoadAIF: '/data/data/com.sysync/syncml_client_sysynclib/events_AIW.txt' not found
        • ##### ds_calendar LoadAIF: '/data/data/com.sysync/syncml_client_sysynclib/events_AIW.txt' false -1
        • +
          –
          [2012-11-21 16:02:20.376] 'DispAIF' -  [--][++] [->end] [->enclosing]
          • ##### ds_calendar DispAIF: ----------------------------------------------------------------------------

          –[2012-11-21 16:02:20.377] End of 'DispAIF' [->top] [->enclosing]
        • +
          –
          [2012-11-21 16:02:20.377] 'LoadHIF' -  [--][++] [->end] [->enclosing]
          • ##### ds_calendar 'R' (events) LoadHIF: hFile='/data/data/com.sysync/syncml_client_sysynclib/sysynclib_aevt4.bfi'
          • ##### ds_calendar 'R' (events) LoadHIF: i=0 inUse=o ACC name='PC Sync' / type='com.htc.pcsc'
          • ##### ds_calendar 'R' (events) LoadHIF: i=1 inUse=o ACC name='MyPhonebook' / type='com.htc.android.myphonebook'
          • ##### ds_calendar 'R' (events) LoadHIF: i=2 inUse=o ACC name='Kontakte' / type='HTC_BirthdayEvent'
          • ##### ds_calendar 'R' (events) LoadHIF: i=3 inUse=o ACC name='Tasks' / type='com.google/task'
          • ##### ds_calendar 'R' (events) LoadHIF: i=4 inUse=o ACC name='on.thomas.k@googlemail.com' / type='com.google'
          • ##### ds_calendar 'R' (events) LoadHIF: i=5 inUse=o ACC name='Deutsche Feiertage' / type='com.google'
          • ##### ds_calendar 'R' (events) LoadHIF: i=6 inUse=o ACC name='Geburts- und Jahrestage meiner Kontakte' / type='com.google'
          • ##### ds_calendar 'R' (events) LoadHIF: i=7 inUse=o ACC name='Wochennummern' / type='com.google'
          • ##### ds_calendar 'R' (events) LoadHIF: i=8 inUse=o ACC name='Tasks' / type='com.htc.task/task'
          • ##### ds_calendar 'R' (events) LoadHIF: i=9 inUse=v ACC name='cal1' / type='com.sysync'
          • ##### ds_calendar 'R' (events) LoadHIF: ACC name='PC Sync' / type='com.htc.pcsc' / vers=3 / with_tmp / with_aID=v
          • ##### ds_calendar 'R' (events) LoadHIF: ACC name='MyPhonebook' / type='com.htc.android.myphonebook' / vers=3 / with_tmp / with_aID=v
          • ##### ds_calendar 'R' (events) LoadHIF: ACC name='Kontakte' / type='HTC_BirthdayEvent' / vers=3 / with_tmp / with_aID=v
          • ##### ds_calendar 'R' (events) LoadHIF: ACC name='Tasks' / type='com.google/task' / vers=3 / with_tmp / with_aID=v
          • ##### ds_calendar 'R' (events) LoadHIF: ACC name='on.thomas.k@googlemail.com' / type='com.google' / vers=3 / with_tmp / with_aID=v
          • ##### ds_calendar 'R' (events) LoadHIF: ACC name='Deutsche Feiertage' / type='com.google' / vers=3 / with_tmp / with_aID=v
          • ##### ds_calendar 'R' (events) LoadHIF: ACC name='Geburts- und Jahrestage meiner Kontakte' / type='com.google' / vers=3 / with_tmp / with_aID=v
          • ##### ds_calendar 'R' (events) LoadHIF: ACC name='Wochennummern' / type='com.google' / vers=3 / with_tmp / with_aID=v
          • ##### ds_calendar 'R' (events) LoadHIF: ACC name='Tasks' / type='com.htc.task/task' / vers=3 / with_tmp / with_aID=v
          • ##### ds_calendar 'R' (events) LoadHIF: ACC name='cal1' / type='com.sysync' / vers=3 / with_tmp / with_aID=v
          • ##### ds_calendar 'R' (events) inUse=o: ACC name='PC Sync' / type='com.htc.pcsc' / cnt=0
          • ##### ds_calendar 'R' (events) inUse=o: ACC name='MyPhonebook' / type='com.htc.android.myphonebook' / cnt=0
          • ##### ds_calendar 'R' (events) inUse=o: ACC name='Kontakte' / type='HTC_BirthdayEvent' / cnt=0
          • ##### ds_calendar 'R' (events) inUse=o: ACC name='Tasks' / type='com.google/task' / cnt=0
          • ##### ds_calendar 'R' (events) inUse=o: ACC name='on.thomas.k@googlemail.com' / type='com.google' / cnt=0
          • ##### ds_calendar 'R' (events) inUse=o: ACC name='Deutsche Feiertage' / type='com.google' / cnt=0
          • ##### ds_calendar 'R' (events) inUse=o: ACC name='Geburts- und Jahrestage meiner Kontakte' / type='com.google' / cnt=0
          • ##### ds_calendar 'R' (events) inUse=o: ACC name='Wochennummern' / type='com.google' / cnt=0
          • ##### ds_calendar 'R' (events) inUse=o: ACC name='Tasks' / type='com.htc.task/task' / cnt=0
          • ##### ds_calendar 'R' (events) inUse=v: ACC name='cal1' / type='com.sysync' / cnt=0

          –[2012-11-21 16:02:20.388] End of 'LoadHIF' [->top] [->enclosing]
        • (1DD7E08) JNI (LNK): StartDataRead: n=7 tCB=0229A838 cC=37502008 mC=37502008 sC=0
        • (1DD7E08) JNI (LNK): StartDataRead: err=0
        • (1DD7E08) JNI (LNK): EndDataRead: n=8 tCB=0229A838 cC=37502008 mC=37502008 sC=0
        • (1DD7E08) JNI (LNK): EndDataRead
        • ##### ds_calendar 'R' (events) EndDataRead

        –[2012-11-21 16:02:20.391] End of 'ReadSyncSet' [->top] [->enclosing]

      –[2012-11-21 16:02:20.391] End of 'DSStateChange' [->top] [->enclosing]
    • +
      –
      [2012-11-21 16:02:20.391] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=data_access_started, newstate=sync_set_ready [--][++] [->end] [->enclosing]
      –[2012-11-21 16:02:20.391] End of 'DSStateChange' [->top] [->enclosing]
    • events: testState=TRUE - expected state>='data_access_started', found state=='sync_set_ready'
    • events: testState=FALSE - expected state>='client_sync_gen_started', found state=='sync_set_ready'
    • +
      –
      [2012-11-21 16:02:20.392] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=sync_set_ready, newstate=client_sync_gen_started [--][++] [->end] [->enclosing]
      –[2012-11-21 16:02:20.392] End of 'DSStateChange' [->top] [->enclosing]
    • Created command 'Sync' (outgoing)
    • +
      –
      [2012-11-21 16:02:20.392] 'issue' - issuing command, Cmd=Sync [--][++] [->end] [->enclosing]
      • Command 'Sync': is 2-th counted cmd, cmdsize(+tags needed to end msg)=39, available=98209 (maxfree=98209, freeaftersend=98170, notUsableBufferBytes()=0)
      • +
        –
        [2012-11-21 16:02:20.392] 'sync' - Opened Sync command bracket, Reopen=no, SourceURI=./events, TargetURI=calendar, IncomingMsgID=2, CmdID=4 [--][++] [->end] [->enclosing]
        • Opened <Sync> bracket, Source='./events', Target='calendar' as (outgoing MsgID=2, CmdID=4)
        • +
          –
          [2012-11-21 16:02:20.392] 'SyncGen' - Now generating sync commands, datastore=events [--][++] [->end] [->enclosing]
          • +
            –
            [2012-11-21 16:02:20.392] 'changeLogPreflight' [--][++] [->end] [->enclosing]
            • changeLogPreflight: fCurrentModCount=3, fPreviousToRemoteModCount=0, fPreviousSyncTime(UTC)=<no time>
            • changeLogPreflight: numberOfLocalChanges=0, status=0 (binfileerr=0)

            –[2012-11-21 16:02:20.393] End of 'changeLogPreflight' [->top] [->enclosing]
          • +
            –
            [2012-11-21 16:02:20.393] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=client_sync_gen_started, newstate=sync_gen_done [--][++] [->end] [->enclosing]
            • (1DD7E08) JNI (LNK): StartDataWrite: n=9 tCB=0229A838 cC=37502008 mC=37502008 sC=0
            • (1DD7E08) JNI (LNK): StartDataWrite
            • ##### ds_calendar 'W' (events) StartDataWrite
            • +
              –
              [2012-11-21 16:02:20.394] 'RemoveUnusedAIF' -  [--][++] [->end] [->enclosing]
              –[2012-11-21 16:02:20.394] End of 'RemoveUnusedAIF' [->top] [->enclosing]
            • +
              –
              [2012-11-21 16:02:20.394] 'DispAIF' -  [--][++] [->end] [->enclosing]
              • ##### ds_calendar DispAIF: ----------------------------------------------------------------------------

              –[2012-11-21 16:02:20.394] End of 'DispAIF' [->top] [->enclosing]
            • (1DD7E08) JNI (LNK): DeleteSyncSet: n=10 tCB=0229A838 cC=37502008 mC=37502008 sC=0
            • (1DD7E08) JNI (LNK): DeleteSyncSet
            • ##### ds_calendar 'W' (events) DeleteSyncSet
            • ##### uiapp sync_reporting: events: aTot=0 / cN=0 / cX=-2
            • ##### ds_calendar 'W' (events) DeleteAllFields: i=0 / cal=1 acc=null / inUse=false / selOn=false
            • ##### ds_calendar 'W' (events) DeleteAllFields: i=1 / cal=2 acc=null / inUse=false / selOn=false
            • ##### ds_calendar 'W' (events) DeleteAllFields: i=2 / cal=3 acc=null / inUse=false / selOn=false
            • ##### ds_calendar 'W' (events) DeleteAllFields: i=3 / cal=4 acc=null / inUse=false / selOn=false
            • ##### ds_calendar 'W' (events) DeleteAllFields: i=4 / cal=5 acc=null / inUse=false / selOn=false
            • ##### ds_calendar 'W' (events) DeleteAllFields: i=5 / cal=6 acc=null / inUse=false / selOn=false
            • ##### ds_calendar 'W' (events) DeleteAllFields: i=6 / cal=7 acc=null / inUse=false / selOn=false
            • ##### ds_calendar 'W' (events) DeleteAllFields: i=7 / cal=8 acc=null / inUse=false / selOn=false
            • ##### ds_calendar 'W' (events) DeleteAllFields: i=8 / cal=12 acc=null / inUse=false / selOn=false
            • ##### ds_calendar 'W' (events) DeleteAllFields: i=9 / cal=13 acc=ACC name='sysync' / type='com.sysync' / inUse=true / selOn=true
            • ##### uiapp DeleteItems: iID='' cID=13
            • ##### uiapp DeleteItems: url=content://com.android.calendar/events
            • ##### uiapp DeleteItems: url=content://com.android.calendar/events?caller_is_syncadapter=true&account_name=sysync&account_type=com.sysync / sel='calendar_id=13'
            • ##### uiapp DeleteItems: url=content://com.android.calendar/events?caller_is_syncadapter=true&account_name=sysync&account_type=com.sysync / nDel=0
            • ##### ds_calendar 'W' (events) DeleteAllFields: del # of items: 0
            • +
              –
              [2012-11-21 16:02:20.432] 'DeleteAllFields alarms' -  [--][++] [->end] [->enclosing]
              • ##### uiapp DeleteAllFields alarms: i=1 '720' '1' '21' '30'
              • ##### uiapp DeleteAllFields alarms: i=2 '720' '1' '24' '35'
              • ##### uiapp DeleteAllFields alarms: i=3 '720' '1' '26' '38'
              • ##### uiapp DeleteAllFields alarms: i=4 '720' '1' '30' '118'
              • ##### uiapp DeleteAllFields alarms: i=5 '720' '1' '34' '124'
              • ##### uiapp DeleteAllFields alarms: i=6 '720' '1' '36' '127'
              • ##### uiapp DeleteAllFields alarms: i=7 '720' '1' '38' '130'
              • ##### uiapp DeleteAllFields alarms: i=8 '720' '1' '40' '133'
              • ##### uiapp DeleteAllFields alarms: i=9 '720' '1' '42' '136'
              • ##### uiapp DeleteAllFields alarms: i=10 '720' '1' '46' '142'
              • ##### uiapp DeleteAllFields alarms: i=11 '720' '1' '48' '145'
              • ##### uiapp DeleteAllFields alarms: i=12 '720' '1' '50' '148'
              • ##### uiapp DeleteAllFields alarms: i=13 '720' '1' '52' '151'
              • ##### uiapp DeleteAllFields alarms: i=14 '720' '1' '56' '157'
              • ##### uiapp DeleteAllFields alarms: i=15 '720' '1' '58' '160'
              • ##### uiapp DeleteAllFields alarms: i=16 '720' '1' '60' '163'
              • ##### uiapp DeleteAllFields alarms: i=17 '720' '1' '66' '172'
              • ##### uiapp DeleteAllFields alarms: i=18 '720' '1' '69' '176'
              • ##### uiapp DeleteAllFields alarms: i=19 '720' '1' '71' '179'
              • ##### uiapp DeleteAllFields alarms: i=20 '720' '1' '73' '182'
              • ##### uiapp DeleteAllFields alarms: i=21 '720' '1' '80' '192'
              • ##### uiapp DeleteAllFields alarms: i=22 '720' '1' '139' '267'
              • ##### uiapp DeleteAllFields alarms: i=23 '720' '1' '648' '837'
              • ##### uiapp DeleteAllFields alarms: i=24 '720' '1' '698' '888'
              • ##### uiapp DeleteAllFields alarms: i=25 '720' '1' '710' '900'
              • ##### uiapp DeleteAllFields alarms: i=26 '720' '1' '757' '961'
              • ##### uiapp DeleteAllFields alarms: i=27 '720' '1' '759' '963'
              • ##### uiapp DeleteAllFields alarms: i=28 '720' '1' '761' '965'
              • ##### uiapp DeleteAllFields alarms: i=29 '720' '1' '763' '968'
              • ##### uiapp DeleteAllFields alarms: i=30 '720' '1' '766' '971'
              • ##### uiapp DeleteAllFields alarms: i=31 '720' '1' '770' '975'
              • ##### uiapp DeleteAllFields alarms: i=32 '720' '1' '774' '979'
              • ##### uiapp DeleteAllFields alarms: i=33 '720' '1' '783' '991'
              • ##### uiapp DeleteAllFields alarms: i=34 '720' '1' '789' '997'
              • ##### uiapp DeleteAllFields alarms: i=35 '720' '1' '793' '1001'
              • ##### uiapp DeleteAllFields alarms: i=36 '720' '1' '797' '1005'
              • ##### uiapp DeleteAllFields alarms: i=37 '720' '1' '801' '1009'
              • ##### uiapp DeleteAllFields alarms: i=38 '720' '1' '805' '1013'
              • ##### uiapp DeleteAllFields alarms: i=39 '720' '1' '807' '1018'
              • ##### uiapp DeleteAllFields alarms: i=40 '720' '1' '816' '1027'
              • ##### uiapp DeleteAllFields alarms: i=41 '720' '1' '822' '1033'
              • ##### uiapp DeleteAllFields alarms: i=42 '720' '1' '826' '1037'
              • ##### uiapp DeleteAllFields alarms: i=43 '720' '1' '832' '1044'
              • ##### uiapp DeleteAllFields alarms: i=44 '720' '1' '840' '1060'
              • ##### uiapp DeleteAllFields alarms: i=45 '720' '1' '899' '1167'
              • ##### uiapp DeleteAllFields alarms: i=46 '720' '1' '1115' '1414'
              • ##### uiapp DeleteAllFields alarms: i=47 '720' '1' '1379' '2293'
              • ##### uiapp DeleteAllFields alarms: i=48 '720' '1' '1380' '2295'

              –[2012-11-21 16:02:20.437] End of 'DeleteAllFields alarms' [->top] [->enclosing]
            • ##### ds_calendar 'W' (events) ds_hif.MarkHash: iID=''
            • ##### ds_calendar 'W' (events) ds_hif.MarkHash: mode='D' iID= title='' any=o
            • ##### ds_calendar 'W' (events) ds_hif.MarkHash: 'D' ACC name='cal1' / type='com.sysync' iID= / ca=v / sUID_cre=o
            • startDataWrite called, status=0

            –[2012-11-21 16:02:20.438] End of 'DSStateChange' [->top] [->enclosing]
          • engGenerateSyncCommands ended, state='sync_gen_done', sync generation done

          –[2012-11-21 16:02:20.438] End of 'SyncGen' [->top] [->enclosing]
        • Closed </Sync> bracket, final

        –[2012-11-21 16:02:20.438] End of 'sync' [->top] [->enclosing]
      • Sync: issued as (outgoing MsgID=2, CmdID=4), now queueing for status
      • Outgoing Message size is now 443 bytes

      –[2012-11-21 16:02:20.439] End of 'issue' [->top] [->enclosing]
    • Ending message with 0 next-message/0 next-package commands: FINAL (final allowed by caller)
    • XML translation enabled...
    • - Writing outgoing XML translation
    • Outgoing WBXML message msgID=2 saved as XML translation
    • Entire message size is now 446 Bytes
    • ---> NextMessage, outgoing state='sync', incoming state='sync'
    • Local Datastore 'events': State=sync_gen_done, SLOW sync, from server only

    –[2012-11-21 16:02:20.440] End of 'SyncML_Outgoing' [->top] [->enclosing]

  –[2012-11-21 16:02:20.440] End of 'SyncML_Incoming' [->top]
• ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
• ##### uiapp ReportStep: 101 6 0 0 0 0 / err=0
• ##### uiapp ReportStep: 110 6 0 0 0 0 / err=0
• ##### uiapp DoPost URL: https://webmail.your-server.de/rpc.php (redirect=o withHttp=v n=1)
• ##### uiapp POST 2 =>: size=446
• ##### uiapp DoPost https: err=0
• ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
• ##### uiapp ReportStep: 101 7 0 0 0 0 / err=0
• ##### uiapp ReportStep: 111 7 0 0 0 0 / err=0
• ##### uiapp POST 2 <=: size=498
• ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
• ##### uiapp ReportStep: 101 9 0 0 0 0 / err=0
• ##### uiapp ReportStep: 100 9 0 0 0 0 / err=0
• Calling smlProcessData(NEXT_COMMAND)
• +
  –
  [2012-11-21 16:02:20.573] 'SyncML_Incoming' - Starting to analyze incoming message, RequestNo=0, SySyncVers=3.4.0.53 [--][++] [->end]
  • =================> Starting to analyze incoming message, SySync V3.4.0.53, RequestNo=0
  • Initializing incoming XML translation instance
  • Created command 'SyncHdr' (incoming)
  • +
    –
    [2012-11-21 16:02:20.573] 'processHdr' - Processing incoming SyncHdr [--][++] [->end] [->enclosing]
    • +
      –
      [2012-11-21 16:02:20.573] 'SyncHdr' - Processing incoming SyncHdr, IncomingMsgID=2 [--][++] [->end] [->enclosing]
      • Started Processing of message #2 (SyncML/1.2)
      • MaxMsgSize for outgoing msgs set to 1000000000
      • MaxObjSize found in SyncHdr: 1000000000
      • Created command 'Status' (outgoing)
      • RespURI set to = 'https://webmail.your-server.de/rpc.php'
      • +
        –
        [2012-11-21 16:02:20.574] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
        • Outgoing message header not yet generated, command 'Status' queued

        –[2012-11-21 16:02:20.574] End of 'issue' [->top] [->enclosing]
      • Incoming SyncHdr processed, incomingMsgID=2, SyncMLVers=1.2
      • - Session ID='227'
      • - Source (Remote party): URI='https://webmail.your-server.de/rpc.php' DisplayName=''
      • - Response to be sent to URI='https://webmail.your-server.de/rpc.php'
      • - Target (Local party) : URI='1cb094accb4a-359901043542659' DisplayName='Android Device'
      • SyncHdr: finished execution -> deleting
      • Deleted command 'SyncHdr' (incoming MsgID=2, CmdID=0)
      • New message: Executing 0 delayed commands
      • 0 delayed commands could not yet be executed and are left in the queue for next message
      • New package: Sending 0 commands that were generated earlier for this package

      –[2012-11-21 16:02:20.574] End of 'SyncHdr' [->top] [->enclosing]

    –[2012-11-21 16:02:20.574] End of 'processHdr' [->top] [->enclosing]
  • Created command 'Status' (incoming)
  • +
    –
    [2012-11-21 16:02:20.575] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
    • Started processing Command 'Status' (incoming MsgID=2, CmdID=1)
    • WARNING: RECEIVED NON-OK STATUS 407 for command 'SyncHdr' (outgoing MsgID=2, CmdID=0)
    • - TargetRef (remoteID) = 'https://webmail.your-server.de/rpc.php'
    • - SourceRef (localID) = '1cb094accb4a-359901043542659'
    • Found matching command 'SyncHdr' for Status
    • Remote requests auth type='syncml:auth-basic'
    • Next Cred will have type='basic' and format='base64' and use nonce=''
    • Authentication failed (status=407) with remote server
    • +
      –
      [2012-11-21 16:02:20.575] 'SessionAbort' - Aborting Session, Status=400, ProblemSource=LOCAL [--][++] [->end] [->enclosing]
      • WARNING: Aborting Session with Reason Status 400 (LOCAL problem) ***
      • --------------- Ignoring all commands in this message (after 0 sec. request processing, 1 sec. total) with Status 514 (0=none) from here on
      • +
        –
        [2012-11-21 16:02:20.575] 'DSAbort' - Aborting datastore sync, abortStatusCode=400, localProblem=yes, resumable=yes [--][++] [->end] [->enclosing]
        • events: testState=TRUE - expected state>='sync_set_ready', found state=='sync_gen_done'
        • +
          –
          [2012-11-21 16:02:20.575] 'SaveSuspendState' - Saving state for suspend/resume, datastore=events [--][++] [->end] [->enclosing]
          • +
            –
            [2012-11-21 16:02:20.576] 'SaveResumeMarks' - let implementation save resume info, datastore=events [--][++] [->end] [->enclosing]
            • +
              –
              [2012-11-21 16:02:20.576] 'SaveAdminData' - Saving changelog, target and map info [--][++] [->end] [->enclosing]
              • SaveAdminData: saving remote anchor = ''
              • SaveAdminData: lastTwoWayModCount = 0, lastSuspendModCount = 3
              • SaveAdminData: saving 0 entries from fPendingAddMaps to fPendingMaps binfile
              • SaveAdminData: saving 0 entries from fUnconfirmedMaps to fPendingMaps binfile
              • SaveAdminData: creating pending item file: file name='/data/data/com.sysync/syncml_client_sysynclib/events_4_pitm_sysynclib_.bfi', storing 0 bytes
              • SaveAdminData: saved pending item: src='', targ='', laststatus=0, pistate=0, total=0, unconfirmed=0, stored=0
              • SaveAdminData: resumeAlertCode = 201, lastSuspendModCount = 3

              –[2012-11-21 16:02:20.577] End of 'SaveAdminData' [->top] [->enclosing]

            –[2012-11-21 16:02:20.577] End of 'SaveResumeMarks' [->top] [->enclosing]

          –[2012-11-21 16:02:20.591] End of 'SaveSuspendState' [->top] [->enclosing]
        • *************** Warning: Datastore flagged aborted (after 0 sec. request processing, 1 sec. total) with LOCAL Status 400

        –[2012-11-21 16:02:20.591] End of 'DSAbort' [->top] [->enclosing]

      –[2012-11-21 16:02:20.592] End of 'SessionAbort' [->top] [->enclosing]
    • Status: processed, removed command 'SyncHdr' from status wait queue
    • Status: command 'SyncHdr' has handled status and allows to be deleted
    • Deleted command 'SyncHdr' (outgoing MsgID=2, CmdID=0)
    • Deleted command 'Status' (incoming MsgID=2, CmdID=1)

    –[2012-11-21 16:02:20.592] End of 'processStatus' [->top] [->enclosing]
  • ##### uiapp ReportStep: 100 3 0 0 0 0 / err=0
  • ##### uiapp ReportStep: 101 24 1002 10400 2 0 / err=0
  • ##### uiapp ReportStep: 101 16 0 10400 0 0 / err=0
  • ##### uiapp ReportStep: 100 16 0 10400 0 0 / err=0
  • ##### uiapp ReportStep: 120 3 0 0 0 0 / err=0
  • ##### uiapp DoSyncSession: sync done: err=10400 cmd=120
  • +
    –
    [2012-11-21 16:02:20.598] 'WriteLogData' - JNI log [--][++] [->end] [->enclosing]
    • lastsync:20121121T150219Z
      targetkey:
      userkey:
      devicekey:
      domain:
      dsname:events
      dsremotepath:calendar
      dslocalpath:./events
      folderkey:
      remoteuri:https://webmail.your-server.de/rpc.php
      remotedesc:[unknown remote] (no devInf)
      remoteinfo:
      remoteuser:
      sessionid:212220313339451
      syncstatus:400
      sessionstatus:10400
      syncmlvers:1.2
      syncmode:1
      synctype:2
      localadded:0
      deviceadded:0
      localdeleted:0
      devicedeleted:0
      localrejected:0
      devicerejected:0
      localupdated:0
      deviceupdated:0
      sessionbytesin:0
      sessionbytesout:0
      databytesin:0
      databytesout:0

    –[2012-11-21 16:02:20.599] End of 'WriteLogData' [->top] [->enclosing]
  • +
    –
    [2012-11-21 16:02:20.608] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=sync_gen_done, newstate=completed [--][++] [->end] [->enclosing]
    • Sync Statistics for 'events' (./events), slow sync

    • ==================================================
                                   on Client   on Server
      Added:                               0           0
      Deleted:                             0           0
      Updated:                             0           0
      Rejected with error:                 0           0
      Content Data Bytes sent:             0
      Content Data Bytes received:         0
      Duration of sync [seconds]:          1

    • Warning: Failed with status code=400, statistics are incomplete!!

    –[2012-11-21 16:02:20.609] End of 'DSStateChange' [->top] [->enclosing]
  • +
    –
    [2012-11-21 16:02:20.609] 'DSStateChange' - Datastore changes state, datastore=events, oldstate=completed, newstate=idle [--][++] [->end] [->enclosing]
    • endDataWrite called, write is started
    • (1DD7E08) JNI (LNK): EndDataWrite: n=11 tCB=0229A838 cC=37502008 mC=37502008 sC=0
    • (1DD7E08) JNI (LNK): EndDataWrite
    • ##### ds_calendar 'W' (events) EndDataWrite
    • +
      –
      [2012-11-21 16:02:20.616] 'DispExdates' -  [--][++] [->end] [->enclosing]
      –[2012-11-21 16:02:20.616] End of 'DispExdates' [->top] [->enclosing]
    • +
      –
      [2012-11-21 16:02:20.616] 'FixedEx' -  [--][++] [->end] [->enclosing]
      –[2012-11-21 16:02:20.617] End of 'FixedEx' [->top] [->enclosing]
    • +
      –
      [2012-11-21 16:02:20.617] 'TouchAllEx' -  [--][++] [->end] [->enclosing]
      –[2012-11-21 16:02:20.617] End of 'TouchAllEx' [->top] [->enclosing]
    • ##### ds_calendar 'E' (events) HandleDuplicates: <none>
    • +
      –
      [2012-11-21 16:02:20.631] 'SaveHIF' -  [--][++] [->end] [->enclosing]
      • ##### ds_calendar 'E' (events) inUse=o: ACC name='PC Sync' / type='com.htc.pcsc' / cnt=0
      • ##### ds_calendar 'E' (events) inUse=o: ACC name='MyPhonebook' / type='com.htc.android.myphonebook' / cnt=0
      • ##### ds_calendar 'E' (events) inUse=o: ACC name='Kontakte' / type='HTC_BirthdayEvent' / cnt=0
      • ##### ds_calendar 'E' (events) inUse=o: ACC name='Tasks' / type='com.google/task' / cnt=0
      • ##### ds_calendar 'E' (events) inUse=o: ACC name='on.thomas.k@googlemail.com' / type='com.google' / cnt=0
      • ##### ds_calendar 'E' (events) inUse=o: ACC name='Deutsche Feiertage' / type='com.google' / cnt=0
      • ##### ds_calendar 'E' (events) inUse=o: ACC name='Geburts- und Jahrestage meiner Kontakte' / type='com.google' / cnt=0
      • ##### ds_calendar 'E' (events) inUse=o: ACC name='Wochennummern' / type='com.google' / cnt=0
      • ##### ds_calendar 'E' (events) inUse=o: ACC name='Tasks' / type='com.htc.task/task' / cnt=0
      • ##### ds_calendar 'E' (events) inUse=v: ACC name='cal1' / type='com.sysync' / cnt=0
      • ##### ds_calendar 'E' (events) SaveHIF: '%3 PC Sync com.htc.pcsc 0' (cnt=0)
      • ##### ds_calendar 'E' (events) SaveHIF: '%3 MyPhonebook com.htc.android.myphonebook 0' (cnt=0)
      • ##### ds_calendar 'E' (events) SaveHIF: '%3 Kontakte HTC_BirthdayEvent 0' (cnt=0)
      • ##### ds_calendar 'E' (events) SaveHIF: '%3 Tasks com.google/task 0' (cnt=0)
      • ##### ds_calendar 'E' (events) SaveHIF: '%3 on.thomas.k@googlemail.com com.google 0' (cnt=0)
      • ##### ds_calendar 'E' (events) SaveHIF: '%3 Deutsche Feiertage com.google 0' (cnt=0)
      • ##### ds_calendar 'E' (events) SaveHIF: '%3 Geburts- und Jahrestage meiner Kontakte com.google 0' (cnt=0)
      • ##### ds_calendar 'E' (events) SaveHIF: '%3 Wochennummern com.google 0' (cnt=0)
      • ##### ds_calendar 'E' (events) SaveHIF: '%3 Tasks com.htc.task/task 0' (cnt=0)
      • ##### ds_calendar 'E' (events) SaveHIF: '%3 cal1 com.sysync 0' (cnt=0)
      • ##### ds_calendar 'E' (events) SaveHIF: '/data/data/com.sysync/syncml_client_sysynclib/sysynclib_aevt4_W.bfi' 344
      • ##### ds_calendar 'E' (events) SaveHIF: => '/data/data/com.sysync/syncml_client_sysynclib/sysynclib_aevt4.bfi'
      • ##### ds_calendar 'E' (events) SaveHIF: '/sdcard/download/sysynclib_aevt4_W.bfi' 344
      • ##### ds_calendar 'E' (events) SaveHIF: => '/sdcard/download/sysynclib_aevt4.bfi'
      • ##### ds_calendar 'E' (events) SaveHIF: '/sdcard/download/sysynclib/sessions/212220313339451_events.txt' 344

      –[2012-11-21 16:02:20.654] End of 'SaveHIF' [->top] [->enclosing]
    • +
      –
      [2012-11-21 16:02:20.661] 'DispAIF' -  [--][++] [->end] [->enclosing]
      • ##### ds_calendar DispAIF: ----------------------------------------------------------------------------

      –[2012-11-21 16:02:20.661] End of 'DispAIF' [->top] [->enclosing]
    • ##### ds_calendar SaveAIF: write '/data/data/com.sysync/syncml_client_sysynclib/sysynclib_acal4_W.bfi'
    • ##### ds_calendar SaveAIF: write '/data/data/com.sysync/syncml_client_sysynclib/sysynclib_acal4_W.bfi' # lines=0
    • ##### ds_calendar SaveAIF: FC='/data/data/com.sysync/syncml_client_sysynclib/sysynclib_acal4.bfi' 0
    • ##### ds_calendar SaveAIF: WC='/data/data/com.sysync/syncml_client_sysynclib/sysynclib_acal4_W.bfi' 0
    • ##### ds_calendar SaveAIF: FC='/data/data/com.sysync/syncml_client_sysynclib/sysynclib_acal4.bfi' 0
    • ##### ds_calendar SaveAIF: WC='/data/data/com.sysync/syncml_client_sysynclib/sysynclib_acal4_W.bfi' -1
    • ##### ds_calendar SaveAIF: ok=true
    • ##### ds_calendar 'E' (events) EndDataWrite: success=v
    • ##### JNI (LNK): DisposeObj: free at 02355360

    –[2012-11-21 16:02:20.669] End of 'DSStateChange' [->top] [->enclosing]
  • (1DD7E08) JNI (LNK): DeleteContext: n=12 tCB=0229A838 cC=37502008 mC=37502008 sC=0
  • (1DD7E08) JNI (LNK): DeleteContext: aContext=37028704
  • ##### ds_calendar 'E' (events) DeleteContext
  • (1DD7E08) JNI (LNK): Session_DeleteContext: n=2 tCB=022CA200 cC=36257368 mC=36257368 sC=36277416
  • (1DD7E08) JNI (LNK): Session_DeleteContext: sContext=36277416
  • ##### session_plugin (212220313339451) Session_DeleteContext
  • TSyncSession::TerminateSession: calling InternalResetSession
  • Never received status for command 'Sync', (outgoing MsgID=2, CmdID=4)
  • Deleted command 'Sync' (outgoing MsgID=2, CmdID=4)
  • Deleted command 'Status' (outgoing MsgID=0, CmdID=0)
  • TSyncSession::TerminateSession: InternalResetSession called
  • Deleting 1 datastores
  • XML translation enabled...
  • - Writing incoming XML translation
  • Incoming WBXML message msgID=0 saved as XML translation
  • Session is now terminated (but not yet deleted)

  –[2012-11-21 16:02:20.697] End of 'SyncML_Incoming' - closed because log ends here - Block Nest Warning: closing '', but expected 'SyncML_Incoming' [->top]

End of log