Wednesday, October 20, 2010

X-Lite calls through NET VX1200 to OCS extensions getting sent directly to Exchange UM – Calling number matters!

The following is a perfect example of the smallest misconfiguration on my part in something seemingly so trivial can cause calls to fail. I’d have to say that what tipped me off was a problem I had before with another integration which I wrote a blog post about here: http://terenceluk.blogspot.com/2010/08/transferring-calls-from-exchange-um-aa_20.html so have a look at that post if you haven’t seen it before and you’ll know what I mean.

Environment

  • Integrating a Nortel CS1000 at a version that cannot do direct SIP to Microsoft OCS 2007 R2.
  • As a direct SIP cannot be used, the client has agreed to use an NET VX1200 gateway for the integration.
  • Since the NET VX1200 offers so much more than passing calls between the CS1000 and OCS 2007, the client also purchased the licenses to hang SIP phones off of the gateway.
  • I was working from remote and could not use one of the SoundPoint 335 SIP phones the gateway has certified for testing so I ended up installing X-Lite on my laptop to test via VPN.

The problem I ran into was when I was testing the “SIP phones off the gateway” feature of the NET VX1200 and while the call seemingly connected, it never rang the OCS user’s extension and it would go straight to voicemail. I’m not extremely fluent with the NET VX1200 but managed to figure out how to do SIP traces and saw this in the logs:

Username: admin

Password: ********

Last successful login date by: admin, from machine: 10.102.1.4, at time: Wed Oct

20 12:49:09 2010.

NETVX1200> trace sip

Trace level set to info 2.

[ 154][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (No CSN): SIPChannel::HandleI

nvite: Handling Invite Message

[ 154][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleI

nvite: New CSN for this call is 0x0025

[ 154][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleI

nvite: New Called Number is [8559@10.102.1.5:5060]

[ 154][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleI

nvite: External address 8559 is translated to Local address 8559, based on Subsc

riber table:1

[ 154][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleI

nvite: Called number is set to [8559]

[ 154][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleI

nvite: Original calling: number = [terence@10.102.1.5], name = [8555], case=3

[ 154][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleI

nvite: Translated calling number is [terence]

[ 154][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleI

nvite: preferred media is now [G.711mu-law/sil ]

[ 154][20101020.115721.154:sip.i4] SIPChannel::ReplacePrefferedCodec: Did not r

eplace MSRTA with G.711: 9 - 1

[ 154][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleI

nvite: Routing call to [8559]

[ 6f4][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleR

oute: Handling Route response

[ 6f4][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleR

oute: Setting reverse media 802.1p to be mirrored

[ 6f4][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleR

oute: Translated called number is [+8559]

[ 6f4][20101020.115721.154:sip.i4] SIPChannel::ReplacePrefferedCodec: Did not r

eplace MSRTA with G.711: 9 - 1

[ 6f4][20101020.115721.154:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleS

etup: Handling Setup

[ 6f4][20101020.115721.154:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleS

etup: Translated calling number is +TERENCE

[ 6f4][20101020.115721.154:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleS

etup: Address [0a660104] is resolved immediately.

[ 6f4][20101020.115721.154:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleS

etupAck: Handling Setup Ack

[ 6f4][20101020.115721.154:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleI

nitialDNS: Handling InitialDNS response

[ 154][20101020.115721.170:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleP

rovisionalResponse: Handling 100 provisional response.

[ 154][20101020.115721.170:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleP

rovisionalResponse: Received 100 Trying. Sending PROGRESS to peer with PI=1.

[ 154][20101020.115721.170:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleP

rogress: Handling Progress

[ 6f4][20101020.115721.295:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::Handle1

8xInbandResponse: Handling 18x InbandResponse

[ 6f4][20101020.115721.295:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::Handle1

8xInbandResponse: negotiated media is now [G.711mu-law ] stream(0)

[ 6f4][20101020.115721.295:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::Handle1

8xInbandResponse: Received 183 with SDP. Sending PROGRESS with PI=8 to peer and

cutting through audio.

[ 154][20101020.115721.295:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::Send18x

ProvisionalResponse: Handling Inband Information message

[ 154][20101020.115721.295:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::CheckNe

gotiatedMediaClass: Media Class is [G.711mu-law ]

[ 6f4][20101020.115721.295:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStarted: Handling Tx Stream Started message

[ 6f4][20101020.115721.295:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleO

kOnPRACK: Handling Ok On PRACK

[ 6f4][20101020.115721.295:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStarted: Handling Rx Stream Started message

[ 154][20101020.115721.295:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStarted: Handling Rx Stream Started message

[ 154][20101020.115721.295:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStarted: Handling Tx Stream Started message

[ 6f4][20101020.115721.732:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleP

rovisionalResponse: Handling 183 provisional response.

[ 6f4][20101020.115721.732:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleP

rovisionalResponse: Prefer Inband Ringing. Ignoring 183 with no SDP.

[ 154][20101020.115721.951:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleP

rovisionalResponse: Handling 181 provisional response.

[ 154][20101020.115722.123:sip.m0] CSipLQMOptionsSession::receiveMessage: OPTIO

NS session with callid 192720101020115650123@10.102.1.5 will be reset

[ 6f4][20101020.115722.795:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleO

kOnInvite: Handling OkOnInvite

[ 6f4][20101020.115722.795:sip.i4] SIPChannel::ReplacePrefferedCodec: Did not r

eplace MSRTA with G.711: 9 - 1

[ 6f4][20101020.115722.795:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleA

nswer: Handling Answer message

[ 154][20101020.115722.811:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStarted: Handling Tx Stream Started message

[ 154][20101020.115722.811:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStarted: Stream was started already. Ignoring.

[ 154][20101020.115722.811:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStarted: Handling Tx Stream Started message

[ 154][20101020.115722.811:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStarted: Stream was started already. Ignoring.

[ 154][20101020.115722.811:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStarted: Handling Rx Stream Started message

[ 154][20101020.115722.811:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStarted: Stream was started already. Ignoring.

[ 154][20101020.115722.811:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStarted: Handling Rx Stream Started message

[ 154][20101020.115722.811:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStarted: Stream was started already. Ignoring.

[ 6f4][20101020.115722.811:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStarted: Handling Rx Stream Started message

[ 6f4][20101020.115722.811:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStarted: Stream was started already. Ignoring.

[ 6f4][20101020.115722.811:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStarted: Handling Rx Stream Started message

[ 6f4][20101020.115722.811:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStarted: Stream was started already. Ignoring.

[ 6f4][20101020.115722.811:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStarted: Handling Tx Stream Started message

[ 6f4][20101020.115722.811:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStarted: Stream was started already. Ignoring.

[ 6f4][20101020.115722.811:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStarted: Handling Tx Stream Started message

[ 6f4][20101020.115722.811:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStarted: Stream was started already. Ignoring.

[ 154][20101020.115723.045:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleA

ck: Handling Ack Request

[ 6f4][20101020.115727.279:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleB

ye: Handling Bye Request

[ 6f4][20101020.115727.279:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 6f4][20101020.115727.279:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::UnMuteA

udio: UN-Muting 0x03028025

[ 6f4][20101020.115727.279:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::Release

All: Setting actual call clearing cause code to 0x10 - later cause codes will no

t be stored for this call.

[ 154][20101020.115727.279:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 154][20101020.115727.279:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::UnMuteA

udio: UN-Muting 0x03020025

[ 6f4][20101020.115727.279:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStopped: Handling Rx Stream Stopped message

[ 6f4][20101020.115727.279:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 6f4][20101020.115727.279:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStopped: Handling Tx Stream Stopped message

[ 6f4][20101020.115727.279:sip.i2] 1:15:3:1: SIP: (0x0025): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 154][20101020.115727.279:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleT

xStreamStopped: Handling Tx Stream Stopped message

[ 154][20101020.115727.279:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 154][20101020.115727.279:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleR

xStreamStopped: Handling Rx Stream Stopped message

[ 154][20101020.115727.279:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

NETVX1200>

After reviewing the traces, what caught my eye was the following line:

[ 6f4][20101020.115721.154:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleS

etup: Translated calling number is +TERENCE

[ 6f4][20101020.115721.154:sip.i2] 1:15:1:1: SIP: (0x0025): SIPChannel::HandleS

etup: Address [0a660104] is resolved immediately.

As mentioned in the post I included above about the caller ID matters, I suspected that the call wasn’t going through because I had misconfigured the calling number on my X-Lite client. Opening the account settings of my X-Lite client shows the following:

Display Name: 8555

User name: terence

Password: xxxxx

Authorization user name: 8555

Domain: 10.102.1.5

Domain Proxy--

Register with domain and receive incoming calls: Disabled

Send outbound via:--

domain: Enabled

proxy: Disabled

Dialing plan: #1\a\a.T;match=1;prestrip=2;

image

If I proceed to make a call with these settings, I get the following:

image

image

image

image

image

As shown in the screenshot above, although the call does connect and shows as Call established, the OCS user’s MOC does not ring and we are sent directly to their voicemail on Exchange UM. So now going back to the account settings for X-Lite, I changed the following:

image

…to:

image

… having the settings now set to:

Display Name: terence

User name: 8555

Password: xxxxx

Authorization user name: 8555

Domain: 10.102.1.5

Domain Proxy--

Register with domain and receive incoming calls: Disabled

Send outbound via:--

domain: Enabled

proxy: Disabled

Dialing plan: #1\a\a.T;match=1;prestrip=2;

From there on, I proceeded to make another test call to my MOC client extension:

image

image

image

Call was successfully established and here is what the trace on the gateway looks like now:

NETVX1200> trace sip

Trace level set to info 2.

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (No CSN): SIPChannel::HandleI

nvite: Handling Invite Message

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleI

nvite: New CSN for this call is 0x0057

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleI

nvite: New Called Number is [8559@10.102.1.5:5060]

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleI

nvite: External address 8559 is translated to Local address 8559, based on Subsc

riber table:1

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleI

nvite: Called number is set to [8559]

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleI

nvite: Original calling: number = [8555@10.102.1.5], name = [terence], case=3

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleI

nvite: Translated calling number is [8555]

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleI

nvite: preferred media is now [G.711mu-law/sil ]

[ 154][20101020.132020.576:sip.i4] SIPChannel::ReplacePrefferedCodec: Did not r

eplace MSRTA with G.711: 9 - 1

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleI

nvite: Routing call to [8559]

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleR

oute: Handling Route response

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleR

oute: Setting reverse media 802.1p to be mirrored

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleR

oute: Translated called number is [+8559]

[ 154][20101020.132020.576:sip.i4] SIPChannel::ReplacePrefferedCodec: Did not r

eplace MSRTA with G.711: 9 - 1

[ 6f4][20101020.132020.576:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleS

etup: Handling Setup

[ 6f4][20101020.132020.576:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleS

etup: Translated calling number is +8555

[ 6f4][20101020.132020.576:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleS

etup: Address [0a660104] is resolved immediately.

[ 6f4][20101020.132020.576:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleI

nitialDNS: Handling InitialDNS response

[ 154][20101020.132020.576:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleS

etupAck: Handling Setup Ack

[ 154][20101020.132020.592:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleP

rovisionalResponse: Handling 100 provisional response.

[ 154][20101020.132020.592:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleP

rovisionalResponse: Received 100 Trying. Sending PROGRESS to peer with PI=1.

[ 154][20101020.132020.592:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleP

rogress: Handling Progress

[ 6f4][20101020.132020.811:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::Handle1

8xInbandResponse: Handling 18x InbandResponse

[ 6f4][20101020.132020.811:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::Handle1

8xInbandResponse: negotiated media is now [G.711mu-law ] stream(0)

[ 6f4][20101020.132020.811:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::Handle1

8xInbandResponse: Received 183 with SDP. Sending PROGRESS with PI=8 to peer and

cutting through audio.

[ 154][20101020.132020.811:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::Send18x

ProvisionalResponse: Handling Inband Information message

[ 154][20101020.132020.811:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::CheckNe

gotiatedMediaClass: Media Class is [G.711mu-law ]

[ 6f4][20101020.132020.811:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleT

xStreamStarted: Handling Tx Stream Started message

[ 6f4][20101020.132020.811:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleO

kOnPRACK: Handling Ok On PRACK

[ 6f4][20101020.132020.811:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleR

xStreamStarted: Handling Rx Stream Started message

[ 154][20101020.132020.811:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleR

xStreamStarted: Handling Rx Stream Started message

[ 154][20101020.132020.811:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleT

xStreamStarted: Handling Tx Stream Started message

[ 6f4][20101020.132021.295:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleP

rovisionalResponse: Handling 183 provisional response.

[ 6f4][20101020.132021.295:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleP

rovisionalResponse: Prefer Inband Ringing. Ignoring 183 with no SDP.

[ 6f4][20101020.132021.498:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleR

ingingResponse: Handling Ringing Response

[ 6f4][20101020.132021.498:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleR

ingingResponse: Inband Ringback preferred. Sending Alert with no Progress Indica

tor with local ringback generation

[ 6f4][20101020.132021.498:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleA

lert: Handling Alert

[ 6f4][20101020.132021.498:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::PlayCal

lProgressTone: No Tone Table configured or Service Tones/Preemption Tones not en

abled. Not generating any Call Progress Tone

[ 6f4][20101020.132023.248:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleC

ancel: Handling Cancel

[ 6f4][20101020.132023.248:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 6f4][20101020.132023.248:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::UnMuteA

udio: UN-Muting 0x03028057

[ 6f4][20101020.132023.248:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::Release

All: Setting actual call clearing cause code to 0x10 - later cause codes will no

t be stored for this call.

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleR

eleaseBeforeConnect: Handling Release message Before Connect

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::UnMuteA

udio: UN-Muting 0x03020057

[ 154][20101020.132023.248:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleR

xStreamStopped: Handling Rx Stream Stopped message

[ 154][20101020.132023.248:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 154][20101020.132023.248:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::HandleT

xStreamStopped: Handling Tx Stream Stopped message

[ 154][20101020.132023.248:sip.i2] 1:15:3:1: SIP: (0x0057): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleT

xStreamStopped: Handling Tx Stream Stopped message

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleR

xStreamStopped: Handling Rx Stream Stopped message

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleR

xStreamStopped: Handling Rx Stream Stopped message

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleR

xStreamStopped: Stream was not started. Ignoring.

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleT

xStreamStopped: Handling Tx Stream Stopped message

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleT

xStreamStopped: Stream was not started. Ignoring.

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x10, non-Bruta

l, 0x10

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::HandleC

ommonErrorOnInvite: Handling Error OnInvite

[ 6f4][20101020.132023.248:sip.i2] 1:15:1:1: SIP: (0x0057): SIPChannel::Release

All: Attempting to release and reset channel ... with cause code 0x7f, non-Bruta

l, 0x7f

[ 154][20101020.132023.264:sip.i4] CSipSession::SipMsgIn: Session is not open,

dropping message -- CallID [87-603b20101020132020576@10.102.1.5], ChannelID [OUT

BOUND 1:15:1:1]

[ 6f4][20101020.132023.279:sip.i4] CSipSession::SipMsgIn: Session is not open,

dropping message -- CallID [YTllMTExZWExZmY4ZTQ2NTQ4ZTBkNWIyYzE0Y2U3ZGM.], Chann

elID [INBOUND 1:15:3:1]

NETVX1200>

-------------------------------------------------------------------------------------------------------------------------------------------------------------------

Lesson learned? Pay attention to the details as to how you’re supposed to configure the end-point (X-Lite in this case) because little fields such as these can cause problems. I didn’t have a manual to refer to as the guide I was using showed how to configure the gateway and not the client to test but I’m glad I went through the process of troubleshooting.

As usual, I had to continue on with the configuration of the gateway and get someone to test out the SoundPoint phone so I did not have much time to further look into it but I hope this helps anyone who may come across this problem.

No comments: