h323 problem

techguy · ‎05-13-2012

anyone knows the issue?

May 13 11:00:04.479: //239/97B8EB11806A/CCAPI/ccCallDisconnect:

Cause Value=18, Call Entry(Responsed=TRUE, Cause Value=18)

May 13 11:00:04.479: //239/97B8EB11806A/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

Alexander Maroukian · ‎05-13-2012

Hi,

No user responding.

No user responding

Typical scenarios include:

•The user is not answering the telephone.

18

Used when the called party does not respond to a call establishment message with either an alerting or connect indication within the time allotted. The number that is being dialed has an active D-channel, but the far end chooses not to answer.

HTH,

Alex

*Please rate helpful posts

techguy · ‎05-13-2012

but the called number (extension number where i am sending call) is not ringing

Alexander Maroukian · ‎05-13-2012

Maybe that is the problem. Do you get answer from the other side? Are you sending the call correctly?

You have posted only these two lines from the CCAPI with no other explanation and that is what they usualy mean.

Best regards,

Alex

techguy · ‎05-13-2012

Router=2901 with 4-port FXO Card

Connection plar = 3705 (which is extension registered on CUCM)

Both incoming and outgoing calls are not working

MCTRTR#sh tech-support | sec voice

voice-card 0

dspfarm

dsp services dspfarm

voice service voip

allow-connections h323 to h323

allow-connections h323 to sip

allow-connections sip to h323

allow-connections sip to sip

fax protocol t38 version 0 ls-redundancy 0 hs-redundancy 0 fallback none

h323

h225 connect-passthru

sip

bind control source-interface Loopback10

bind media source-interface Loopback10

registrar server

no update-callerid

voice class codec 1

codec preference 1 g711ulaw

codec preference 2 g711alaw

codec preference 3 g729r8

voice class h323 1

h225 timeout tcp establish 3

voice register global

mode cme

source-address 10.1.210.193 port 5060

max-dn 10

max-pool 10

authenticate register

voice register dn 1

number 3700

name Operator

voice register dn 2

number 3701

name PRO OFFICE

voice register dn 3

number 3702

name Rashid

voice register dn 4

number 3703

name Salim

voice register dn 5

number 3704

name test

voice register pool 1

id mac 0014.7F00.682A

number 1 dn 1

username mstuser1 password sale8833

codec g711ulaw

voice register pool 2

id mac 0014.7F00.64CA

number 1 dn 2

username mstuser2 password sale8833

codec g711ulaw

voice register pool 3

id mac 0014.7F00.6620

number 1 dn 3

username mstuser3 password sale8833

codec g711ulaw

voice register pool 4

id mac 0014.7F00.69D1

number 1 dn 4

username mstuser4 password sale8833

codec g711ulaw

voice register pool 5

id mac 0014.7F00.64A4

number 1 dn 5

username mstuser5 password sale8833

codec g711ulaw

voice-port 0/1/0

trunk-group FXO

input gain -3

output attenuation -1

echo-cancel coverage 64

no comfort-noise

cptone OM

connection plar opx 3705

music-threshold -45

bearer-cap Speech

caller-id enable

voice-port 0/1/1

trunk-group FXO

input gain -3

output attenuation -1

echo-cancel coverage 64

no comfort-noise

cptone OM

connection plar opx 3705

music-threshold -45

bearer-cap Speech

caller-id enable

voice-port 0/1/2

trunk-group FXO

input gain -3

output attenuation -1

echo-cancel coverage 64

no comfort-noise

cptone OM

connection plar opx 3705

music-threshold -45

bearer-cap Speech

caller-id enable

voice-port 0/1/3

trunk-group FXO

input gain -3

output attenuation -1

echo-cancel coverage 64

no comfort-noise

cptone OM

connection plar opx 3705

music-threshold -45

bearer-cap Speech

caller-id enable

dial-peer voice 16 voip

destination-pattern 2000

session protocol sipv2

session target ipv4:10.1.210.200

dtmf-relay sip-notify

codec g711ulaw

no vad

dial-peer voice 2 pots

trunkgroup FXO

tone ringback alert-no-PI

description National

destination-pattern 92.......$

fax rate disable

prefix 2

dial-peer voice 4 pots

trunkgroup FXO

tone ringback alert-no-PI

description International

destination-pattern 900.T

fax rate disable

prefix 00

dial-peer voice 1 pots

description ***Incoming from PSTN***

incoming called-number .

direct-inward-dial

dial-peer voice 3702 voip

description To CallManager Pub

destination-pattern 37..$

session target ipv4:10.50.2.3

voice-class codec 1

voice-class h323 1

dtmf-relay h245-alphanumeric

req-qos guaranteed-delay audio

acc-qos guaranteed-delay audio

fax-relay ecm disable

fax rate 14400

fax nsf 000000

fax protocol pass-through g711ulaw

no vad

dial-peer voice 3 pots

trunkgroup FXO

destination-pattern 99.......

prefix 9

dial-peer voice 3701 voip

description To CallManager Pub

destination-pattern 37..$

session target ipv4:10.50.2.4

voice-class codec 1

voice-class h323 1

dtmf-relay h245-alphanumeric

req-qos guaranteed-delay audio

acc-qos guaranteed-delay audio

fax-relay ecm disable

fax rate 14400

fax nsf 000000

fax protocol pass-through g711ulaw

no vad

Adrian Saavedra · ‎05-13-2012

Hi,

Some ideas:

- Use debug voip dialpeer to see dial-peer matching to make sure you're routing calls correctly.
- You don't need dial-peer 1 pots since true DID services in FXO can never be utilized. You're using connection plar to route inbound calls to a default location (perhaps to a recepcionist).
- To follow best practices (when you have Pub and Sub) you should route calls primarily to Sub and if Sub is down then you route calls to Pub. So, if dial-peer 3701 is for Sub you should configure it with a higher preference than dial-peer for Pub.
- Check if the h323 gw in UCM is defined with the IP address of the router's interface that has the h323-gateway voip bind srcaddr command.
- Check CSS of the h323 gw defined in UCM.

Hope it helps,

Kind regards,

- Adrian.

techguy · ‎05-14-2012

Following debug is ON to fix the issue

Voice Port Module port specific debugging is on for 0/1/0

CCAPI:

debug voip ccapi inout is ON (filter is OFF)

May 14 08:10:14.430: //-1/118CE82D87B3/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=9820

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=0

cisco-rdnplan=0

cisco-rdnpi=0

cisco-rdnsi=0

cisco-redirectreason=0 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

May 14 08:10:14.430: //-1/118CE82D87B3/CCAPI/cc_api_call_setup_ind_common:

Interface=0x3176D83C, Call Info(

Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=9820(TON=Unknown, NPI=Unknown),

Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE,

Incoming Dial-peer=0, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=FALSE,

Source Trkgrp Route Label=FXO, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1

May 14 08:10:14.430: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 14 08:10:14.430: :cc_get_feature_vsa malloc success

May 14 08:10:14.430: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 14 08:10:14.430: cc_get_feature_vsa count is 1

May 14 08:10:14.430: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 14 08:10:14.430: :FEATURE_VSA attributes are: feature_name:0,feature_time:805690104,feature_id:83

May 14 08:10:14.430: //5412/118CE82D87B3/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=9820(TON=Unknown, NPI=Unknown))

May 14 08:10:14.430: //5412/118CE82D87B3/CCAPI/cc_process_call_setup_ind:

Event=0x2B913860

May 14 08:10:14.430: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Try with the demoted called number 9820

May 14 08:10:14.434: //5412/118CE82D87B3/CCAPI/ccCallSetContext:

Context=0x32E9F4F8

May 14 08:10:14.434: //5412/118CE82D87B3/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 5412 with tag 0 to app "_ManagedAppProcess_Default"

May 14 08:10:14.434: //5412/118CE82D87B3/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

May 14 08:10:14.434: //5412/118CE82D87B3/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=FALSE, Mode=0,

Outgoing Dial-peer=1, Params=0x32E9A4E8, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)

May 14 08:10:14.434: //5412/118CE82D87B3/CCAPI/ccCheckClipClir:

In: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

May 14 08:10:14.434: //5412/118CE82D87B3/CCAPI/ccCheckClipClir:

Out: Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

May 14 08:10:14.434: //5412/118CE82D87B3/CCAPI/ccCallSetupRequest:

Destination Pattern=9820, Called Number=9820, Digit Strip=FALSE

May 14 08:10:14.434: //5412/118CE82D87B3/CCAPI/ccCallSetupRequest:

Calling Number=(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=9820(TON=Unknown, NPI=Unknown),

Redirect Number=, Display Info=

Account Number=, Final Destination Flag=TRUE,

Guid=118CE82D-9CD3-11E1-87B3-943A7377E196, Outgoing Dial-peer=1

May 14 08:10:14.434: //5412/118CE82D87B3/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=9820

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=0

cisco-rdnplan=0

cisco-rdnpi=0

cisco-rdnsi=0

cisco-redirectreason=0 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

May 14 08:10:14.434: //5412/118CE82D87B3/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x2B69F1EC, Interface Type=3, Destination=, Mode=0x0,

Call Params(Calling Number=,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=9820(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=RegularLine, FinalDestinationFlag=TRUE, Outgoing Dial-peer=1, Call Count On=FALSE,

Source Trkgrp Route Label=FXO, Target Trkgrp Route Label=, tg_label_flag=1, Application Call Id=)

May 14 08:10:14.434: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 14 08:10:14.434: :cc_get_feature_vsa malloc success

May 14 08:10:14.434: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 14 08:10:14.434: cc_get_feature_vsa count is 2

May 14 08:10:14.434: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

May 14 08:10:14.434: :FEATURE_VSA attributes are: feature_name:0,feature_time:805689880,feature_id:84

May 14 08:10:14.434: //5413/118CE82D87B3/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1

May 14 08:10:14.434: //5413/118CE82D87B3/CCAPI/ccCallSetContext:

Context=0x32E9A498

May 14 08:10:14.434: //5412/118CE82D87B3/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=1

May 14 08:10:14.438: //5413/118CE82D87B3/CCAPI/cc_api_call_proceeding:

Interface=0x2B69F1EC, Progress Indication=NULL(0)

May 14 08:10:15.158: //5413/118CE82D87B3/CCAPI/cc_api_call_disconnected:

Cause Value=17, Interface=0x2B69F1EC, Call Id=5413

May 14 08:10:15.158: //5413/118CE82D87B3/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=17, Retry Count=0)

May 14 08:10:15.158: //5412/118CE82D87B3/CCAPI/ccCallReleaseResources:

release reserved xcoding resource.

May 14 08:10:15.158: //5413/118CE82D87B3/CCAPI/ccCallSetAAA_Accounting:

Accounting=0, Call Id=5413

May 14 08:10:15.158: //5413/118CE82D87B3/CCAPI/ccCallDisconnect:

Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=17)

May 14 08:10:15.158: //5413/118CE82D87B3/CCAPI/ccCallDisconnect:

Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17)

May 14 08:10:15.158: //5413/118CE82D87B3/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x2B69F1EC, Tag=0x0, Call Id=5413,

Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0)

May 14 08:10:15.158: //5413/118CE82D87B3/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

May 14 08:10:15.158: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

May 14 08:10:15.158: :cc_free_feature_vsa freeing 3005DA10

May 14 08:10:15.158: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

May 14 08:10:15.158: vsacount in free is 1

May 14 08:10:15.158: //5412/118CE82D87B3/CCAPI/ccCallDisconnect:

Cause Value=17, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

May 14 08:10:15.158: //5412/118CE82D87B3/CCAPI/ccCallDisconnect:

Cause Value=17, Call Entry(Responsed=TRUE, Cause Value=17)

May 14 08:10:15.158: //5412/118CE82D87B3/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

May 14 08:10:45.158: //5412/118CE82D87B3/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x3176D83C, Tag=0x0, Call Id=5412,

Call Entry(Disconnect Cause=17, Voice Class Cause Code=0, Retry Count=0)

May 14 08:10:45.158: //5412/118CE82D87B3/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

May 14 08:10:45.158: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

May 14 08:10:45.158: :cc_free_feature_vsa freeing 3005DAF0

May 14 08:10:45.158: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

May 14 08:10:45.158: vsacount in free is 0

techguy · ‎05-14-2012

DEBUG H245 OUTPUT:

May 14 10:53:03.793: Changing to new event: CONNECT

h323chan_chn_connect: connecting to 10.50.2.4:1720

May 14 10:53:03.793: h323chan_gw_conn: Created socket fd=3

May 14 10:53:03.793: h323chan_gw_conn: connect in progress on fd=3h323chan_chn_connect: using fd=3, owner_data(ccb) 0x3001D3D4

changing from NONE state to CONNECTING state

May 14 10:53:04.161: h323chan_chn_process_read_socket: fd=3 of type CONNECT_PENDING has data

May 14 10:53:04.161: Changing to new event: CONNECTED

changing from CONNECTING state to CONNECTED state

May 14 10:53:04.161: h245_encode_faststart

May 14 10:53:04.161: h323chan_chn_process_read_socket: fd=3 of type CONNECTED has data

May 14 10:53:04.161: h323chan_recvdata: No Data on fd=3

PROCESS_READ: FAILED/NOT COMPLETE,rc 10, fd=3

May 14 10:53:19.161: h323chan_close: TCP connection from fd=3 closed

May 14 10:53:19.161: Changing to new event: CONNECT

h323chan_chn_connect: connecting to 10.50.2.3:1720

May 14 10:53:19.161: h323chan_gw_conn: Created socket fd=3

May 14 10:53:19.161: h323chan_gw_conn: connect in progress on fd=3h323chan_chn_connect: using fd=3, owner_data(ccb) 0x3001D3D4

changing from NONE state to CONNECTING state

May 14 10:53:19.785: h323chan_chn_process_read_socket: fd=3 of type CONNECT_PENDING has data

May 14 10:53:19.785: Changing to new event: CONNECTED

changing from CONNECTING state to CONNECTED state

May 14 10:53:19.785: h245_encode_faststart

May 14 10:53:19.789: h323chan_chn_process_read_socket: fd=3 of type CONNECTED has data

May 14 10:53:19.789: h323chan_recvdata: No Data on fd=3

PROCESS_READ: FAILED/NOT COMPLETE,rc 10, fd=3

May 14 10:53:34.789: h323chan_close: TCP connection from fd=3 closed