cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
1108
Views
15
Helpful
10
Replies

No audio on hardware transcoded calls

Ess Emque
Level 1
Level 1

Hi all,

We are running into an issue when invoking our hardware transcoder. Set up is as follows:

CUBE with transcoder configured:

dspfarm profile 3 transcode  
 codec g729abr8
 codec g729ar8
 codec g711alaw
 codec g711ulaw
 codec g729r8
 codec g729br8
maximum sessions 5


This is registered in CUCM and added the MRG and MRGL of the device pool that the SIP trunk is using.

CUBE -> REG-SiteA
Phone-A -> REG-SiteA
SIP Trunk -> REG-SiteA

 

Transcoder - REG-MOH
Phone B -> REG-SiteB

Working call flow:

ITSP --- g711 ---> CUBE --- g711 ---> SIP Trunk --- g711 --> Phone-SiteA

Non-working call flow:

ITSP --- g711 ---> CUBE --- g711 ---> SIP Trunk --- g729 --> Phone-SiteB

In the non-working scenario, the phone at Site B rings, but when picked up, there is no audio (the call doesn't drop unless it's hung up)

I have looked at the logs and I can see the caps mismatch and inserting the transcoder, but from my limited knowledge, it appears to actually be inserting it correctly:

47948121.003 |21:41:17.047 |AppInfo  |SIPTcp - SignalCounter = 2960007
47948122.000 |21:41:17.048 |SdlSig   |SIPSPISignal                           |wait                           |SIPHandler(2,100,80,1)           |SIPTcp(2,100,72,1)               |2,100,14,82980.397^192.168.82.92^*       |*TraceFlagOverrode
47948122.001 |21:41:17.048 |AppInfo  |//SIP/SIPHandler/ccbId=0/scbId=0/sip_stop_timer_tcb: timerContext=0x1161a750 type=SIP_TIMER_CLIENT_TCB value=32000 retries=0
47948122.002 |21:41:17.048 |AppInfo  |//SIP/SIPHandler/ccbId=0/scbId=2909506/findDevicePID: Routed to SIPD by ccbId/scbId
47948123.000 |21:41:17.048 |SdlSig   |SIPReferResult                         |wait                           |SIPStationD(2,100,74,6478)       |SIPHandler(2,100,80,1)           |2,100,14,82980.397^192.168.82.92^*       |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]  ccbID= 0 --TransType=1 --TransSecurity=0 PeerAddr= 192.168.82.92:51083 respCode=200 scbID= 2909506
47948123.001 |21:41:17.048 |AppInfo  |SIPStationD(6478) - Terminating transaction for scbId 2909506, pkgId 5
47948124.000 |21:41:17.048 |SdlSig   |SIPSubTerminated                       |wait                           |SIPHandler(2,100,80,1)           |SIPStationD(2,100,74,6478)       |2,100,14,82980.397^192.168.82.92^*       |[T:N-H:0,N:0,L:0,V:0,Z:0,D:0]  --TransType=1 --TransSecurity=0 PeerAddr=192.168.82.92:51083 scbId=2909506 event.id= event.name=refer eventType=0
47948124.001 |21:41:17.048 |AppInfo  |//SIP/SIPHandler/ccbId=0/scbId=0/wait_SIPSubTerminated: inDialogSubReferCounter=0 scbid=2909506
47948124.002 |21:41:17.048 |AppInfo  |//SIP/SIPHandler/ccbId=0/scbId=2909506/ccsip_platform_delete_scb: scb mgmt deleted 0x11dbac48 (context_id=2830245)
47948125.000 |21:41:17.800 |SdlSig-I |AllocateMtpResourceReq                 |waiting                        |MediaTerminationPointControl(2,100,139,17) |MediaResourceCdpc(1,100,141,713)                                                             |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]  CI=28273167 Kpbs=0 SuppressFlag=0 RegionA=REG-SiteA CapsA[1] = cap=2(20) RegionB=REG-SiteB CapsB[8] = cap=25(40),cap=4(40),cap=2(40),cap=15(60),cap=16(60),cap=11(60),cap=12(60),cap=257(1) DeviceCapability= [0x9 DETECT_2833 PT_2833] MandatoryCaps= [0x0] Type=3 PassThru=F Count=1 ThrottleMtpPercent=95
47948125.001 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::waiting_AllocateMtpResourceReq - (capCount,region),A(1,REG-SiteA),B(8,REG-SiteB), reqDevCap=0x9, reqMandatoryCaps=0x0, supDevCap=0x129, passthru=0, resourceCount=1
47948125.002 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::getResourcesAllocated -- DeviceName=XCODE-01 Ci=28273167 ResourceCount=1
47948125.003 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::getResourcesAllocated -- Logging RegionA=REG-SiteA Caps and MTP/XCoder Region=REG-MOH Caps
47948125.004 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::logCapabilitiesinTrace -- MTP/XCoder Device Caps = 15 11 4 2 12 16 257 259 261
47948125.005 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::logCapabilitiesinTrace -- Device Caps = 2
47948125.006 |21:41:17.800 |AppInfo  |DET-RegionsServer::matchCapabilities-- savedOption=0, PREF_LIST, regionA=REG-MOH regionB=REG-SiteA latentCaps(A=0, B=0) kbps=64, capACount=9, capBCount=1
47948125.007 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::getResourcesAllocated -- Logging RegionB=REG-SiteB Caps and MTP/XCoder Region=REG-MOH Caps
47948125.008 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::logCapabilitiesinTrace -- MTP/XCoder Device Caps = 15 11 4 2 12 16 257 259 261
47948125.009 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::logCapabilitiesinTrace -- Device Caps = 25 4 2 15 16 11 12 257
47948125.010 |21:41:17.800 |AppInfo  |DET-RegionsServer::matchCapabilities-- savedOption=0, PREF_LIST, regionA=REG-MOH regionB=REG-SiteB latentCaps(A=0, B=0) kbps=64, capACount=9, capBCount=8
47948125.011 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::getResourcesAllocated -- match1=1 match2=6
47948125.012 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::getResourcesAllocated -- DeviceName=XCODE-01 Ci=28273167 ResourceAllocated=1
47948125.013 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::getResourcesAllocated -- allocateErrBitset=0x0
47948125.014 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::handleMtpDeviceFound
47948125.015 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::logResourceStatusinTrace -- Device Name=XCODE-01 ResourceAvailable=5 ResourceUsed=0
47948125.016 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::handleMtpDeviceFound ConversationId=34368505 CI=28273167 Allocated resource=1 Device Capability = 297
47948125.017 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::incActiveCounter - Count=1
47948125.018 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::decAvailableCounter - Count=1
47948125.019 |21:41:17.800 |AppInfo  |MediaTerminationPointControl(17)::logResourceStatusinTrace -- Device Name=XCODE-01 ResourceAvailable=4 ResourceUsed=1
47948126.000 |21:41:17.800 |SdlSig-O |AllocateMtpResourceRes                 |NA RemoteSignal                |MediaResourceCdpc(1,100,141,713)                                                             |MediaTerminationPointControl(2,100,139,17) |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]  CI=28273167 ConvId=34368505 Caps[9] = cap=15(60),cap=11(60),cap=4(30),cap=2(30),cap=12(60),cap=16(60),cap=257(30),cap=259(30),cap=261(30) Pid=100,2,139,17 Type=1 Region=REG-MOH Device Capability= [0x129 DETECT_2833 PT_2833 PORT_CAP MM_CAP] Count=1
47948127.000 |21:41:17.805 |SdlSig-I |MediaExchangeAgenaAssociateReq         |waiting                        |MediaTerminationPointControl(2,100,139,17) |MTPAgenaInterface(1,100,247,531)                                                             |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:2,L:0,V:0,Z:0,D:0] confID=34368505 CI=28273167
47948127.001 |21:41:17.805 |AppInfo  |MediaTerminationPointControl(17) - MediaExchangeAgenaAssociateReq confId=34368505 inserted AIF(531,0)
47948128.000 |21:41:17.805 |SdlSig-I |MediaExchangeAgenaQueryCapability      |waiting                        |MediaTerminationPointControl(2,100,139,17) |MTPAgenaInterface(1,100,247,531)                                                             |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:2,L:0,V:0,Z:0,D:0]  CI=28273167
47948129.000 |21:41:17.805 |SdlSig-I |MediaExchangeAgenaAssociateReq         |waiting                        |MediaTerminationPointControl(2,100,139,17) |MTPAgenaInterface(1,100,247,532)                                                             |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:2,L:0,V:0,Z:0,D:0] confID=34368505 CI=28273167
47948129.001 |21:41:17.805 |AppInfo  |MediaTerminationPointControl(17) - MediaExchangeAgenaAssociateReq confId=34368505 inserted AIF(531,532)
47948130.000 |21:41:17.805 |SdlSig-I |MediaExchangeAgenaQueryCapability      |waiting                        |MediaTerminationPointControl(2,100,139,17) |MTPAgenaInterface(1,100,247,532)                                                             |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:1,L:0,V:0,Z:0,D:0]  CI=28273167
47948131.000 |21:41:17.805 |SdlSig-O |MediaExchangeAgenaUpdateCapabilities   |NA RemoteSignal                |MTPAgenaInterface(1,100,247,531)                                                             |MediaTerminationPointControl(2,100,139,17) |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:1,L:0,V:0,Z:0,D:0] AudioCapCount=9(15,60)(11,60)(4,30)(2,30)(12,60)(16,60)(257,30)(259,30)(261,30) CryptoAudioCapCount0 VideoCapCount=0 CryptoVidCount0 AudioCapDir=0 VideoCapDir=0 DataCapDir=0 ipAddrType=0 ipv4=192.168.204.252 Supp.Payload RFC[0 0 0 0 0 ] CustomPictureFormatCount=0 devCap= [0x129 DETECT_2833 PT_2833 PORT_CAP MM_CAP] PortInfoList [ confID=0 callRefID=0] v150MER=F T38MER=F
47948132.000 |21:41:17.805 |SdlSig-O |MediaExchangeAgenaUpdateCapabilities   |NA RemoteSignal                |MTPAgenaInterface(1,100,247,532)                                                             |MediaTerminationPointControl(2,100,139,17) |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] AudioCapCount=9(15,60)(11,60)(4,30)(2,30)(12,60)(16,60)(257,30)(259,30)(261,30) CryptoAudioCapCount0 VideoCapCount=0 CryptoVidCount0 AudioCapDir=0 VideoCapDir=0 DataCapDir=0 ipAddrType=0 ipv4=192.168.204.252 Supp.Payload RFC[0 0 0 0 0 ] CustomPictureFormatCount=0 devCap= [0x129 DETECT_2833 PT_2833 PORT_CAP MM_CAP] PortInfoList [ confID=0 callRefID=0] v150MER=F T38MER=F
47948133.000 |21:41:17.813 |SdlSig-I |MXAgenaOpenLogicalChannel              |waiting                        |MediaTerminationPointControl(2,100,139,17) |MTPAgenaInterface(1,100,247,531)                                                             |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:1,L:0,V:0,Z:0,D:0] codec=2 CI=28273167 mediaType=1 audioRate=20 iLBCMode=1 rfc2833PayloadNum=101 confId=34368505 streamFlag=F partyId=16779323 PT=F PTId=16777216 dyn payloadTypeNum=0 MediaEncrAlgo=0 ConnType=3 ReqIpAddrType=0 ipAddrType=0 ipv4=192.168.204.251:10710 dscpForMyParty=0xb8 (DSCP=0x2e) dscpForOtherEndParty=0xb8 (DSCP=0x2e) v150MER=F T38MER=F
47948133.001 |21:41:17.813 |AppInfo  |MediaTerminationPointControl(17)::star_MediaExchangeAgenaOpenLogicalChannel - PartyId = 16779323
47948133.002 |21:41:17.813 |AppInfo  |MediaTerminationPointControl(17)::fillAudioPartyIDtoMediaPidTable - PartyId = 16779323, OLCRecd=1, StartTlkRecd=0
47948133.003 |21:41:17.813 |AppInfo  |MediaTerminationPointControl(17)::star_MediaExchangeAgenaOpenLogicalChannel - confernceId=34368505 inserted AgenaInstance=531
47948134.000 |21:41:17.813 |SdlSig-I |MXStartTalking                         |waiting                        |MediaTerminationPointControl(2,100,139,17) |MTPAgenaInterface(1,100,247,531)                                                             |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:1,L:0,V:0,Z:0,D:0] codec=2 aRate(msec)=20 confId=34368505 ipAddrType=0 ipv4=192.168.204.251 port=10710 silence=0 partyId=0x100083b callid=28273167 startTalkingFlag=T DiffServ=0xb8 (DSCP=0x2e) RFC2833 payload type=101 MediaEncryptionAlgorithm=0 PT=F PTId = 16777216 rfc_number=0x0 dyn payloadTypeNum=0 ILBCBRate=20ms ConnType=3 v150MER=F T38MER=F
47948134.001 |21:41:17.813 |AppInfo  |MediaTerminationPointControl(17)::star_MediaExchangeStartTalking - CI=34368505, DSCPValue = 0x2e
47948134.002 |21:41:17.813 |AppInfo  |MediaTerminationPointControl(17)::fillAudioPartyIDtoMediaPidTable - PartyId = 16779323, OLCRecd=1, StartTlkRecd=1
47948135.000 |21:41:17.813 |SdlSig   |StationOutputOpenReceiveChannel        |waiting                        |MediaTerminationPointControl(2,100,139,17) |MediaTerminationPointControl(2,100,139,17) |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:1,L:0,V:0,Z:0,D:0] ConfId=34368505 CI=28273167 PacketSize=20 PayloadType=2 EchoCancellation=0 G723BitRate=0 PartyId=0x100083b SPId=0x1000000 RFC2833PayloadType=101 mixingMode=0 partyDir=0 srcIP=.type=0 .addr=0x{c0,a8,cc,fb,0,0,0,0,0,0,0,0,0,0,0,0}(192.168.204.251) Port=10710 requestedIpAddrType=0
47948135.001 |21:41:17.813 |AppInfo  |MediaTerminationPointControl(17)::star_StationOutputOpenReceiveChannel - TCPPid = [2.100.14.60337] myIP: 0xfccca8c0 (192.168.204.252) ConferenceID: 34368505, MediaPartyId: 16779323, msecPacketSize: 20 compressionType: 2
47948136.000 |21:41:17.813 |SdlSig   |StationOutputStartMediaTransmission    |waiting                        |MediaTerminationPointControl(2,100,139,17) |MediaTerminationPointControl(2,100,139,17) |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ConfId=34368505 remoteIpAddr=.type=0 .addr=0x{c0,a8,cc,fb,0,0,0,0,0,0,0,0,0,0,0,0}(192.168.204.251) Port=10710 PacketSize=20 PayloadType=2 CI=28273167 DiffServ=0xb8 (DSCP=0x2e) Silent=0 MaxFrms=0 G723BitRate=0 PartyId=0x100083b RFC2833PayloadType=101 mixingMode=0 partyDir=0
47948136.001 |21:41:17.813 |AppInfo  |MediaTerminationPointControl(17)::star_StationOutputStartMediaTransmission - TCPPid = [2.100.14.60337] myIP: 0xfccca8c0 (192.168.204.252)
47948136.002 |21:41:17.813 |AppInfo  |MediaTerminationPointControl(17)::star_StationOutputStartMediaTransmission - ConferenceID: 34368505, MediaPartyId: 16779323, RemoteIpAddr: 0xfbcca8c0 (192.168.204.251) RemoteRtpPortNumber: 10710 msecPacketSize: 20 compressionType: 2
47948137.000 |21:41:17.814 |SdlSig   |SdlDataInd                             |wait                           |StationInit(2,100,63,1)          |SdlTCPConnection(2,100,14,60337)                                                             |2,100,14,60337.70429^192.168.204.252^XCODE-01 |*TraceFlagOverrode
47948137.001 |21:41:17.814 |AppInfo  |StationInit: (0000017) OpenReceiveChannelAck Status=0, IpAddr=IpAddr.type:0 ipAddr:0xc0a8ccfc0000001b0000000000007f52(192.168.204.252), Port=8084, PartyID=16779323
47948138.000 |21:41:17.815 |SdlSig   |StationOpenReceiveChannelAck           |waiting                        |MediaTerminationPointControl(2,100,139,17) |StationInit(2,100,63,1)          |2,100,14,60337.70429^192.168.204.252^XCODE-01 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]  ORCStatus = 0 ipAddr=.type=0 .addr=0x{c0,a8,cc,fc,0,0,0,1b,0,0,0,0,0,0,7f,52}(192.168.204.252) Port=8084 CI=0 PartyId=0x100083b
47948139.000 |21:41:17.815 |SdlSig-O |MXAgenaOpenLogicalChannelAck           |NA RemoteSignal                |MTPAgenaInterface(1,100,247,531)                                                             |MediaTerminationPointControl(2,100,139,17) |2,100,14,60337.70429^192.168.204.252^XCODE-01 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] rc=0 isMultiMedia=F LCN=0 partyId=16779323 port=8084 ipAddrType=0 ipv4=192.168.204.252
47948140.000 |21:41:17.855 |SdlSig   |StationStartMediaTransmissionAck       |waiting                        |MediaTerminationPointControl(2,100,139,17) |StationInit(2,100,63,1)          |2,100,14,60337.70430^192.168.204.252^XCODE-01 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] rc=0 ConfId=34368505 PartyId=0x100083b CallRef=28273167 transmitIpAddr=.type=0 .addr=0x{c0,a8,cc,fc,0,0,7f,52,84,0,f,e0,29,d6,e8,a4}(192.168.204.252) Port=8084
47948141.000 |21:41:17.855 |SdlSig-O |MediaExchangeAgenaStartTalkingAck      |NA RemoteSignal                |MTPAgenaInterface(1,100,247,531)                                                             |MediaTerminationPointControl(2,100,139,17) |2,100,14,60337.70430^192.168.204.252^XCODE-01 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] result=0 mediaType=1 confID=34368505 partyID=0x100083b CI=28273167 ipAddrType=0 ipv4=192.168.204.252 port=8084
47948142.000 |21:41:17.870 |SdlSig-I |MXAgenaOpenLogicalChannel              |waiting                        |MediaTerminationPointControl(2,100,139,17) |MTPAgenaInterface(1,100,247,532)                                                             |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] codec=4 CI=28273167 mediaType=1 audioRate=20 iLBCMode=1 rfc2833PayloadNum=101 confId=34368505 streamFlag=F partyId=16779324 PT=F PTId=16777216 dyn payloadTypeNum=0 MediaEncrAlgo=0 ConnType=3 ReqIpAddrType=0 ipAddrType=0 ipv4=192.168.50.122:4000 dscpForMyParty=0xb8 (DSCP=0x2e) dscpForOtherEndParty=0xb8 (DSCP=0x2e) v150MER=F T38MER=F
47948142.001 |21:41:17.870 |AppInfo  |MediaTerminationPointControl(17)::star_MediaExchangeAgenaOpenLogicalChannel - PartyId = 16779324
47948142.002 |21:41:17.870 |AppInfo  |MediaTerminationPointControl(17)::fillAudioPartyIDtoMediaPidTable - PartyId = 16779324, OLCRecd=1, StartTlkRecd=0
47948142.003 |21:41:17.870 |AppInfo  |MediaTerminationPointControl(17)::star_MediaExchangeAgenaOpenLogicalChannel - confernceId=34368505 inserted AgenaInstance=532
47948143.000 |21:41:17.870 |SdlSig   |StationOutputOpenReceiveChannel        |waiting                        |MediaTerminationPointControl(2,100,139,17) |MediaTerminationPointControl(2,100,139,17) |3,100,14,925.1812^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ConfId=34368505 CI=28273167 PacketSize=20 PayloadType=4 EchoCancellation=0 G723BitRate=0 PartyId=0x100083c SPId=0x1000000 RFC2833PayloadType=101 mixingMode=0 partyDir=0 srcIP=.type=0 .addr=0x{c0,a8,32,7a,0,0,0,0,0,0,0,0,0,0,0,0}(192.168.50.122) Port=4000 requestedIpAddrType=0
47948143.001 |21:41:17.870 |AppInfo  |MediaTerminationPointControl(17)::star_StationOutputOpenReceiveChannel - TCPPid = [2.100.14.60337] myIP: 0xfccca8c0 (192.168.204.252) ConferenceID: 34368505, MediaPartyId: 16779324, msecPacketSize: 20 compressionType: 4
47948144.000 |21:41:17.876 |SdlSig   |SdlDataInd                             |wait                           |StationInit(2,100,63,1)          |SdlTCPConnection(2,100,14,60337)                                                             |2,100,14,60337.70431^192.168.204.252^XCODE-01 |*TraceFlagOverrode
47948144.001 |21:41:17.876 |AppInfo  |StationInit: (0000017) OpenReceiveChannelAck Status=0, IpAddr=IpAddr.type:0 ipAddr:0xc0a8ccfc0000001b0000000000007f52(192.168.204.252), Port=8086, PartyID=16779324
47948145.000 |21:41:17.876 |SdlSig   |StationOpenReceiveChannelAck           |waiting                        |MediaTerminationPointControl(2,100,139,17) |StationInit(2,100,63,1)          |2,100,14,60337.70431^192.168.204.252^XCODE-01 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0]  ORCStatus = 0 ipAddr=.type=0 .addr=0x{c0,a8,cc,fc,0,0,0,1b,0,0,0,0,0,0,7f,52}(192.168.204.252) Port=8086 CI=0 PartyId=0x100083c
47948146.000 |21:41:17.876 |SdlSig-O |MXAgenaOpenLogicalChannelAck           |NA RemoteSignal                |MTPAgenaInterface(1,100,247,532)                                                             |MediaTerminationPointControl(2,100,139,17) |2,100,14,60337.70431^192.168.204.252^XCODE-01 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] rc=0 isMultiMedia=F LCN=0 partyId=16779324 port=8086 ipAddrType=0 ipv4=192.168.204.252
47948147.000 |21:41:17.983 |SdlSig-I |MXStartTalking                         |waiting                        |MediaTerminationPointControl(2,100,139,17) |MTPAgenaInterface(1,100,247,532)                                                             |3,100,14,925.1813^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] codec=4 aRate(msec)=20 confId=34368505 ipAddrType=0 ipv4=192.168.50.122 port=25502 silence=0 partyId=0x100083c callid=28273167 startTalkingFlag=T DiffServ=0xb8 (DSCP=0x2e) RFC2833 payload type=101 MediaEncryptionAlgorithm=0 PT=F PTId = 16777216 rfc_number=0x0 dyn payloadTypeNum=0 ILBCBRate=20ms ConnType=3 v150MER=F T38MER=F
47948147.001 |21:41:17.983 |AppInfo  |MediaTerminationPointControl(17)::star_MediaExchangeStartTalking - CI=34368505, DSCPValue = 0x2e
47948147.002 |21:41:17.983 |AppInfo  |MediaTerminationPointControl(17)::fillAudioPartyIDtoMediaPidTable - PartyId = 16779324, OLCRecd=1, StartTlkRecd=1
47948148.000 |21:41:17.983 |SdlSig   |StationOutputStartMediaTransmission    |waiting                        |MediaTerminationPointControl(2,100,139,17) |MediaTerminationPointControl(2,100,139,17) |3,100,14,925.1813^192.168.50.122^SEP0019305D1063 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] ConfId=34368505 remoteIpAddr=.type=0 .addr=0x{c0,a8,32,7a,0,0,0,0,0,0,0,0,0,0,0,0}(192.168.50.122) Port=25502 PacketSize=20 PayloadType=4 CI=28273167 DiffServ=0xb8 (DSCP=0x2e) Silent=0 MaxFrms=0 G723BitRate=0 PartyId=0x100083c RFC2833PayloadType=101 mixingMode=0 partyDir=0
47948148.001 |21:41:17.983 |AppInfo  |MediaTerminationPointControl(17)::star_StationOutputStartMediaTransmission - TCPPid = [2.100.14.60337] myIP: 0xfccca8c0 (192.168.204.252)
47948148.002 |21:41:17.983 |AppInfo  |MediaTerminationPointControl(17)::star_StationOutputStartMediaTransmission - ConferenceID: 34368505, MediaPartyId: 16779324, RemoteIpAddr: 0x7a32a8c0 (192.168.50.122) RemoteRtpPortNumber: 25502 msecPacketSize: 20 compressionType: 4
47948149.000 |21:41:17.985 |SdlSig   |StationStartMediaTransmissionAck       |waiting                        |MediaTerminationPointControl(2,100,139,17) |StationInit(2,100,63,1)          |2,100,14,60337.70432^192.168.204.252^XCODE-01 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] rc=0 ConfId=34368505 PartyId=0x100083c CallRef=28273167 transmitIpAddr=.type=0 .addr=0x{c0,a8,cc,fc,0,0,7f,52,84,0,f,e0,0,0,0,5e}(192.168.204.252) Port=8086
47948150.000 |21:41:17.985 |SdlSig-O |MediaExchangeAgenaStartTalkingAck      |NA RemoteSignal                |MTPAgenaInterface(1,100,247,532)                                                             |MediaTerminationPointControl(2,100,139,17) |2,100,14,60337.70432^192.168.204.252^XCODE-01 |[R:N-H:0,N:0,L:0,V:0,Z:0,D:0] result=0 mediaType=1 confID=34368505 partyID=0x100083c CI=28273167 ipAddrType=0 ipv4=192.168.204.252 port=8086
47948151.000 |21:41:18.066 |SdlSig   |DbObjectCacheTimer                     |initialized                    |Db(2,100,211,1)                  |SdlTimerService(2,100,3,1)       |2,100,148,1.1^*^*                        |[T:H-H:0,N:0,L:0,V:0,Z:0,D:0]  AppCorr: 0
47948152.000 |21:41:18.709 |SdlSig   |SdlReadRsp                             |wait                           |SIPTcp(2,100,72,1)               |SdlTCPConnection(2,100,14,79685)                                                             |2,100,14,79685.3266^192.168.84.38^*      |*TraceFlagOverrode
47948152.001 |21:41:18.709 |AppInfo  |SIPTcp - SdlRead bufferLen=981
47948152.002 |21:41:18.710 |AppInfo  |SIPTcp - wait_SdlReadRsp: Incoming SIP TCP message from 192.168.84.38 on port 49888 index 79290 with 981 bytes:
[8099054,NET]



However, when the call connects, we get no audio at either side.

Any ideas on things that I can check?

1 Accepted Solution

Accepted Solutions

Absolutely it will be. The only way to recover this is by a reload. Service Engine on ISR-4k need to be in a functional/UP state.



View solution in original post

10 Replies 10

R0g22
Cisco Employee
Cisco Employee
Are these resources on a ISR-4k ? Can you attach the complete trace file ?

Hi Nipun,

 

Yes, this is on a new ISR4331 running 155-3.S4b. We actually have two CUBEs both with transcoders configured and added to the MRG, but the same issue seems to happen with either one.

 

I don't have the full trace right now, but will be able to attach it later today.

 

Thanks

Ess

Ok. There are a couple of relevant defects on ISR-4k related to media resources. Do you also use conferencing resources off this GW, if yes, is there audio issues on conferences as well ? Was this ever working fine before and broke post a IOS upgrade ?

Hi Nipun,

 

I have narrowed this down a bit now. We actually migrated to a pair of ISR4K gateways from a 2921. I removed all the 4Ks from the MRG and added in the old 2921 transcoder and the call worked correctly. I then removed it and added just one of the ISR4Ks (CUBE01) and this worked correctly. When I add the other, it appears to fail, so I think I narrowed the issue to a single CUBE.

 

On that particular CUBE (CUBE02), I go on and see that the interface Service-Engine0/4/0 is shut down. It's my understanding from the documents that this should be up (  ) however, when I try to open this, it says:

 

CUBE02(config)#interface Service-Engine0/4/0
CUBE02(config-if)#no shut
% Shutdown not allowed on this interface.

Could this be the reason that the media resources are failing on this CUBE? They both have exactly the same configuration otherwise, and the media resources of both CUBEs are showing registered correctly.

 

Many thanks

Absolutely it will be. The only way to recover this is by a reload. Service Engine on ISR-4k need to be in a functional/UP state.



Thanks Nipun, I will reboot and let you know.

One question - in the media resource group, how does it assign a particular transcoder? Is it round-robin? Or simply top-down in order? Or does it have an algorithm? e.g. if I have a single MRG with 3 hardware transcoders, how does CUCM decide which one to insert?

Many thanks

Good question. I am not aware of a specific algorithm that is used but the resource allocation within the MRG is based on which was configured/registered first in CUCM. So imagine that there are 3 Xcoders in a MRG - X1,X2,X3. They were configured/registered in the exact same order. The X1 resource will be allocated first until it runs out of any available sessions.

As per SRND

"Within an MRG, resources are allocated based on their order in their configuration even though Unified CM Administration displays the devices in an MRG in alphabetical order. If you want media resources to be allocated in a specific order, Cisco recommends that you create a separate MRG for each individual resource and use MRGLs to specify the order of allocation."

However when a call is already using a Transcoder resource and a second call comes in with the requirement for a transcoder.

"When there are multiple devices providing the same type of resource within an MRG, the algorithm for allocating that resource load-balances across all those devices. Cisco Unified CM uses a throttling mechanism to load balance across MTP and transcoder resources using the MTP and Transcoder Resource Throttling Percentage service parameter, which defines a percentage of the configured number of MTP or transcoder resources. When the number of active MTP or transcoder resources is equal to or greater than the percentage that is configured for this parameter, Cisco Unified CM stops sending calls to this resource and hunts through the MRGL (including the default MRG) one time to find a resource that uses matching codecs on both sides of the call. If Cisco Unified CM cannot find an available resource with matching codecs, it returns to the top of the MRGL to repeat the search, which then includes those resources that are in a throttled state and that match a smaller subset of capabilities for the call. Cisco Unified CM extends the call to the resource that is the best match for the call when such a resource is available. The call fails when Cisco Unified CM cannot allocate a resource for the call."

So within a MRG we can not control how a resource is allocated as the allocation would be in a round robin sort of fashion. If you want the transcoders to be allocated in a specific order you would need to put them in different MRG's and prioritize in the MRGL.

http://www.cisco.com/c/en/us/td/docs/voice_ip_comm/cucm/srnd/collab10/collab10/media.html

Please rate if you find this helpful.

Regards,
Aeby



Please rate if you find this helpful.

Regards,
Aeby

Hey Aeby,

Though I agree with a part of what the SRND says, the SRND also states the following and you highlighted that as well -

Within an MRG, resources are allocated based on their order in their configuration even though Unified CM Administration displays the devices in an MRG in alphabetical order.

Well if we look at the two points, they are quite conflicting in nature. Also if my memory serves it right, the throttling value is set to something in the 90's by default with the maximum being 100 that is the full capacity of the MR being allocated.
So, if for let's say my example X1 is being used for a call already and a second call comes in and if the throttle has not been reached, resource hunting should not happen. :)

Hey Nipun,

The Round-Robin fashioned allocation is exhibited only when there are more than one active call. So within an MRG with multiple similar resource kind, The first call can go to a device based on the order which is built when they are configured. So if a second calls comes in after the first call is terminated it will go to the first device in the list, and if the first call was still ongoing when the second call came in, it will choose the next device in the list in a round robin fashion. The throttling mechanism makes sure that we have a count of the sessions used and if it the throttle is met for all the devices in the first MRG of the MRGL further hunting will be done.

Please rate if you find this helpful.

Regards,
Aeby


Please rate if you find this helpful.

Regards,
Aeby
Getting Started

Find answers to your questions by entering keywords or phrases in the Search bar above. New here? Use these resources to familiarize yourself with the community: