[cisco-voip] SIP Provider Disconnects Calls to CUE at 30 Seconds Into Message Recording.
Kris D. Thompson, P. Eng.
krist at kttex.com
Wed May 6 10:21:47 EDT 2009
Using CiscoIOS 12.4(24)T and CUE 7.0(2).
Calls arrive to a Internet edge router (3825 with NM-CUE) with NAT
enabled between SIP UA and Voice/CUE subnets.
Calls forwarded to voicemail go to CUE via a B2BUA Dial-Peer.
When the message starts being recorded, we see no more RX packets on
the call leg to CUE and no more TX packets on the call leg to the SIP
provider.
My understanding is that the CUE device should also be sourcing RTCP
packets which will be forwarded by the B2BUA to the SIP provider. This
will keep the RTP session alive for the full duration of the message
recording (up to 4 minutes currently).
After 30 seconds, the SIP provider sends a BYE message to the router
and the call is disconnected.
How can I confirm if CUE is sending any RTCP packets or not?
Isn't this a requirement of the protocol specification?
Can the CUE be tuned to send RTCP?
I have tried a number of debugs including:
debug ip udp
debug voip rtcp packet
We see no indications that we ever receive RTCP packets from CUE.
Any help would be MUCH appreciated as this has been highly
educational, but has consume far too much time for this project.
Thanks,
Kris Thompson, P.Eng., CCIE
KT Technology Services
====================================================================================================
Below is a sample of what these debugs show. We clearly see UDP
packets that represent RTCP inbound from the SIP provider, but see no
RTCP packets from the CUE device.
customer-gw1#debug voip rtcp packet
VOIP RTCP Packets debugging is on
customer-gw1#debug ip udp
UDP packet debugging is on
May 6 08:49:55.062 CDT: UDP: rcvd src=74.54.54.178(5060),
dst=172.16.2.1(5060), length=837
! RTCP Reserve UDP Port - call leg 1 - from SIP provider
May 6 08:49:55.066 CDT: Reserved port 17875 in Transport Port Agent
for UDP IP type 1
May 6 08:49:55.070 CDT: UDP: sent src=24.99.199.99(5060),
dst=74.54.54.178(5060), length=389
May 6 08:49:55.078 CDT: UDP: sent src=24.99.199.99(5060),
dst=74.54.54.178(5060), length=553
! RTCP Reserve UDP Port - call leg 2
May 6 08:50:05.082 CDT: Reserved port 16473 in Transport Port Agent
for UDP IP type 1
May 6 08:50:05.082 CDT: UDP: Random local port generated 60315, network 1
! UDP Src Port - SIP Signalling - Call Leg 2
May 6 08:50:05.082 CDT: Reserved port 60315 in Transport Port Agent
for UDP IP type 1
May 6 08:50:05.082 CDT: Released port 16473 in Transport Port Agent
for IP type 1
May 6 08:50:05.086 CDT: Reserved port 16473 in Transport Port Agent
for UDP IP type 1
! SIP signalling for leg 2
May 6 08:50:05.086 CDT: UDP: sent src=172.16.2.1(60315),
dst=172.16.2.2(5060), length=1166
May 6 08:50:05.090 CDT: UDP: rcvd src=172.16.2.2(32802),
dst=172.16.2.1(5060), length=351
May 6 08:50:05.114 CDT: UDP: rcvd src=172.16.2.2(32802),
dst=172.16.2.1(5060), length=329
May 6 08:50:05.114 CDT: UDP: sent src=24.99.199.99(5060),
dst=74.54.54.178(5060), length=546
May 6 08:50:05.134 CDT: UDP: rcvd src=172.16.2.2(32802),
dst=172.16.2.1(5060), length=648
May 6 08:50:05.138 CDT: Released port 17875 in Transport Port Agent
for IP type 1
May 6 08:50:05.138 CDT: Reserved port 17875 in Transport Port Agent
for UDP IP type 1
May 6 08:50:05.138 CDT: UDP: sent src=172.16.2.1(60315),
dst=172.16.2.2(5060), length=392
May 6 08:50:05.142 CDT: UDP: sent src=24.99.199.99(5060),
dst=74.54.54.178(5060), length=920
! Call is setup on both legs.
! RTP Data in from CUE
May 6 08:50:05.154 CDT: UDP: rcvd src=172.16.2.2(32774),
dst=172.16.2.1(16472), length=180
May 6 08:50:05.154 CDT: UDP: rcvd src=172.16.2.2(32774),
dst=172.16.2.1(16472), length=180
May 6 08:50:05.154 CDT: UDP: rcvd src=172.16.2.2(32774),
dst=172.16.2.1(16472), length=180
! Final SIP Ack
May 6 08:50:05.162 CDT: UDP: rcvd src=74.54.54.178(5060),
dst=172.16.2.1(5060), length=410
May 6 08:50:05.262 CDT: UDP: rcvd src=74.54.54.178(16594),
dst=24.99.199.99(17874), length=180
! RTCP in from SIP provider shows every 5 seconds
May 6 08:50:10.642 CDT: UDP: rcvd src=74.54.54.178(16595),
dst=24.99.199.99(17875), length=52
May 6 08:50:10.642 CDT: recv:
May 6 08:50:10.642 CDT: RR: ssrc=0x2F7C8023 rr_loss=0x0 rr_ehsr=26
rr_jitter=0 rr_lsr=0x0 rr_dlsr=0x1181CA
May 6 08:50:10.642 CDT: SDES: ssrc=0x719EFC71 name=1 len=0 data=
! RTCP is forwarded to CUE Call Leg
May 6 08:50:10.642 CDT: UDP: sent src=172.16.2.1(16473),
dst=172.16.2.2(16909), length=52
! Repeats every 5 seconds. RTCP from SIP provider to IOS, then forward
to CUE leg.
May 6 08:50:15.642 CDT: UDP: rcvd src=74.54.54.178(16595),
dst=24.99.199.99(17875), length=52
May 6 08:50:15.642 CDT: recv:
May 6 08:50:15.642 CDT: RR: ssrc=0x2F7C8023 rr_loss=0x0 rr_ehsr=26
rr_jitter=0 rr_lsr=0x0 rr_dlsr=0x1681CA
May 6 08:50:15.642 CDT: SDES: ssrc=0x719EFC71 name=1 len=0 data=
May 6 08:50:15.642 CDT: UDP: sent src=172.16.2.1(16473),
dst=172.16.2.2(16909), length=52
May 6 08:50:20.642 CDT: UDP: rcvd src=74.54.54.178(16595),
dst=24.99.199.99(17875), length=52
May 6 08:50:20.642 CDT: recv:
May 6 08:50:20.642 CDT: RR: ssrc=0x2F7C8023 rr_loss=0xFF000223
rr_ehsr=575 rr_jitter=0 rr_lsr=0x0 rr_dlsr=0x1B81CA
May 6 08:50:20.642 CDT: SDES: ssrc=0x719EFC71 name=1 len=0 data=
May 6 08:50:20.642 CDT: UDP: sent src=172.16.2.1(16473),
dst=172.16.2.2(16909), length=52
May 6 08:50:25.642 CDT: UDP: rcvd src=74.54.54.178(16595),
dst=24.99.199.99(17875), length=52
May 6 08:50:25.642 CDT: recv:
May 6 08:50:25.642 CDT: RR: ssrc=0x2F7C8023 rr_loss=0x223 rr_ehsr=575
rr_jitter=0 rr_lsr=0x0 rr_dlsr=0x2081CA
May 6 08:50:25.642 CDT: SDES: ssrc=0x719EFC71 name=1 len=0 data=
May 6 08:50:25.642 CDT: UDP: sent src=172.16.2.1(16473),
dst=172.16.2.2(16909), length=52
May 6 08:50:30.642 CDT: UDP: rcvd src=74.54.54.178(16595),
dst=24.99.199.99(17875), length=52
May 6 08:50:30.642 CDT: recv:
May 6 08:50:30.642 CDT: RR: ssrc=0x2F7C8023 rr_loss=0x223 rr_ehsr=575
rr_jitter=0 rr_lsr=0x0 rr_dlsr=0xFFE3F894
May 6 08:50:30.642 CDT: SDES: ssrc=0x719EFC71 name=1 len=0 data=
May 6 08:50:30.642 CDT: UDP: sent src=172.16.2.1(16473),
dst=172.16.2.2(16909), length=52
May 6 08:50:35.642 CDT: UDP: rcvd src=74.54.54.178(16595),
dst=24.99.199.99(17875), length=52
May 6 08:50:35.642 CDT: recv:
May 6 08:50:35.642 CDT: RR: ssrc=0x2F7C8023 rr_loss=0x223 rr_ehsr=575
rr_jitter=0 rr_lsr=0x0 rr_dlsr=0xFFE8F894
May 6 08:50:35.642 CDT: SDES: ssrc=0x719EFC71 name=1 len=0 data=
May 6 08:50:35.642 CDT: UDP: sent src=172.16.2.1(16473),
dst=172.16.2.2(16909), length=52
May 6 08:50:40.643 CDT: UDP: rcvd src=74.54.54.178(16595),
dst=24.99.199.99(17875), length=52
May 6 08:50:40.643 CDT: recv:
May 6 08:50:40.643 CDT: RR: ssrc=0x2F7C8023 rr_loss=0x223 rr_ehsr=575
rr_jitter=0 rr_lsr=0x0 rr_dlsr=0xFFEDF894
May 6 08:50:40.643 CDT: SDES: ssrc=0x719EFC71 name=1 len=0 data=
May 6 08:50:40.643 CDT: UDP: sent src=172.16.2.1(16473),
dst=172.16.2.2(16909), length=52
May 6 08:50:45.643 CDT: UDP: rcvd src=74.54.54.178(16595),
dst=24.99.199.99(17875), length=52
May 6 08:50:45.643 CDT: recv:
May 6 08:50:45.643 CDT: RR: ssrc=0x2F7C8023 rr_loss=0x223 rr_ehsr=575
rr_jitter=0 rr_lsr=0x0 rr_dlsr=0xFFF2F894
May 6 08:50:45.643 CDT: SDES: ssrc=0x719EFC71 name=1 len=0 data=
May 6 08:50:45.643 CDT: UDP: sent src=172.16.2.1(16473),
dst=172.16.2.2(16909), length=52
! BYE is received from SIP provider since it see no RTCP to keep the
media active.
May 6 08:50:48.175 CDT: UDP: rcvd src=74.54.54.178(5060),
dst=172.16.2.1(5060), length=370
May 6 08:50:48.179 CDT: UDP: sent src=24.99.199.99(5060),
dst=74.54.54.178(5060), length=393
May 6 08:50:48.179 CDT: UDP: sent src=172.16.2.1(60315),
dst=172.16.2.2(5060), length=448
May 6 08:50:48.179 CDT: UDP: sent src=24.99.199.99(17875),
dst=74.54.54.178(16595), length=80
May 6 08:50:48.183 CDT: Released port 17875 in Transport Port Agent
for IP type 1
May 6 08:50:48.183 CDT: UDP: rcvd src=172.16.2.2(32802),
dst=172.16.2.1(5060), length=286
May 6 08:50:48.187 CDT: UDP: sent src=172.16.2.1(16473),
dst=172.16.2.2(16909), length=76
May 6 08:50:48.187 CDT: Released port 16473 in Transport Port Agent
for IP type 1
May 6 08:50:48.915 CDT: UDP: rcvd src=172.16.2.2(32802),
dst=172.16.2.1(5060), length=735
May 6 08:50:48.919 CDT: Reserved port 18113 in Transport Port Agent
for UDP IP type 1
May 6 08:50:48.927 CDT: UDP: sent src=172.16.2.1(5060),
dst=172.16.2.2(5060), length=375
May 6 08:50:48.931 CDT: UDP: sent src=172.16.2.1(5060),
dst=172.16.2.2(5060), length=533
May 6 08:50:52.443 CDT: UDP: rcvd src=10.36.0.1(67),
dst=255.255.255.255(68), length=324
May 6 08:50:53.919 CDT: UDP: rcvd src=172.16.2.2(32802),
dst=172.16.2.1(5060), length=325
May 6 08:50:53.923 CDT: UDP: sent src=172.16.2.1(5060),
dst=172.16.2.2(5060), length=305
May 6 08:50:53.927 CDT: UDP: sent src=172.16.2.1(5060),
dst=172.16.2.2(5060), length=428
May 6 08:50:53.931 CDT: UDP: rcvd src=172.16.2.2(32802),
dst=172.16.2.1(5060), length=337
May 6 08:50:53.931 CDT: Released port 18113 in Transport Port Agent
for IP type 1
Some of the relevant config (some fields hacked for security)
<code>
voice rtp send-recv
!
voice service voip
allow-connections sip to sip
no supplementary-service sip moved-temporarily
no supplementary-service sip refer
fax protocol t38 ls-redundancy 0 hs-redundancy 0 fallback cisco
sip
localhost dns:24.99.199.99
no call service stop
! the dial peer to CUE
dial-peer voice 2 voip
destination-pattern 3..
b2bua
session protocol sipv2
session target ipv4:172.16.2.2
dtmf-relay sip-notify
codec g711ulaw
no vad
! the SIP-UA
!
sip-ua
authentication username xxxx password 7 yyyy realm voipms
authentication username xxxx password 7 yyyy realm sip.us2.voip.ms
no remote-party-id
retry invite 2
retry register 10
timers connect 100
sip-server dns:sip.us2.voip.ms
More information about the cisco-voip
mailing list