Skip to content

Instantly share code, notes, and snippets.

@shadeslayer
Created April 23, 2011 14:20
Show Gist options
  • Select an option

  • Save shadeslayer/938639 to your computer and use it in GitHub Desktop.

Select an option

Save shadeslayer/938639 to your computer and use it in GitHub Desktop.
Start of log - Synthesis SyncML Engine 3.4.0.27
[-- collapse all --][++ expand all ++]
[2011-04-23 15:16:05.394] CreateContext SyncEvolution// => 0
[2011-04-23 15:16:05.394] 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-04-23 15:16:05.395] Module_PluginParams
Engine=01080000
+
[2011-04-23 15:16:05.396] 'loadSystemZoneDefinitions' - Linux system time zones [--][++] [->end]
[2011-04-23 15:16:05.417] SyncML server account: shadeslayer
[2011-04-23 15:16:05.418] client: SyncEvolution 1.1.99.4 for workstation
[2011-04-23 15:16:05.418] device ID: syncevolution-0adfe220-3117-49f4-aec5-6e3b794fc7f1
[2011-04-23 15:16:05.419] memo: inactive
[2011-04-23 15:16:05.420] calendar: inactive
[2011-04-23 15:16:05.420] todo: inactive
[2011-04-23 15:16:05.440] Module_DeleteContext 'session'
[2011-04-23 15:16:05.447] CreateContext SyncEvolution// => 0
[2011-04-23 15:16:05.447] 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-04-23 15:16:05.448] Module_PluginParams
Engine=01080000
[2011-04-23 15:16:05.448] CreateContext SyncEvolution//addressbook => 0
[2011-04-23 15:16:05.448] Module_Version = 01080000
[2011-04-23 15:16:05.448] 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:yes
plugin_datastoreadmin:yes
[2011-04-23 15:16:05.449] Module_PluginParams
Engine=01080000
[2011-04-23 15:16:05.449] 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:yes
plugin_datastoreadmin:yes
[2011-04-23 15:16:05.450] CreateContext SyncEvolution//addressbook => 0
[2011-04-23 15:16:05.450] Module_Version = 01080000
[2011-04-23 15:16:05.450] 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:yes
plugin_datastoreadmin:yes
[2011-04-23 15:16:05.451] Module_PluginParams
Engine=01080000
[2011-04-23 15:16:05.452] --------- START of embedded log for session ID '12450557701303568034' ---------
[2011-04-23 15:16:05.452] Executing Script 'sessioninitscript'
[2011-04-23 15:16:05.452] ==== Server Session started with SyncML (Server+Client) Engine Version 3.4.0.27
[2011-04-23 15:16:05.452] ---- Hardcoded Product name: SySync SyncML Library OpenSource Linux
[2011-04-23 15:16:05.452] ---- Configured Model/Manufacturer: SyncEvolution / Patrick Ohly
[2011-04-23 15:16:05.452] ---- Running on Linux, URI/deviceID='linux:saphira'
[2011-04-23 15:16:05.452] ---- Platform Hardware Name/Version = 'Linux PC', Firmware/OS Version = 'unknown'
[2011-04-23 15:16:05.452] ---- Configured Hardware Version = 'unknown', Firmware Version = '1.1.99.4'
[2011-04-23 15:16:05.452] ---- System local time : 2011-04-23 15:16:05.452 (time zone 'IST', offset 1:00 hours east of UTC)
[2011-04-23 15:16:05.452] ---- System time in UTC : 2011-04-23 14:16:05.452
[2011-04-23 15:16:05.452] ==== Config file='', Last Change=2011-04-23 14:16:05.000
[2011-04-23 15:16:05.452] ==== Config ID string='SyncEvolution client config'
[2011-04-23 15:16:05.452] Session_CreateContext '12450557701303568034' found
[2011-04-23 15:16:05.453] 'SyncML_Outgoing' - preparing for response before starting to analyze new incoming message [--][++] [->end]
[2011-04-23 15:16:05.453] 'SyncML_Incoming' - Starting to analyze incoming message, RequestNo=0, SySyncVers=3.4.0.27 [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.453] =================> Starting to analyze incoming message, SySync V3.4.0.27, RequestNo=0
[2011-04-23 15:16:05.453] Created command 'SyncHdr' (incoming)
[2011-04-23 15:16:05.453] 'processHdr' - Processing incoming SyncHdr [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.453] 'SyncHdr' - Processing incoming SyncHdr, IncomingMsgID=1 [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.453] Started Processing of message #1 (SyncML/1.2)
[2011-04-23 15:16:05.453] MaxMsgSize for outgoing msgs set to 65536
[2011-04-23 15:16:05.453] Created command 'Status' (outgoing)
[2011-04-23 15:16:05.453] Session_CheckDevice dev='fac-353833044195409' nonce='_OYSL#!!' res=0
[2011-04-23 15:16:05.453] Session_Login usr='shadeslayer' expected user='shadeslayer' res=0
[2011-04-23 15:16:05.453] Username = shadeslayer
[2011-04-23 15:16:05.454] ==== api authentication for 'shadeslayer' (userkey='') successful
[2011-04-23 15:16:05.454] ==== Config file='', Last Change=2011-04-23 14:16:05.000
[2011-04-23 15:16:05.454] ==== Config ID string='SyncEvolution client config'
[2011-04-23 15:16:05.454] ==== Authorisation successful with SyncML Engine Version 3.4.0.27
[2011-04-23 15:16:05.454] ==== SyncML URL used = 'http://10.0.0.2:7000/syncevolution', username as sent by remote = 'shadeslayer'
[2011-04-23 15:16:05.454] Session_SaveNonce nonce='D+KKK$!!'
[2011-04-23 15:16:05.454] Challenge for next auth: AuthType=syncml:auth-md5, Nonce='D+KKK$!!', binary NOT allowed
[2011-04-23 15:16:05.454] Authorization accepted: 212
[2011-04-23 15:16:05.454] Created command 'SyncHdr' (outgoing)
[2011-04-23 15:16:05.454] Target (Remote URI) = 'fac-353833044195409'
[2011-04-23 15:16:05.454] 'SyncHdr' - SyncHdr generation, SyncMLVers=1.2, OutgoingMsgID=1 [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.454] SyncHdr: issued in MsgID=1, now queueing for status
[2011-04-23 15:16:05.454] Outgoing Message size is now 558 bytes
–[2011-04-23 15:16:05.454] End of 'SyncHdr' [->top] [->enclosing]
[2011-04-23 15:16:05.454] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.454] Status Code 212 issued for Cmd=SyncHdr, (incoming MsgID=1, CmdID=0)
[2011-04-23 15:16:05.454] - TargetRef (localID) = 'http://10.0.0.2:7000/syncevolution'
[2011-04-23 15:16:05.454] - SourceRef (remoteID) = 'fac-353833044195409'
[2011-04-23 15:16:05.454] Status: issued as (outgoing MsgID=1, CmdID=1), not waiting for status
[2011-04-23 15:16:05.455] Deleted command 'Status' (outgoing MsgID=1, CmdID=1)
[2011-04-23 15:16:05.455] Outgoing Message size is now 935 bytes
–[2011-04-23 15:16:05.455] End of 'issue' [->top] [->enclosing]
[2011-04-23 15:16:05.455] Incoming SyncHdr processed, incomingMsgID=1, SyncMLVers=1.2
[2011-04-23 15:16:05.455] - Session ID='1303568165300'
[2011-04-23 15:16:05.455] - Source (Remote party): URI='fac-353833044195409' DisplayName='shadeslayer'
[2011-04-23 15:16:05.455] - Response to be sent to URI='[none specified, back to source]'
[2011-04-23 15:16:05.455] - Target (Local party) : URI='http://10.0.0.2:7000/syncevolution' DisplayName=''
[2011-04-23 15:16:05.455] Deleted command 'SyncHdr' (incoming MsgID=1, CmdID=0)
–[2011-04-23 15:16:05.455] End of 'SyncHdr' [->top] [->enclosing]
–[2011-04-23 15:16:05.455] End of 'processHdr' [->top] [->enclosing]
[2011-04-23 15:16:05.455] Created command 'Alert' (incoming)
[2011-04-23 15:16:05.455] Started processing Command 'Alert' (incoming MsgID=1, CmdID=1)
[2011-04-23 15:16:05.455] 'processCmd' - Processing incoming command, Cmd=Alert, IncomingMsgID=1, CmdID=1 [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.455] Code=200. No Cred. Analyzing Items
[2011-04-23 15:16:05.455] Created command 'Status' (outgoing)
[2011-04-23 15:16:05.455] - Processing Alert Item (code=200), Source='contacts', Target='card'
[2011-04-23 15:16:05.455] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.455] WARNING: Non-OK Status 404 returned to remote!
[2011-04-23 15:16:05.455] Status Code 404 issued for Cmd=Alert, (incoming MsgID=1, CmdID=1)
[2011-04-23 15:16:05.455] - TargetRef (localID) = 'card'
[2011-04-23 15:16:05.455] - SourceRef (remoteID) = 'contacts'
[2011-04-23 15:16:05.455] Status: issued as (outgoing MsgID=1, CmdID=2), not waiting for status
[2011-04-23 15:16:05.455] Deleted command 'Status' (outgoing MsgID=1, CmdID=2)
[2011-04-23 15:16:05.455] Outgoing Message size is now 1094 bytes
–[2011-04-23 15:16:05.455] End of 'issue' [->top] [->enclosing]
[2011-04-23 15:16:05.455] Deleted command 'Alert' (incoming MsgID=1, CmdID=1)
–[2011-04-23 15:16:05.455] End of 'processCmd' [->top] [->enclosing]
[2011-04-23 15:16:05.456] Created command 'Put' (incoming)
[2011-04-23 15:16:05.456] Started processing Command 'Put' (incoming MsgID=1, CmdID=2)
[2011-04-23 15:16:05.456] 'processCmd' - Processing incoming command, Cmd=Put, IncomingMsgID=1, CmdID=2 [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.456] processing item with locURI=./devinf12
[2011-04-23 15:16:05.456] Created command 'Status' (outgoing)
[2011-04-23 15:16:05.456] 'DevInf_Analyze' - Analyzing remote devInf [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.456] Device ID='fac-353833044195409', Type='phone', Model='HTC Desire'
[2011-04-23 15:16:05.456] Manufacturer='HTC', OEM=''
[2011-04-23 15:16:05.456] Softwarevers='REL(10)', Firmwarevers='', Hardwarevers='htc_wwe/htc_bravo/bravo/bravo:'
[2011-04-23 15:16:05.456] SyncML Version: SyncML/1.2
[2011-04-23 15:16:05.456] SyncML capability flags: wantsNOC=Yes, canHandleUTC=Yes, supportsLargeObjs=Yes
[2011-04-23 15:16:05.456] 'RemoteRules' - Checking for remote rules [--][++] [->end] [->enclosing]
–[2011-04-23 15:16:05.456] End of 'RemoteRules' [->top] [->enclosing]
[2011-04-23 15:16:05.456] Summary of all behaviour options (possibly modified by remote rule(s))
[2011-04-23 15:16:05.456] - Remote Description : HTC HTC Desire
[2011-04-23 15:16:05.456] - Legacy mode : No
[2011-04-23 15:16:05.456] - Lenient mode : No
[2011-04-23 15:16:05.456] - Limited Field Lengths : No
[2011-04-23 15:16:05.456] - Do not send empty props : No
[2011-04-23 15:16:05.456] - Quote 8bit content : No
[2011-04-23 15:16:05.456] - Prevent Content Folding : No
[2011-04-23 15:16:05.456] - No replace in slowsync : No
[2011-04-23 15:16:05.456] - Treat remote TZ as local : No
[2011-04-23 15:16:05.456] - Treat remote TZ as UTC : No
[2011-04-23 15:16:05.456] - Use 23:59:59 end dates : No
[2011-04-23 15:16:05.456] - Ignore field maxSize : No
[2011-04-23 15:16:05.456] - Ignore CTCap : No
[2011-04-23 15:16:05.456] - send DS path in devInf : Yes
[2011-04-23 15:16:05.456] - send DS CGI in devInf : Yes
[2011-04-23 15:16:05.456] - Update Client in slowsync : No
[2011-04-23 15:16:05.456] - Update Server in slowsync : No
[2011-04-23 15:16:05.457] - Allow message retries : Yes
[2011-04-23 15:16:05.457] - Strict SyncML exec order : Yes
[2011-04-23 15:16:05.457] - Treat copy like add : No
[2011-04-23 15:16:05.457] - Complete From-Client-Only : No
[2011-04-23 15:16:05.457] - Remote can handle UTC : Yes
[2011-04-23 15:16:05.457] - Max Request time [sec] : 0
[2011-04-23 15:16:05.457] - Content output charset : UTF-8
[2011-04-23 15:16:05.457] - Content input charset : UTF-8
[2011-04-23 15:16:05.457] 'RemoteDatastores' - Analyzing remote datastores [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.457] 'RemoteDSDevInf' - Registering remote Datastore from devInf [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.457] Datastore DevInf does not specify MaxGUIDSize -> using default
[2011-04-23 15:16:05.457] Remote Datastore Name='contacts', DisplayName='', MaxGUIDSize=0
[2011-04-23 15:16:05.457] Preferred Rx='text/x-vcard' version '', preferred Tx='text/x-vcard' version ''
[2011-04-23 15:16:05.457] 'RemoteTypes' - Analyzing remote types listed in datastore level CTCap [--][++] [->end] [->enclosing]
–[2011-04-23 15:16:05.457] End of 'RemoteTypes' [->top] [->enclosing]
[2011-04-23 15:16:05.457] WARNING: Registering type with no version specification!
[2011-04-23 15:16:05.457] Registered Type 'text/x-vcard' Version='2.1', implemented by local type vCard21, related to remote datastore 'contacts'
[2011-04-23 15:16:05.457] WARNING: Registering type with no version specification!
[2011-04-23 15:16:05.457] Registered Type 'text/x-vcard' Version='2.1', implemented by local type vCard21, related to remote datastore 'contacts'
–[2011-04-23 15:16:05.457] End of 'RemoteDSDevInf' [->top] [->enclosing]
–[2011-04-23 15:16:05.457] End of 'RemoteDatastores' [->top] [->enclosing]
[2011-04-23 15:16:05.457] Session_SaveDeviceInfo info='REMOTE_URI:fac-353833044195409
REMOTE_DESC:HTC HTC Desire
REMOTE_INFO:phone (htc_wwe/htc_bravo/bravo/bravo:2.2/FRF91/226611:user/release-keys, , REL(10))
DOMAIN:
MOD:HTC Desire
MAN:HTC
OEM:
FWV:
SWV:REL(10)
HWV:htc_wwe/htc_bravo/bravo/bravo:2.2/FRF91/226611:user/release-keys
'
–[2011-04-23 15:16:05.458] End of 'DevInf_Analyze' [->top] [->enclosing]
[2011-04-23 15:16:05.458] 'issue' - issuing command, Cmd=Status [--][++] [->end] [->enclosing]
[2011-04-23 15:16:05.458] Status Code 200 issued for Cmd=Put, (incoming MsgID=1, CmdID=2)
[2011-04-23 15:16:05.458] - SourceRef (remoteID) = './devinf12'
[2011-04-23 15:16:05.458] Status: issued as (outgoing MsgID=1, CmdID=3), not waiting for status
[2011-04-23 15:16:05.458] Deleted command 'Status' (outgoing MsgID=1, CmdID=3)
[2011-04-23 15:16:05.458] Outgoing Message size is now 1226 bytes
–[2011-04-23 15:16:05.458] End of 'issue' [->top] [->enclosing]
[2011-04-23 15:16:05.458] Deleted command 'Put' (incoming MsgID=1, CmdID=2)
–[2011-04-23 15:16:05.458] End of 'processCmd' [->top] [->enclosing]
[2011-04-23 15:16:05.458] =================> Finished processing incoming message #1 (final), request=0
–[2011-04-23 15:16:05.458] End of 'SyncML_Incoming' [->top] [->enclosing]
[2011-04-23 15:16:05.458] Created command 'Status' (outgoing)
[2011-04-23 15:16:05.458] Deleted command 'Status' (outgoing MsgID=0, CmdID=0)
[2011-04-23 15:16:05.458] Ending message with 0 next-message/0 next-package commands: FINAL (final allowed by caller)
[2011-04-23 15:16:05.458] Entire message size is now 1254 Bytes
[2011-04-23 15:16:05.458] ---> MessageEnded finishes : new incoming state='sync', new outgoing state='sync', NeedToAnswer
[2011-04-23 15:16:05.458] Local Datastore 'addressbook': State=idle, normal sync, two-way
[2011-04-23 15:16:05.458] =================> Finished generating outgoing message #1, request=0
–[2011-04-23 15:16:05.458] End of 'SyncML_Outgoing' [->top]
[2011-04-23 15:16:05.458] ========= Finished request (session in progress), processing time=5 msec, incoming bytes=1579, outgoing bytes=1254
[2011-04-23 15:16:43.025] D-Bus client :1.100 has disconnected
[2011-04-23 15:16:43.025] D-Bus client :1.100 is destructing
[2011-04-23 15:16:48.936] D-Bus client :1.101 has disconnected
[2011-04-23 15:16:48.937] D-Bus client :1.101 is destructing
[2011-04-23 15:16:56.505] D-Bus client :1.102 has disconnected
[2011-04-23 15:16:56.506] D-Bus client :1.102 is destructing
[2011-04-23 15:17:13.936] D-Bus client :1.103 has disconnected
[2011-04-23 15:17:13.937] D-Bus client :1.103 is destructing
[2011-04-23 15:17:41.896] D-Bus client :1.104 has disconnected
[2011-04-23 15:17:41.896] D-Bus client :1.104 is destructing
[2011-04-23 15:17:43.749] session 5138460391303568035 expired
[2011-04-23 15:17:48.758] session 4584866221303568036 expired
[2011-04-23 15:17:56.781] session 577049911303568037 expired
[2011-04-23 15:18:10.297] D-Bus client :1.105 has disconnected
[2011-04-23 15:18:10.297] D-Bus client :1.105 is destructing
[2011-04-23 15:18:13.753] session 1988556581303568038 expired
[2011-04-23 15:18:23.276] D-Bus client :1.106 has disconnected
[2011-04-23 15:18:23.276] D-Bus client :1.106 is destructing
[2011-04-23 15:18:41.617] D-Bus client :1.107 has disconnected
[2011-04-23 15:18:41.617] D-Bus client :1.107 is destructing
[2011-04-23 15:18:41.742] session 8391262871303568039 expired
[2011-04-23 15:18:55.396] D-Bus client :1.108 has disconnected
[2011-04-23 15:18:55.396] D-Bus client :1.108 is destructing
[2011-04-23 15:19:10.760] session 3922584561303568040 expired
[2011-04-23 15:19:15.235] D-Bus client :1.109 has disconnected
[2011-04-23 15:19:15.236] D-Bus client :1.109 is destructing
[2011-04-23 15:19:23.744] session 18021597591303568041 expired
[2011-04-23 15:19:34.950] connecting D-Bus client :1.93 with connection /org/syncevolution/Connection/20267352311303568046 'syncevo-server-http.py (HTTP)'
[2011-04-23 15:19:34.955] D-Bus client :1.93 sends 1579 bytes via connection /org/syncevolution/Connection/20267352311303568046, application/vnd.syncml+xml
[2011-04-23 15:19:35.001] CreateContext SyncEvolution// => 0
[2011-04-23 15:19:35.002] 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-04-23 15:19:35.002] Module_PluginParams
Engine=01080000
[2011-04-23 15:19:35.026] Session_CreateContext '8069045829263969714' found
[2011-04-23 15:19:35.027] Session_CheckDevice dev='fac-353833044195409' nonce='' res=0
[2011-04-23 15:19:35.027] Session_SaveNonce nonce='@(W,I#!!'
[2011-04-23 15:19:35.027] Session_SaveDeviceInfo info='REMOTE_URI:fac-353833044195409
REMOTE_DESC:HTC HTC Desire
REMOTE_INFO:phone (htc_wwe/htc_bravo/bravo/bravo:2.2/FRF91/226611:user/release-keys, , REL(10))
DOMAIN:
MOD:HTC Desire
MAN:HTC
OEM:
FWV:
SWV:REL(10)
HWV:htc_wwe/htc_bravo/bravo/bravo:2.2/FRF91/226611:user/release-keys
'
[2011-04-23 15:19:35.028] Module_DeleteContext 'session'
[2011-04-23 15:19:35.029] matched deviceID fac-353833044195409 against config android (/home/shadeslayer/.config/syncevolution/default/peers/android)
[2011-04-23 15:19:35.030] aborting active session 12450557701303568034 because it matches deviceID fac-353833044195409
[2011-04-23 15:19:35.032] aborting before SessionStep() in STEPCMD_NEEDDATA as requested by script
[2011-04-23 15:19:35.032] 'SessionAbort' - Aborting Session, Status=20017, ProblemSource=LOCAL [--][++] [->end]
[2011-04-23 15:19:35.032] WARNING: Aborting Session with Reason Status 20017 (LOCAL problem) ***
[2011-04-23 15:19:35.032] --------------- Ignoring all commands in this message (after 209 sec. request processing, 209 sec. total) with Status 514 (0=none) from here on
–[2011-04-23 15:19:35.032] End of 'SessionAbort' [->top]
[2011-04-23 15:19:35.033] Never received status for command 'SyncHdr', (outgoing MsgID=1, CmdID=0)
[2011-04-23 15:19:35.033] Deleted command 'SyncHdr' (outgoing MsgID=1, CmdID=0)
[2011-04-23 15:19:35.033] Session data transfer statistics: incoming bytes=1579, outgoing bytes=1254
[2011-04-23 15:19:35.033] TSyncAgent::~TSyncAgent: Deleted SyncML session (local session id=12450557701303568034)
[2011-04-23 15:19:35.033] --------- END of embedded log for session ID '12450557701303568034' ---------
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment