Synchro avec evolution

classic Classic list List threaded Threaded
6 messages Options
benoits benoits
Reply | Threaded
Open this post in threaded view
|

Synchro avec evolution

Bonjour,
J'utilise Egw 1.8 et j'en suis très content (serveur debian 6)
Je souhaiterai synchroniser Egw avec mes clients evolution (ubuntu 11.10)
J'ai donc essayé Syncevolution, mais il y a une erreur que je ne comprends pas. A priori, il me dit que l'identifiant ou le mdp sont faux. Mais je suis sur que non, et dans le journal des sessions, les connexions sont données "success".
Ci dessous le log de connexion.

Merci pour les idées.
Benoit

Start of log - Synthesis SyncML Engine 3.4.0.27

    [-- collapse all --][++ expand all ++]
    +
    [2011-12-02 21:37:33.198] 'loadSystemZoneDefinitions' - Linux system time zones [--][++] [->end]
    [2011-12-02 21:37:33.292] SyncML server account: benoit
    [2011-12-02 21:37:33.294] client: SyncEvolution 1.2.1 for workstation
    [2011-12-02 21:37:33.296] device ID: syncevolution-5694a4ec-d7e7-47b2-92ce-36f5958be8d9
    [2011-12-02 21:37:33.298] using libedataserver-1.2.so.15 - might not be compatible!
    using libebook-1.2.so.12 - might not be compatible!
    using libecal-1.2.so.10 - might not be compatible!
    using libecal-1.2.so.10 - might not be compatible!
    using libbluetooth.so.3
    sdp_extract_pdu_safe not found
    sdp_extract_seqtype_safe not found
    [2011-12-02 21:37:33.299] Scanning backend libraries in /usr/lib/syncevolution/backends/
    Loading backend library synckcalextended.so
    Loading backend library syncakonadi.so
    Loading backend library syncdav.so
    Loading backend library syncsqlite.so
    Loading backend library syncmaemocal.so
    Loading backend library syncxmlrpc.so
    Loading backend library syncaddressbook.so
    Loading backend library syncebook.so
    Loading backend library syncqtcontacts.so
    Loading backend library syncfile.so
    Loading backend library syncecal.so
    [2011-12-02 21:37:33.301] memo: inactive
    [2011-12-02 21:37:33.303] todo: inactive
    [2011-12-02 21:37:33.539] CreateContext SyncEvolution//addressbook => 0
    [2011-12-02 21:37:33.540] Module_Version = 01080000
    [2011-12-02 21:37:33.540] 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-12-02 21:37:33.542] Module_PluginParams
    Engine=01080000
    [2011-12-02 21:37:33.542] 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-12-02 21:37:33.543] CreateContext SyncEvolution//calendar => 0
    [2011-12-02 21:37:33.544] Module_Version = 01080000
    [2011-12-02 21:37:33.544] 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-12-02 21:37:33.546] Module_PluginParams
    Engine=01080000
    [2011-12-02 21:37:33.546] 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-12-02 21:37:33.551] --------- START of embedded log for session ID '212189661453551' ---------
    [2011-12-02 21:37:33.551] Executing Script 'sessioninitscript'
    [2011-12-02 21:37:33.552] ==== Client Session started with SyncML (Server+Client) Engine Version 3.4.0.27
    [2011-12-02 21:37:33.552] ---- Hardcoded Product name: SySync SyncML Library OpenSource Linux
    [2011-12-02 21:37:33.552] ---- Configured Model/Manufacturer: SyncEvolution / Patrick Ohly
    [2011-12-02 21:37:33.552] ---- Running on Linux, URI/deviceID='linux:rantanplan'
    [2011-12-02 21:37:33.552] ---- Platform Hardware Name/Version = 'Linux PC', Firmware/OS Version = 'unknown'
    [2011-12-02 21:37:33.552] ---- Configured Hardware Version = 'unknown', Firmware Version = '1.2.1'
    [2011-12-02 21:37:33.552] ---- System local time : 2011-12-02 21:37:33.552 (time zone 'CET/CEST', offset 1:00 hours east of UTC)
    [2011-12-02 21:37:33.552] ---- System time in UTC : 2011-12-02 20:37:33.552
    [2011-12-02 21:37:33.552] ==== Config file='', Last Change=2011-12-02 20:37:33.000
    [2011-12-02 21:37:33.552] ==== Config ID string='SyncEvolution client config'
    [2011-12-02 21:37:33.553] Executing Script 'sessioninitscript'
    –
    [2011-12-02 21:37:33.553] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=idle, newstate=client_initialized [--][++] [->end]
    –[2011-12-02 21:37:33.553] End of 'DSStateChange' [->top]
    –
    [2011-12-02 21:37:33.553] 'DSStateChange' - Datastore changes state, datastore=calendar, oldstate=idle, newstate=client_initialized [--][++] [->end]
    –[2011-12-02 21:37:33.553] End of 'DSStateChange' [->top]
    [2011-12-02 21:37:33.554]
    [2011-12-02 21:37:33.554] =================> Starting new client session
    –
    [2011-12-02 21:37:33.554] 'SyncML_Outgoing' - start of new outgoing message [--][++] [->end]
        [2011-12-02 21:37:33.554] =================> Started new outgoing message
        [2011-12-02 21:37:33.554] Created command 'SyncHdr' (outgoing)
        [2011-12-02 21:37:33.554] Target (Remote URI) = 'https://192.168.5.173/egroupware/rpc.php'
        –
        [2011-12-02 21:37:33.554] 'SyncHdr' - SyncHdr generation, SyncMLVers=1.2, OutgoingMsgID=1 [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:33.554] SyncHdr: issued in MsgID=1, now queueing for status
            [2011-12-02 21:37:33.555] Outgoing Message size is now 240 bytes
        –[2011-12-02 21:37:33.555] End of 'SyncHdr' [->top] [->enclosing]
        +
        [2011-12-02 21:37:33.555] 'MakeAdminReady' - Making Admin Data ready to check sync anchors, localDB=addressbook, remoteDB=./contacts [--][++] [->end] [->enclosing]
        –
        [2011-12-02 21:37:33.555] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=client_initialized, newstate=admin_ready [--][++] [->end] [->enclosing]
        –[2011-12-02 21:37:33.555] End of 'DSStateChange' [->top] [->enclosing]
        +
        [2011-12-02 21:37:33.556] 'MakeAdminReady' - Making Admin Data ready to check sync anchors, localDB=calendar, remoteDB=./calendar [--][++] [->end] [->enclosing]
        –
        [2011-12-02 21:37:33.556] 'DSStateChange' - Datastore changes state, datastore=calendar, oldstate=client_initialized, newstate=admin_ready [--][++] [->end] [->enclosing]
        –[2011-12-02 21:37:33.556] End of 'DSStateChange' [->top] [->enclosing]
        [2011-12-02 21:37:33.556] Created command 'Put' (outgoing)
        –
        [2011-12-02 21:37:33.558] 'issue' - issuing command, Cmd=Put [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:33.562] Put: issued as (outgoing MsgID=1, CmdID=1), now queueing for status
            [2011-12-02 21:37:33.564] Outgoing Message size is now 6649 bytes
        –[2011-12-02 21:37:33.564] End of 'issue' [->top] [->enclosing]
        [2011-12-02 21:37:33.564] Nothing known about server, request DevInf using GET command
        [2011-12-02 21:37:33.565] Created command 'Get' (outgoing)
        –
        [2011-12-02 21:37:33.565] 'issue' - issuing command, Cmd=Get [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:33.565] Get: issued as (outgoing MsgID=1, CmdID=2), now queueing for status
            [2011-12-02 21:37:33.565] Outgoing Message size is now 6719 bytes
        –[2011-12-02 21:37:33.565] End of 'issue' [->top] [->enclosing]
        [2011-12-02 21:37:33.565] (Saved) Last Local Client Anchor='', (generated) Next Local Client Anchor='20111202T203733Z' (sent to server as <last>/<next> in <alert>)
        [2011-12-02 21:37:33.566] Created command 'Alert' (outgoing)
        [2011-12-02 21:37:33.566] addressbook: ALERTING server for slow first time Sync
        –
        [2011-12-02 21:37:33.566] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=admin_ready, newstate=client_sent_alert [--][++] [->end] [->enclosing]
        –[2011-12-02 21:37:33.566] End of 'DSStateChange' [->top] [->enclosing]
        –
        [2011-12-02 21:37:33.566] 'issue' - issuing command, Cmd=Alert [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:33.566] Alert Code 201 sent
            [2011-12-02 21:37:33.566] - Alert Item: Source='./addressbook', Target='./contacts'
            [2011-12-02 21:37:33.566] Alert: issued as (outgoing MsgID=1, CmdID=3), now queueing for status
            [2011-12-02 21:37:33.566] Outgoing Message size is now 6807 bytes
        –[2011-12-02 21:37:33.566] End of 'issue' [->top] [->enclosing]
        [2011-12-02 21:37:33.566] (Saved) Last Local Client Anchor='', (generated) Next Local Client Anchor='20111202T203733Z' (sent to server as <last>/<next> in <alert>)
        [2011-12-02 21:37:33.566] Created command 'Alert' (outgoing)
        [2011-12-02 21:37:33.567] calendar: ALERTING server for slow first time Sync
        –
        [2011-12-02 21:37:33.567] 'DSStateChange' - Datastore changes state, datastore=calendar, oldstate=admin_ready, newstate=client_sent_alert [--][++] [->end] [->enclosing]
        –[2011-12-02 21:37:33.567] End of 'DSStateChange' [->top] [->enclosing]
        –
        [2011-12-02 21:37:33.567] 'issue' - issuing command, Cmd=Alert [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:33.567] Alert Code 201 sent
            [2011-12-02 21:37:33.567] - Alert Item: Source='./calendar', Target='./calendar'
            [2011-12-02 21:37:33.567] Alert: issued as (outgoing MsgID=1, CmdID=4), now queueing for status
            [2011-12-02 21:37:33.567] Outgoing Message size is now 6894 bytes
        –[2011-12-02 21:37:33.567] End of 'issue' [->top] [->enclosing]
        [2011-12-02 21:37:33.567] Ending message with 0 next-message/0 next-package commands: FINAL (final allowed by caller)
        [2011-12-02 21:37:33.567] Entire message size is now 6899 Bytes
    –[2011-12-02 21:37:33.567] End of 'SyncML_Outgoing' [->top]
    –
    [2011-12-02 21:37:33.819] 'SyncML_Incoming' - Starting to analyze incoming message, RequestNo=0, SySyncVers=3.4.0.27 [--][++] [->end]
        [2011-12-02 21:37:33.819] =================> Starting to analyze incoming message, SySync V3.4.0.27, RequestNo=0
        [2011-12-02 21:37:33.819] Created command 'SyncHdr' (incoming)
        –
        [2011-12-02 21:37:33.819] 'processHdr' - Processing incoming SyncHdr [--][++] [->end] [->enclosing]
            –
            [2011-12-02 21:37:33.819] 'SyncHdr' - Processing incoming SyncHdr, IncomingMsgID=1 [--][++] [->end] [->enclosing]
                [2011-12-02 21:37:33.819] Started Processing of message #1 (SyncML/1.2)
                [2011-12-02 21:37:33.819] MaxMsgSize for outgoing msgs set to 10000
                [2011-12-02 21:37:33.819] Created command 'Status' (outgoing)
                [2011-12-02 21:37:33.819] RespURI set to = 'https://192.168.5.173/egroupware/rpc.php?syncml_sessionid=syncml-1c892ce5864ead67011b6ff882de4129'
                –
                [2011-12-02 21:37:33.820] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
                –[2011-12-02 21:37:33.820] End of 'issue' [->top] [->enclosing]
                [2011-12-02 21:37:33.820] Incoming SyncHdr processed, incomingMsgID=1, SyncMLVers=1.2
                [2011-12-02 21:37:33.820] - Session ID='45'
                [2011-12-02 21:37:33.820] - Source (Remote party): URI='https://192.168.5.173/egroupware/rpc.php' DisplayName=''
                [2011-12-02 21:37:33.820] - Response to be sent to URI='https://192.168.5.173/egroupware/rpc.php?syncml_sessionid=syncml-1c892ce5864ead67011b6ff882de4129'
                [2011-12-02 21:37:33.820] - Target (Local party) : URI='syncevolution-5694a4ec-d7e7-47b2-92ce-36f5958be8d9' DisplayName='Linux PC'
                [2011-12-02 21:37:33.820] Deleted command 'SyncHdr' (incoming MsgID=1, CmdID=0)
            –[2011-12-02 21:37:33.820] End of 'SyncHdr' [->top] [->enclosing]
        –[2011-12-02 21:37:33.820] End of 'processHdr' [->top] [->enclosing]
        [2011-12-02 21:37:33.820] Created command 'Status' (incoming)
        –
        [2011-12-02 21:37:33.820] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:33.820] Started processing Command 'Status' (incoming MsgID=1, CmdID=1)
            [2011-12-02 21:37:33.821] WARNING: RECEIVED NON-OK STATUS 401 for command 'SyncHdr' (outgoing MsgID=1, CmdID=0)
            [2011-12-02 21:37:33.821] - TargetRef (remoteID) = 'https://192.168.5.173/egroupware/rpc.php'
            [2011-12-02 21:37:33.821] - SourceRef (localID) = 'syncevolution-5694a4ec-d7e7-47b2-92ce-36f5958be8d9'
            [2011-12-02 21:37:33.821] Found matching command 'SyncHdr' for Status
            [2011-12-02 21:37:33.821] Remote requests auth type='syncml:auth-basic'
            [2011-12-02 21:37:33.821] Next Cred will have type='basic' and format='base64' and use nonce=''
            [2011-12-02 21:37:33.821] Authentication failed (status=401) with remote server
        –[2011-12-02 21:37:33.821] End of 'processStatus' [->top] [->enclosing]
        [2011-12-02 21:37:33.821] =================> Retrying Client Session Start
    –[2011-12-02 21:37:33.821] End of 'SyncML_Incoming' [->top]
    [2011-12-02 21:37:33.821] Deleted command 'Status' (outgoing MsgID=0, CmdID=0)
    –
    [2011-12-02 21:37:33.821] 'SyncML_Outgoing' - start of new outgoing message [--][++] [->end]
        [2011-12-02 21:37:33.821] =================> Started new outgoing message
        [2011-12-02 21:37:33.821] Created command 'SyncHdr' (outgoing)
        [2011-12-02 21:37:33.822] Target (Remote URI) = 'https://192.168.5.173/egroupware/rpc.php'
        –
        [2011-12-02 21:37:33.822] 'SyncHdr' - SyncHdr generation, SyncMLVers=1.2, OutgoingMsgID=1 [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:33.822] SyncHdr: issued in MsgID=1, now queueing for status
            [2011-12-02 21:37:33.822] Outgoing Message size is now 240 bytes
        –[2011-12-02 21:37:33.822] End of 'SyncHdr' [->top] [->enclosing]
        [2011-12-02 21:37:33.822] --------------- Ignoring all commands in this message (after 0 sec. request processing, 0 sec. total) with Status 0 (0=none) from here on
        [2011-12-02 21:37:33.822] Deleted command 'SyncHdr' (outgoing MsgID=1, CmdID=0)
        [2011-12-02 21:37:33.822] Deleted command 'Status' (incoming MsgID=1, CmdID=1)
    –[2011-12-02 21:37:33.822] End of 'SyncML_Outgoing' - Block Nest Warning: closing 'processStatus', but expected 'SyncML_Outgoing' [->top]
    [2011-12-02 21:37:33.823] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:33.823] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:33.823] Started processing Command 'Status' (incoming MsgID=0, CmdID=2)
        [2011-12-02 21:37:33.823] WARNING: RECEIVED NON-OK STATUS 401 for command 'Put' (outgoing MsgID=1, CmdID=1)
        [2011-12-02 21:37:33.823] - SourceRef (localID) = './devinf12'
        [2011-12-02 21:37:33.823] Found matching command 'Put' for Status
        [2011-12-02 21:37:33.823] Deleted command 'Put' (outgoing MsgID=1, CmdID=1)
        [2011-12-02 21:37:33.823] Deleted command 'Status' (incoming MsgID=0, CmdID=2)
    –[2011-12-02 21:37:33.823] End of 'processStatus' [->top]
    [2011-12-02 21:37:33.824] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:33.824] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:33.825] Started processing Command 'Status' (incoming MsgID=0, CmdID=3)
        [2011-12-02 21:37:33.825] WARNING: RECEIVED NON-OK STATUS 401 for command 'Get' (outgoing MsgID=1, CmdID=2)
        [2011-12-02 21:37:33.825] - TargetRef (remoteID) = './devinf12'
        [2011-12-02 21:37:33.825] Found matching command 'Get' for Status
        [2011-12-02 21:37:33.825] Deleted command 'Get' (outgoing MsgID=1, CmdID=2)
        [2011-12-02 21:37:33.825] Deleted command 'Status' (incoming MsgID=0, CmdID=3)
    –[2011-12-02 21:37:33.825] End of 'processStatus' [->top]
    [2011-12-02 21:37:33.826] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:33.826] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:33.826] Started processing Command 'Status' (incoming MsgID=0, CmdID=4)
        [2011-12-02 21:37:33.826] WARNING: RECEIVED NON-OK STATUS 401 for command 'Alert' (outgoing MsgID=1, CmdID=3)
        [2011-12-02 21:37:33.826] Found matching command 'Alert' for Status
        [2011-12-02 21:37:33.826] Deleted command 'Alert' (outgoing MsgID=1, CmdID=3)
        [2011-12-02 21:37:33.826] Deleted command 'Status' (incoming MsgID=0, CmdID=4)
    –[2011-12-02 21:37:33.826] End of 'processStatus' [->top]
    [2011-12-02 21:37:33.826] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:33.826] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:33.826] Started processing Command 'Status' (incoming MsgID=0, CmdID=5)
        [2011-12-02 21:37:33.827] WARNING: RECEIVED NON-OK STATUS 401 for command 'Alert' (outgoing MsgID=1, CmdID=4)
        [2011-12-02 21:37:33.827] Found matching command 'Alert' for Status
        [2011-12-02 21:37:33.827] Deleted command 'Alert' (outgoing MsgID=1, CmdID=4)
        [2011-12-02 21:37:33.827] Deleted command 'Status' (incoming MsgID=0, CmdID=5)
    –[2011-12-02 21:37:33.827] End of 'processStatus' [->top]
    [2011-12-02 21:37:33.827] =================> Finished processing incoming message #0 (not final), request=0
    [2011-12-02 21:37:33.827] MessageEnded finishes : new outgoing state='init', new incoming state='idle', NO NeedToAnswer
    [2011-12-02 21:37:33.827] Local Datastore 'addressbook': State=client_sent_alert, SLOW sync, two-way
    [2011-12-02 21:37:33.827] Local Datastore 'calendar': State=client_sent_alert, SLOW sync, two-way
    +
    [2011-12-02 21:37:33.828] 'MakeAdminReady' - Making Admin Data ready to check sync anchors, localDB=addressbook, remoteDB=./contacts [--][++] [->end]
    –
    [2011-12-02 21:37:33.828] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=client_sent_alert, newstate=admin_ready [--][++] [->end]
    –[2011-12-02 21:37:33.828] End of 'DSStateChange' [->top]
    +
    [2011-12-02 21:37:33.828] 'MakeAdminReady' - Making Admin Data ready to check sync anchors, localDB=calendar, remoteDB=./calendar [--][++] [->end]
    –
    [2011-12-02 21:37:33.829] 'DSStateChange' - Datastore changes state, datastore=calendar, oldstate=client_sent_alert, newstate=admin_ready [--][++] [->end]
    –[2011-12-02 21:37:33.829] End of 'DSStateChange' [->top]
    [2011-12-02 21:37:33.829] Created command 'Put' (outgoing)
    –
    [2011-12-02 21:37:33.831] 'issue' - issuing command, Cmd=Put [--][++] [->end]
        [2011-12-02 21:37:33.832] Put: issued as (outgoing MsgID=1, CmdID=1), now queueing for status
        [2011-12-02 21:37:33.832] Outgoing Message size is now 6649 bytes
    –[2011-12-02 21:37:33.832] End of 'issue' [->top]
    [2011-12-02 21:37:33.833] Nothing known about server, request DevInf using GET command
    [2011-12-02 21:37:33.833] Created command 'Get' (outgoing)
    –
    [2011-12-02 21:37:33.833] 'issue' - issuing command, Cmd=Get [--][++] [->end]
        [2011-12-02 21:37:33.833] Get: issued as (outgoing MsgID=1, CmdID=2), now queueing for status
        [2011-12-02 21:37:33.833] Outgoing Message size is now 6719 bytes
    –[2011-12-02 21:37:33.833] End of 'issue' [->top]
    [2011-12-02 21:37:33.833] (Saved) Last Local Client Anchor='', (generated) Next Local Client Anchor='20111202T203733Z' (sent to server as <last>/<next> in <alert>)
    [2011-12-02 21:37:33.833] Created command 'Alert' (outgoing)
    [2011-12-02 21:37:33.833] addressbook: ALERTING server for slow first time Sync
    –
    [2011-12-02 21:37:33.833] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=admin_ready, newstate=client_sent_alert [--][++] [->end]
    –[2011-12-02 21:37:33.834] End of 'DSStateChange' [->top]
    –
    [2011-12-02 21:37:33.834] 'issue' - issuing command, Cmd=Alert [--][++] [->end]
        [2011-12-02 21:37:33.834] Alert Code 201 sent
        [2011-12-02 21:37:33.834] - Alert Item: Source='./addressbook', Target='./contacts'
        [2011-12-02 21:37:33.834] Alert: issued as (outgoing MsgID=1, CmdID=3), now queueing for status
        [2011-12-02 21:37:33.834] Outgoing Message size is now 6807 bytes
    –[2011-12-02 21:37:33.834] End of 'issue' [->top]
    [2011-12-02 21:37:33.834] (Saved) Last Local Client Anchor='', (generated) Next Local Client Anchor='20111202T203733Z' (sent to server as <last>/<next> in <alert>)
    [2011-12-02 21:37:33.834] Created command 'Alert' (outgoing)
    [2011-12-02 21:37:33.834] calendar: ALERTING server for slow first time Sync
    –
    [2011-12-02 21:37:33.834] 'DSStateChange' - Datastore changes state, datastore=calendar, oldstate=admin_ready, newstate=client_sent_alert [--][++] [->end]
    –[2011-12-02 21:37:33.834] End of 'DSStateChange' [->top]
    –
    [2011-12-02 21:37:33.834] 'issue' - issuing command, Cmd=Alert [--][++] [->end]
        [2011-12-02 21:37:33.835] Alert Code 201 sent
        [2011-12-02 21:37:33.835] - Alert Item: Source='./calendar', Target='./calendar'
        [2011-12-02 21:37:33.835] Alert: issued as (outgoing MsgID=1, CmdID=4), now queueing for status
        [2011-12-02 21:37:33.835] Outgoing Message size is now 6894 bytes
    –[2011-12-02 21:37:33.835] End of 'issue' [->top]
    [2011-12-02 21:37:33.835] Ending message with 0 next-message/0 next-package commands: FINAL (final allowed by caller)
    [2011-12-02 21:37:33.835] Entire message size is now 6899 Bytes
    –
    [2011-12-02 21:37:33.985] 'SyncML_Incoming' - Starting to analyze incoming message, RequestNo=0, SySyncVers=3.4.0.27 [--][++] [->end]
        [2011-12-02 21:37:33.985] =================> Starting to analyze incoming message, SySync V3.4.0.27, RequestNo=0
        [2011-12-02 21:37:33.985] Created command 'SyncHdr' (incoming)
        –
        [2011-12-02 21:37:33.986] 'processHdr' - Processing incoming SyncHdr [--][++] [->end] [->enclosing]
            –
            [2011-12-02 21:37:33.986] 'SyncHdr' - Processing incoming SyncHdr, IncomingMsgID=1 [--][++] [->end] [->enclosing]
                [2011-12-02 21:37:33.986] Started Processing of message #1 (SyncML/1.2)
                [2011-12-02 21:37:33.986] MaxMsgSize for outgoing msgs set to 10000
                [2011-12-02 21:37:33.986] Created command 'Status' (outgoing)
                [2011-12-02 21:37:33.986] RespURI set to = 'https://192.168.5.173/egroupware/rpc.php?syncml_sessionid=syncml-b3048d37d0ad20d6c7009ef12d15ea9c'
                –
                [2011-12-02 21:37:33.986] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
                –[2011-12-02 21:37:33.986] End of 'issue' [->top] [->enclosing]
                [2011-12-02 21:37:33.986] Incoming SyncHdr processed, incomingMsgID=1, SyncMLVers=1.2
                [2011-12-02 21:37:33.986] - Session ID='46'
                [2011-12-02 21:37:33.986] - Source (Remote party): URI='https://192.168.5.173/egroupware/rpc.php' DisplayName=''
                [2011-12-02 21:37:33.986] - Response to be sent to URI='https://192.168.5.173/egroupware/rpc.php?syncml_sessionid=syncml-b3048d37d0ad20d6c7009ef12d15ea9c'
                [2011-12-02 21:37:33.986] - Target (Local party) : URI='syncevolution-5694a4ec-d7e7-47b2-92ce-36f5958be8d9' DisplayName='Linux PC'
                [2011-12-02 21:37:33.987] Deleted command 'SyncHdr' (incoming MsgID=1, CmdID=0)
            –[2011-12-02 21:37:33.987] End of 'SyncHdr' [->top] [->enclosing]
        –[2011-12-02 21:37:33.987] End of 'processHdr' [->top] [->enclosing]
        [2011-12-02 21:37:33.987] Created command 'Status' (incoming)
        –
        [2011-12-02 21:37:33.987] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:33.987] Started processing Command 'Status' (incoming MsgID=1, CmdID=1)
            [2011-12-02 21:37:33.987] WARNING: RECEIVED NON-OK STATUS 401 for command 'SyncHdr' (outgoing MsgID=1, CmdID=0)
            [2011-12-02 21:37:33.987] - TargetRef (remoteID) = 'https://192.168.5.173/egroupware/rpc.php'
            [2011-12-02 21:37:33.987] - SourceRef (localID) = 'syncevolution-5694a4ec-d7e7-47b2-92ce-36f5958be8d9'
            [2011-12-02 21:37:33.987] Found matching command 'SyncHdr' for Status
            [2011-12-02 21:37:33.987] Remote requests auth type='syncml:auth-basic'
            [2011-12-02 21:37:33.987] Next Cred will have type='basic' and format='base64' and use nonce=''
            [2011-12-02 21:37:33.988] Authentication failed (status=401) with remote server
        –[2011-12-02 21:37:33.988] End of 'processStatus' [->top] [->enclosing]
        [2011-12-02 21:37:33.988] =================> Retrying Client Session Start
        [2011-12-02 21:37:33.988] Smart retry with modified behaviour: newSessionForAuthRetry=0, noRespURIForAuthRetry=0
        [2011-12-02 21:37:33.988] Deleted command 'Status' (outgoing MsgID=0, CmdID=0)
        –
        [2011-12-02 21:37:33.988] 'SyncML_Outgoing' - start of new outgoing message [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:33.988] =================> Started new outgoing message
            [2011-12-02 21:37:33.988] Created command 'SyncHdr' (outgoing)
            [2011-12-02 21:37:33.988] Target (Remote URI) = 'https://192.168.5.173/egroupware/rpc.php?syncml_sessionid=syncml-b3048d37d0ad20d6c7009ef12d15ea9c'
            –
            [2011-12-02 21:37:33.988] 'SyncHdr' - SyncHdr generation, SyncMLVers=1.2, OutgoingMsgID=2 [--][++] [->end] [->enclosing]
                [2011-12-02 21:37:33.988] SyncHdr: issued in MsgID=2, now queueing for status
                [2011-12-02 21:37:33.988] Outgoing Message size is now 297 bytes
            –[2011-12-02 21:37:33.989] End of 'SyncHdr' [->top] [->enclosing]
            [2011-12-02 21:37:33.989] --------------- Ignoring all commands in this message (after 0 sec. request processing, 0 sec. total) with Status 0 (0=none) from here on
            [2011-12-02 21:37:33.989] Deleted command 'SyncHdr' (outgoing MsgID=1, CmdID=0)
            [2011-12-02 21:37:33.989] Deleted command 'Status' (incoming MsgID=1, CmdID=1)
        –[2011-12-02 21:37:33.989] End of 'SyncML_Outgoing' - Block Nest Warning: implicitly closed (by explicitly closing 'processStatus') [->top] [->enclosing]
    –[2011-12-02 21:37:33.989] End of 'SyncML_Incoming' - Block Nest Warning: closing 'processStatus', but expected 'SyncML_Incoming' [->top]
    [2011-12-02 21:37:33.989] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:33.989] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:33.989] Started processing Command 'Status' (incoming MsgID=1, CmdID=2)
        [2011-12-02 21:37:33.989] WARNING: RECEIVED NON-OK STATUS 401 for command 'Put' (outgoing MsgID=1, CmdID=1)
        [2011-12-02 21:37:33.990] - SourceRef (localID) = './devinf12'
        [2011-12-02 21:37:33.990] Found matching command 'Put' for Status
        [2011-12-02 21:37:33.990] Deleted command 'Put' (outgoing MsgID=1, CmdID=1)
        [2011-12-02 21:37:33.990] Deleted command 'Status' (incoming MsgID=1, CmdID=2)
    –[2011-12-02 21:37:33.990] End of 'processStatus' [->top]
    [2011-12-02 21:37:33.990] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:33.990] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:33.990] Started processing Command 'Status' (incoming MsgID=1, CmdID=3)
        [2011-12-02 21:37:33.990] WARNING: RECEIVED NON-OK STATUS 401 for command 'Get' (outgoing MsgID=1, CmdID=2)
        [2011-12-02 21:37:33.990] - TargetRef (remoteID) = './devinf12'
        [2011-12-02 21:37:33.990] Found matching command 'Get' for Status
        [2011-12-02 21:37:33.990] Deleted command 'Get' (outgoing MsgID=1, CmdID=2)
        [2011-12-02 21:37:33.990] Deleted command 'Status' (incoming MsgID=1, CmdID=3)
    –[2011-12-02 21:37:33.990] End of 'processStatus' [->top]
    [2011-12-02 21:37:33.996] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:33.996] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:33.996] Started processing Command 'Status' (incoming MsgID=1, CmdID=4)
        [2011-12-02 21:37:33.996] WARNING: RECEIVED NON-OK STATUS 401 for command 'Alert' (outgoing MsgID=1, CmdID=3)
        [2011-12-02 21:37:33.996] Found matching command 'Alert' for Status
        [2011-12-02 21:37:33.996] Deleted command 'Alert' (outgoing MsgID=1, CmdID=3)
        [2011-12-02 21:37:33.997] Deleted command 'Status' (incoming MsgID=1, CmdID=4)
    –[2011-12-02 21:37:33.997] End of 'processStatus' [->top]
    [2011-12-02 21:37:33.997] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:33.997] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:33.997] Started processing Command 'Status' (incoming MsgID=1, CmdID=5)
        [2011-12-02 21:37:33.997] WARNING: RECEIVED NON-OK STATUS 401 for command 'Alert' (outgoing MsgID=1, CmdID=4)
        [2011-12-02 21:37:33.997] Found matching command 'Alert' for Status
        [2011-12-02 21:37:33.997] Deleted command 'Alert' (outgoing MsgID=1, CmdID=4)
        [2011-12-02 21:37:33.997] Deleted command 'Status' (incoming MsgID=1, CmdID=5)
    –[2011-12-02 21:37:33.997] End of 'processStatus' [->top]
    [2011-12-02 21:37:33.998] =================> Finished processing incoming message #1 (not final), request=0
    [2011-12-02 21:37:33.998] MessageEnded finishes : new outgoing state='init', new incoming state='idle', NO NeedToAnswer
    [2011-12-02 21:37:33.998] Local Datastore 'addressbook': State=client_sent_alert, SLOW sync, two-way
    [2011-12-02 21:37:33.998] Local Datastore 'calendar': State=client_sent_alert, SLOW sync, two-way
    +
    [2011-12-02 21:37:33.998] 'MakeAdminReady' - Making Admin Data ready to check sync anchors, localDB=addressbook, remoteDB=./contacts [--][++] [->end]
    –
    [2011-12-02 21:37:33.998] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=client_sent_alert, newstate=admin_ready [--][++] [->end]
    –[2011-12-02 21:37:33.998] End of 'DSStateChange' [->top]
    +
    [2011-12-02 21:37:33.998] 'MakeAdminReady' - Making Admin Data ready to check sync anchors, localDB=calendar, remoteDB=./calendar [--][++] [->end]
    –
    [2011-12-02 21:37:33.999] 'DSStateChange' - Datastore changes state, datastore=calendar, oldstate=client_sent_alert, newstate=admin_ready [--][++] [->end]
    –[2011-12-02 21:37:33.999] End of 'DSStateChange' [->top]
    [2011-12-02 21:37:33.999] Created command 'Put' (outgoing)
    –
    [2011-12-02 21:37:34.001] 'issue' - issuing command, Cmd=Put [--][++] [->end]
        [2011-12-02 21:37:34.002] Put: issued as (outgoing MsgID=2, CmdID=1), now queueing for status
        [2011-12-02 21:37:34.002] Outgoing Message size is now 6706 bytes
    –[2011-12-02 21:37:34.002] End of 'issue' [->top]
    [2011-12-02 21:37:34.003] Nothing known about server, request DevInf using GET command
    [2011-12-02 21:37:34.013] Created command 'Get' (outgoing)
    –
    [2011-12-02 21:37:34.013] 'issue' - issuing command, Cmd=Get [--][++] [->end]
        [2011-12-02 21:37:34.013] Get: issued as (outgoing MsgID=2, CmdID=2), now queueing for status
        [2011-12-02 21:37:34.013] Outgoing Message size is now 6776 bytes
    –[2011-12-02 21:37:34.013] End of 'issue' [->top]
    [2011-12-02 21:37:34.013] (Saved) Last Local Client Anchor='', (generated) Next Local Client Anchor='20111202T203733Z' (sent to server as <last>/<next> in <alert>)
    [2011-12-02 21:37:34.013] Created command 'Alert' (outgoing)
    [2011-12-02 21:37:34.014] addressbook: ALERTING server for slow first time Sync
    –
    [2011-12-02 21:37:34.014] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=admin_ready, newstate=client_sent_alert [--][++] [->end]
    –[2011-12-02 21:37:34.014] End of 'DSStateChange' [->top]
    –
    [2011-12-02 21:37:34.014] 'issue' - issuing command, Cmd=Alert [--][++] [->end]
        [2011-12-02 21:37:34.014] Alert Code 201 sent
        [2011-12-02 21:37:34.014] - Alert Item: Source='./addressbook', Target='./contacts'
        [2011-12-02 21:37:34.014] Alert: issued as (outgoing MsgID=2, CmdID=3), now queueing for status
        [2011-12-02 21:37:34.014] Outgoing Message size is now 6864 bytes
    –[2011-12-02 21:37:34.014] End of 'issue' [->top]
    [2011-12-02 21:37:34.014] (Saved) Last Local Client Anchor='', (generated) Next Local Client Anchor='20111202T203733Z' (sent to server as <last>/<next> in <alert>)
    [2011-12-02 21:37:34.014] Created command 'Alert' (outgoing)
    [2011-12-02 21:37:34.014] calendar: ALERTING server for slow first time Sync
    –
    [2011-12-02 21:37:34.015] 'DSStateChange' - Datastore changes state, datastore=calendar, oldstate=admin_ready, newstate=client_sent_alert [--][++] [->end]
    –[2011-12-02 21:37:34.015] End of 'DSStateChange' [->top]
    –
    [2011-12-02 21:37:34.015] 'issue' - issuing command, Cmd=Alert [--][++] [->end]
        [2011-12-02 21:37:34.015] Alert Code 201 sent
        [2011-12-02 21:37:34.015] - Alert Item: Source='./calendar', Target='./calendar'
        [2011-12-02 21:37:34.015] Alert: issued as (outgoing MsgID=2, CmdID=4), now queueing for status
        [2011-12-02 21:37:34.015] Outgoing Message size is now 6951 bytes
    –[2011-12-02 21:37:34.015] End of 'issue' [->top]
    [2011-12-02 21:37:34.015] Ending message with 0 next-message/0 next-package commands: FINAL (final allowed by caller)
    [2011-12-02 21:37:34.015] Entire message size is now 6956 Bytes
    –
    [2011-12-02 21:37:34.135] 'SyncML_Incoming' - Starting to analyze incoming message, RequestNo=0, SySyncVers=3.4.0.27 [--][++] [->end]
        [2011-12-02 21:37:34.135] =================> Starting to analyze incoming message, SySync V3.4.0.27, RequestNo=0
        [2011-12-02 21:37:34.136] Created command 'SyncHdr' (incoming)
        –
        [2011-12-02 21:37:34.136] 'processHdr' - Processing incoming SyncHdr [--][++] [->end] [->enclosing]
            –
            [2011-12-02 21:37:34.136] 'SyncHdr' - Processing incoming SyncHdr, IncomingMsgID=2 [--][++] [->end] [->enclosing]
                [2011-12-02 21:37:34.136] Started Processing of message #2 (SyncML/1.2)
                [2011-12-02 21:37:34.136] MaxMsgSize for outgoing msgs set to 10000
                [2011-12-02 21:37:34.136] Created command 'Status' (outgoing)
                –
                [2011-12-02 21:37:34.136] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
                –[2011-12-02 21:37:34.136] End of 'issue' [->top] [->enclosing]
                [2011-12-02 21:37:34.136] Incoming SyncHdr processed, incomingMsgID=2, SyncMLVers=1.2
                [2011-12-02 21:37:34.136] - Session ID='46'
                [2011-12-02 21:37:34.136] - Source (Remote party): URI='https://192.168.5.173/egroupware/rpc.php' DisplayName=''
                [2011-12-02 21:37:34.136] - Response to be sent to URI='https://192.168.5.173/egroupware/rpc.php?syncml_sessionid=syncml-b3048d37d0ad20d6c7009ef12d15ea9c'
                [2011-12-02 21:37:34.136] - Target (Local party) : URI='syncevolution-5694a4ec-d7e7-47b2-92ce-36f5958be8d9' DisplayName='Linux PC'
                [2011-12-02 21:37:34.137] Deleted command 'SyncHdr' (incoming MsgID=2, CmdID=0)
            –[2011-12-02 21:37:34.137] End of 'SyncHdr' [->top] [->enclosing]
        –[2011-12-02 21:37:34.137] End of 'processHdr' [->top] [->enclosing]
        [2011-12-02 21:37:34.137] Created command 'Status' (incoming)
        –
        [2011-12-02 21:37:34.137] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:34.137] Started processing Command 'Status' (incoming MsgID=2, CmdID=1)
            [2011-12-02 21:37:34.137] WARNING: RECEIVED NON-OK STATUS 401 for command 'SyncHdr' (outgoing MsgID=2, CmdID=0)
            [2011-12-02 21:37:34.137] - TargetRef (remoteID) = 'https://192.168.5.173/egroupware/rpc.php?syncml_sessionid=syncml-b3048d37d0ad20d6c7009ef12d15ea9c'
            [2011-12-02 21:37:34.137] - SourceRef (localID) = 'syncevolution-5694a4ec-d7e7-47b2-92ce-36f5958be8d9'
            [2011-12-02 21:37:34.137] Found matching command 'SyncHdr' for Status
            [2011-12-02 21:37:34.137] Remote requests auth type='syncml:auth-basic'
            [2011-12-02 21:37:34.138] Next Cred will have type='basic' and format='base64' and use nonce=''
            [2011-12-02 21:37:34.138] Authentication failed (status=401) with remote server
        –[2011-12-02 21:37:34.138] End of 'processStatus' [->top] [->enclosing]
        [2011-12-02 21:37:34.138] =================> Retrying Client Session Start
        [2011-12-02 21:37:34.138] Smart retry with modified behaviour: newSessionForAuthRetry=0, noRespURIForAuthRetry=0
        [2011-12-02 21:37:34.138] Deleted command 'Status' (outgoing MsgID=0, CmdID=0)
        –
        [2011-12-02 21:37:34.138] 'SyncML_Outgoing' - start of new outgoing message [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:34.138] =================> Started new outgoing message
            [2011-12-02 21:37:34.138] Created command 'SyncHdr' (outgoing)
            [2011-12-02 21:37:34.138] Target (Remote URI) = 'https://192.168.5.173/egroupware/rpc.php?syncml_sessionid=syncml-b3048d37d0ad20d6c7009ef12d15ea9c'
            –
            [2011-12-02 21:37:34.138] 'SyncHdr' - SyncHdr generation, SyncMLVers=1.2, OutgoingMsgID=3 [--][++] [->end] [->enclosing]
                [2011-12-02 21:37:34.138] SyncHdr: issued in MsgID=3, now queueing for status
                [2011-12-02 21:37:34.138] Outgoing Message size is now 297 bytes
            –[2011-12-02 21:37:34.139] End of 'SyncHdr' [->top] [->enclosing]
            [2011-12-02 21:37:34.139] --------------- Ignoring all commands in this message (after 0 sec. request processing, 0 sec. total) with Status 0 (0=none) from here on
            [2011-12-02 21:37:34.139] Deleted command 'SyncHdr' (outgoing MsgID=2, CmdID=0)
            [2011-12-02 21:37:34.139] Deleted command 'Status' (incoming MsgID=2, CmdID=1)
        –[2011-12-02 21:37:34.139] End of 'SyncML_Outgoing' - Block Nest Warning: implicitly closed (by explicitly closing 'processStatus') [->top] [->enclosing]
    –[2011-12-02 21:37:34.139] End of 'SyncML_Incoming' - Block Nest Warning: closing 'processStatus', but expected 'SyncML_Incoming' [->top]
    [2011-12-02 21:37:34.139] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:34.139] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:34.140] Started processing Command 'Status' (incoming MsgID=2, CmdID=2)
        [2011-12-02 21:37:34.140] WARNING: RECEIVED NON-OK STATUS 401 for command 'Put' (outgoing MsgID=2, CmdID=1)
        [2011-12-02 21:37:34.140] - SourceRef (localID) = './devinf12'
        [2011-12-02 21:37:34.140] Found matching command 'Put' for Status
        [2011-12-02 21:37:34.140] Deleted command 'Put' (outgoing MsgID=2, CmdID=1)
        [2011-12-02 21:37:34.140] Deleted command 'Status' (incoming MsgID=2, CmdID=2)
    –[2011-12-02 21:37:34.140] End of 'processStatus' [->top]
    [2011-12-02 21:37:34.140] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:34.140] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:34.140] Started processing Command 'Status' (incoming MsgID=2, CmdID=3)
        [2011-12-02 21:37:34.141] WARNING: RECEIVED NON-OK STATUS 401 for command 'Get' (outgoing MsgID=2, CmdID=2)
        [2011-12-02 21:37:34.141] - TargetRef (remoteID) = './devinf12'
        [2011-12-02 21:37:34.141] Found matching command 'Get' for Status
        [2011-12-02 21:37:34.141] Deleted command 'Get' (outgoing MsgID=2, CmdID=2)
        [2011-12-02 21:37:34.141] Deleted command 'Status' (incoming MsgID=2, CmdID=3)
    –[2011-12-02 21:37:34.141] End of 'processStatus' [->top]
    [2011-12-02 21:37:34.141] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:34.141] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:34.141] Started processing Command 'Status' (incoming MsgID=2, CmdID=4)
        [2011-12-02 21:37:34.141] WARNING: RECEIVED NON-OK STATUS 401 for command 'Alert' (outgoing MsgID=2, CmdID=3)
        [2011-12-02 21:37:34.142] Found matching command 'Alert' for Status
        [2011-12-02 21:37:34.142] Deleted command 'Alert' (outgoing MsgID=2, CmdID=3)
        [2011-12-02 21:37:34.142] Deleted command 'Status' (incoming MsgID=2, CmdID=4)
    –[2011-12-02 21:37:34.142] End of 'processStatus' [->top]
    [2011-12-02 21:37:34.142] Created command 'Status' (incoming)
    –
    [2011-12-02 21:37:34.142] 'processStatus' - Processing incoming Status [--][++] [->end]
        [2011-12-02 21:37:34.142] Started processing Command 'Status' (incoming MsgID=2, CmdID=5)
        [2011-12-02 21:37:34.142] WARNING: RECEIVED NON-OK STATUS 401 for command 'Alert' (outgoing MsgID=2, CmdID=4)
        [2011-12-02 21:37:34.142] Found matching command 'Alert' for Status
        [2011-12-02 21:37:34.142] Deleted command 'Alert' (outgoing MsgID=2, CmdID=4)
        [2011-12-02 21:37:34.143] Deleted command 'Status' (incoming MsgID=2, CmdID=5)
    –[2011-12-02 21:37:34.143] End of 'processStatus' [->top]
    [2011-12-02 21:37:34.143] =================> Finished processing incoming message #2 (not final), request=0
    [2011-12-02 21:37:34.143] MessageEnded finishes : new outgoing state='init', new incoming state='idle', NO NeedToAnswer
    [2011-12-02 21:37:34.143] Local Datastore 'addressbook': State=client_sent_alert, SLOW sync, two-way
    [2011-12-02 21:37:34.143] Local Datastore 'calendar': State=client_sent_alert, SLOW sync, two-way
    +
    [2011-12-02 21:37:34.143] 'MakeAdminReady' - Making Admin Data ready to check sync anchors, localDB=addressbook, remoteDB=./contacts [--][++] [->end]
    –
    [2011-12-02 21:37:34.144] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=client_sent_alert, newstate=admin_ready [--][++] [->end]
    –[2011-12-02 21:37:34.144] End of 'DSStateChange' [->top]
    +
    [2011-12-02 21:37:34.144] 'MakeAdminReady' - Making Admin Data ready to check sync anchors, localDB=calendar, remoteDB=./calendar [--][++] [->end]
    –
    [2011-12-02 21:37:34.144] 'DSStateChange' - Datastore changes state, datastore=calendar, oldstate=client_sent_alert, newstate=admin_ready [--][++] [->end]
    –[2011-12-02 21:37:34.145] End of 'DSStateChange' [->top]
    [2011-12-02 21:37:34.145] Created command 'Put' (outgoing)
    –
    [2011-12-02 21:37:34.146] 'issue' - issuing command, Cmd=Put [--][++] [->end]
        [2011-12-02 21:37:34.148] Put: issued as (outgoing MsgID=3, CmdID=1), now queueing for status
        [2011-12-02 21:37:34.148] Outgoing Message size is now 6706 bytes
    –[2011-12-02 21:37:34.148] End of 'issue' [->top]
    [2011-12-02 21:37:34.148] Nothing known about server, request DevInf using GET command
    [2011-12-02 21:37:34.148] Created command 'Get' (outgoing)
    –
    [2011-12-02 21:37:34.149] 'issue' - issuing command, Cmd=Get [--][++] [->end]
        [2011-12-02 21:37:34.149] Get: issued as (outgoing MsgID=3, CmdID=2), now queueing for status
        [2011-12-02 21:37:34.149] Outgoing Message size is now 6776 bytes
    –[2011-12-02 21:37:34.149] End of 'issue' [->top]
    [2011-12-02 21:37:34.149] (Saved) Last Local Client Anchor='', (generated) Next Local Client Anchor='20111202T203734Z' (sent to server as <last>/<next> in <alert>)
    [2011-12-02 21:37:34.149] Created command 'Alert' (outgoing)
    [2011-12-02 21:37:34.149] addressbook: ALERTING server for slow first time Sync
    –
    [2011-12-02 21:37:34.149] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=admin_ready, newstate=client_sent_alert [--][++] [->end]
    –[2011-12-02 21:37:34.149] End of 'DSStateChange' [->top]
    –
    [2011-12-02 21:37:34.149] 'issue' - issuing command, Cmd=Alert [--][++] [->end]
        [2011-12-02 21:37:34.150] Alert Code 201 sent
        [2011-12-02 21:37:34.150] - Alert Item: Source='./addressbook', Target='./contacts'
        [2011-12-02 21:37:34.150] Alert: issued as (outgoing MsgID=3, CmdID=3), now queueing for status
        [2011-12-02 21:37:34.150] Outgoing Message size is now 6864 bytes
    –[2011-12-02 21:37:34.150] End of 'issue' [->top]
    [2011-12-02 21:37:34.150] (Saved) Last Local Client Anchor='', (generated) Next Local Client Anchor='20111202T203734Z' (sent to server as <last>/<next> in <alert>)
    [2011-12-02 21:37:34.150] Created command 'Alert' (outgoing)
    [2011-12-02 21:37:34.150] calendar: ALERTING server for slow first time Sync
    –
    [2011-12-02 21:37:34.150] 'DSStateChange' - Datastore changes state, datastore=calendar, oldstate=admin_ready, newstate=client_sent_alert [--][++] [->end]
    –[2011-12-02 21:37:34.150] End of 'DSStateChange' [->top]
    –
    [2011-12-02 21:37:34.150] 'issue' - issuing command, Cmd=Alert [--][++] [->end]
        [2011-12-02 21:37:34.150] Alert Code 201 sent
        [2011-12-02 21:37:34.150] - Alert Item: Source='./calendar', Target='./calendar'
        [2011-12-02 21:37:34.151] Alert: issued as (outgoing MsgID=3, CmdID=4), now queueing for status
        [2011-12-02 21:37:34.151] Outgoing Message size is now 6951 bytes
    –[2011-12-02 21:37:34.151] End of 'issue' [->top]
    [2011-12-02 21:37:34.151] Ending message with 0 next-message/0 next-package commands: FINAL (final allowed by caller)
    [2011-12-02 21:37:34.151] Entire message size is now 6956 Bytes
    –
    [2011-12-02 21:37:34.269] 'SyncML_Incoming' - Starting to analyze incoming message, RequestNo=0, SySyncVers=3.4.0.27 [--][++] [->end]
        [2011-12-02 21:37:34.270] =================> Starting to analyze incoming message, SySync V3.4.0.27, RequestNo=0
        [2011-12-02 21:37:34.270] Created command 'SyncHdr' (incoming)
        –
        [2011-12-02 21:37:34.270] 'processHdr' - Processing incoming SyncHdr [--][++] [->end] [->enclosing]
            –
            [2011-12-02 21:37:34.270] 'SyncHdr' - Processing incoming SyncHdr, IncomingMsgID=3 [--][++] [->end] [->enclosing]
                [2011-12-02 21:37:34.270] Started Processing of message #3 (SyncML/1.2)
                [2011-12-02 21:37:34.270] MaxMsgSize for outgoing msgs set to 10000
                [2011-12-02 21:37:34.270] Created command 'Status' (outgoing)
                –
                [2011-12-02 21:37:34.270] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
                –[2011-12-02 21:37:34.270] End of 'issue' [->top] [->enclosing]
                [2011-12-02 21:37:34.270] Incoming SyncHdr processed, incomingMsgID=3, SyncMLVers=1.2
                [2011-12-02 21:37:34.270] - Session ID='46'
                [2011-12-02 21:37:34.270] - Source (Remote party): URI='https://192.168.5.173/egroupware/rpc.php' DisplayName=''
                [2011-12-02 21:37:34.271] - Response to be sent to URI='https://192.168.5.173/egroupware/rpc.php?syncml_sessionid=syncml-b3048d37d0ad20d6c7009ef12d15ea9c'
                [2011-12-02 21:37:34.271] - Target (Local party) : URI='syncevolution-5694a4ec-d7e7-47b2-92ce-36f5958be8d9' DisplayName='Linux PC'
                [2011-12-02 21:37:34.271] Deleted command 'SyncHdr' (incoming MsgID=3, CmdID=0)
            –[2011-12-02 21:37:34.271] End of 'SyncHdr' [->top] [->enclosing]
        –[2011-12-02 21:37:34.271] End of 'processHdr' [->top] [->enclosing]
        [2011-12-02 21:37:34.271] Created command 'Status' (incoming)
        –
        [2011-12-02 21:37:34.271] 'processStatus' - Processing incoming Status [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:34.271] Started processing Command 'Status' (incoming MsgID=3, CmdID=1)
            [2011-12-02 21:37:34.271] WARNING: RECEIVED NON-OK STATUS 401 for command 'SyncHdr' (outgoing MsgID=3, CmdID=0)
            [2011-12-02 21:37:34.271] - TargetRef (remoteID) = 'https://192.168.5.173/egroupware/rpc.php?syncml_sessionid=syncml-b3048d37d0ad20d6c7009ef12d15ea9c'
            [2011-12-02 21:37:34.271] - SourceRef (localID) = 'syncevolution-5694a4ec-d7e7-47b2-92ce-36f5958be8d9'
            [2011-12-02 21:37:34.271] Found matching command 'SyncHdr' for Status
            [2011-12-02 21:37:34.271] Remote requests auth type='syncml:auth-basic'
            [2011-12-02 21:37:34.272] Next Cred will have type='basic' and format='base64' and use nonce=''
            [2011-12-02 21:37:34.272] Authentication failed (status=401) with remote server
            –
            [2011-12-02 21:37:34.272] 'SessionAbort' - Aborting Session, Status=401, ProblemSource=REMOTE [--][++] [->end] [->enclosing]
                [2011-12-02 21:37:34.272] WARNING: Aborting Session with Reason Status 401 (REMOTE problem) ***
                [2011-12-02 21:37:34.272] --------------- Ignoring all commands in this message (after 0 sec. request processing, 0 sec. total) with Status 514 (0=none) from here on
                –
                [2011-12-02 21:37:34.272] 'DSAbort' - Aborting datastore sync, abortStatusCode=401, localProblem=no, resumable=yes [--][++] [->end] [->enclosing]
                    –
                    [2011-12-02 21:37:34.272] 'SaveSuspendState' - Saving state for suspend/resume, datastore=addressbook [--][++] [->end] [->enclosing]
                        +
                        [2011-12-02 21:37:34.272] 'SaveResumeMarks' - let implementation save resume info, datastore=addressbook [--][++] [->end] [->enclosing]
                    –[2011-12-02 21:37:34.273] End of 'SaveSuspendState' [->top] [->enclosing]
                    [2011-12-02 21:37:34.273] *************** Warning: Datastore flagged aborted (after 0 sec. request processing, 0 sec. total) with REMOTE Status 401
                –[2011-12-02 21:37:34.273] End of 'DSAbort' [->top] [->enclosing]
                –
                [2011-12-02 21:37:34.273] 'DSAbort' - Aborting datastore sync, abortStatusCode=401, localProblem=no, resumable=yes [--][++] [->end] [->enclosing]
                    –
                    [2011-12-02 21:37:34.273] 'SaveSuspendState' - Saving state for suspend/resume, datastore=calendar [--][++] [->end] [->enclosing]
                        +
                        [2011-12-02 21:37:34.273] 'SaveResumeMarks' - let implementation save resume info, datastore=calendar [--][++] [->end] [->enclosing]
                    –[2011-12-02 21:37:34.274] End of 'SaveSuspendState' [->top] [->enclosing]
                    [2011-12-02 21:37:34.275] *************** Warning: Datastore flagged aborted (after 0 sec. request processing, 0 sec. total) with REMOTE Status 401
                –[2011-12-02 21:37:34.275] End of 'DSAbort' [->top] [->enclosing]
            –[2011-12-02 21:37:34.275] End of 'SessionAbort' [->top] [->enclosing]
            [2011-12-02 21:37:34.275] Deleted command 'SyncHdr' (outgoing MsgID=3, CmdID=0)
            [2011-12-02 21:37:34.276] Deleted command 'Status' (incoming MsgID=3, CmdID=1)
        –[2011-12-02 21:37:34.276] End of 'processStatus' [->top] [->enclosing]
        [2011-12-02 21:37:34.276] addressbook: inactive
        [2011-12-02 21:37:34.276] authorization failed, check username 'benoit' and password
        [2011-12-02 21:37:34.277] calendar: inactive
        [2011-12-02 21:37:34.277] authorization failed, check username 'benoit' and password
        –
        [2011-12-02 21:37:34.278] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=client_sent_alert, newstate=completed [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:34.278] Sync Statistics for 'addressbook' (), slow sync
            [2011-12-02 21:37:34.278]

            ==================================================
                                         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]:          0

            [2011-12-02 21:37:34.279] Warning: Failed with status code=401, statistics are incomplete!!
        –[2011-12-02 21:37:34.279] End of 'DSStateChange' [->top] [->enclosing]
        –
        [2011-12-02 21:37:34.279] 'DSStateChange' - Datastore changes state, datastore=addressbook, oldstate=completed, newstate=idle [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:34.279] endDataWrite called, write not started
        –[2011-12-02 21:37:34.279] End of 'DSStateChange' [->top] [->enclosing]
        –
        [2011-12-02 21:37:34.279] 'DSStateChange' - Datastore changes state, datastore=calendar, oldstate=client_sent_alert, newstate=completed [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:34.279] Sync Statistics for 'calendar' (), slow sync
            [2011-12-02 21:37:34.279]

            ==================================================
                                         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]:          0

            [2011-12-02 21:37:34.280] Warning: Failed with status code=401, statistics are incomplete!!
        –[2011-12-02 21:37:34.280] End of 'DSStateChange' [->top] [->enclosing]
        –
        [2011-12-02 21:37:34.280] 'DSStateChange' - Datastore changes state, datastore=calendar, oldstate=completed, newstate=idle [--][++] [->end] [->enclosing]
            [2011-12-02 21:37:34.280] endDataWrite called, write not started
        –[2011-12-02 21:37:34.280] End of 'DSStateChange' [->top] [->enclosing]
        [2011-12-02 21:37:34.280] Never received status for command 'Put', (outgoing MsgID=3, CmdID=1)
        [2011-12-02 21:37:34.280] Deleted command 'Put' (outgoing MsgID=3, CmdID=1)
        [2011-12-02 21:37:34.280] Never received status for command 'Get', (outgoing MsgID=3, CmdID=2)
        [2011-12-02 21:37:34.280] Deleted command 'Get' (outgoing MsgID=3, CmdID=2)
        [2011-12-02 21:37:34.281] Never received status for command 'Alert', (outgoing MsgID=3, CmdID=3)
        [2011-12-02 21:37:34.281] Deleted command 'Alert' (outgoing MsgID=3, CmdID=3)
        [2011-12-02 21:37:34.281] Never received status for command 'Alert', (outgoing MsgID=3, CmdID=4)
        [2011-12-02 21:37:34.281] Deleted command 'Alert' (outgoing MsgID=3, CmdID=4)
        [2011-12-02 21:37:34.281] Deleted command 'Status' (outgoing MsgID=0, CmdID=0)
        [2011-12-02 21:37:34.281] --------- END of embedded log for session ID '212189661453551' ---------
    –[2011-12-02 21:37:34.281] End of 'SyncML_Incoming' - closed because sub-log ends here - Block Nest Warning: closing '', but expected 'SyncML_Incoming' [->top]
trollinegw trollinegw
Reply | Threaded
Open this post in threaded view
|

Re: Synchro avec evolution

Bonjour,
Selon moi, c'est plutôt via groupdav et/ou ldap qu'il faut regarder pour evolution... (tous deux testés et fonctionnels sur Ubuntu 11.04).
Bye
trollinegw trollinegw
Reply | Threaded
Open this post in threaded view
|

Re: Synchro avec evolution

Et c'est du brut, sans syncevolution...
benoits benoits
Reply | Threaded
Open this post in threaded view
|

Re: Synchro avec evolution

Bonsoir,
Je vais regarder, mais LDAP c'est un annuaire, pas un logiciel de synchro?
Merci
Benoit
trollinegw trollinegw
Reply | Threaded
Open this post in threaded view
|

Re: Synchro avec evolution

Bonjour,
LDAP, c'est avant tout un protocole (tout comme GroupDAV) qui permet -entre autres- la synchronisation. eGroupware permet l'utilisation d'un serveur LDAP pour stocker les users et/ou les carnets d'adresses. S'il s'agit de synchroniser uniquement le calendrier, alors LDAP n'est pas une solution.
Pour une synchro avec evolution, et au jour d'aujourd'hui, GroupDAV est le plus simple à mettre en oeuvre (c'est mon avis). Il y a quelques temps, je n'aurais pas dis la même chose...
Voili voilà...
benoits benoits
Reply | Threaded
Open this post in threaded view
|

Re: Synchro avec evolution

Bonsoir,
Merci pour les infos, j'ai essayé çà:
http://community.egroupware.org/index.php?full=1&wikipage=SyncEvolution

çà marche presque, quand je veux ajouter un rendez-vous dans évolution, il m'affiche:

Impossible de créer l'objet calendrier : Le calendrier n'existe pas

je suis obligé d'abandonner les modifications, le rendez vous n’apparaît pas, mais il est envoyé à egroupware qui met evolution à jour.

Si tu as une piste.
En tout cas, merci
Benoit