02-21-2013 10:37 PM - edited 03-16-2019 03:52 PM
Hi,
Environment is CUCM - H323 - CUBE - ISP
Inbound & outbound calls all work fine however MOH does not work. Have checked that MOH is streaming from CUCM and is Unicast. Have checked all codecs in the Service Parameters for MOH to include G729 and restarted Streaming Service.
Attached is my config (cutdown), debug of CCSIp when triggering MOH and screenshot from CUCM of MOH service parameters.
Any ideas appreciated!
Cheers
Pieter
Solved! Go to Solution.
02-22-2013 03:26 AM
Pieter,
I have looked at your attached CUBE traces. I need to see CUCM traces. To invoke MOH over a sip trunk here is what happens
1. CUCM sends invite (re-invite) with an inactive SDP (a=inactive) to indicate a break in media path
2. CUCM sends a Delayed offer to insert MOH or to resume Media stream
From the traces, I can see the first step happened., CUBE sennt an inactive media to the ITSP, that means that CUBE received an inactive media from CUCM..However I dint see CUBE connecting to MOH, probably because it didnt receive instructionf rom CUCM..This is why we need CUCM traces
I also noticed that this is your call flow....
CUCM-----h323------CUBE----SIP----ITSP
I guessed this because I cant see the sip messages on the lge from CUCM..Is this correct. If it is, i will advise you to change this to a full sip setup. Use a sip trunk from CUCM to CUBE instead of h323
Sent:
INVITE sip:0429988277@210.193.202.230:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.11.2.254:5060;branch=z9hG4bK2B8F23F7
From: <>>0383702460@macquarietelecom.com>;tag=80757F58-2A
To: <0429988277>;tag=1710219635-1361507764438
Date: Fri, 22 Feb 2013 04:39:48 GMT
Call-ID: A56C235E-7BE011E2-943A9268-86818484@10.11.2.254
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 0010985561-2952093970-0722698241-0168493677
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 103 INVITE
Max-Forwards: 70
Timestamp: 1361507988
Contact: <0383702460>
Expires: 300
Allow-Events: telephone-event
Content-Type: application/sdp
Content-Length: 2710383702460>0429988277>
v=0
o=CiscoSystemsSIP-GW-UserAgent 4195 9232 IN IP4 10.11.2.254
s=SIP Call
c=IN IP4 0.0.0.0
t=0 0
m=audio 19394 RTP/AVP 18 101
c=IN IP4 0.0.0.0---------------------------------------Call is muted (usually because of transfer or hold)
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=inactive-------------------------------------------media is inactive
+++++++Cube received inactive media response back from ITSP..which is expected++++++++
Feb 22 04:39:49: //44838/00A7A0592B13/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.11.2.254:5060;branch=z9hG4bK2B8F23F7
From: <>>0383702460@macquarietelecom.com>;tag=80757F58-2A
To: <0429988277>;tag=1710219635-1361507764438
Call-ID: A56C235E-7BE011E2-943A9268-86818484@10.11.2.254
CSeq: 103 INVITE
Timestamp: 1361507988
Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY,UPDATE
Supported:
Accept: application/media_control+xml,application/sdp
Contact: <0429988277>
Content-Type: application/sdp
Content-Length: 2680429988277>0429988277>
v=0
o=BroadWorks 17488194 2 IN IP4 210.193.202.230
s=-
c=IN IP4 210.193.202.230
t=0 0
m=audio 10718 RTP/AVP 18 101
a=inactive
a=ptime:20
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=silenceSupp:off - - - -
02-22-2013 12:25 AM
Pieter,
We had love to h elp out here but there is a problem with CSC website. The files you have attached are showing as 31 bytes...and Queued for virsu scanning..Which means we cant download them.
You can send me an email to deji_ok@hotmail.co.uk
Please include the full CUCM trace for when MOH was attempted along with the CUBE traces..Also the calling and called number
Please rate all useful posts
"opportunity is a haughty goddess who waste no time with those who are unprepared"
02-22-2013 02:18 AM
Yeah - I also tried opening few hours back - since then keeps showing queued for virus scanning or blocked.
Terry
02-22-2013 03:18 AM
Terry, How you doing buddy! Hope you are good.. I have sent an email to one of the cummunity managers. Hoepfully it will be resolved soon.
Please rate all useful posts
"opportunity is a haughty goddess who waste no time with those who are unprepared"
02-22-2013 10:55 AM
Very well Deji - hope you are doing good too. Thanks mate - I started noticing this a day or two back - hopefully it will be resolved soon.
Terry
Sent from Cisco Technical Support iPhone App
02-22-2013 03:26 AM
Pieter,
I have looked at your attached CUBE traces. I need to see CUCM traces. To invoke MOH over a sip trunk here is what happens
1. CUCM sends invite (re-invite) with an inactive SDP (a=inactive) to indicate a break in media path
2. CUCM sends a Delayed offer to insert MOH or to resume Media stream
From the traces, I can see the first step happened., CUBE sennt an inactive media to the ITSP, that means that CUBE received an inactive media from CUCM..However I dint see CUBE connecting to MOH, probably because it didnt receive instructionf rom CUCM..This is why we need CUCM traces
I also noticed that this is your call flow....
CUCM-----h323------CUBE----SIP----ITSP
I guessed this because I cant see the sip messages on the lge from CUCM..Is this correct. If it is, i will advise you to change this to a full sip setup. Use a sip trunk from CUCM to CUBE instead of h323
Sent:
INVITE sip:0429988277@210.193.202.230:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 10.11.2.254:5060;branch=z9hG4bK2B8F23F7
From: <>>0383702460@macquarietelecom.com>;tag=80757F58-2A
To: <0429988277>;tag=1710219635-1361507764438
Date: Fri, 22 Feb 2013 04:39:48 GMT
Call-ID: A56C235E-7BE011E2-943A9268-86818484@10.11.2.254
Supported: 100rel,timer,resource-priority,replaces,sdp-anat
Min-SE: 1800
Cisco-Guid: 0010985561-2952093970-0722698241-0168493677
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 103 INVITE
Max-Forwards: 70
Timestamp: 1361507988
Contact: <0383702460>
Expires: 300
Allow-Events: telephone-event
Content-Type: application/sdp
Content-Length: 2710383702460>0429988277>
v=0
o=CiscoSystemsSIP-GW-UserAgent 4195 9232 IN IP4 10.11.2.254
s=SIP Call
c=IN IP4 0.0.0.0
t=0 0
m=audio 19394 RTP/AVP 18 101
c=IN IP4 0.0.0.0---------------------------------------Call is muted (usually because of transfer or hold)
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=inactive-------------------------------------------media is inactive
+++++++Cube received inactive media response back from ITSP..which is expected++++++++
Feb 22 04:39:49: //44838/00A7A0592B13/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.11.2.254:5060;branch=z9hG4bK2B8F23F7
From: <>>0383702460@macquarietelecom.com>;tag=80757F58-2A
To: <0429988277>;tag=1710219635-1361507764438
Call-ID: A56C235E-7BE011E2-943A9268-86818484@10.11.2.254
CSeq: 103 INVITE
Timestamp: 1361507988
Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY,UPDATE
Supported:
Accept: application/media_control+xml,application/sdp
Contact: <0429988277>
Content-Type: application/sdp
Content-Length: 2680429988277>0429988277>
v=0
o=BroadWorks 17488194 2 IN IP4 210.193.202.230
s=-
c=IN IP4 210.193.202.230
t=0 0
m=audio 10718 RTP/AVP 18 101
a=inactive
a=ptime:20
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=fmtp:18 annexb=no
a=silenceSupp:off - - - -
02-22-2013 11:04 PM
Wow! I am humbled by the effort you have put in and your experience. Thank you very much!
Yes you are correct. Reason I have kept it CUCM-h323-cube is for the amount of route patterns associated to the H323 gateway. I did see that this is supported with Cube, so felt it was safe. Would you say totally forget about H323 trunk to Cube or can I still try to get it working. It is still preferred method in y environment.
I will only get onsite again next week to collect the CUCM traces. I will endeavour to get onsite on Tuesday to collect.
Again, thank you!
Pieter
02-23-2013 12:02 AM
Pieter,
From experience it is better to to have an all sip integration where possible. One of the many factors is inter operability, another is troubleshooting. In your scenario we will need to troubleshoot both sip and h323 leg. I am sure it can work, its just not the recommended approach.
To troubleshoot this scenario we will need
1. Debug h225 asn1
2. Debug h245 asn1
3. Debug ccsip messages
4. Cucm SDI traces
5. Cube config
If we had only a sip integration, we won't need the h225,h245 debugs. That's one of the advantages.
You also need to ensure that your cube is assigned a MRGL with the correct MOH MRG.
Sent from Cisco Technical Support Android App
02-26-2013 08:37 PM
Thank you very much. I have collected the traces and sent to your inbox.
Look forward to what you find.
Cheers!!!
02-27-2013 11:18 AM
Pieter,
I have looked at the traces and here is what I found...
++++Here we see CUCM selected "MOH_EQT_MEL" for the session+++
11:51:28.091 |MediaResourceCdpc(3273)::resource_rsvp_AllocateMohResourceRes CI=18514284, DeviceName=MOH_EQT_MEL|1,100,17,21533.10^10.11.2.254^Port 50568
+++Here we see the region setting between MOH server and the held and holding party+++
11:51:28.091 |MohDControl - findUnicastSourceGivenSourceNum - Device Name = MOH_EQT_MEL, MohRegion = REG_EQT_MEL, Held Party Region = REG_EQT_MEL, Holding Party Region =REG_EQT_MEL|1,100,17,21533.10^10.11.2.254^Port 50568
+++++++Here we see the unicast audio source to be streamed, its audio source 51+++++++++=
11:51:28.091 |MohDControl - handleMohSuccess - Call Id = 18514284 AudioSourceID = 51 MuticastFlag = 0|1,100,17,21533.10^10.11.2.254^Port 50568
11:51:28.091 |MohDControl - handleUnicastResourceAllocation - Device Name = MOH_EQT_MEL|1,100,17,21533.10^10.11.2.254^Port 50568
++++CUCM says MOH allocate success+++
11:51:28.091 |MohDControl - handleMohSuccess - Sent out Allocate MOH Response CallId=18514284, TransferMode=4, Ip=0, Port=0, CapCount=5, AudioSourceID = 51 MuticastFlag = 0|1,100,17,21533.10^10.11.2.254^Port 50568
++++Here we see the MOH counter increased by 1...this is a sign that MOH device was succesfully allocated+++++
11:51:28.091 |MRM::updateMohCounter devName=MOH_EQT_MEL, countChange=1|1,100,17,21533.10^10.11.2.254^Port 50568
++++Here CUCM sends a start media transmission for MOH to the CUBE+++++
11:51:28.183 |MohDControl - stationOutputStartMediaTransmission tcpPID = [1.100.9.41051] myIP: fd020b0a (10.11.2.253)|1,100,17,21533.16^10.11.2.254^Port 50568
11:51:28.183 |MohDControl - RemoteIpAddr: fe020b0a (10.11.2.254) RemoteRtpPortNumber: 29350 msecPacketSize: 20 compressionType: 11|1,100,17,21533.16^10.11.2.254^Port 50568
So everything looks okay from CUCM....So why is it not working...Lets look at what we see in IPVMS traces
At 11:51:28 the same time that CUCM sends a start media transmission for MOH stream, we get this error from the CMOHMgr process on the IPVMS...."Fixed Audio Source Melbourne Live failed to start"
11:51:28.816 | CMOHMgr::PlayStream (12,51,0) Fixed Audio Source Melbourne Live failed to start
So here is our Culprit..Looks like there is a problem with the fixed audio source.....Can you try another audio source and see if that works
Please rate all useful posts
"opportunity is a haughty goddess who waste no time with those who are unprepared"
02-27-2013 09:04 PM
Thank you, good find!
I did ask them to chnage and advise but they say it is still a problem. I will go onsite tomorrow to address and collect more traces to see if this is the case.
Regards
Pieter
02-28-2013 06:59 PM
Hi,
I have done the tests and collect logs.
Thanks!
Pieter
03-04-2013 12:30 AM
Hi Aokanlaon,
Have you had a moment to have a look at my lastest traces yet?
Thanks
Pieter
03-04-2013 04:32 AM
Pieter,
I am sorry I havent had time to look at it. I have been so busy! Hopefully I can look at it tonight..sorry again
Please rate all useful posts
"opportunity is a haughty goddess who waste no time with those who are unprepared"
03-04-2013 09:13 AM
Pieter,
Ok I had a look and unfortunately the log for IPVmS service stops at 11:17
CUCM sends a start media transmission for MOH at 11:23..So I cant see what happened on the server..
11:23:43.296 |MohDControl - stationOutputStartMediaTransmission tcpPID = [1.100.9.51468] myIP: fd020b0a (10.11.2.253)|1,100,17,23601.16^10.11.2.254^Port 57560
11:23:43.296 |MohDControl - RemoteIpAddr: fe020b0a (10.11.2.254) RemoteRtpPortNumber: 19542 msecPacketSize: 20 compressionType: 11|1,100,17,23601.16^10.11.2.254^Port 57560
However I see a lot of errors on the IPVMS component of CUCM
10:52:02.441 | [MTP][10.11.2.253]CTTCPSocket::HandleWrite *ERROR* EMPTY in TCP Write
10:52:02.441 | [CFB][10.11.2.253]CTTCPSocket::HandleWrite *ERROR* EMPTY in TCP Write
10:52:02.441 | [MOH][10.11.2.253]CTTCPSocket::HandleWrite *ERROR* EMPTY in TCP Write
10:52:02.445 | [ANN][10.11.2.253]CTTCPSocket::HandleWrite *ERROR* EMPTY in TCP Write
kMOHTFTPGoRequestFailed - Transfer of MOH source file to working path failed. Error Description:Error getting MOH source file File Name:Windows XP Aanmelden.g729.wav
Can you look download the logs again and see if you get any entry for 11:23. Its important to see what happens there.
The Audio source file CUCM is trying to use is "2"
Do you know if MOH works between IP phones. Do you get MOH when you do an internal call with this file?
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: