Solved: Re: jabber MRA failed to connect phone services

alex.kanaykin · ‎12-20-2017

Hi,

I've setup expressway 8.10 MRA. I'm using scheme with 1 firewall (reflexive nat).

SIP inspection disabled on firewall.

IM connected fine.

Phone services failed to connect.

Jabber log:

2017-12-20 16:02:48,343 ERROR [0xeaf29538] [ice/TelephonyAdapterServerHealth.cpp(66)] [jcf.tel.adapter] [getConnectionIpProtocol] - No connected ConnectionInfo of type: [eCTI]. Could not determine connection IP Protocol
2017-12-20 16:02:48,344 DEBUG [0xeaf29538] [/impl/TelephonyServerHealthImpl.cpp(280)] [jcf.tel.health] [updateHealth] - updating health with serverType [CucmDeskphone] serverHealthStatus [None] serverConnectionStatus [Disconnected] serverAddress [ (CTI)] serviceEventCode [UnknownConnectionError] transportProtocol [CTI] ipProtocol [Unknown]

Expc log:

2017-12-20T17:01:25.406+02:00	tvcs: Event="Response Received" Service="SIP" Src-ip="public.ip.address" Src-port="7001" Dst-ip="10.100.128.97" Dst-port="25003" Protocol="TLS" Method="OPTIONS" To="sip:public.ip.address:7001" Response-code="200" Level="3" UTCTime="2017-12-20 15:01:25,406"
2017-12-20T17:01:25.406+02:00	tvcs: Event="Message Received" Service="SIP" Src-ip="public.ip.address" Src-port="7001" Dst-ip="10.100.128.97" Dst-port="25003" Protocol="TLS" Num-bytes="973" Level="4" UTCTime="2017-12-20 15:01:25,405"
2017-12-20T17:01:25.400+02:00	tvcs: Event="Message Sent" Service="SIP" Src-ip="10.100.128.97" Src-port="25003" Dst-ip="public.ip.address" Dst-port="7001" Protocol="TLS" Num-bytes="1356" Level="4" UTCTime="2017-12-20 15:01:25,400"
2017-12-20T17:01:25.400+02:00	tvcs: Event="Request Sent" Service="SIP" Src-ip="10.100.128.97" Src-port="25003" Dst-ip="public.ip.address" Dst-port="7001" Protocol="TLS" Method="OPTIONS" To="sip:public.ip.address:7001;transport\=tls" Level="3" UTCTime="2017-12-20 15:01:25,400"
2017-12-20T17:01:25.400+02:00	tvcs: Event="Response Received" Service="SIP" Src-ip="public.ip.address" Src-port="7001" Dst-ip="10.100.128.97" Dst-port="25003" Protocol="TLS" Method="OPTIONS" To="sip:public.ip.address:7001" Response-code="401" Level="3" UTCTime="2017-12-20 15:01:25,400"
2017-12-20T17:01:25.400+02:00	tvcs: Event="Message Received" Service="SIP" Src-ip="public.ip.address" Src-port="7001" Dst-ip="10.100.128.97" Dst-port="25003" Protocol="TLS" Num-bytes="567" Level="4" UTCTime="2017-12-20 15:01:25,400"
2017-12-20T17:01:25.396+02:00	tvcs: Event="Message Sent" Service="SIP" Src-ip="10.100.128.97" Src-port="25003" Dst-ip="public.ip.address" Dst-port="7001" Protocol="TLS" Num-bytes="976" Level="4" UTCTime="2017-12-20 15:01:25,398"
2017-12-20T17:01:25.396+02:00	tvcs: Event="Request Sent" Service="SIP" Src-ip="10.100.128.97" Src-port="25003" Dst-ip="public.ip.address" Dst-port="7001" Protocol="TLS" Method="OPTIONS" To="sip:public.ip.address:7001;transport\=tls" Level="3" UTCTime="2017-12-20 15:01:25,397"
2017-12-20T17:01:05.427+02:00	tvcs: Event="Response Received" Service="SIP" Src-ip="public.ip.address" Src-port="7001" Dst-ip="10.100.128.97" Dst-port="25003" Protocol="TLS" Method="OPTIONS" To="sip:public.ip.address:7001" Response-code="200" Level="3" UTCTime="2017-12-20 15:01:05,405"
2017-12-20T17:01:05.427+02:00	tvcs: Event="Message Received" Service="SIP" Src-ip="public.ip.address" Src-port="7001" Dst-ip="10.100.128.97" Dst-port="25003" Protocol="TLS" Num-bytes="973" Level="4" UTCTime="2017-12-20 15:01:05,404"
2017-12-20T17:01:05.427+02:00	tvcs: Event="Message Sent" Service="SIP" Src-ip="10.100.128.97" Src-port="25003" Dst-ip="public.ip.address" Dst-port="7001" Protocol="TLS" Num-bytes="1356" Level="4" UTCTime="2017-12-20 15:01:05,399"
2017-12-20T17:01:05.427+02:00	tvcs: Event="Request Sent" Service="SIP" Src-ip="10.100.128.97" Src-port="25003" Dst-ip="public.ip.address" Dst-port="7001" Protocol="TLS" Method="OPTIONS" To="sip:public.ip.address:7001;transport\=tls" Level="3" UTCTime="2017-12-20 15:01:05,399"
2017-12-20T17:01:05.427+02:00	tvcs: Event="Response Received" Service="SIP" Src-ip="public.ip.address" Src-port="7001" Dst-ip="10.100.128.97" Dst-port="25003" Protocol="TLS" Method="OPTIONS" To="sip:public.ip.address:7001" Response-code="401" Level="3" UTCTime="2017-12-20 15:01:05,399"
2017-12-20T17:01:05.427+02:00	tvcs: Event="Message Received" Service="SIP" Src-ip="public.ip.address" Src-port="7001" Dst-ip="10.100.128.97" Dst-port="25003" Protocol="TLS" Num-bytes="567" Level="4" UTCTime="2017-12-20 15:01:05,399"
2017-12-20T17:01:05.397+02:00	tvcs: Event="Message Sent" Service="SIP" Src-ip="10.100.128.97" Src-port="25003" Dst-ip="public.ip.address" Dst-port="7001" Protocol="TLS" Num-bytes="976" Level="4" UTCTime="2017-12-20 15:01:05,397"
2017-12-20T17:01:05.397+02:00	tvcs: Event="Request Sent" Service="SIP" Src-ip="10.100.128.97" Src-port="25003" Dst-ip="public.ip.address" Dst-port="7001" Protocol="TLS" Method="OPTIONS" To="sip:public.ip.address:7001;transport\=tls" Level="3" UTCTime="2017-12-20 15:01:05,397"

Expe log:

2017-12-20T17:05:05.402+02:00	tvcs: UTCTime="2017-12-20 15:05:05,402" Module="network.sip" Level="INFO": Action="Sent" Local-ip="172.17.100.4" Local-port="7001" Dst-ip="10.100.128.97" Dst-port="25003" Detail="Sending Response Code=200, Method=OPTIONS, CSeq=17898, To=sip:public.ip.address:7001, Call-ID=c08b345c449f7b2a@10.100.128.97, From-Tag=df47749a6d5772a8, To-Tag=1c1c813522112333, Msg-Hash=1817881899676259991, Local-SessionID=, Remote-SessionID="
2017-12-20T17:05:05.398+02:00	tvcs: UTCTime="2017-12-20 15:05:05,398" Module="network.sip" Level="INFO": Action="Received" Local-ip="172.17.100.4" Local-port="7001" Src-ip="10.100.128.97" Src-port="25003" Detail="Receive Request Method=OPTIONS, CSeq=17898, Request-URI=sip:public.ip.address:7001;transport=tls, Call-ID=c08b345c449f7b2a@10.100.128.97, From-Tag=df47749a6d5772a8, To-Tag=, Msg-Hash=4735025321105240090, Local-SessionID=, Remote-SessionID="
2017-12-20T17:05:05.395+02:00	tvcs: UTCTime="2017-12-20 15:05:05,395" Module="network.sip" Level="INFO": Action="Sent" Local-ip="172.17.100.4" Local-port="7001" Dst-ip="10.100.128.97" Dst-port="25003" Detail="Sending Response Code=401, Method=OPTIONS, CSeq=17897, To=sip:public.ip.address:7001, Call-ID=c08b345c449f7b2a@10.100.128.97, From-Tag=df47749a6d5772a8, To-Tag=57ff7edce62c08d1, Msg-Hash=5914414856466073539, Local-SessionID=, Remote-SessionID="
2017-12-20T17:05:05.395+02:00	tvcs: UTCTime="2017-12-20 15:05:05,395" Module="network.sip" Level="INFO": Action="Received" Local-ip="172.17.100.4" Local-port="7001" Src-ip="10.100.128.97" Src-port="25003" Detail="Receive Request Method=OPTIONS, CSeq=17897, Request-URI=sip:public.ip.address:7001;transport=tls, Call-ID=c08b345c449f7b2a@10.100.128.97, From-Tag=df47749a6d5772a8, To-Tag=, Msg-Hash=11689200796447383707, Local-SessionID=, Remote-SessionID="
2017-12-20T17:04:45.402+02:00	tvcs: UTCTime="2017-12-20 15:04:45,402" Module="network.sip" Level="INFO": Action="Sent" Local-ip="172.17.100.4" Local-port="7001" Dst-ip="10.100.128.97" Dst-port="25003" Detail="Sending Response Code=200, Method=OPTIONS, CSeq=29933, To=sip:public.ip.address:7001, Call-ID=92ce58edf601f4a7@10.100.128.97, From-Tag=12621c065bbd668a, To-Tag=dcdcc02bf8c1d287, Msg-Hash=4430907435541056523, Local-SessionID=, Remote-SessionID="

Maybe someone have any thoughts?

alex.kanaykin · ‎12-27-2017

The problem was in the line. Directory number must be assigned to device. Otherwise phone services does not connect.

View solution in original post

Joel Jackson · ‎12-20-2017

Did you create the corresponding BOT/TCT/TAB device in CUCM?

alex.kanaykin · ‎12-20-2017

Yes. Corresponding device recognized by jabber

alex.kanaykin · ‎12-27-2017

The problem was in the line. Directory number must be assigned to device. Otherwise phone services does not connect.

Bisharat Akram · ‎01-18-2020

Hi Alex
I'm facing same problem. I have done all the configuration but every time i try to connect my desk phone in PC Jabber to use my desk phone for calls error occurred the connection to phone services failed.