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: https://blog.terenceluk.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: #1aa.T;match=1;prestrip=2;
If I proceed to make a call with these settings, I get the following:
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:
…to:
… 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: #1aa.T;match=1;prestrip=2;
From there on, I proceeded to make another test call to my MOC client extension:
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.
