[Asterisk-Users] ShoreTel 210 MGCP phone drops calls with MGCP RSIP

Ben Dugdale ben at apachecounty.net
Mon May 16 14:14:09 MST 2005


Not sure how much of this you want to see.  The drop is at the end.

MGCP Debugging Enabled
MGCP read:
NTFY 4255 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1C8A5876
O: l/hd

from 192.168.1.137:2427
Verb: 'NTFY', Identifier: '4255', Endpoint:
'SHOR_001049007E83@[192.168.1.137]', Version: 'MGCP 1.0'
3 headers, 0 lines
Handling request 'NTFY' on SHOR_001049007E83 at 192.168.1.137
Transmitting:
200 4255 OK

 to 192.168.1.137:2427
    -- Creating connection for SHOR_001049007E83 at 192.168.1.137-1 in
cxmode: sendrecv callid: 5af2b0987ba3281b
We're at 192.168.0.5 port 5004
Answering with capability 4
Posting Request:
CRCX 209 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
C: 5af2b0987ba3281b
L: p:20, a:PCMU
M: sendrecv
X: 7ba3281b

v=0
o=root 28578 28578 IN IP4 192.168.0.5
s=session
c=IN IP4 192.168.0.5
t=0 0
m=audio 5004 RTP/AVP 0
a=rtpmap:0 PCMU/8000
 to 192.168.1.137:2427
    -- MGCP Asked to indicate tone: L/dl on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Posting Request:
RQNT 210 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
S: L/dl
 to 192.168.1.137:2427
MGCP read:
200 209 OK
I: 3886

v=0
o=Broadcom 14470 209 IN IP4 192.168.1.137
s=MGCP call
c=IN IP4 192.168.1.137
b=AS:64
t=0 0
m=audio 5004 RTP/AVP 0
a=sendrecv
a=ptime:20

from 192.168.1.137:2427
Verb: '200', Identifier: '209', Endpoint: 'OK', Version: '(null)'
2 headers, 9 lines
Capabilities: us - 4, them - 4, combined - 4
Non-codec capabilities: us - 1, them - 0, combined - 0
MGCP read:
NTFY 4256 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 7BA3281B
O: aud(2)

from 192.168.1.137:2427
Verb: 'NTFY', Identifier: '4256', Endpoint:
'SHOR_001049007E83@[192.168.1.137]', Version: 'MGCP 1.0'
3 headers, 0 lines
Handling request 'NTFY' on SHOR_001049007E83 at 192.168.1.137
Transmitting:
200 4256 OK

 to 192.168.1.137:2427
    -- MGCP Asked to indicate tone: L/dl on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Queueing Request:
RQNT 211 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
S: L/dl
 to 192.168.1.137:2427
May 16 13:47:07 NOTICE[28578]: chan_mgcp.c:3242 handle_request: Received
unknown event 'aud(2)' from SHOR_001049007E83 at 192.168.1.137
MGCP read:
200 210 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '210', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
Posting Queued Request:
RQNT 211 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
S: L/dl
 to 192.168.1.137:2427
MGCP read:
200 211 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '211', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
    -- MGCP asked to indicate -1 'UNKNOWN' condition on channel
MGCP/SHOR_001049007E83 at 192.168.1.137-1
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Posting Request:
RQNT 212 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
    -- MGCP asked to indicate -1 'UNKNOWN' condition on channel
MGCP/SHOR_001049007E83 at 192.168.1.137-1
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Queueing Request:
RQNT 213 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
MGCP read:
200 212 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '212', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
Posting Queued Request:
RQNT 213 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
MGCP read:
200 213 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '213', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
    -- MGCP asked to indicate -1 'UNKNOWN' condition on channel
MGCP/SHOR_001049007E83 at 192.168.1.137-1
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Posting Request:
RQNT 214 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
    -- MGCP asked to indicate -1 'UNKNOWN' condition on channel
MGCP/SHOR_001049007E83 at 192.168.1.137-1
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Queueing Request:
RQNT 215 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
MGCP read:
200 214 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '214', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
Posting Queued Request:
RQNT 215 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
MGCP read:
200 215 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '215', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
    -- MGCP asked to indicate -1 'UNKNOWN' condition on channel
MGCP/SHOR_001049007E83 at 192.168.1.137-1
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Posting Request:
RQNT 216 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
    -- MGCP asked to indicate -1 'UNKNOWN' condition on channel
MGCP/SHOR_001049007E83 at 192.168.1.137-1
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Queueing Request:
RQNT 217 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
MGCP read:
200 216 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '216', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
Posting Queued Request:
RQNT 217 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
MGCP read:
200 217 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '217', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
    -- MGCP asked to indicate -1 'UNKNOWN' condition on channel
MGCP/SHOR_001049007E83 at 192.168.1.137-1
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Posting Request:
RQNT 218 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
    -- MGCP asked to indicate -1 'UNKNOWN' condition on channel
MGCP/SHOR_001049007E83 at 192.168.1.137-1
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Queueing Request:
RQNT 219 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
MGCP read:
200 218 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '218', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
Posting Queued Request:
RQNT 219 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
MGCP read:
200 219 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '219', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
    -- MGCP asked to indicate -1 'UNKNOWN' condition on channel
MGCP/SHOR_001049007E83 at 192.168.1.137-1
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Posting Request:
RQNT 220 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
    -- MGCP asked to indicate -1 'UNKNOWN' condition on channel
MGCP/SHOR_001049007E83 at 192.168.1.137-1
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Queueing Request:
RQNT 221 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
MGCP read:
200 220 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '220', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
Posting Queued Request:
RQNT 221 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
MGCP read:
200 221 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '221', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
    -- Modified SHOR_001049007E83 at 192.168.1.137-1 with new mode:
sendrecv on callid: 5af2b0987ba3281b
Posting Request:
MDCX 222 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
C: 5af2b0987ba3281b
M: sendrecv
X: 7ba3281b
I: 3886
R: L/hu(N), L/hf(N)
 to 192.168.1.137:2427
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Posting Request:
RQNT 223 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
    -- Modified SHOR_001049007E83 at 192.168.1.137-1 with new mode:
sendrecv on callid: 5af2b0987ba3281b
Queueing Request:
MDCX 224 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
C: 5af2b0987ba3281b
M: sendrecv
X: 7ba3281b
I: 3886
R: L/hu(N), L/hf(N)
 to 192.168.1.137:2427
MGCP read:
200 222 OK

v=0
o=Broadcom 14470 222 IN IP4 192.168.1.137
s=MGCP call
c=IN IP4 192.168.1.137
b=AS:256
t=0 0
m=audio 5004 RTP/AVP 0 8 121 4 122 123 18 124 125 126 127 96
a=rtpmap:121 G.723.1-5.3/8000
a=rtpmap:122 G723.1A-5.3/8000
a=rtpmap:123 G723.1A/8000
a=rtpmap:124 G729B/8000
a=rtpmap:125 BV32/16000
a=rtpmap:126 BV16/8000
a=rtpmap:127 LRWB/16000
a=rtpmap:96 TELEPHONE-EVENT/8000
a=fmtp:96 NE-EVENT/8000
0-15
a=sendrecv
a=ptime:20

from 192.168.1.137:2427
Verb: '200', Identifier: '222', Endpoint: 'OK', Version: '(null)'
1 headers, 19 lines
Posting Queued Request:
MDCX 224 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
C: 5af2b0987ba3281b
M: sendrecv
X: 7ba3281b
I: 3886
R: L/hu(N), L/hf(N)
 to 192.168.1.137:2427
Capabilities: us - 4, them - 269, combined - 4
Non-codec capabilities: us - 1, them - 5, combined - 0
MGCP read:
200 223 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '223', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
MGCP read:
200 224 OK

from 192.168.1.137:2427
Verb: '200', Identifier: '224', Endpoint: 'OK', Version: '(null)'
1 headers, 0 lines
MGCP read:
RSIP 4257 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
RM: disconnected
X-ShoreModel: S1

from 192.168.1.137:2427
Verb: 'RSIP', Identifier: '4257', Endpoint:
'SHOR_001049007E83@[192.168.1.137]', Version: 'MGCP 1.0'
3 headers, 0 lines
Handling request 'RSIP' on SHOR_001049007E83 at 192.168.1.137
Transmitting:
200 4257 OK

 to 192.168.1.137:2427
    -- MGCP Asked to indicate tone:  on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Posting Request:
RQNT 225 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
 to 192.168.1.137:2427
Posting Request:
AUEP 226 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
F: A
 to 192.168.1.137:2427
    -- MGCP mgcp_hangup(MGCP/SHOR_001049007E83 at 192.168.1.137-1) on
SHOR_001049007E83 at 192.168.1.137
  == MGCP free dsp on SHOR_001049007E83 at 192.168.1.137
    -- Delete connection 3886 SHOR_001049007E83 at 192.168.1.137-1 with new
mode: sendrecv on callid: 5af2b0987ba3281b
Posting Request:
DLCX 227 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
C: 5af2b0987ba3281b
X: 7ba3281b
I: 3886
 to 192.168.1.137:2427
    -- MGCP Asked to indicate tone: L/ro on
SHOR_001049007E83 at 192.168.1.137-1 in cxmode: sendrecv
Queueing Request:
RQNT 228 SHOR_001049007E83@[192.168.1.137] MGCP 1.0
X: 1c8a5876
R: L/hu(N),L/hf(N)
S: L/ro
 to 192.168.1.137:2427
MGCP read:
200 225 OK

Thanks,

Duane Cox wrote:
> RSIP = Restart in Progress.  This message is sent from and endpoint to the call-agent when the line is being disabled/reenabled,
> and at startup.
> 
> I would think that * should tear down any active calls that is going on when a RSIP comes in, so from what I am reading this is
> correct.  The bigger question is, why is your phone sending RSIP during a call?  Maybe your phone isn't 100% setup yet, but enough
> to get calls up.
> 
> See if you can capture an "mgcp debug" from the * CLI.
> 
> Duane
> 
> 
> ----- Original Message ----- 
> From: "Ben Dugdale" <ben at apachecounty.net>
> To: <asterisk-users at lists.digium.com>
> Sent: Monday, May 16, 2005 12:39 PM
> Subject: [Asterisk-Users] ShoreTel 210 MGCP phone drops calls with MGCP RSIP
> 
> 
> 
>>I've got a ShoreTel 210 MGCP phone drops calls.  My packet
>>capture indicates that the phone may be trying to renew its registration
>>with *, but reports Restart Method of Disconnected (frame 2), then *
>>seems to take that as a sign that it has lost the connection and closes
>>things down.  The phone, meanwhile, seems to think it can continue the
>>conversation until a few ICMP "port unreachable" packets set it straight.
>>
>>I am able to duplicate this on a fairly regular basis and it appears
>>that the call is always dropped similarly.  Everything is groovy until
>>the first MGCP RSIP during a call.  The phone seems to send these MGCP
>>RSIPs (perhaps on a schedule, looks like every 120 seconds) even when
>>there is no call taking place.
>>
>>Is my diagnosis correct?  What can I do about it?
>>
>>...Normal RTP, then one final RTP packet and the following...
>>
>>  1   0.000000  192.168.0.5 5004 192.168.1.137 5004 UDP Source port:
>>5004  Destination port: 5004
>>  2   0.007970 192.168.1.137 2427 192.168.0.5  2727 MGCP RSIP 14831
>>SHOR_001049007E83@[192.168.1.137] MGCP 1.0
>>  3   0.008096  192.168.0.5 2727 192.168.1.137 2427 MGCP 200 14831 OK
>>  4   0.008150  192.168.0.5 2727 192.168.1.137 2427 MGCP RQNT 7
>>SHOR_001049007E83@[192.168.1.137] MGCP 1.0
>>  5   0.008186  192.168.0.5 2727 192.168.1.137 2427 MGCP AUEP 8
>>SHOR_001049007E83@[192.168.1.137] MGCP 1.0
>>  6   0.008267  192.168.0.5 2727 192.168.1.137 2427 MGCP DLCX 9
>>SHOR_001049007E83@[192.168.1.137] MGCP 1.0
>>  7   0.009226  192.168.0.5 4569 192.168.1.135 4569 IAX2 IAX, source
>>call# 2, timestamp 61056ms HANGUP
>>  8   0.012188 192.168.1.135 4569 192.168.0.5  4569 IAX2 IAX, source
>>call# 10281, timestamp 61056ms ACK
>>  9   0.015216 192.168.1.137 5004 192.168.0.5  5004 UDP Source port:
>>5004  Destination port: 5004
>> 10   0.015265  192.168.0.5  192.168.1.137  ICMP Destination unreachable
>>(Port unreachable)
>> 11   0.029923 192.168.1.137 2427 192.168.0.5  2727 MGCP 200 7 OK
>> 12   0.030090  192.168.0.5 2727 192.168.1.137 2427 MGCP RQNT 10
>>SHOR_001049007E83@[192.168.1.137] MGCP 1.0
>> 13   0.035268 192.168.1.137 5004 192.168.0.5  5004 UDP Source port:
>>5004  Destination port: 5004
>> 14   0.035306  192.168.0.5  192.168.1.137  ICMP Destination unreachable
>>(Port unreachable)
>> 15   0.050434 192.168.1.137 2427 192.168.0.5  2727 MGCP 200 8 OK
>> 16   0.055251 192.168.1.137 5004 192.168.0.5  5004 UDP Source port:
>>5004  Destination port: 5004
>> 17   0.055282  192.168.0.5  192.168.1.137  ICMP Destination unreachable
>>(Port unreachable)
>> 18   0.077638 192.168.1.137 2427 192.168.0.5  2727 MGCP 250 9
>>Connection was deleted
>> 19   0.100808 192.168.1.137 2427 192.168.0.5  2727 MGCP 200 10 OK
>>
>>rtp.conf:
>>
>>[general]
>>rtpstart=5004
>>rtpend=5005
>>
>>mgcp.conf:
>>
>>[general]
>>port = 2727
>>bindaddr = 0.0.0.0
>>
>>[192.168.1.137]
>>host            = 192.168.1.137
>>context         = home
>>callerid        = "ShoreTel" <4368>
>>dtmfmode        = inband
>>accountcode     = 1000
>>amaflags        = billing
>>callwaiting     = no
>>callreturn      = no
>>cancallforward  = no
>>canreinvite     = no
>>transfer        = no
>>line => SHOR_001049007E83
>>
>>-- 
>>Ben Dugdale <ben at apachecounty.net>
>>
>>
>>
> 
> 
> --------------------------------------------------------------------------------
> 
> 
> 
>>_______________________________________________
>>Asterisk-Users mailing list
>>Asterisk-Users at lists.digium.com
>>http://lists.digium.com/mailman/listinfo/asterisk-users
>>To UNSUBSCRIBE or update options visit:
>>   http://lists.digium.com/mailman/listinfo/asterisk-users
> 
> 
> _______________________________________________
> Asterisk-Users mailing list
> Asterisk-Users at lists.digium.com
> http://lists.digium.com/mailman/listinfo/asterisk-users
> To UNSUBSCRIBE or update options visit:
>    http://lists.digium.com/mailman/listinfo/asterisk-users
> [Scanned for viruses]
> 
> [Scanned for viruses]
> 
> 

-- 
Ben Dugdale <ben at apachecounty.net>
Network Administrator
Apache County Schools Business Consortium www.acsbc.net
Apache County Arizona www.co.apache.az.us
(928) 337-7507



More information about the asterisk-users mailing list