Callmanager Express MOH for Internal SCCP to SCCP Calls not working

faiqmahdi · ‎04-20-2016

Hi

I am having issue with Music on Hold for Internal Calls.

Music on Hold works for SIP to SCCP and SCCP to SIP

But It does not work SCCP to SCCP

!!

Here are the details of CUCME:

!!

interface GigabitEthernet0/0

ip address 10.20.30.2 255.255.255.0

ip pim dense-mode

duplex auto

speed auto

h323-gateway voip interface

h323-gateway voip bind srcaddr 10.20.30.2

!!

Router#sh ip multicast

Multicast Routing: enabled

Multicast Multipath: disabled

Multicast Route limit: No limit

Multicast Fallback group mode: Dense

Number of multicast boundaries configured with filter-autorp option: 0

MoFRR: Disabled

!!

ccm-manager music-on-hold

!!

ROUTER#dir

Directory of flash0:/

1 drw- 0 Apr 14 2016 17:18:34 +03:00 its

77 -rw- 496521 Apr 20 2016 14:08:38 +03:00 music-on-hold.au

83 -rw- 109748168 Jan 20 2016 15:56:20 +03:00 c2951-universalk9-mz.SPA.156-1.T0a.bin

!!

ROUTER#show voice moh-group

telephony-service

internal-call moh-group 0

G.711 Music-on-Hold file: moh enable-g711 "flash0:music-on-hold.au"

moh-file-buffer (not configured, default size 64 Kb)

Moh multicast 239.1.1.5 port 16384 route 10.20.30.2

=== no voice moh-groups configured ===

!!

ROUTER#show ephone moh

Skinny Music-on-Hold Process ID is 120.

-------------------------------------

Skinny Music On Hold Status - group 0

Active MOH clients 0 (max 1300), Media Clients 0, B-ACD Clients 0

File flash0:music-on-hold.au (not cached) type AU Media_Payload_G711Ulaw64k 160 bytes

Moh multicast 239.1.1.5 port 16384 route 10.20.30.2

G.729 Music-on-Hold File Status

No MOH file loaded

!!

Router#sh telephony

CONFIG (Version=11.0)

=====================

Version 11.0

ip source-address 10.20.30.2 port 2000

cnf-file location: flash:

cnf-file option: PER-PHONE

phone service webAccess 0

phone service displayOnWhenIncomingCall 1

phone service phoneBookWebAccess 1

mwi relay

moh enable-g711 "flash0:music-on-hold.au"

multicast moh 239.1.1.5 port 16384 route 10.20.30.2

Router#

Debugs:

Hold from SIP (9001) to SCCP (9000)à Working

Router#

000764: Apr 20 18:44:06.957 KSA: SkinnyAddMediaMohClient: MOH re-Negotiation is NOT needed, start Association

000765: Apr 20 18:44:06.957 KSA: Added callID 46 to MOH in position 0 with 1 clients

000766: Apr 20 18:44:06.961 KSA: skinnyMohHandleCCEvent:889: receive CC_EV_CALL_ASSOCIATED, calllID=46

000767: Apr 20 18:44:06.961 KSA: skinnyMohHandleCCEvent:924: find media_play_client

000768: Apr 20 18:44:06.961 KSA: media_play_client:

000769: Apr 20 18:44:06.961 KSA: media_play_client->callID=46

000770: Apr 20 18:44:06.961 KSA: media_play_client->context=0x0x22EB59C

000771: Apr 20 18:44:06.961 KSA: media_play_client->stream_id=1

000772: Apr 20 18:44:06.961 KSA: media_play_client->xmitFunc=0x0x0

000773: Apr 20 18:44:06.961 KSA: media_play_client->spi_context=0x0x0

000774: Apr 20 18:44:06.961 KSA: media_play_client->streamCallID=50

000775: Apr 20 18:44:06.961 KSA: media_play_client->codec=5

000776: Apr 20 18:44:06.961 KSA: media_play_client->xcodeXmitFunc=0x0

000777: Apr 20 18:44:06.961 KSA: media_play_client->xcodeNeeded=FALSE

000778: Apr 20 18:44:06.961 KSA: media_play_client->nego_codec= voipCodecG711ulaw

000779: Apr 20 18:44:06.961 KSA: media_play_client->srcXcodeStreamID=-1

000780: Apr 20 18:44:06.961 KSA: media_play_client->dstXcodeStreamID=-1

000781: Apr 20 18:44:06.961 KSA: skinnyMohHandleCCEvent:1012:Call ID 46 associated, disposition[0], pVdb[18C478C8], playFunc[966AABC], spi_context[13A26AB8]

000782: Apr 20 18:44:06.961 KSA: media_play_client:

000783: Apr 20 18:44:06.961 KSA: media_play_client->callID=46

000784: Apr 20 18:44:06.961 KSA: media_play_client->context=0x0x22EB59C

000785: Apr 20 18:44:06.961 KSA: media_play_client->stream_id=1

000786: Apr 20 18:44:06.961 KSA: media_play_client->xmitFunc=0x0x0

000787: Apr 20 18:44:06.961 KSA: media_play_client->spi_context=0x0x0

000788: Apr 20 18:44:06.961 KSA: media_play_client->streamCallID=50

000789: Apr 20 18:44:06.961 KSA: media_play_client->codec=5

000790: Apr 20 18:44:06.961 KSA: media_play_client->xcodeXmitFunc=0x0

000791: Apr 20 18:44:06.961 KSA: media_play_client->xcodeNeeded=FALSE

000792: Apr 20 18:44:06.961 KSA: media_play_client->nego_codec= voipCodecG711ulaw

000793: Apr 20 18:44:06.961 KSA: media_play_client->srcXcodeStreamID=-1

000794: Apr 20 18:44:06.961 KSA: media_play_client->dstXcodeStreamID=-1

000795: Apr 20 18:44:06.961 KSA: skinnyMohHandleCCEvent:1025: the existing call xcode info:: Dst[46,0]

000796: Apr 20 18:44:06.961 KSA: skinnyMohHandleCCEvent:1058: xcode NOT involves, codec is: voipCodecG711ulaw, nego_codec is:voipCodecG711ulaw

000797: Apr 20 18:44:06.961 KSA: skinnyMohHandleCCEvent:1230: ERROR: media_play_client->callID[46] has NO gccb

000798: Apr 20 18:44:06.961 KSA: media_play_client:

000799: Apr 20 18:44:06.961 KSA: media_play_client->callID=46

000800: Apr 20 18:44:06.961 KSA: media_play_client->context=0x0x22EB59C

000801: Apr 20 18:44:06.961 KSA: media_play_client->stream_id=1

000802: Apr 20 18:44:06.961 KSA: media_play_client->xmitFunc=0x0x966AABC

000803: Apr 20 18:44:06.961 KSA: media_play_client->spi_context=0x0x13A26AB8

Router#

000804: Apr 20 18:44:06.961 KSA: media_play_client->streamCallID=50

000805: Apr 20 18:44:06.961 KSA: media_play_client->codec=5

000806: Apr 20 18:44:06.961 KSA: media_play_client->xcodeXmitFunc=0x0

000807: Apr 20 18:44:06.961 KSA: media_play_client->xcodeNeeded=FALSE

000808: Apr 20 18:44:06.961 KSA: media_play_client->nego_codec= voipCodecG711ulaw

000809: Apr 20 18:44:06.961 KSA: media_play_client->srcXcodeStreamID=-1

000810: Apr 20 18:44:06.961 KSA: media_play_client->dstXcodeStreamID=-1

Router#

000811: Apr 20 18:44:08.929 KSA: ifs_read flash0:music-on-hold.au end of file at 490108 read 6413 = 496521

000812: Apr 20 18:44:08.933 KSA: moh-group 0: Tail fill from 24 at 0x444371A1 length 1587

Router#

000813: Apr 20 18:44:15.509 KSA: skinnyMohMediaClientCleanUp:128: Info: media_play_client->callID[46] has NO gccb

000814: Apr 20 18:44:15.509 KSA: skinnyMohMediaClientCleanUp:146: the existing call has xcode involved: stream3[0]

000815: Apr 20 18:44:15.509 KSA: MOH repack CallID 46 from position 0 to 0 (0 active)

000816: Apr 20 18:44:15.509 KSA: Removed callID 46 from MOH 0 clients at position 0

Router#

Hold from SCCP (9000) to SIP (9001) à Working

Router#

000820: Apr 20 18:45:34.473 KSA: skinny_find_mohgroup_by_dn: dn=100, chan=1, callState=9(HOLD), peer_dn=-1

000821: Apr 20 18:45:34.473 KSA: skinny_find_mohgroup_by_dn: external peer put on hold by DN(100), or hold ended

000822: Apr 20 18:45:34.473 KSA: skinny_match_mohgroup_by_dn_ext: ephone_dn_template->moh_group=0

000823: Apr 20 18:45:34.473 KSA: skinny_match_mohgroup_by_dn_ext: dn=100, chan=1, holder callState=9(HOLD)

000824: Apr 20 18:45:34.473 KSA: *** dn_index=100, incoming=1, dn_ext='9000', called#='9000', calling#='9001'

000825: Apr 20 18:45:34.473 KSA: Added DN 100 chan 1 to MOH-group 0 in position 0 with 1 clients

Router#

000826: Apr 20 18:45:36.737 KSA: DN 100 chan 1 switching to MOH process at 57412 (was 57410) timestamp_offset 0

Router#

000827: Apr 20 18:45:43.077 KSA: SkinnyDeleteMohClientDn: dn=100, chan=1

000828: Apr 20 18:45:43.077 KSA: skinny_get_moh_entry_from_dn: retrieving moh_group 0 from skinnyDP[100]

000829: Apr 20 18:45:43.077 KSA: MOH repack DN 100 from position 0 to 0 (0 active)

000830: Apr 20 18:45:43.077 KSA: Removed DN 100 from MOH-group 0 at position 0, 0 clients remained

Router#

Debugs:

Not Working:

Hold from SCCP (9000) to SCCP (9004) à Not Working

Router#

000840: Apr 20 18:48:58.151 KSA: ephone_hold_resume ignored for s2s set on dn=100 chan=1 hold=1 callID=56

000841: Apr 20 18:48:58.155 KSA: skinny_find_mohgroup_by_dn: dn=100, chan=1, callState=9(HOLD), peer_dn=4

000842: Apr 20 18:48:58.155 KSA: skinny_find_mohgroup_by_dn: internal peer_dn(4) put on hold by DN(100)

000843: Apr 20 18:48:58.155 KSA: skinny_find_mohgroup_by_dn: internal-call rule applies, use moh-group 0

000844: Apr 20 18:48:58.155 KSA: McastMoh is Available for moh-group 0

000845: Apr 20 18:48:58.155 KSA: SkinnyGetMohMulticastInfo: dn=4, chan=1, peer_dn=100, peer_chan=1, dn callState=4(CONNECTED), peer_dn callState=9(HOLD)

Router#

Router#internal-call rule applies, use moh-group 0

Router#

000846: Apr 20 18:49:08.432 KSA: SkinnyDeleteMohClientDn: dn=100, chan=1

000847: Apr 20 18:49:08.432 KSA: skinny_find_mohgroup_by_dn: dn=100, chan=1, callState=0(IDLE), peer_dn=4

000848: Apr 20 18:49:08.432 KSA: skinny_match_mohgroup_by_dn_ext: ephone_dn_template->moh_group=0

000849: Apr 20 18:49:08.432 KSA: skinny_match_mohgroup_by_dn_ext: dn=4, chan=1, holder callState=4(CONNECTED)

000850: Apr 20 18:49:08.432 KSA: *** dn_index=4, incoming=0, dn_ext='9004', called#='9000', calling#='9004'

000851: Apr 20 18:49:08.436 KSA: No MOH entry for DN 100 in moh-group 0

Router#

000852: Apr 20 18:49:10.548 KSA: MoH route If GigabitEthernet0/0 ETHERNET 10.20.30.2 via ARP

Router#