Tek-Tips is the largest IT community on the Internet today!

Members share and learn making Tek-Tips Forums the best source of peer-reviewed technical information on the Internet!

  • Congratulations Mike Lewis on being selected by the Tek-Tips community for having the most helpful posts in the forums last week. Way to Go!

no audio fro voice mail

Status
Not open for further replies.

phoneproblems

Programmer
Sep 21, 2019
7
US
Specs IPO SVR Edition R.11. ( Service Pack 4 ) - Virtualized in AWS CLoud ( ec2 )
Voice: Sip Trunks
VM Pro

Problem; no audio when calling from an external call.
inbound and outbound calls work with no problem. SIP Line is register and active. voicemail audio calls work internally, *17 works, checking voicemail etc. when calling the DID, or re-route to hunt group. I see the call in SS being pick up from the system (mailbox#3001) but I cant hear audio.

steps taken.
recorded name and greeting in mailbox, changed SIP Settings, changed Stun Server settings, upgraded to the latest release, rebooted the whole system, restarted services on vmpro. I cant seem to pin the fix. any help will be much appreciated!.. thanks an advance


monitor

09:22:26 69622067mS CMCallEvt: 0000000000000000 0.1621.0 -1 BaseEP: NEW CMEndpoint f75a6be0 TOTAL NOW=4 CALL_LIST=1
09:22:26 69622067mS CMTARGET: ac1f25fd00000655 0.1621.0 30 RAS.0: ADD PRIMARY
09:22:26 69622067mS CMTARGET: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint: ADD VM TARGET: SUCCEEDED
09:22:26 69622067mS CMTARGET: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint: GetNoAnswerTimer:15
09:22:26 69622067mS CMCallEvt: ac1f25fd00000655 0.1621.0 30 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
09:22:26 69622067mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1621 )
09:22:26 69622067mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1010 in=0
Called[#Mgarrido] Type=Voicemail (102) Reason=107 SndComp Calling[2816697684@198.58.45.55] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=2816697684
IE CMIECalledPartyName (224)(Type=CMNameInternalUser) name=Mgarrido
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Miguel Garrido
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=2816697684
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=2816697684@198.58.45.55
IE CMIEDeviceDetail (231) ac1f25fd00000652 LOCALE=enu HW=11 VER=11 class=CMDeviceSIPTrunk type=0 number=1 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=1618 ipaddr=172.31.37.253 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
IE CMIERedirectingPartyName (232)(Type=CMNameInternalUser) name=Mgarrido
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=Miguel Garrido
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=3001
IE CMIEOriginalCalledPartyName (236)(Type=CMNameInternalUser) name=Mgarrido
IE CMIEOriginalCalledPartyKName (237)(Type=CMNameDefault) name=Miguel Garrido
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:3) number=3001
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=7133240901
IE CMIEMohSourceId (247) MOH Source = 1
Display [2816697684>Mgarrido]
Timed: 21/09/19 09:22
Locale: enu
09:22:26 69622067mS JadeMapper: Allocated media RTP port: 40868 from rangeId: 0 (inUsePorts: 2, activeChannels: 0, freeChannels: 2520)
09:22:26 69622067mS VMAIL: Allocated port: 34756
09:22:26 69622068mS H323Evt: SESS 9 SetOperational local 172.31.37.253:0 remote 0.0.0.0:0 to 0
09:22:26 69622068mS H323Evt: SESS 9 Configure: Ulaw64K packet size 160
09:22:26 69622068mS H323Evt: SESS 9 SetRemUDP 0 -> 40868, remote IP 0.0.0.0 -> 172.31.37.253
09:22:26 69622068mS H323Evt: SESS 9 SetRemUDP local IP 172.31.37.253 -> 127.0.0.1
09:22:26 69622068mS JadeMapper: Activated media RTP port: 40868, isVMConn: 1 (inUsePorts: 2, activeChannels: 1, freeChannels: 2519)
09:22:26 69622068mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMName_VMPro) name=#Mgarrido
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
IE CMIEDeviceDetail (231) ac1f25fd00000655 LOCALE=enu HW=11 VER=11 class=CMDeviceVoicemail type=0 number=0 channel=0 features=0x0 rx_gain=32 tx_gain=32
ep_callid=1621 ipaddr=172.31.37.253 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIERedirectingPartyName (232)(Type=CMNameInternalUser) name=Mgarrido
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=Miguel Garrido
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=3001
09:22:26 69622068mS CMCallEvt: ac1f25fd00000655 0.1621.0 30 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
09:22:26 69622068mS CMCallEvt: 0000000000000000 0.1619.0 30 TargetingEP: RequestEnd ac1f25fd00000655 0.1621.0 30 RAS.0
09:22:26 69622068mS CMTARGET: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint: CancelTimer CMTCNoAnswerTimeout
09:22:26 69622068mS CMExtnEvt: Mgarrido: CALL LOST (CMCauseForwardToVoicemail)
09:22:26 69622068mS CMExtnEvt: Mgarrido: Extn(3001) Calling Party Number(2816697684@198.58.45.55) Type(CMNTypeUnknown)
09:22:26 69622068mS CMCallEvt: ac1f25fd00000654 351.1620.0 -1 Mgarrido.0: StateChange: END=X CMCSRinging->CMCSCompleted
09:22:26 69622068mS CMExtnEvt: v=0 State, new=PortRecoverDelay old=Ringing,0,0,Mgarrido
09:22:26 69622068mS Sip: (f7594350) INFO SENT TO 76.30.245.249 50751
09:22:26 69622069mS CMExtnTx: v=3001, p1=0
CMReleaseComp
Line: type=SIPLine 360 Call: lid=351 id=1620 in=0
Called[3001] Type=Default (100) Reason=CMDRdirect Calling[2816697684@198.58.45.55] Type=Unknown Plan=Default
Cause=123, Forward To Voicemail(IPO)
Timed: 21/09/19 09:22
09:22:26 69622069mS Sip: (f7594350) SIPDialog::BuildSDPFromFastStart
09:22:26 69622069mS Sip: (f7594350) ForwardFacility::Same as last sent SDP. do not send re_INVITE
09:22:26 69622069mS Sip: MessageToPhone (3001):Failed to forward the facility
09:22:26 69622069mS CMExtnEvt: Mgarrido: CMExtnHandler::SetCurrent( id: 1620->0 )
09:22:26 69622069mS Sip: (f7594350) INFO SENT TO 76.30.245.249 50751
09:22:26 69622069mS CMCallEvt: ac1f25fd00000654 351.1620.0 -1 Mgarrido.-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:22:26 69622069mS CMCallEvt: ac1f25fd00000654 351.1620.0 -1 BaseEP: DELETE CMEndpoint f66e5b38 TOTAL NOW=3 CALL_LIST=1
09:22:26 69622069mS CMExtnEvt: v=1010 State, new=Connected old=Idle,0,0,RAS
09:22:26 69622069mS CMCallEvt: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint: StateChange: END=A CMCSRingBack->CMCSOGConnReq
09:22:26 69622069mS JadeMapper: Allocated media RTP port: 40870 from rangeId: 2 (inUsePorts: 3, activeChannels: 1, freeChannels: 2519)
09:22:26 69622070mS CMLineTx: v=0
CMConnect
Line: type=SIPLine 1 Call: lid=1 id=1618 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageISO8859-1
invokeId 14666
user '#Mgarrido' presentation Allowed
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIERespondingPartyName (228)(Type=CMName_VMPro) name=#Mgarrido
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=
IE CMIEDeviceDetail (231) ac1f25fd00000655 LOCALE=enu HW=11 VER=11 class=CMDeviceVoicemail type=0 number=0 channel=0 features=0x0 rx_gain=32 tx_gain=32
ep_callid=1621 ipaddr=172.31.37.253 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIERedirectingPartyName (232)(Type=CMNameInternalUser) name=Mgarrido
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=Miguel Garrido
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:3) number=3001
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 4)
}
09:22:26 69622070mS NAT: EPNatMediaHelper f751d450 for ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint created, parent f66d7498 list size 1
09:22:26 69622070mS NAT: EPNatMediaHelper for ep f66b5dc0 ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint ReplaceRtpAndRtcpAddress fast start audio with 3.227.199.69:40870
09:22:26 69622070mS Sip: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint(f66b5dc0) received CMConnect
09:22:26 69622070mS Sip: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint(f66d96c8) ExtractMedia from CMConnect
09:22:26 69622070mS Sip: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint(f66d96c8) SetLocalRTPAddress to 3.227.199.69:40870
09:22:26 69622070mS Sip: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint(f66d96c8) SIPEndPoint: Received an CMConnect State Transition to SIPDialog::INV_PROV_RESP_SENT(11)
09:22:26 69622070mS Sip: SIPDialog::ExtractResponseParamsFromViaHeader remote sent_by: 198.58.45.213:5060 trunk
09:22:26 69622070mS Sip: SIPDialog::ExtractResponseParamsFromViaHeader remote sent by transport: SIP/2.0/UDP trunk
09:22:26 69622070mS Sip: SIPTrunkEndpointDialogOwner::SetRemoteAddressForResponse from 198.58.45.213:5060 to 198.58.45.213:5060
09:22:26 69622070mS Sip: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint(f66d96c8) BuildSDPResponse: sdpmsg f75616e8 l_pOrgSdpMsg f66bcdf0 msg f758f240 full_list 0 local_fs_info f7550f08 (2) local_sec_info 0 (0) cs1k_emu 0
09:22:26 69622070mS Sip: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint(f66d96c8) SetRfc2833TxPayload: payload 101 set_rx 1
09:22:26 69622070mS Sip: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint(f66d96c8) UpdateSDPState SIPDialog::OFFER_RCVD(2) -> SIPDialog::COMPLETE(7)
09:22:26 69622070mS Sip: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint(f66d96c8) SendSIPResponse: INVITE code 200 SENT TO 198.58.45.213 5060
09:22:26 69622070mS SIP Tx: UDP 172.31.37.253:5060 -> 198.58.45.213:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 198.58.45.213:5060;branch=z9hG4bKj1qes92060knos3vk8l0.1
From: "2816697684" <sip:2816697684@198.58.45.213:5060>;tag=as06b490fd
Call-ID: 255198df53aef41b33a7c0b240dcbc4e@198.58.45.55:5060
CSeq: 102 INVITE
Contact: <sip:7133240901@a2west.sipregistration.com:5060;transport=udp>
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
Server: IP Office 11.0.4.1.0 build 11
Content-Type: application/sdp
Content-Length: 203
To: <sip:7133240901@3.227.199.69:5060>;tag=2587c70f9188580d

v=0
o=UserA 690913684 1215166050 IN IP4 3.227.199.69
s=Session SDP
c=IN IP4 3.227.199.69
t=0 0
m=audio 40870 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
09:22:26 69622070mS Sip: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint(f66d96c8) SendSIPResponse clearCMFacilityTimer
09:22:26 69622070mS Sip: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint(f66d96c8) UpdateSIPCallState SIPDialog::INV_PROV_RESP_SENT(11) -> SIPDialog::INV_200_OK_SENT(12)
09:22:26 69622070mS Sip: ac1f25fd00000652 1.1618.1 30 SIPTrunk Endpoint(f66d96c8) OnCmMessage end, session level 16, incoming_sdp_status[MEDIA_TYPE_AUDIO] 4 incoming_sdp_status[MEDIA_TYPE_DATA] 8 OutboundHoldState 0 InboundHoldState 0
09:22:26 69622070mS CMCallEvt: 0000000000000000 0.1619.0 -1 BaseEP: DELETE CMEndpoint f66e3ff0 TOTAL NOW=2 CALL_LIST=1
09:22:26 69622070mS CD: CALL: 1.1618.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (0.0) Bend="Mgarrido(3001)" [VoiceMail] (0.0) CalledNum=#Mgarrido (Mgarrido) CallingNum=2816697684@198.58.45.55 (2816697684) Internal=0 Time=15005 AState=Connected
09:22:26 69622071mS JadeMapper: Activated media RTP port: 40870, isVMConn: 0 (inUsePorts: 3, activeChannels: 2, freeChannels: 2518)
09:22:26 69622071mS PRN: RTCP collector is NOT initialized
09:22:26 69622071mS CMMap: PCG::MapBChan pcp[135]b0r1 cp_b f66ac9c4 other_cp_b 0 type CGTypeSimple
09:22:26 69622071mS CMMap: PCGS CPReserveCodec (pcp[1]b1r1) true
09:22:26 69622071mS CMMap: PCGS CPReserveCodec for the other end (pcp[135]b0r1) true
09:22:26 69622071mS CMMap: PCG::MapBChan pcp[1]b1r1 cp_b f6a7761c other_cp_b f66ac9c4 type CGTypeSimple
09:22:26 69622071mS JadeMapper: Sending Map B Chan: 0x1 0x1f4 0x1 0x0 1 2
09:22:26 69622071mS CMMap: PlatformConnectionAudioSAP::ConnectVoice (connect) pcp[135]b0r1 echo_cancellation set to 0
09:22:26 69622071mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[135]b0r1 Configure 1.500
09:22:26 69622072mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[135]b0r1 ConnectIndication 1.500
09:22:26 69622072mS CMMap: a=1.501 b=0.0 Mapper::FreeCodec freed CMRTVocoder(f66e7f08) resource busy 1, total 2520
09:22:26 69622072mS JadeMapper: Deallocated media RTP port: 40866 (inUsePorts: 2, activeChannels: 2, freeChannels: 2518, activeChannelFreed: 0)
09:22:26 69622072mS JadeMapper: Media RTP port: 40866 is already freed (inUsePorts: 2, activeChannels: 2, freeChannels: 2518)
09:22:26 69622151mS Sip: SIPDialog TXN : Decoding of message Succeeded 2
09:22:26 69622151mS Sip: TCP packet known set owner
09:22:26 69622151mS Sip: (f7594350) Process SIP response dialog f7594350, method INFO, CodeNum 200 in state SIPDialog::CALL_UP(16)
09:22:26 69622170mS Sip: SendPhoneCtrlMsgToPhone:Multi-Message
09:22:26 69622170mS Sip: (f7594350) INFO SENT TO 76.30.245.249 50751
09:22:26 69622211mS Sip: SendPhoneCtrlMsgToPhone:Multi-Message
09:22:26 69622211mS Sip: (f7594350) INFO SENT TO 76.30.245.249 50751
09:22:26 69622226mS Sip: SIPDialog TXN : Decoding of message Succeeded 2
09:22:26 69622226mS Sip: TCP packet known set owner
09:22:26 69622226mS Sip: (f7594350) Process SIP response dialog f7594350, method INFO, CodeNum 200 in state SIPDialog::CALL_UP(16)
09:22:26 69622401mS Sip: SIPDialog TXN : Decoding of message Succeeded 2
09:22:26 69622401mS Sip: TCP packet known set owner
09:22:26 69622401mS Sip: (f7594350) Process SIP response dialog f7594350, method INFO, CodeNum 200 in state SIPDialog::CALL_UP(16)
09:22:26 69622490mS Sip: SIPDialog TXN : Decoding of message Succeeded 2
09:22:26 69622490mS Sip: TCP packet known set owner
09:22:26 69622490mS Sip: (f7594350) Process SIP response dialog f7594350, method INFO, CodeNum 200 in state SIPDialog::CALL_UP(16)
09:22:27 69623002mS Sip: sip_indicateTimeOut Timer 11
09:22:27 69623002mS Sip: Timer 11 callback found dialog f66acd50 46237ecdf23f112dda033d3d7ce85956 OPTIONS SIPDialog::FINAL
09:22:27 69623002mS Sip: Completed ... (todelete) removing/preserving Dialog f66acd50 of CallId: 46237ecdf23f112dda033d3d7ce85956 and State: SIPDialog::FINAL(28)
09:22:27 69623002mS Sip: (f66acd50) SetUnIntTransactionCondition to UnInt_None
09:22:27 69623002mS Sip: SIPDialog f66acd50 deleted, dialogs 6 txn_keys 1
09:22:27 69623002mS Sip: sip_indicateTimeOut txn_keys
 
 https://files.engineering.com/getfile.aspx?folder=40a8a4a4-b129-463d-81d7-8ad7d1f18f0d&file=3001_mail_box.JPG
Same issue here.
Have you found a solution or a workaround ?
 
Looks like could be a codec mismatch. Make sure your supported codecs match on both ends.

"It's all ball bearings these days" Fletch F. Fletch
 
I don't think it is a codec problem. Look at this screenshot I have made.
Also, I've tried between 2 devices using TLS and SRTP same issue...

2019-12-10_18h17_24_unzszc.png
 
Status
Not open for further replies.

Part and Inventory Search

Sponsor

Back
Top