[cisco-voip] R: R: Jabber 10.5 and Remotedestination, disconnected!

Mauro Celli mauro.celli at 2000net.it
Thu Oct 16 17:41:21 EDT 2014


Another info,
i have compared working and non working jabber log, 500 internal server error is not the problem?!?!
This is also when it works….

Jabber log with mobility identity, not working

14:43.303 23450 23803 D              csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 70929: 10-16 23:14:43.303 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 70988: 10-16 23:14:43.303 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 71052: 10-16 23:14:43.533 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 100 Trying
                Line 71072: 10-16 23:14:43.533 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- NOTIFY sip:72 at 172.20.250.60:38229 SIP/2.0
                Line 71100: 10-16 23:14:43.533 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 71436: 10-16 23:14:43.573 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 71450: 10-16 23:14:43.573 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 71480: 10-16 23:14:43.583 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:38229 SIP/2.0
                Line 71536: 10-16 23:14:43.583 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:38229 SIP/2.0
                Line 71745: 10-16 23:14:43.603 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 71763: 10-16 23:14:43.623 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SUBSCRIBE sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:38229 SIP/2.0
                Line 71785: 10-16 23:14:43.623 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 500 Internal Server Error
                Line 71796: 10-16 23:14:43.743 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 100 Trying
                Line 71809: 10-16 23:14:43.743 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 500 Internal Server Error
                STOP


Jabber log without mobility identity, working

Line 70870: 10-16 23:14:43.303 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 70929: 10-16 23:14:43.303 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 70988: 10-16 23:14:43.303 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 71052: 10-16 23:14:43.533 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 100 Trying
                Line 71072: 10-16 23:14:43.533 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- NOTIFY sip:72 at 172.20.250.60:38229 SIP/2.0
                Line 71100: 10-16 23:14:43.533 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 71436: 10-16 23:14:43.573 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 71450: 10-16 23:14:43.573 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 71480: 10-16 23:14:43.583 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:38229 SIP/2.0
                Line 71536: 10-16 23:14:43.583 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:38229 SIP/2.0
                Line 71745: 10-16 23:14:43.603 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 71763: 10-16 23:14:43.623 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SUBSCRIBE sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:38229 SIP/2.0
                Line 71785: 10-16 23:14:43.623 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 500 Internal Server Error
                Line 71796: 10-16 23:14:43.743 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 100 Trying
                Line 71809: 10-16 23:14:43.743 23450 23803 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 500 Internal Server Error
                Line 76883: 10-16 23:17:23.423 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REFER sip:172.20.10.5 SIP/2.0
                Line 76933: 10-16 23:17:23.423 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REFER sip:172.20.10.5 SIP/2.0
                Line 77049: 10-16 23:17:23.453 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 77108: 10-16 23:17:23.453 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 77167: 10-16 23:17:23.453 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 77336: 10-16 23:17:34.613 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 202 Accepted
                Line 77356: 10-16 23:17:35.853 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 100 Trying
                Line 77377: 10-16 23:17:35.883 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- NOTIFY sip:72 at 172.20.250.60:58254 SIP/2.0
                Line 77405: 10-16 23:17:35.893 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 77666: 10-16 23:17:35.913 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:58254 SIP/2.0
                Line 77722: 10-16 23:17:35.913 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:58254 SIP/2.0
                Line 77873: 10-16 23:17:35.963 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SUBSCRIBE sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:58254 SIP/2.0
                Line 77895: 10-16 23:17:35.963 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 500 Internal Server Error
                Line 77930: 10-16 23:17:35.973 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 77995: 10-16 23:17:35.983 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 78010: 10-16 23:17:35.983 23450 24212 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 82001: 10-16 23:20:21.373 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 82060: 10-16 23:20:21.373 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 82119: 10-16 23:20:21.373 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 82185: 10-16 23:20:22.243 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 100 Trying
                Line 82206: 10-16 23:20:22.253 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- NOTIFY sip:72 at 172.20.250.60:60068 SIP/2.0
                Line 82234: 10-16 23:20:22.253 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 82494: 10-16 23:20:22.303 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:60068 SIP/2.0
                Line 82550: 10-16 23:20:22.303 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:60068 SIP/2.0
                Line 82726: 10-16 23:20:22.333 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 82757: 10-16 23:20:22.333 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 82771: 10-16 23:20:22.333 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 82836: 10-16 23:20:22.483 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SUBSCRIBE sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:60068 SIP/2.0
                Line 82858: 10-16 23:20:22.483 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 500 Internal Server Error
                Line 82869: 10-16 23:20:22.483 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 100 Trying
                Line 82882: 10-16 23:20:22.483 23450 24540 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 500 Internal Server Error
                Line 86986: 10-16 23:23:21.453 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 87045: 10-16 23:23:21.453 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 87104: 10-16 23:23:21.453 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 87169: 10-16 23:23:22.353 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 100 Trying
                Line 87189: 10-16 23:23:22.403 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- NOTIFY sip:72 at 172.20.250.60:53951 SIP/2.0
                Line 87217: 10-16 23:23:22.403 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 87522: 10-16 23:23:22.423 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 87536: 10-16 23:23:22.423 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 87561: 10-16 23:23:22.433 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:53951 SIP/2.0
                Line 87617: 10-16 23:23:22.433 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:53951 SIP/2.0
                Line 87765: 10-16 23:23:22.443 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 87818: 10-16 23:23:22.453 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SUBSCRIBE sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:53951 SIP/2.0
                Line 87840: 10-16 23:23:22.453 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 500 Internal Server Error
                Line 87851: 10-16 23:23:22.593 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 100 Trying
                Line 87864: 10-16 23:23:22.593 23450 24666 D    csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 500 Internal Server Error
                Line 105297: 10-16 23:30:33.783 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 105357: 10-16 23:30:33.783 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 105417: 10-16 23:30:33.783 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 105483: 10-16 23:30:33.983 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 100 Trying
                Line 105503: 10-16 23:30:33.983 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 200 OK
                Line 105742: 10-16 23:30:34.023 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SUBSCRIBE sip:72 at 172.20.10.5 SIP/2.0
                Line 105777: 10-16 23:30:34.033 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:48793 SIP/2.0
                Line 105833: 10-16 23:30:34.033 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- REFER sip:72 at 172.20.250.60:48793 SIP/2.0
                Line 106011: 10-16 23:30:34.053 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 106397: 10-16 23:30:34.083 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SUBSCRIBE sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:48793 SIP/2.0
                Line 106440: 10-16 23:30:34.093 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 107104: 10-16 23:30:34.133 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 200 OK
                Line 107245: 10-16 23:30:34.143 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- NOTIFY sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:48793;transport=tcp SIP/2.0
                Line 107325: 10-16 23:30:34.143 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> SIP/2.0 200 OK
                Line 109626: 10-16 23:30:34.403 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-sent---> REGISTER sip:172.20.10.5 SIP/2.0
                Line 110772: 10-16 23:30:34.533 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 100 Trying
                Line 110792: 10-16 23:30:34.533 25672 26189 D csf.ecc.sipcc: [CC_SIPCCService.cpp(342)] [_SIPCCLoggerFunction] - sipio-recv<--- SIP/2.0 200 OK



Da: bmeade90 at gmail.com [mailto:bmeade90 at gmail.com] Per conto di Brian Meade
Inviato: giovedì 16 ottobre 2014 23.16
A: Mauro Celli
Cc: cisco-voip at puck.nether.net
Oggetto: Re: [cisco-voip] R: Jabber 10.5 and Remotedestination, disconnected!

That doesn't show the 100 Trying sent or the 500 Internal Server Error.  Can you send the full log as an attachment?

On Thu, Oct 16, 2014 at 4:39 PM, Mauro Celli <mauro.celli at 2000net.it<mailto:mauro.celli at 2000net.it>> wrote:
This is detailed sdi cucm and sip log


[740334,NET]
REGISTER sip:172.20.10.5 SIP/2.0
Via: SIP/2.0/TCP 172.20.250.60:52993;branch=z9hG4bK7255336e
From: <sip:72 at 172.20.10.5<mailto:sip%3A72 at 172.20.10.5>>;tag=d600811c0d11001751400975-00483fd2
To: <sip:72 at 172.20.10.5<mailto:sip%3A72 at 172.20.10.5>>
Call-ID: d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.60<mailto:d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.60>
Max-Forwards: 70
Date: Thu, 16 Oct 2014 20:21:43 GMT
CSeq: 121 REGISTER
User-Agent: Cisco-SOUNDWAVE
Contact: <sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:52993;transport=tcp>;+sip.instance="<urn:uuid:00000000-0000-0000-0000-d600811c0d11>";+u.sip!devicename.ccm.cisco.com<http://devicename.ccm.cisco.com>="BOT123456789";+u.sip!model.ccm.cisco.com<http://model.ccm.cisco.com>="575";+u.sip!mtpreferredmode.ccm.cisco.com<http://mtpreferredmode.ccm.cisco.com>="cellular";video
Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1,X-cisco-graceful-reg,X-cisco-duplicate-reg
Reason: SIP;cause=200;text="cisco-alarm:111 Name=BOT123456789 ActiveLoad=Jabber_for_Android-10.5.1 InactiveLoad=Jabber_for_Android-10.5.1 Last=Application-Requested-Destroy"
Expires: 3600
Content-Length: 1044
Content-Type: application/x-cisco-remotecc-request+xml
Content-Disposition: session;handling=optional

<?xml version="1.0" encoding="UTF-8"?>
<x-cisco-remotecc-request>
  <optionsind>
    <supportedMobileExtensionFeatures>
      <DynamicDVOSelection/>
      <MediaConnectionReuse/>
      <Redial-DVOF/>
      <HoldResume/>
      <Transfer/>
      <Conference/>
      <Park/>
      <Dpark/>
      <ManualHandoff/>
      <SessionHandoff/>
      <MobileStatusUpdate/>
      <ManualLCRHandoff/>
      <SingleRegistration/>
    </supportedMobileExtensionFeatures>
    <combine max="6">
      <remotecc>
        <status></status>
      </remotecc>
      <service-control></service-control>
    </combine>
    <dialog usage="shared line">
      <unot></unot>
      <sub></sub>
    </dialog>
    <presence usage="blf speed dial">
      <unot></unot>
      <sub></sub>
    </presence>
    <joinreq></joinreq>
    <cfwdall-anyline></cfwdall-anyline>
    <coaching></coaching>
    <oosalarm></oosalarm>
    <x-cisco-number></x-cisco-number>
    <bfcp></bfcp>
    <ix></ix>
    <gatewayrecording></gatewayrecording>
  </optionsind>
</x-cisco-remotecc-request>|1,100,63,1.73806^172.20.250.60^*
0128.046 |//SIP/SIPTcp/wait_SdlReadRsp: SignalCounter = 73804|1,100,63,1.73806^172.20.250.60^*
22:21:45.046 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 2 for event 1|1,100,63,1.73806^172.20.250.60^*
22:21:45.046 |//SIP/Stack/Transport/0x0/context=(nil)|1,100,63,1.73806^172.20.250.60^*
22:21:45.046 |//SIP/Stack/Transport/0x0/gConnTab=0xff55338, addr=172.20.250.60, port=52993, connid=8067, transport=TCP|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/Return existing connection for port 52993 connId 8067|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/Checking Invite Dialog|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/*****CCB NOT found in UAS Request table. ccb=(nil)|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/Added context(0x10aaa8b8) with key=[125069] to table|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x0/Dialog Transaction Address 172.20.250.60,Port 52993, Transport 2, SentBy Port 52993|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/States/0x10aaa8b8/0x10aaa8b8 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x0/Subsq Transaction Address 172.20.250.60,Port 52993, Transport 2, SentBy Port 5060|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x0/Subsq Transaction Address 172.20.250.60,Port 52993, Transport 2, SentBy Port 52993|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/sipConstructContainerContext #### Created container=0x10c52e88|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/httpish_msg_lock, Increment msg (0xb204ad18) counter, current msg->refCount = 2|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/httpish_msg_create, #### created msg=0x10bcb6e0 with refCount = 1|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/Sending INFO Response to the transport layer|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/msg=0x10bcb6e0, addr=172.20.250.60, port=52993, sentBy_port=52993, is_req=0, transport=2, switch=0, callBack=|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/Proceedable for sending msg immediately|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/switch transport is 0|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/Trying to send resp=0x10bcb6e0 to default port=52993|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x0/Registering gcb=0x10aaa8b8 with connection=0xffd1d38 context list|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x10aaa8b8/Connection obtained...sending msg=0x10bcb6e0|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Transport/0x0/Posting send for msg=0x10bcb6e0, addr=172.20.250.60, port=52993, connId=8067 for TCP|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x0/sipSPIAssignCcbLastRequest, #### Current ccb(0x10aaa8b8)->last_request = 0xb204ad18, refCount = 2|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x10aaa8b8/****Adding to UAS Request table.|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x10aaa8b8/Added to table. ccb=0x10aaa8b8 key=d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.6072d600811c0d11001751400975-00483fd2|1,100,63,1.73806^172.20.250.60^*<mailto:key=d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.6072d600811c0d11001751400975-00483fd2|1,100,63,1.73806%5e172.20.250.60%5e*>
22:21:45.047 |//SIP/SIPHandler/ccbId=128990/scbId=0/findDevicePID: Routed to SIPStationInit|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Event/0x10aaa8b8/
ccsip_api_register_ind return value : SIP_SUCCESS
|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/Info/0x10aaa8b8/Adding call id 1f7de to table|1,100,63,1.73806^172.20.250.60^*
22:21:45.047 |//SIP/Stack/States/0x10aaa8b8/0x10aaa8b8 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (SIP_STATE_INCOMING_REGISTER, SUBSTATE_NONE)|1,100,63,1.73806^172.20.250.60^*
22:21:45.048 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|1,100,63,1.73806^172.20.250.60^*
22:21:45.048 |//SIP/Stack/Info/0x0/httpish_msg_free, #### Freed msg=0x10bcb6e0|1,100,71,1.1^*^*
22:21:45.049 |SIPRegistrationTable::addToConnectionIndexTableInternal: Adding BOT123456789 to connectionIndexTable|*^*^*
22:21:45.049 |SIPStationInit: connId=8067, BOT123456789, 120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:52993<http://120683ae-0fca-8bc2-bd00-35e584c45cdc@172.20.250.60:52993>, New [static] device registering over (Non-Shared) TCP transport|1,100,63,1.73806^172.20.250.60^*
22:21:45.049 |SIPStationInit: connID=8067, BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, DevStat-InitState: STATIC_IDLE --> REGISTERED|1,100,63,1.73806^172.20.250.60^*
22:21:45.049 |SIPStationInit: connID=8067, BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, checkTcpConnection:  Connection index 8067 matches|1,100,63,1.73806^172.20.250.60^*
22:21:45.049 |SIPStationInit: connID=8067, BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, Routing to D (1,100,201,46), regCount=1|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.049 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:59007<http://172.20.250.60:59007>, primaryDN=72, parseRegisterRemoteccBody:  X-cisco-callinfo=F X-cisco-serviceuri=F X-cisco-escapecodes=F X-cisco-service-control=F X-cisco-duplicate-reg=F X-cisco-srtp-fallback=F X-cisco-monrec=F X-cisco-xsi=F xsi-version=8765.217030272.195060966 X-cisco-sis=F sis-version=195066871.3069999229.3069999244 extended-refer=F norefersub=F join=F cso[rcc=T,sc=T,max=6] slNotify=3 hsNotify=283537204 blfNotify=3 fi[joinReq=T,cfwdAny=T,coaching=T,oosAlarm=T,joinDXferPolicy=2,OrigCalled=F]MobilityInd[dynamicDVOSelection=T,mediaConnectionReuse=T,holdResume=T,transfer=T,conference=T,park=T,dPark=T,manualHandoff=T,sessionHandoff=T,redial_DVOF=T,singleRegistration=T,manualLCRHandoff=T]|0,0,0,0.0^*^*
22:21:45.049 |DMMS: (0000046) checkSingleRegRequest -- new single registration message|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.049 |DMMS: (0000046) SIPRegisterInd, single registration case. MtPreferredMode = 1, oldMtPreferredMode = 0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.049 |DMMSStationD-SD(1,100,201,46), , UNKNOWN:0, primaryDN=UNKNOWN, Primary expires 120, secondary expires 3600|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), , 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, setUaTypeAndCepn: uaType is CISCO_ENHANCED_PHONE|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), , 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, wait_register_SIPRegisterInd: model=575, mUaType=1|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), , 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Register instanceId (MAC addr) =D600811C0D11|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), , 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Register deviceName (database key) =BOT123456789|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, DevStat-Start    : transport TCP, model 575|0,0,0,0.0^*^*
22:21:45.049 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseSupportedHeader:  X-cisco-callinfo=T X-cisco-serviceuri=T X-cisco-escapecodes=T X-cisco-service-control=T X-cisco-duplicate-reg=T X-cisco-srtp-fallback=T X-cisco-monrec=T X-cisco-xsi=T xsi-version=8.5.1 X-cisco-sis=T sis-version=7.0.0 extended-refer=T norefersub=T join=T cso[rcc=F,sc=F,max=0] slNotify=0 hsNotify=0 blfNotify=0 fi[joinReq=F,cfwdAny=F,coaching=F,oosAlarm=F,joinDXferPolicy=2,OrigCalled=F]MobilityExtension Feature Indication is empty|0,0,0,0.0^*^*
22:21:45.050 |CcmCcmdbHelper::getProcessConfigGivenProcessnodeParamnameService - Value of DeviceMobilityMode from process config table  is (0)|*^*^*
22:21:45.050 |CcmCcmdbHelper::getProcessConfigGivenProcessnodeParamnameService - Value of DeviceMobilityMode from process config table  is (0)|*^*^*
22:21:45.050 |GetMaxCallForDevice: cannot find entry in ProductSupportsFeature table for product 462 and protocol 11feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.050 |GetMaxCallForDevice: cannot find entry in ProductSupportsFeature table for product 462 and protocol 99feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.050 |getLiteDnAndMwiData ####  device name(BOT123456789): old maxLineIndexSeen = 0, new maxLineIndexSeen = 1|*^*^*
22:21:45.050 |getLiteDnAndMwiData ####  device name(BOT123456789): maxLineIndexSeen changed!|*^*^*
22:21:45.051 |getMaxAuthnameLenFromModel: cannot find entry in ProductSupportsFeature table for model 575 and protocol 11 feature SUPPORTS_FEATURE_MAXIMUM_AUTHNAME_LENGTH|*^*^*
22:21:45.051 |getMaxAuthnameLenFromModel: cannot find entry in ProductSupportsFeature table for model 575 and protocol 99 feature SUPPORTS_FEATURE_MAXIMUM_AUTHNAME_LENGTH|*^*^*
22:21:45.051 |getMaxCallForDeviceFromModel: ProductSupportsFeature table entry for model 575 and feature SUPPORTS_FEATURE_MULTIPLE_CALL_DISPLAY  has deviceprotocol = 11 maxcalls = 3|*^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, trustedDevice == 1, deviceSecurityMode == 1, clusterSecurityMode == 0, exp = 3/1|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, VerFeatMask=0x00000000, hasCrypto=0|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, mtpRequired = 0 useTRP = 0  mtpPrefOrgCodec = 1 unAttendedPort = 0  requireDTMFReception = 0  packetCaptureMode = 0  packetCaptureDuration = 0|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, securityProfileName = Cisco Dual Mode for Android - Standard SIP Non-Secure Profile, nonceValidityTime = 600  transportTypeValue = 4  digestAuthorization = 0|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, deviceSecurityMode = 1|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, subscribeCSS = 4c877f5e-e0e5-5e50-67c6-626856dc2eb7  css_reroute = 4c877f5e-e0e5-5e50-67c6-626856dc2eb7  css_ood =   presenceGroup = ad243d17-98b4-4118-8feb-5ff2e1b781ac  dialRules = |0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, versionStamp = 1413457250-0647b8c0-5ef2-4e32-9d74-34d021a27da9, softkeyTemplateVersionStamp = 1334731085-f729f55a-7710-4d08-8190-f8bb9cf9bc9f, dialRulesVersionStamp = , featureControlVersionStamp = 1372623678-fd2190e8-290d-4d32-a98b-c2e959753428|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, SIPDigestUserEntry: userName =   credentials =  SIPDevice = BOT123456789 enduserPresenceGroup = |0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, SIPProfile: SIPProfileInfoName = Jabber-Android  rerouteType = 0  timeInviteExpires = 180  timerRegDelta = 30  timerRegExpires = 660|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, SIPProfile: timerT1 = 500, timerT2 = 4000, retryInvite = 6, tryOnInvite = 10, startMediaPort = 16384, ringing180 = 0  defaultTelephonyEvent = 101|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, callPickupURI = [x-cisco-serviceuri-pickup]  callPickupGroupURI = [x-cisco-serviceuri-gpickup]|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, callPickupOtherGroupURI = [x-cisco-serviceuri-opickup]  meetMeURI = [x-cisco-serviceuri-meetme]|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, callForwardURI = [x-cisco-serviceuri-cfwdall]  abbrdialURI = [x-cisco-serviceuri-abbrdial]|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, blfpickupURI = [x-cisco-serviceuri-blfpickup]|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Device Mobility: MobileDevice = false DevicePoolPkid = 20775d43-2f7d-55e1-c1c7-6a0573c11257 RoamingDevicePoolPkid =  PhysicalLocation =  AARNeighborHood = |0,0,0,0.0^*^*
22:21:45.051 |<MN::Directory Number><MV::72>|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, initLineStruct: Initializing index 1, DN 72|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, initLineStruct: MonRec data: recType=0, recDN=, recCSS=, monCSS=|0,0,0,0.0^*^*
22:21:45.051 |setIsWildcardDN: digits 72, isWildcardDN false|*^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, isPrivacyButtonConfigured:false isPrivacyOn:true|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, checkDNsReceived: TotalCount=1|0,0,0,0.0^*^*
22:21:45.051 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, sendLineRegisterReq: mLineRegisterReqsOutstanding=1|0,0,0,0.0^*^*
22:21:45.053 |checkTCPReconnectEnabled: TCP Reconnect feature is enabled for device model (575) and protocal (11)|*^*^*
22:21:45.053 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, transport TCP security None, model 575|0,0,0,0.0^*^*
22:21:45.053 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, setOptionsIndicationDefaultOptions: INFO - user agent 1|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseRegisterRemoteccBody:  X-cisco-callinfo=T X-cisco-serviceuri=T X-cisco-escapecodes=T X-cisco-service-control=T X-cisco-duplicate-reg=T X-cisco-srtp-fallback=T X-cisco-monrec=T X-cisco-xsi=T xsi-version=8.5.1 X-cisco-sis=T sis-version=7.0.0 extended-refer=T norefersub=T join=T cso[rcc=T,sc=T,max=6] slNotify=3 hsNotify=1 blfNotify=3 fi[joinReq=T,cfwdAny=T,coaching=T,oosAlarm=T,joinDXferPolicy=2,OrigCalled=F]MobilityInd[dynamicDVOSelection=T,mediaConnectionReuse=T,holdResume=T,transfer=T,conference=T,park=T,dPark=T,manualHandoff=T,sessionHandoff=T,redial_DVOF=T,singleRegistration=T,manualLCRHandoff=T]|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, wait_register_SIPRegisterInd Supports Original Called 0 |0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseReasonHeader: SIP;cause=200;text="cisco-alarm:111 Name=BOT123456789 ActiveLoad=Jabber_for_Android-10.5.1 InactiveLoad=Jabber_for_Android-10.5.1 Last=Application-Requested-Destroy"|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseFirmwareLoadId: Name=BOT123456789 ActiveLoad=Jabber_for_Android-10.5.1 InactiveLoad=Jabber_for_Android-10.5.1 Last=Application-Requested-Destroy|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseLoad: ActiveLoad= name is: [Jabber_for_Android-10.5.1]|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseLoad: InactiveLoad= name is: [Jabber_for_Android-10.5.1]|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, line_register_LineRegisterRes: mLineRegisterReqsOutstanding=0|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, storeContactHeader: Request-URI is sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:52993;transport=tcp|0,0,0,0.0^*^*<sip:120683ae-0fca-8bc2-bd00-35e584c45cdc at 172.20.250.60:52993;transport=tcp|0,0,0,0.0%5e*%5e*>
22:21:45.054 |setSIPAoR: aor = sip:72 at 172.20.10.5<mailto:sip%3A72 at 172.20.10.5>|*^*^*
22:21:45.054 |setIsWildcardDN: digits 72, isWildcardDN false|*^*^*
22:21:45.054 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Started 1 second partially-registered alarm timer.|0,0,0,0.0^*^*
22:21:45.054 |getRestrictMaxCallForDevice: cannot find entry in ProductSupportsFeature table for model 575 and protocol 0feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.054 |getRestrictMaxCallForDevice: cannot find entry in ProductSupportsFeature table for model 575 and protocol 99feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.054 |StationCtiUtils::GetLoginStatus profilePkid is EMPTY|*^*^*
22:21:45.054 |StationCtiUtils::GetLoginStatus Incorrect EM Info|*^*^*
22:21:45.054 |getRestrictMaxCallForDevice: cannot find entry in ProductSupportsFeature table for model 575 and protocol 0feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.054 |getRestrictMaxCallForDevice: cannot find entry in ProductSupportsFeature table for model 575 and protocol 99feature SUPPORTS_FEATURE_RESTRICT_MAX_CALLS|*^*^*
22:21:45.054 |StationCtiUtils::GetLoginStatus profilePkid is EMPTY|*^*^*
22:21:45.054 |StationCtiUtils::GetLoginStatus Incorrect EM Info|*^*^*
22:21:45.054 |StationCtiD::getDeviceAndLineInfo featureID=9|*^*^*
22:21:45.054 |CcmCcmdbHelper::getPickupGroupInfoGivenLinePkid - 120683ae-0fca-8bc2-bd00-35e584c45cdc :  :  |*^*^*
22:21:45.054 |CcmCcmdbHelper::getPickupGroupInfoGivenLinePkid - built list from numplan pkid 120683ae-0fca-8bc2-bd00-35e584c45cdc |*^*^*
22:21:45.054 |CcmCcmdbHelper::getPickupGroupInfoGivenLinePkid - found pickuptable entry|*^*^*
22:21:45.054 |CcmCcmdbHelper::getPickupGroupInfoGivenLinePkid - pattern is  = 02071, Partition is = _XXXnet-0207_PhonePT|*^*^*
22:21:45.054 |StationCtiD::getDeviceAndLineInfo PickupDN=02071|*^*^*
22:21:45.054 |StationCtiD::getDeviceAndLineInfo PickupPartition=_XXXnet-0207_PhonePT|*^*^*
22:21:45.054 |//SIP/Stack/Info/0x0/sipConstructContainerContext #### Created container=0xb270dc00|1,100,71,1.1^*^*
22:21:45.054 |DMMS (0000046): singleRegCallBack_VoIP, resp=200, expires = 660|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.054 |MobileClientD(1,100,201,46) - state[wait] signal[SIPRegisterInd]|0,0,0,0.0^*^*
22:21:45.054 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseSupportedHeader:  X-cisco-callinfo=T X-cisco-serviceuri=T X-cisco-escapecodes=T X-cisco-service-control=T X-cisco-duplicate-reg=T X-cisco-srtp-fallback=T X-cisco-monrec=T X-cisco-xsi=T xsi-version=8.5.1 X-cisco-sis=T sis-version=7.0.0 extended-refer=T norefersub=T join=T cso[rcc=T,sc=T,max=6] slNotify=3 hsNotify=0 blfNotify=3 fi[joinReq=T,cfwdAny=T,coaching=T,oosAlarm=T,joinDXferPolicy=2,OrigCalled=F]MobilityInd[dynamicDVOSelection=T,mediaConnectionReuse=T,holdResume=T,transfer=T,conference=T,park=T,dPark=T,manualHandoff=T,sessionHandoff=T,redial_DVOF=T,singleRegistration=T,manualLCRHandoff=T]|0,0,0,0.0^*^*
22:21:45.055 |MobileClientD(     46) -- star_SIPRegisterInd: Setting the mPrimaryLineOptions|0,0,0,0.0^*^*
22:21:45.055 |MobileClientD(     46) -- star_SIPRegisterInd: Keep Alive timer got from CUMA = 3600|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseRegisterRemoteccBody:  X-cisco-callinfo=T X-cisco-serviceuri=T X-cisco-escapecodes=T X-cisco-service-control=T X-cisco-duplicate-reg=T X-cisco-srtp-fallback=T X-cisco-monrec=T X-cisco-xsi=T xsi-version=8.5.1 X-cisco-sis=T sis-version=7.0.0 extended-refer=T norefersub=T join=T cso[rcc=T,sc=T,max=6] slNotify=3 hsNotify=0 blfNotify=3 fi[joinReq=T,cfwdAny=T,coaching=T,oosAlarm=T,joinDXferPolicy=2,OrigCalled=F]MobilityInd[dynamicDVOSelection=T,mediaConnectionReuse=T,holdResume=T,transfer=T,conference=T,park=T,dPark=T,manualHandoff=T,sessionHandoff=T,redial_DVOF=T,singleRegistration=T,manualLCRHandoff=T]|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseReasonHeader: SIP;cause=200;text="cisco-alarm:111 Name=BOT123456789 ActiveLoad=Jabber_for_Android-10.5.1 InactiveLoad=Jabber_for_Android-10.5.1 Last=Application-Requested-Destroy"|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseFirmwareLoadId: Name=BOT123456789 ActiveLoad=Jabber_for_Android-10.5.1 InactiveLoad=Jabber_for_Android-10.5.1 Last=Application-Requested-Destroy|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseLoad: ActiveLoad= name is: [Jabber_for_Android-10.5.1]|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, parseLoad: InactiveLoad= name is: [Jabber_for_Android-10.5.1]|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Warning: Configured transport is unknown, cannot verify transport match.|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, setUaTypeAndCepn: uaType is CISCO_ENHANCED_PHONE|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Duplicate device instance detected, terminating previous instance|0,0,0,0.0^*^*
22:21:45.055 |MobileClientD(     46) - sendDeviceUnregisteredAlarm ignored due to dual mode phone.|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, buildServiceControl: primary line (1) registration callID is null|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, sendServiceControlNotify: setting servicecontrol NOTIFY timer|0,0,0,0.0^*^*
22:21:45.055 |//SIP/Stack/Info/0x0/sipConstructContainerContext #### Created container=0xb2978c50|1,100,71,1.1^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, sendServiceControlNotify: sending service-control NOTIFY|0,0,0,0.0^*^*
22:21:45.055 |MobileClientD:    (     46) - shutdownDevice: , Name=BOT123456789 , shutting down the SIP side|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, DevStat-StopSoft : New device instance registering, terminating previous instance|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Removing subscription for scbId 128991, pkgId 6|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, Terminating transaction for scbId 128991, pkgId 6|0,0,0,0.0^*^*
22:21:45.055 |DMMSStationD-MD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, retryRegister: Caching REGISTER to be sent to SIPStationInit after shutdown complete|0,0,0,0.0^*^*
22:21:45.055 |//SIP/Stack/Info/0x0/sipDestructContainerContext #### Deleted container=0xb2b39758|1,100,71,1.1^*^*
22:21:45.055 |//SIP/Stack/Info/0x0/httpish_msg_free, #### Freed msg=0xb27c8db8|1,100,71,1.1^*^*
22:21:45.055 |//SIP/Stack/Info/0x0/sipDestructContainerContext #### Deleted container=0xb270dc00|1,100,71,1.1^*^*
22:21:45.055 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, DevStat-NewState : line 72: Null ==> Registered|0,0,0,0.0^*^*
22:21:45.056 |-->RISCMAccess::DeviceRegister(...)|*^*^*
22:21:45.056 |Device Register deviceName : BOT123456789, IPAdress : 172.20.250.60, IPv6Address : not shown, IPv4Attribute : 0, IPv6Attribute : 0, LoadID : , ActiveLoadID : Jabber_for_Android-10.5.1, InactiveLoadID : Jabber_for_Android-10.5.1, DeviceType : 575, Protocol : 2|*^*^*
22:21:45.056 |DebugMsg deviceName : BOT123456789, DeviceType : 575, risClass: 1|*^*^*
22:21:45.056 |SIP Device Register from Unregister: deviceName(BOT123456789), Protocol(2), RegisteredSCCP(203), RegisteredSIP(25)|*^*^*
22:21:45.056 |<--RISCMAccess::DeviceRegister(...)|*^*^*
22:21:45.058 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, sendDeviceRegisteredAlarm: Raised Device Registered Alarm with Serviceability|0,0,0,0.0^*^*
22:21:45.058 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, VerFeatMask=0xC1000015, hasCrypto=0|0,0,0,0.0^*^*
22:21:45.059 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, establishDialogSubscription: Added subscription for line 72 with scbId = 128992, pkgId = 4|0,0,0,0.0^*^*
22:21:45.059 |DMMSStationD-SD(1,100,201,46), BOT123456789, 172.20.250.60:52993<http://172.20.250.60:52993>, primaryDN=72, buildServiceControl: Sending callid from mPrimaryLineNum 1: d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.60<mailto:d600811c-0d11000c-4c93ea29-78e643b7 at 172.20.250.60>|0,0,0,0.0^*^*
22:21:45.059 |//SIP/Stack/Info/0x0/sipConstructContainerContext #### Created container=0xb270dc00|1,100,71,1.1^*^*
22:21:45.059 |MobileClientD(1,100,201,46) - state[wait] signal[LineRegisterRes]|0,0,0,0.0^*^*
22:21:45.059 |SIPEventPkg::subscribeReq address list is NOT empty|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.059 |//SIP/Stack/Info/0x0/sipConstructContainerContext #### Created container=0xb23c5850|1,100,71,1.1^*^*
22:21:45.059 |getCcdSafRouteFromAddrList SafTransAddrList is empty|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.059 |SIPEventPkg::subscribeReq exp = 28100 t = 26300 caller = 72, callee = 120683ae-0fca-8bc2-bd00-35e584c45cdc|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |DeviceManager::star_DmUpdateRoamingInfo - DeviceMobility is disabled for the device = [BOT123456789], Checking if we need to de-associate Roaming Device Pool.|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |DeviceManager::star_DmUpdateRoamingInfo - Roaming Device pool is empty or null|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Searching for SCB in subID table with key 128991|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Added context(0xd242000) with key=[125070] to table|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/SIPHandler/ccbId=0/scbId=128991/ccsip_platform_init_scb: scb mgmt added 0xd242000 (context_id=125070)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/Created new scb: 0xd242000 with id: 128991|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/SIPHandler/ccbId=0/scbId=0/populateTargetInfo: port=52993, isIPAddr=1, type=2, target_port=5060, outboundProxyAddr.isIPAddr=0, target_type=0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 7 for event 204|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Event [SIPSPI_EV_CC_NOTIFY] received in State [SUBSCRIBE_STATE_IDLE]|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/Inserting SCB with key 128991 into SubIDTable|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Created TCB: 0xb2221030|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/TCB 0xb2221030's parent is 0xd242000|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0x0/Dialog Transaction Address 172.20.250.60,Port 52993, Port Present TRUE, Transport 2, FQDN 183963413|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Generated Subscribe Key : b24d800-440128d9-4d27-50a14ac at 172.20.10.51330246167|1,100,63,1.73806^172.20.250.60^BOT123456789<mailto:b24d800-440128d9-4d27-50a14ac at 172.20.10.51330246167|1,100,63,1.73806%5e172.20.250.60%5eBOT123456789>
22:21:45.060 |//SIP/Stack/Info/0xd242000/Inserting SCB with key b24d800-440128d9-4d27-50a14ac at 172.20.10.51330246167<mailto:b24d800-440128d9-4d27-50a14ac at 172.20.10.51330246167> into ClientTable|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/httpish_msg_create, #### created msg=0xd1ea088 with refCount = 1|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/TCB 0xb2221030's method is 111|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/sipSPIUpdateTCBPackage, ##== Current tcb(0xb2221030)->package = 0xd1ea088, refCount = 1|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Client TCB's Marker Updated to: z9hG4bKaeefed130a3|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/Entering ...|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Created new edb(0xb29c4ca0)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/EDB updated with Event Name: service-control Event ID: |1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/Q'd EDB(0xb29c4ca0) on SCB(0xd242000)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/There are 1 EDB's on edbQ|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Q'd TCB(0xb2221030) on EDB(0xb29c4ca0)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0xd242000/There are 1 TCB's on tcbQ|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Entering ...|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/Sending Package on tcb: 0xb2221030 and parent: 0xd242000
|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Info/0x0/httpish_msg_lock, Increment msg (0xd1ea088) counter, current msg->refCount = 2|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0xd242000/msg=0xd1ea088, addr=172.20.250.60, port=52993, sentBy_port=0, is_req=1, transport=2, switch=0, callBack=|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0xd242000/Proceedable for sending msg immediately|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0xd242000/switch transport is 0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0x0/Registering gcb=0xd242000 with connection=0xffd1d38 context list|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0xd242000/Set to send the msg=0xd1ea088|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.060 |//SIP/Stack/Transport/0x0/Posting send for msg=0xd1ea088, addr=172.20.250.60, port=52993, connId=8067 for TCP|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Entering sipSPISentTCBClient...|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Kicking off ReTx Timer for Client TCB (0xb2221030) with 0 retries and 32000 milliseconds initial retx |1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Created timer context(0x10b65698)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer: type=SIP_TIMER_CLIENT_TCB value=32000 retries=0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPHandler/ccbId=0/scbId=0/sip_start_timer: type=SIP_TIMER_CLIENT_TCB value=32000 retries=0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Changing from State: SUBSCRIBE_STATE_IDLE to state SUBSCRIBE_STATE_ACTIVE|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Searching for SCB in subID table with key 128991|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPHandler/ccbId=0/scbId=0/wait_SIPSubTerminated: inDialogSubReferCounter=0 scbid=128991|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/ccsip_spi_get_msg_type returned: 7 for event 210|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPTcp/wait_SdlSPISignal: received a spi signal ...|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Event [SIPSPI_EV_SUBSCRIPTION_TERMINATED] received in State [SUBSCRIBE_STATE_ACTIVE]|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0xd242000/Resetting all EDB flags|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0xd242000/1 Transactions still active on Event:service-control ID:|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Searching for SCB in subID table with key 128993|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x0/Added context(0x10d92e68) with key=[125071] to table|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPHandler/ccbId=0/scbId=128993/ccsip_platform_init_scb: scb mgmt added 0x10d92e68 (context_id=125071)|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/Stack/Info/0x10d92e68/Created new scb: 0x10d92e68 with id: 128993|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPHandler/ccbId=0/scbId=0/populateTargetInfo: port=52993, isIPAddr=1, type=2, target_port=5060, outboundProxyAddr.isIPAddr=0, target_type=0|1,100,63,1.73806^172.20.250.60^BOT123456789
22:21:45.061 |//SIP/SIPTcp/wait_SdlSPISignal: Outgoing SIP TCP message to 172.20.250.60 on port 52993 index 8067
[740336,NET]
NOTIFY sip:72 at 172.20.250.60:52993<http://sip:72@172.20.250.60:52993> SIP/2.0
Via: SIP/2.0/TCP 172.20.10.5:5060;branch=z9hG4bKaeefed130a3
From: <sip:172.20.10.5>;tag=1330246167
To: <sip:72 at 172.20.250.60<mailto:sip%3A72 at 172.20.250.60>>
Call-ID: b24d800-440128d9-4d27-50a14ac at 172.20.10.5<mailto:b24d800-440128d9-4d27-50a14ac at 172.20.10.5>
CSeq: 101 NOTIFY
Max-Forwards: 70
Date: Thu, 16 Oct 2014 20:21:45 GMT
User-Agent: Cisco-CUCM8.6
Event: service-control
Subscription-State: active
Contact: <sip:172.20.10.5:5060;transport=tcp>
Content-Type: text/plain
Content-Length: 37

action=terminate
RegisterCallId={}


Da: bmeade90 at gmail.com<mailto:bmeade90 at gmail.com> [mailto:bmeade90 at gmail.com<mailto:bmeade90 at gmail.com>] Per conto di Brian Meade
Inviato: giovedì 16 ottobre 2014 16.13
A: Mauro Celli
Cc: cisco-voip at puck.nether.net<mailto:cisco-voip at puck.nether.net>
Oggetto: Re: [cisco-voip] R: Jabber 10.5 and Remotedestination, disconnected!

Nevermind, those seem to be 9.x traces but you mentioned CUCM version 8.5.  Is this the full trace?

On Thu, Oct 16, 2014 at 10:10 AM, Brian Meade <bmeade90 at vt.edu<mailto:bmeade90 at vt.edu>> wrote:
Can you provide the SDI traces as well?

On Thu, Oct 16, 2014 at 6:16 AM, Mauro Celli <mauro.celli at 2000net.it<mailto:mauro.celli at 2000net.it>> wrote:
I have same problem in one cucm 8.5, and same problem if I install one older version of jabber 4 android.
When I enable Dial Via Office and I put one number in Mobility Identity, jabber stay offline in cucm.
If I install jabber voice, all work.

At sip I get
366         9.803706              172.16.2.32         10.0.8.10             SIP         1423      Request: REGISTER sip:10.0.8.10  (remove 1 binding) |
367         9.806513              10.0.8.10             172.16.2.32         SIP         375         Status: 100 Trying |
368         9.806590              10.0.8.10             172.16.2.32         SIP         403         Status: 500 Internal Server Error |

At cucm I get

00426426.000 |11:59:36.442 |SdlSig-I |CtiDeviceRegisterNotifyWithLineInfo    |wait                           |CTIRegistrar(1,100,223,1)        |                                 |1,100,13,4805.3^172.16.2.32^BOT12345678 |[R:N-H:0,N:2,L:1,V:0,Z:0,D:0]  deviceName=BOT12345678 EventContent=14 DeviceConfigInfo= Locale=7 AltScrpt= DNDOption=1 ConfigIpAddrMode=3 Hotelling=F Restricted=T OutboundRO=0 BIB=F DNDStatus=F LoginStatus=0 DeviceRegistrationInfo= TerminateMedia=5 ActiveIPAddrMode=0 IPv4=537006252 IPv6= LoginUser=409 ProfilePkid= Encoding=3 CtiControllable=2 AppCapability=20 UserCapability=20Protocol=2 TotalLines=1 LineCount=1 MoreLines=F DN = 409 Part = Phone-0305_PhonePT PkGpDN =  PkGpPart =  num RD = 0 StationPid(0,0,0,0) CTIHandlerId(0) LoginStatus=0
00426426.001 |11:59:36.442 |AppError |wait_CtiDeviceRegisterNotifyWithLineInfo ENTER Device=BOT12345678
00426426.002 |11:59:36.442 |AppError |       senderPid=(0,0) StationPid=(0,0)
00426427.000 |11:59:36.442 |SdlSig   |SNFSubscribeReq                        |init                           |Subscriber(1,100,56,295)         |DMMSStationD(1,100,206,11)       |1,100,13,4805.3^172.16.2.32^BOT12345678 |[R:N-H:0,N:1,L:1,V:0,Z:0,D:0]  SNFSubscriptionId = 1|0|912, SNFSubscriptionMsg contents:  SNFSubscriptionType = , duration = 35910, routeFlag = true, authFlag = true, forwardFlag = true, cacheStateFlag = false, bulkUnSubscribeFlag = false, subscriber = 409, subscribee = 3dc4b3e1-cec9-9f52-eb16-d56d4b3de81f, subscriberUrl = , subscribeeUrl = , subCSS = , srcPresGrpPkid = , destinationPid = (0,0,0), destinationCepn = , pretransformTagsList = , pretransformPositionalMatchList =  FDataType=0opId=0ssType=0 SsKey=0invokeId=0resultExp=Fbpda=F
00426428.000 |11:59:36.442 |SdlSig   |DeviceLineRegister                     |restart0                       |LineControl(1,100,167,235)       |DMMSStationD(1,100,206,11)       |1,100,13,4805.3^172.16.2.32^BOT12345678 |[R:L-H:0,N:0,L:0,V:0,Z:0,D:0] PID=(1, 100, 206, 11) Type=575 BusyTrigger=2 Privacy=T Limit=3 UserMOH=0 NetMOH=0 PresGrp=ad243d17-98b4-4118-8feb-5ff2e1b781ac DNDStatus=F DNDOption=1 users.size()=1 users=409; model=Cisco-SOUNDWAVE Mac=D600811C0D11 HLoggedStatus= T
00426429.000 |11:59:36.442 |SdlSig   |PublishInd                             |wait                           |PublishManager(1,100,105,1)      |LineControl(1,100,167,235)       |1,100,13,4805.3^172.16.2.32^BOT12345678 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] users.size()=1 users=409; pattern=409 numPlanPkid=3dc4b3e1-cec9-9f52-eb16-d56d4b3de81f devicePkid=c6b22498-d169-56b1-d7ca-b8432236a9e0 mobileNumber=3473499480 model=Cisco-SOUNDWAVE state=1 isDnd=F firstRegisterDevice=T deviceMac=D600811C0D11
00426430.000 |11:59:36.442 |SdlSig   |PublishInd                             |restart0                       |PublishEPA(1,100,106,14)         |PublishManager(1,100,105,1)      |1,100,13,4805.3^172.16.2.32^BOT12345678 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] users.size()=1 users=409; pattern=409 numPlanPkid=3dc4b3e1-cec9-9f52-eb16-d56d4b3de81f devicePkid=c6b22498-d169-56b1-d7ca-b8432236a9e0 mobileNumber=3473499480 model=Cisco-SOUNDWAVE state=1 isDnd=F firstRegisterDevice=T deviceMac=D600811C0D11
00426431.000 |11:59:36.443 |SdlSig   |SIPNotifyReq                           |wait                           |SIPHandler(1,100,72,1)           |DMMSStationD(1,100,206,11)       |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:4,L:0,V:0,Z:0,D:0]  --TransType=1 --TransSecurity=0 PeerAddr = 172.16.2.32:51237<http://172.16.2.32:51237> addrList: ||
00426432.000 |11:59:36.443 |SdlSig   |SIPSubTerminated                       |wait                           |SIPHandler(1,100,72,1)           |DMMSStationD(1,100,206,11)       |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:3,L:0,V:0,Z:0,D:0]  --TransType=1 --TransSecurity=0 PeerAddr=172.16.2.32:51237<http://172.16.2.32:51237> scbId=1927 event.id<http://event.id>= event.name<http://event.name>=service-control eventType=0
00426433.000 |11:59:36.443 |SdlSig   |DmUpdateRoamingInfo                    |initialized                    |DeviceManager(1,100,199,1)       |DMMSStationD(1,100,206,11)       |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]  Name=BOT12345678 Cepn=c6b22498-d169-56b1-d7ca-b8432236a9e0 Type=575 ccmType=4 IsActive=T Pid=1,100,206,11 RoamIP= RoamInfoAction=0
00426434.000 |11:59:36.443 |SdlSig   |SIPReferReq                            |wait                           |SIPHandler(1,100,72,1)           |DMMSStationD(1,100,206,11)       |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:2,L:0,V:0,Z:0,D:0]  ccbID= 0 --TransType=1 --TransSecurity=0 PeerAddr = 172.16.2.32:51237monitorCall=F
00426435.000 |11:59:36.443 |SdlSig   |SIPSubscribeReq                        |wait                           |SIPHandler(1,100,72,1)           |Subscriber(1,100,56,295)         |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0]  ccbID=0 --TransType=1 --TransSecurity=0 PeerAddr=172.16.2.32:51237<http://172.16.2.32:51237> scbId=1928 addrList: ||
00426436.000 |11:59:36.443 |SdlSig   |SIPPublishReq                          |wait                           |SIPHandler(1,100,72,1)           |PublishEPA(1,100,106,14)         |1,100,13,4805.3^172.16.2.32^BOT12345678 |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]  scbId=1930 event=presence duration=3600 sip_if_match=4b71e8ef-0001-4356-b8f5-86fa90516da6 callingNumber=409 calledNumber=409 TransType=1 --TransSecurity=0 PeerAddr = 10.0.8.11:5070<http://10.0.8.11:5070> addrList: ||
00426437.000 |11:59:36.444 |SdlSig   |SdlConnectReq                          |started                        |SdlTCPService(1,100,10,1)        |SIPTcp(1,100,64,1)               |1,100,13,4805.3^172.16.2.32^BOT12345678 |*TraceFlagOverrode
00426437.001 |11:59:36.444 |Created  |                                       |                               |SdlTCPConnector(1,100,12,82)     |SdlTCPService(1,100,10,1)        |                                         |NumOfCurrentInstances: 1
00426438.000 |11:59:36.447 |Created  |                                       |                               |SdlTCPConnection(1,100,13,4806)  |SdlTCPConnector(1,100,12,82)     |                                         |NumOfCurrentInstances: 181
00426439.000 |11:59:36.447 |Stopping |                                       |                               |SdlTCPConnector(1,100,12,82)     |SdlTCPConnector(1,100,12,82)     |                                         |NumOfCurrentInstances: 1
00426440.000 |11:59:36.568 |SdlSig   |SIPSubscribeResult                     |gsm                            |DMMSStationD(1,100,206,11)       |SIPHandler(1,100,72,1)           |1,100,13,4805.5^172.16.2.32^*            |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]  --TransType=1 --TransSecurity=0 PeerAddr = 172.16.2.32:51237<http://172.16.2.32:51237>
00426440.001 |11:59:36.568 |AppInfo  |DMMS: (0000011) fireSignalBySIPConnIndex, can't find connection index for this SIP signal. This could be an old signal.
00426441.000 |11:59:36.574 |SdlSig   |SIPSPISignal                           |wait                           |SIPHandler(1,100,72,1)           |SIPTcp(1,100,64,1)               |1,100,13,4805.6^172.16.2.32^*            |*TraceFlagOverrode
00426441.001 |11:59:36.574 |AppInfo  |//SIP/Stack/Error/0xb41a32d8/sipSPICheckFromToRequest:
Failed FROM/TO Request check - IGNORE IF HAIRPIN CALL
                               old_from
00426441.002 |11:59:36.574 |AppInfo  |//SIP/Stack/Error/0xb41a6248/sipSPIUaddCcbToTable: Could not add ccb to table. ccb=0xb41a6248 key=d600811c-0d11001c-3d913449-0601d4e4 at 172.16.2.32409d600811c0d11004037f1f478-06249c20<mailto:key=d600811c-0d11001c-3d913449-0601d4e4 at 172.16.2.32409d600811c0d11004037f1f478-06249c20>
00426441.003 |11:59:36.574 |AppInfo  |//SIP/Stack/Error/0xb41a6248/sact_idle_new_message_register: CCSIP_REGISTER:: Problem adding CCB to UAS Table
00426442.000 |11:59:36.611 |SdlSig   |SIPReferResult                         |gsm                            |DMMSStationD(1,100,206,11)       |SIPHandler(1,100,72,1)           |1,100,13,4805.7^172.16.2.32^*            |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]  ccbID= 0 --TransType=1 --TransSecurity=0 PeerAddr= 172.16.2.32:51237<http://172.16.2.32:51237> respCode=200 scbID= 1929
00426442.001 |11:59:36.611 |AppInfo  |DMMS: (0000011) fireSignalBySIPConnIndex, can't find connection index for this SIP signal. This could be an old signal.
00426443.000 |11:59:36.908 |SdlSig   |DbObjectCacheTimer                     |initialized                    |Db(1,100,202,1)                  |SdlTimerService(1,100,3,1)       |1,100,139,1.1^*^*                        |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0]  AppCorr: 0
00426444.000 |11:59:37.016 |SdlSig   |ReapOldTokenRegistrationsTimer         |wait                           |SIPStationInit(1,100,65,1)       |SdlTimerService(1,100,3,1)       |1,100,139,1.1^*^*                        |[R:H-H:1,N:0,L:0,V:0,Z:0,D:0]
00426445.000 |11:59:37.916 |SdlSig   |DbObjectCacheTimer                     |initialized                    |Db(1,100,202,1)                  |SdlTimerService(1,100,3,1)       |1,100,139,1.1^*^*                        |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0]  AppCorr: 0
00426446.000 |11:59:38.264 |SdlSig   |DeviceEventReceiptMonitoringTimer      |wait                           |StationInit(1,100,57,1)          |SdlTimerService(1,100,3,1)       |1,100,139,1.1^*^*                        |[R:H-H:0,N:0,L:0,V:0,Z:0,D:0]
00426447.000 |11:59:38.264 |SdlStat  |Period: 6s #Lines: 60 #Bytes: 16329 Total Number of Buffers: 10000 Free LWM: 9972 Free LWM(total): 9721
00426448.000 |11:59:38.294 |SdlSig   |SIPPublishResult                       |waitForResponse                |PublishEPA(1,100,106,14)         |SIPHandler(1,100,72,1)           |1,100,13,4806.2^10.0.8.11^*              |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]  scbId=1930 respCode=200 event=presence TransType=1 --TransSecurity=0 PeerAddr=10.0.8.11:5070<http://10.0.8.11:5070>
00426449.000 |11:59:38.599 |SdlSig   |SdlCloseAck                            |wfCloseAck                     |SdlTCPConnection(1,100,13,4805)  |SIPTcp(1,100,64,1)               |1,100,13,4805.8^*^*                      |*TraceFlagOverrode
00426449.001 |11:59:38.599 |Stopping |                                       |                               |SdlTCPConnection(1,100,13,4805)  |SdlTCPConnection(1,100,13,4805)  |                                         |NumOfCurrentInstances: 181
00426450.000 |11:59:38.599 |SdlSig   |SIPSPISignal                           |wait                           |SIPHandler(1,100,72,1)           |SIPTcp(1,100,64,1)               |1,100,13,4805.8^*^*                      |*TraceFlagOverrode
00426450.001 |11:59:38.599 |AppInfo  |//SIP/Stack/Error/0xb41a32d8/sipTransportPostSendFailure: Posting send failure msg with tcb:(nil) reason=0
00426451.000 |11:59:38.599 |SdlSig   |SIPConnControlInd                      |wait                           |SIPHandler(1,100,72,1)           |SIPTcp(1,100,64,1)               |1,100,13,4805.8^*^*                      |[T:N-H:0,N:1,L:0,V:0,Z:0,D:0]  controlOperation=1 IP=172.16.2.32 connIndex=192
00426452.000 |11:59:38.599 |SdlSig   |SIPSPISignal                           |wait                           |SIPHandler(1,100,72,1)           |SIPHandler(1,100,72,1)           |1,100,13,4805.8^*^*                      |*TraceFlagOverrode
00426452.001 |11:59:38.599 |AppInfo  |//SIP/Stack/Error/0x0/act_incoming_register_send_msg_failure: Send Error to 172.16.2.32:51237<http://172.16.2.32:51237> for transport TCP
00426453.000 |11:59:38.599 |SdlSig   |SIPConnControlInd                      |wait                           |SIPStationInit(1,100,65,1)       |SIPHandler(1,100,72,1)           |1,100,13,4805.8^*^*                      |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]  controlOperation=1 IP=172.16.2.32 connIndex=192
00426452.002 |11:59:38.599 |AppInfo  |//SIP/Stack/Error/0xb41a32d8/sipSPIUdeleteCcbFromTable: Entry not found for search key d600811c-0d11001c-3d913449-0601d4e4 at 172.16.2.32409d600811c0d11004037f1f478-06249c20<mailto:d600811c-0d11001c-3d913449-0601d4e4 at 172.16.2.32409d600811c0d11004037f1f478-06249c20>
00426454.000 |11:59:38.599 |SdlSig   |SIPConnControlInd                      |gsm                            |DMMSStationD(1,100,206,11)       |SIPStationInit(1,100,65,1)       |1,100,13,4805.8^*^*                      |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]  controlOperation=1 IP=172.16.2.32 connIndex=192
00426454.001 |11:59:38.599 |AppInfo  |DMMS: (0000011) fireSignalBySIPConnIndex, can't find connection index for this SIP signal. This could be an old signal.
00426452.003 |11:59:38.604 |AppInfo  |//SIP/Stack/Error/0x0/sipConnectionManagerUnregisterCtxtInCon: Connection not found for addr=172.16.2.32, port=51237
00426452.004 |11:59:38.604 |AppInfo  |//SIP/Stack/Error/0x0/httpish_msg_free: Freeing NULL pointer!
00426455.0

Da: cisco-voip [mailto:cisco-voip-bounces at puck.nether.net<mailto:cisco-voip-bounces at puck.nether.net>] Per conto di Mauro Celli
Inviato: mercoledì 15 ottobre 2014 18.10
A: cisco-voip at puck.nether.net<mailto:cisco-voip at puck.nether.net>
Oggetto: [cisco-voip] Jabber 10.5 and Remotedestination, disconnected!

Hi,
I have installed a server cups on a CUCM 9.1  and everything seemed to work properly.
Now as soon as I enable a "remote destination" in the jabber for android phone,
the phone is logged on the server cups but remains unregistered on the CUCM server.
Just take off the remote destination from the phone and  reconnects.
Ideas?
thanks

_______________________________________________
cisco-voip mailing list
cisco-voip at puck.nether.net<mailto:cisco-voip at puck.nether.net>
https://puck.nether.net/mailman/listinfo/cisco-voip



-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20141016/e26d6bdd/attachment-0001.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: JabberWorking-noMobility.zip
Type: application/x-zip-compressed
Size: 1543727 bytes
Desc: JabberWorking-noMobility.zip
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20141016/e26d6bdd/attachment-0001.bin>


More information about the cisco-voip mailing list