Hi,
I can successfully synchronise my address book with my mobile phone.
When I synchronise my calendar+todo, I get – after quite some time – the
error 500 and 406. I appended a snippet of the log file. The messages
with a non-OK status are at the end. What is the best way of debugging
this? Can I find out what element makes problems? (Maybe I can simply
delete it.)
Thank you very much for your help,
Simon
[2010-09-23 18:13:48.026] 'SyncML_Outgoing' - preparing for response before
starting to analyze new incoming message [--][++] [->end]
□ +
–
[2010-09-23 18:13:48.026] 'SyncML_Incoming' - Starting to analyze incoming
message, RequestNo=0, SySyncVers=3.4.0.8 [--][++] [->end] [->enclosing]
☆ [2010-09-23 18:13:48.026] =================> Starting to analyze incoming
message, SySync V3.4.0.8, RequestNo=0
☆ [2010-09-23 18:13:48.026] Created command 'SyncHdr' (incoming)
☆ +
–
[2010-09-23 18:13:48.026] 'processHdr' - Processing incoming SyncHdr
[--][++] [->end] [->enclosing]
○ +
–
[2010-09-23 18:13:48.026] 'SyncHdr' - Processing incoming SyncHdr,
IncomingMsgID=6 [--][++] [->end] [->enclosing]
■ [2010-09-23 18:13:48.027] Started Processing of message #6
(SyncML/1.1)
■ [2010-09-23 18:13:48.027] MaxMsgSize for outgoing msgs set to 65535
■ [2010-09-23 18:13:48.027] Created command 'Status' (outgoing)
■ [2010-09-23 18:13:48.027] Authorization ok from previous request: 200
■ [2010-09-23 18:13:48.027] Created command 'SyncHdr'
(outgoing)
■ [2010-09-23 18:13:48.027] Target (Remote URI) =
'IMEI:351893012204114'
■ +
–
[2010-09-23 18:13:48.027] 'SyncHdr' - SyncHdr generation,
SyncMLVers=1.1, OutgoingMsgID=6 [--][++] [->end] [->enclosing]
★ [2010-09-23 18:13:48.027] SyncHdr: issued in MsgID=6, now queueing
for status
★ [2010-09-23 18:13:48.027] Outgoing Message size is now 107 bytes
–[2010-09-23 18:13:48.028] End of 'SyncHdr' [->top]
[->enclosing]
■ +
–
[2010-09-23 18:13:48.028] 'issue' - issuing command,
Cmd=Status [--][++] [->end] [->enclosing]
★ [2010-09-23 18:13:48.028] Status Code 200 issued for Cmd=SyncHdr,
(incoming MsgID=6, CmdID=0)
★ [2010-09-23 18:13:48.028] - TargetRef (localID) = 'PC
Suite'
★ [2010-09-23 18:13:48.028] - SourceRef (remoteID) =
'IMEI:351893012204114'
★ [2010-09-23 18:13:48.028] Status: issued as (outgoing MsgID=6,
CmdID=1), not waiting for status
★ [2010-09-23 18:13:48.028] Deleted command 'Status'
(outgoing MsgID=6, CmdID=1)
★ [2010-09-23 18:13:48.028] Outgoing Message size is now 178 bytes
–[2010-09-23 18:13:48.028] End of 'issue' [->top]
[->enclosing]
■ [2010-09-23 18:13:48.029] Incoming SyncHdr processed, incomingMsgID=6,
SyncMLVers=1.1
■ [2010-09-23 18:13:48.029] - Session ID='1'
■ [2010-09-23 18:13:48.029] - Source (Remote party):
URI='IMEI:351893012204114' DisplayName=''
■ [2010-09-23 18:13:48.029] - Response to be sent to URI='[none
specified, back to source]'
■ [2010-09-23 18:13:48.029] - Target (Local party) : URI='PC
Suite' DisplayName=''
■ [2010-09-23 18:13:48.029] Deleted command 'SyncHdr' (incoming
MsgID=6, CmdID=0)
–[2010-09-23 18:13:48.029] End of 'SyncHdr' [->top]
[->enclosing]
–[2010-09-23 18:13:48.029] End of 'processHdr' [->top]
[->enclosing]
☆ [2010-09-23 18:13:48.029] Created command 'Status' (incoming)
☆ +
–
[2010-09-23 18:13:48.029] 'processStatus' - Processing incoming Status
[--][++] [->end] [->enclosing]
○ [2010-09-23 18:13:48.030] Started processing Command 'Status'
(incoming MsgID=6, CmdID=1)
○ [2010-09-23 18:13:48.030] RECEIVED STATUS 200 for for command
'SyncHdr' (outgoing MsgID=5, CmdID=0)
○ [2010-09-23 18:13:48.030] - TargetRef (remoteID) =
'IMEI:351893012204114'
○ [2010-09-23 18:13:48.030] - SourceRef (localID) = 'PC Suite'
○ [2010-09-23 18:13:48.030] Found matching command 'SyncHdr' for
Status
○ [2010-09-23 18:13:48.030] Status: 200: successful --> accept as ok
○ [2010-09-23 18:13:48.030] Deleted command 'SyncHdr' (outgoing
MsgID=5, CmdID=0)
○ [2010-09-23 18:13:48.030] Deleted command 'Status' (incoming
MsgID=6, CmdID=1)
–[2010-09-23 18:13:48.030] End of 'processStatus' [->top]
[->enclosing]
☆ [2010-09-23 18:13:48.031] Created command 'Status' (incoming)
☆ +
–
[2010-09-23 18:13:48.031] 'processStatus' - Processing incoming Status
[--][++] [->end] [->enclosing]
○ [2010-09-23 18:13:48.031] Started processing Command 'Status'
(incoming MsgID=6, CmdID=2)
○ [2010-09-23 18:13:48.031] RECEIVED STATUS 200 for for command
'Sync' (outgoing MsgID=5, CmdID=176)
○ [2010-09-23 18:13:48.031] - TargetRef (remoteID) = './C:Calendar'
○ [2010-09-23 18:13:48.031] - SourceRef (localID) = './Calendar'
○ [2010-09-23 18:13:48.031] Found matching command 'Sync' for
Status
○ [2010-09-23 18:13:48.031] Status: 200: successful --> accept as ok
○ [2010-09-23 18:13:48.031] Deleted command 'Sync' (outgoing
MsgID=5, CmdID=176)
○ [2010-09-23 18:13:48.032] Deleted command 'Status' (incoming
MsgID=6, CmdID=2)
–[2010-09-23 18:13:48.032] End of 'processStatus' [->top]
[->enclosing]
☆ [2010-09-23 18:13:48.032] Created command 'Status' (incoming)
☆ +
–
[2010-09-23 18:13:48.032] 'processStatus' - Processing incoming Status
[--][++] [->end] [->enclosing]
○ [2010-09-23 18:13:48.032] Started processing Command 'Status'
(incoming MsgID=6, CmdID=3)
○ [2010-09-23 18:13:48.032] WARNING: RECEIVED NON-OK STATUS 500 for command
'Replace' (outgoing MsgID=5, CmdID=177)
○ [2010-09-23 18:13:48.032] - TargetRef (remoteID) = '922'
○ [2010-09-23 18:13:48.032] Found matching command 'Replace' for
Status
○ [2010-09-23 18:13:48.032] Status: General error 500 (original op was
replace) -> marking item for resend in next session
○ [2010-09-23 18:13:48.033] Deleted command 'Replace' (outgoing
MsgID=5, CmdID=177)
○ [2010-09-23 18:13:48.033] Deleted command 'Status' (incoming
MsgID=6, CmdID=3)
–[2010-09-23 18:13:48.033] End of 'processStatus' [->top]
[->enclosing]
☆ [2010-09-23 18:13:48.033] Created command 'Status' (incoming)
☆ +
–
[2010-09-23 18:13:48.033] 'processStatus' - Processing incoming Status
[--][++] [->end] [->enclosing]
○ [2010-09-23 18:13:48.033] Started processing Command 'Status'
(incoming MsgID=6, CmdID=4)
○ [2010-09-23 18:13:48.033] WARNING: RECEIVED NON-OK STATUS 406 for command
'Replace' (outgoing MsgID=5, CmdID=178)
○ [2010-09-23 18:13:48.033] - TargetRef (remoteID) = '935'
○ [2010-09-23 18:13:48.033] Found matching command 'Replace' for
Status
○ [2010-09-23 18:13:48.034] Status: 406: originator exception
○ +
–
[2010-09-23 18:13:48.034] 'SessionAbort' - Aborting Session,
Status=406, ProblemSource=REMOTE [--][++] [->end] [->enclosing]
■ [2010-09-23 18:13:48.034] WARNING: Aborting Session with Reason Status
406 (REMOTE problem) ***
■ [2010-09-23 18:13:48.034] --------------- Ignoring all commands in
this message (after 0 sec. request processing, 70 sec. total) with Status 514 (0=none)
from here on
Show replies by date
On Do, 2010-09-23 at 09:46 -0700, Simon Siemens wrote:
Hi,
I can successfully synchronise my address book with my mobile phone.
When I synchronise my calendar+todo, I get – after quite some time – the
error 500 and 406. I appended a snippet of the log file. The messages
with a non-OK status are at the end. What is the best way of debugging
this? Can I find out what element makes problems?
The log file should have hyperlinks back to the Replace or Add command
at the point where the bad status is logged. Increase the logging level
to 4 to see what that command did, if it is not obvious at the default
level.
--
Best Regards, Patrick Ohly
The content of this message is my personal opinion only and although
I am an employee of Intel, the statements I make here in no way
represent Intel's position on the issue, nor am I authorized to speak
on behalf of Intel on this matter.