Call Disconnect - Busy Signal

Lulzim Islami · ‎11-14-2012

Hi There,

I have CUCM 7.1 and all calls are working fine through PRI line except Information Center and my Telco Help Desk Number. All numbers I can dial thru E1 PRI only 977 and 988 I'm not able to call them, it gives me a busy signal all the time. It use to work until few weeks but suddenly it stopped working.

Any help will be appreciated.

Thank you,

Router Config:

voice-card 0

dspfarm

dsp services dspfarm

!

voice-card 1

dspfarm

dsp services dspfarm

!

voice class codec 2

codec preference 1 g711alaw

codec preference 2 g729r8

codec preference 3 g729br8

codec preference 4 g723ar53

codec preference 5 g723ar63

codec preference 6 g723r53

codec preference 7 g723r63

codec preference 8 g726r16

codec preference 9 g726r24

codec preference 10 g726r32

codec preference 11 g722-64

codec preference 12 g722-48

codec preference 13 g722-56

codec preference 14 g711ulaw

!

voice class h323 1

h225 timeout tcp establish 4

dial-peer voice 2 voip

description Primary CUCM

answer-address .

destination-pattern [1-8]...

translate-outgoing calling 112

session target ipv4:10.150.10.2

voice-class codec 2

voice-class h323 1

no vad

!

dial-peer voice 3 voip

description Secondary CUCM

preference 1

answer-address .

destination-pattern [1-8]...

translate-outgoing calling 112

session target ipv4:10.150.10.3

voice-class codec 2

voice-class h323 1

dtmf-relay h245-alphanumeric

no vad

!

dial-peer voice 1 pots

description Incoming PSTN Calls

destination-pattern .T

incoming called-number .

direct-inward-dial

port 1/0:15

forward-digits all

!

and here is the debug:

081963: Nov 14 13:30:32.709 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_api_supported_data:

data_mode=0x10082

081964: Nov 14 13:30:32.711 UTC: //138426/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

Call Entry Is Not Found

081965: Nov 14 13:30:32.711 UTC: //-1/80115175AE0D/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=Shemsije Rama Zogu

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

cisco-ani=1115

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=1

dest=988

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-lastrdn=

cisco-rdntype=-1

cisco-rdnplan=-1

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

081966: Nov 14 13:30:32.711 UTC: //-1/80115175AE0D/CCAPI/cc_api_call_setup_ind_common:

Interface=0x1F0658C0, Call Info(

Calling Number=1115,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

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

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

Incoming Dial-peer=2, Progress Indication=NULL(0), Calling IE Present=TRUE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=138426

081967: Nov 14 13:30:32.711 UTC: //-1/80115175AE0D/CCAPI/cc_api_call_setup_ind_common:

Interface Type=0, Protocol=1

081968: Nov 14 13:30:32.711 UTC: //-1/80115175AE0D/CCAPI/ccCheckClipClir:

In: Calling Number=1115(TON=Unknown, NPI=Unknown, Sc

3945voice#reening=User, Passed, Presentation=Allowed)

081969: Nov 14 13:30:32.711 UTC: //-1/80115175AE0D/CCAPI/ccCheckClipClir:

Calling Party Number Is User Provided

081970: Nov 14 13:30:32.711 UTC: //-1/80115175AE0D/CCAPI/ccCheckClipClir:

Out: Calling Number=1115(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

081971: Nov 14 13:30:32.711 UTC: //-1/80115175AE0D/CCAPI/cc_api_call_setup_ind_common:

After Number Translation Checking:

Calling Number=1115(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

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

081972: Nov 14 13:30:32.711 UTC: //138426/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

Total Call Count=3, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)

081973: Nov 14 13:30:32.711 UTC: //138426/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

Total Call Count=4

081974: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

081975: Nov 14 13:30:32.711 UTC: :cc_get_feature_vsa malloc success

081976: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

081977: Nov 14 13:30:32.711 UTC: cc_get_feature_vsa count is 7

081978: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

081979: Nov 14 13:30:32.711 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:550405856,feature_id:171738

081980: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_init_call_entry:

081981: Nov 14 13:30:32.711 UTC: call_init_entry calle is 21218E10,callid is 138426

081982: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/cc_insert_guid_pod_entry:

Incoming=TRUE, Call Id=138426

081983: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=1115(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

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

081984: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/cc_incr_if_call_volume:

Remote IP Address=10.150.10.3, Hwidb=GigabitEthernet0/0

081985: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/cc_incr_if_call_volume:

Total Call Count=4, Voip Call Count=4, MMoip Call Count=0

081986: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/cc_process_call_setup_ind:

Event=0x20502118

081987: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup:

Matching Parameters; Called Number=988, Call Transfer Consult Id=

081988: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Searching Node;

Called Number=988, Call Transfer Consult Id=

081989: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:

081990: Nov 14 13:30:32.711 UTC: Comparing SSOpCode; 0x50 and 0x0

081991: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:

081992: Nov 14 13:30:32.711 UTC: Comparing SSOpCode; 0x12 and 0x0

081993: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Try with the demoted called number 988

081994: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:

081995: Nov 14 13:30:32.71

3945voice#1 UTC: Comparing SSOpCode; 0x50 and 0x0

081996: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:

081997: Nov 14 13:30:32.711 UTC: Comparing SSOpCode; 0x12 and 0x0

081998: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup:

No Matching Node

081999: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/ccCallSetContext:

Context=0x20DCE358

082000: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 138426 with tag 2 to app "_ManagedAppProcess_Default"

082001: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

082002: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:

VALIDATING MLPP INFO:- ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)]

082003: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:

Unsupported MLPP Service Domain Network 0

082004: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_set_mlpp_info:

Invalid MLPP Information

082005: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_is_precedence_mlpp_info:

Precedence not above Routine

082006: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_copy_mlpp_info:

BEFORE COPY - SRC MLPP INFO:ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] DEST MLPP INFO:ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)]

082007: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_copy_mlpp_info:

AFTER COPY - SRC MLPP INFO:ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] DEST MLPP INFO:ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)]

082008: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_copy_mlpp_info:

BEFORE COPY - SRC MLPP INFO:ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] DEST MLPP INFO:ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)]

082009: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_copy_mlpp_info:

AFTER COPY - SRC MLPP INFO:ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)] DEST MLPP INFO:ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)]

082010: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:

VALIDATING MLPP INFO:- ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[0 (INTERNAL_PRECEDENCE_0)]

082011: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:

Unsupported MLPP Service Domain Network 0

082012: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_set_mlpp_info:

Invalid MLPP Information

082013: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_is_precedence_mlpp_info:

Precedence not above Routine

082014: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:

VALIDATING MLPP INFO:- ServiceDomain:[none (0)] DomainIdentifier:[000000] PrecedenceLevel:[-1 (PRECEDENCE_LEVEL_NONE)] NormalizedPrecedence:[-1 (PRECEDENCE_LEVEL_NONE)]

082015: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_validate_mlpp_info:

Unsupported MLPP Service Domain Network 0

082016: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_set_mlpp_info:

Invalid MLPP Information

082017: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_is_precedence_mlpp_info:

Precedence not above Routine

082018: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/ccCallSetupRequest:

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

Outgoing Dial-peer=1, Params=0x20DDCEC4, Progress Indication=NULL(0)

082019: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/ccCheckClipClir:

In: Calling Number=1115(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

082020: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/ccCheckClipClir:

Calling Party Number Is User Provided

082021: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/ccCheckClipClir:

Out: Calling Number=1115(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)

082022: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/ccCallSetupRequest:

Destination Pattern=.T, Called Number=988, Digit Strip=TRUE

082023: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/ccCallSetupRequest:

Calling Number=1115(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

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

Redirect Number=, Display Info=

Account Number=Shemsije Rama Zogu, Final Destination Flag=TRUE,

Guid=80115175-F89C-310A-AE0D-24020A8801D8, Outgoing Dial-peer=1

082024: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=Shemsije Rama Zogu

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

cisco-ani=1115

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=1

dest=988

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-lastrdn=

cisco-rdntype=-1

cisco-rdnplan=-1

cisco-rdnpi=-1

cisco-rdnsi=-1

cisco-redirectreason=-1 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

082025: Nov 14 13:30:32.711 UTC: //138426/80115175AE0D/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x20566D30, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=1115,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),

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

Subscriber Type Str=Unknown, FinalDestination

3945voice#Flag=TRUE, Outgoing Dial-peer=1, Call Count On=FALSE,

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

082026: Nov 14 13:30:32.711 UTC: //138427/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

Total Call Count=4, Call Entry(Call Count On=FALSE, Incoming Call=FALSE)

082027: Nov 14 13:30:32.711 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

082028: Nov 14 13:30:32.713 UTC: :cc_get_feature_vsa malloc success

082029: Nov 14 13:30:32.713 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

082030: Nov 14 13:30:32.713 UTC: cc_get_feature_vsa count is 8

082031: Nov 14 13:30:32.713 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

082032: Nov 14 13:30:32.713 UTC: :FEATURE_VSA attributes are: feature_name:0,feature_time:550403840,feature_id:171739

082033: Nov 14 13:30:32.713 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_init_call_entry:

082034: Nov 14 13:30:32.713 UTC: call_init_entry calle is 2121A5F0,callid is 138427

082035: Nov 14 13:30:32.713 UTC: //138426/80115175AE0D/CCAPI/cc_peer_bind:

Bind=TRUE, Binder Call Id=138426, Bindee Call Id=138427

082036: Nov 14 13:30:32.713 UTC: //138427/80115175AE0D/CCAPI/cc_insert_guid_pod_entry:

Incoming=FALSE, Call Id=138427

082037: Nov 14 13:30:32.713 UTC: //138427/80115175AE0D/CCAPI/cc_set_voice_port_value:

CC_IF_TELEPHONY: Echo=0, Playout=0

082038: Nov 14 13:30:32.713 UTC: //138427/80115175AE0D/CCAPI/ccIFCallSetupRequestPrivate:

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

082039: Nov 14 13:30:32.713 UTC: //138426/80115175AE0D/CCAPI/ccCallSetupRequest:exit@2607

082040: Nov 14 13:30:32.713 UTC: //138427/80115175AE0D/CCAPI/ccCallSetContext:

Context=0x20DDCE78

082041: Nov 14 13:30:32.713 UTC: //138426/80115175AE0D/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=1

082042: Nov 14 13:30:32.713 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_set_outpulsed_digits:

outpulsed_dialstring=988 set

082043: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/cc_api_call_disconnected:

Cause Value=31, Interface=0x20566D30, Call Id=138427

082044: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/cc_api_call_disconnected:

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

082045: Nov 14 13:30:32.735 UTC: //138426/xxxxxxxxxxxx/CCAPI/ccCallReleaseResources:

release reserved xcoding resource.

082046: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/ccCallSetAAA_Accounting:

Accounting=0, Call Id=138427

082047: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/ccCallDisconnect:

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

082048: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/ccCallDisconnect:

Start Calling Accounting;

Call Entry(Incoming=FALSE)

082049: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/ccCallDisconnect:

Cause Value=31, Call Entry(Disconnect Cause=31)

082050: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/ccCallDisconnect:

Call Entry(Disconnect Cause=31)

082051: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/ccCallDisconnect:

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

082052: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

082053: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x20566D30, Tag=0x0, Call Id=138427,

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

082054: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

082055: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/cc_delete_guid_pod_entry:

Incoming=FALSE

082056: Nov 14 13:30:32.735 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

082057: Nov 14 13:30:32.735 UTC: :cc_free_feature_vsa freeing 20CE7EF8

082058: Nov 14 13:30:32.735 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

082059: Nov 14 13:30:32.735 UTC: vsacount in free is 7

082060: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/cc_delete_call_entry:

Total Call Count=4, Call Entry(Call Count On=FALSE, Incoming Call=FALSE)

082061: Nov 14 13:30:32.735 UTC: //138427/80115175AE0D/CCAPI/cc_delete_call_entry:

Deleting profileTable[0x20BB8E2C]

082062: Nov 14 13:30:32.735 UTC: //138427/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

Call Entry Is Not Found

082063: Nov 14 13:30:32.735 UTC: //138427/xxxxxxxxxxxx/CCAPI/ccGetCallGCID:

Bad Call ID: callEntry=NULL

082064: Nov 14 13:30:32.735 UTC: //138427/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

Call Entry Is Not Found

082065: Nov 14 13:30:32.735 UTC: //138427/xxxxxxxxxxxx/CCAPI/ccSetCallGCID:

Bad Call ID: callEntry=NULL

082066: Nov 14 13:30:32.735 UTC: //138427/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

Call Entry Is Not Found

082067: Nov 14 13:30:32.735 UTC: //0/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

Call Entry Is Not Found

082068: Nov 14 13:30:32.735 UTC: //0/xxxxxxxxxxxx/CCAPI/cc_api_call_preempt_done:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

082069: Nov 14 13:30:32.735 UTC: //138427/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

Call Entry Is Not Found

082070: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/ccCallDisconnect:

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

082071: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/ccCallDisconnect:

Start Calling Accounting;

Call Entry(Incoming=TRUE)

082072: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/ccCallDisconnect:

Cause Value=31, Call Entry(Disconnect Cause=0)

082073: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/ccCallDisconnect:

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

082074: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

082075: Nov 14 13:30:32.735 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_api_icpif:

ExpectFactor=0xA

082076: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/cc_decr_if_call_volume:

Remote IP Address=10.150.10.3, Hwidb=GigabitEthernet0/0

082077: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/cc_decr_if_call_volume:

Total Call Count=3, Voip Call Count=3, MMoip Call Count=0

082078: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x1F0658C0, Tag=0x0, Call Id=138426,

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

082079: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

082080: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/cc_delete_guid_pod_entry:

Incoming=TRUE

082081: Nov 14 13:30:32.735 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

082082: Nov 14 13:30:32.735 UTC: :cc_free_feature_vsa freeing 20CE86D8

082083: Nov 14 13:30:32.735 UTC: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

082084: Nov 14 13:30:32.735 UTC: vsacount in free is 6

082085: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/cc_delete_call_entry:

Total Call Count=4, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)

082086: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/cc_delete_call_entry:

Total Call Count=3

082087: Nov 14 13:30:32.735 UTC: //138426/80115175AE0D/CCAPI/cc_delete_call_entry:

Deleting profileTable[0x20BB7D24]

082088: Nov 14 13:30:32.735 UTC: //0/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

Call Entry Is Not Found

082089: Nov 14 13:30:32.735 UTC: //0/xxxxxxxxxxxx/CCAPI/cc_api_call_preempt_done:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

3945voice#un all

Chris Deren · ‎11-14-2012

Your dial peer destination-pattern is:

destination-pattern [1-8]...

going to call agent (assuming CUCM), everything else goes out PSTN, is this what you want?

Can you post "debug isdn q931"?

HTH,

Chris

Lulzim Islami · ‎11-14-2012

Hi Chris,

Yes, destination-pattern [1-8]... is my Call Agent CUCM, outgoing calls are going thru PSTN.

Here is the isdn debug:

3945voice#

082553: Nov 14 14:37:49.080 UTC: ISDN Se1/0:15 Q931: Applying typeplan for sw-type 0x12 is 0x0 0x0, Calling num 0385017011115

082554: Nov 14 14:37:49.080 UTC: ISDN Se1/0:15 Q931: Sending SETUP callref = 0x13C0 callID = 0xFD2E switch = primary-net5 interface = User

082555: Nov 14 14:37:49.080 UTC: ISDN Se1/0:15 Q931: TX -> SETUP pd = 8 callref = 0x13C0

Bearer Capability i = 0x8090A3

Standard = CCITT

Transfer Capability = Speech

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0xA9839E

Exclusive, Channel 30

Calling Party Number i = 0x0081, '0385017011115'

3945voice#

Plan:Unknown, Type:Unknown

Called Party Number i = 0x80, '549411'

Plan:Unknown, Type:Unknown

082556: Nov 14 14:37:49.118 UTC: ISDN Se1/0:15 Q931: RX <- SETUP_ACK pd = 8 callref = 0x93C0

Channel ID i = 0xA9839E

Exclusive, Channel 30

082557: Nov 14 14:37:49.258 UTC: ISDN Se1/0:15 Q931: RX <- ALERTING pd = 8 callref = 0x93C0

Progress Ind i = 0x8488 - In-band info or appropriate now available

Progress Ind i = 0x8282 - Destination address is non-ISDN

3945voice#

082558: Nov 14 14:37:54.119 UTC: ISDN Se1/0:15 Q931: RX <- SETUP pd = 8 callref = 0x311E

Bearer Capability i = 0x9090A3

Standard = CCITT

Transfer Capability = 3.1kHz Audio

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0xA98391

Exclusive, Channel 17

Progress Ind i = 0x8283 - Origination address is non-ISDN

Calling Party Number i = 0x0083, '038236681'

Plan:Unknown, Type:Unknown

082559: Nov 14 14:37:54.119 UTC: ISDN Se1/0:15 Q931: Received SETUP callref = 0xB11E callID = 0x7664 switch = primary-net5 interface = User

082560: Nov 14 14:37:54.121 UTC: ISDN Se1/0:15 Q931: TX -> SETUP_ACK pd = 8 callref = 0xB11E

Channel ID i = 0xA98391

Exclusive, Channel 17

082561: Nov 14 14:37:54.193 UTC: ISDN Se1/0:15 Q931: RX <- CONNECT pd = 8 callref = 0x93C0

Progress Ind i = 0x8282 - Destination address is non-ISDN

Date/Time i = 0x0C0B0E0F25

Date (dd-mm-yr) = 12-11-14

Time (hr:mnt:sec) = 15:37:218

082562: Nov 14 14:37:54.193 UTC: %ISDN-6-CONNECT: Interface Serial1/0:29 is now connected to 549411 N/A

082563: Nov 14 14:37:54.193 UTC: ISDN Se1/0:15 Q931: TX -> CONNECT_ACK pd = 8 callref = 0x13C0

082564: Nov 14 14:37:54.199 UTC: ISDN Se1/0:15 Q931: RX <- FACILITY pd = 8 callref = 0x93C0

Facility i = 0x91A113020269E9020122300AA1053003020101820100

Protocol Profile = Remote Operations Protocol

0xA113020269E9020122300AA1053003020101820100

Component = Invoke component

Invoke Id = 27113

Operation = AOCDChargingUnit

082565: Nov 14 14:37:54.203 UTC: ISDN Se1/0:15 Q931: RX <- INFORMATION pd = 8 callref = 0x311E

Called Party Number i = 0x81, '1'

Plan:ISDN, Type:Unknown

3945voice#

082566: Nov 14 14:37:54.405 UTC: ISDN Se1/0:15 Q931: RX <- INFORMATION pd = 8 callref = 0x311E

Called Party Number i = 0x81, '4'

Plan:ISDN, Type:Unknown

082567: Nov 14 14:37:54.597 UTC: ISDN Se1/0:15 Q931: RX <- INFORMATION pd = 8 callref = 0x311E

Called Party Number i = 0x81, '5'

Plan:ISDN, Type:Unknown

082568: Nov 14 14:37:54.801 UTC: ISDN Se1/0:15 Q931: RX <- INFORMATION pd = 8 callref = 0x311E

Called Party Number i = 0x81, '0'

Plan:ISDN, Type:Unknown

082569: Nov 14 14:37:54.905 UTC: ISDN Se1/0:15 Q931: TX -> CALL_PROC pd = 8 callref = 0xB11E

082570: Nov 14 14:37:54.907 UTC: ISDN Se1/0:15 Q931: TX -> ALERTING pd = 8 callref = 0xB11E

3945voice#

082571: Nov 14 14:37:58.194 UTC: ISDN Se1/0:15 Q931: TX -> CONNECT pd = 8 callref = 0xB11E

082572: Nov 14 14:37:58.224 UTC: ISDN Se1/0:15 Q931: RX <- CONNECT_ACK pd = 8 callref = 0x311E

082573: Nov 14 14:37:58.224 UTC: %ISDN-6-CONNECT: Interface Serial1/0:16 is now connected to 038236681 N/A

082574: Nov 14 14:37:58.268 UTC: %ISDN-6-DISCONNECT: Interface Serial1/0:29 disconnected from 549411 , call lasted 4 seconds

3945voice#

Lulzim Islami · ‎11-14-2012

The previous debug was from the number that i can dial, here is the debug from the number that I'm getting bussy signal:

3945voice#

082642: Nov 14 14:43:50.857 UTC: ISDN Se1/0:15 Q931: Applying typeplan for sw-type 0x12 is 0x0 0x0, Calling num 1200

082643: Nov 14 14:43:50.857 UTC: ISDN Se1/0:15 Q931: Sending SETUP callref = 0x13CB callID = 0xFD39 switch = primary-net5 interface = User

082644: Nov 14 14:43:50.857 UTC: ISDN Se1/0:15 Q931: TX -> SETUP pd = 8 callref = 0x13CB

Bearer Capability i = 0x8890

Standard = CCITT

Transfer Capability = Unrestricted Digital

Transfer Mode = Circuit

Transfer Rate = 64 kbit/s

Channel ID i = 0xA9839F

Exclusive, Channel 31

Calling Party Number i = 0x0081, '1200'

3945voice#

Plan:Unknown, Type:Unknown

Called Party Number i = 0x80, '988'

Plan:Unknown, Type:Unknown

082645: Nov 14 14:43:50.877 UTC: ISDN Se1/0:15 Q931: RX <- RELEASE_COMP pd = 8 callref = 0x93CB

Cause i = 0x829F - Normal, unspecified

3945voice#un all

082646: Nov 14 14:43:53.898 UTC: ISDN Se1/0:15 Q931: RX <- INFORMATION pd = 8 callref = 0x93C9

Display i = '1 UNITS'

3945voice#un all

Chris Deren · ‎11-14-2012

OK, her you are only sending 3 digits, can you post "debug voice dialpeer" for the call that fails?

Chris

Chris Deren · ‎11-14-2012

So, your destination pattern is expectoing 4 digits, yet your call comes in with 6 digits, is this the intent? Can you also post "debug voice dialpeer" and let us know what number you dialed, as there appears to be more than 1 call here.

Chris

Lulzim Islami · ‎11-14-2012

Chris,

4 digits are my internal extensions and you're right there are 2 calls made previously, first dialed number is 549 411 which is working fine, and second debug is dialed number 988 on a same telco provider and gives me busy tone.

Here is the debug from dialed number 988.

3945voice#

082774: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpAssociateIncomingPeerCore:

Calling Number=1115, Called Number=988, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

082775: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpAssociateIncomingPeerCore:

Result=Success(0) after DP_MATCH_ANSWER; Incoming Dial-peer=2

082776: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

082777: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpAssociateIncomingPeerCore:

Calling Number=1115, Called Number=988, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

082778: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpAssociateIncomingPeerCore:

Result=Success(0) after DP_MATCH_ANSWER; Incoming Dial-peer=2

082779: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

082780: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersCore:

Calling Number=, Called Number=988, Peer Info Type=DIALPEER_INFO_SPEECH

082781: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=988

082782: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

082783: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchSafModulePlugin:

dialstring=988, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

082784: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=1

082785: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersCore:

Calling Number=, Called Number=988, Peer Info Type=DIALPEER_INFO_SPEECH

082786: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=988

082787: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

082788: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchSafModulePlugin:

dialstring=988, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

082789: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=1

082790: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=988, Called Number=988, Peer Info Type=DIALPEER_INFO_SPEECH

082791: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=988

082792: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

082793: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=988, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

082794: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=1

082795: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Calling Number=988, Called Number=, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

082796: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=Success(0) after DP_MATCH_ANSWER; Incoming Dial-peer=2

082797: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

082798: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Calling Number=988, Called Number=, Voice-Interface=0x0,

Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,

Peer Info Type=DIALPEER_INFO_SPEECH

082799: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:

Result=Success(0) after DP_MATCH_ANSWER; Incoming Dial-peer=2

082800: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0

082801: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Calling Number=, Called Number=988, Peer Info Type=DIALPEER_INFO_SPEECH

082802: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=988

082803: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

082804: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin:

dialstring=988, saf_enabled=0, saf_dndb_lookup=1, dp_result=0

082805: Nov 14 14:48:42.425 UTC: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=1

082806: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersCore:

3945voice#Calling Number=, Called Number=988, Peer Info Type=DIALPEER_INFO_SPEECH

082807: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersCore:

Match Rule=DP_MATCH_DEST; Called Number=988

082808: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersCore:

Result=Success(0) after DP_MATCH_DEST

082809: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchSafModulePlugin:

dialstring=988, saf_enabled=1, saf_dndb_lookup=1, dp_result=0

082810: Nov 14 14:48:42.425 UTC: //-1/8046C660070E/DPM/dpMatchPeersMoreArg:

Result=SUCCESS(0)

List of Matched Outgoing Dial-peer(s):

1: Dial-peer Tag=1

3945voice#un all

All possible debugging has been turned off

3945voice#

Chris Deren · ‎11-14-2012

As I said earlier the call is attempting to go out back to PSTN as it matches dial-peer 1, is this the intent? If you need it to go to CUCM then you need to add a dial-peer with matching destination-pattern, i.e. 9..

HTH, Please rate all usuful posts!

Chris

Lulzim Islami · ‎11-14-2012

Chris,

Call is attemting to go out to the PSTN, this is the intent, but for some reason it is giving me busy signal? Can it be codec mismatch with my telco provider? All other destinations I'm able to call from same telco provider.

Thanks,

Lulzim Islami · ‎11-25-2012

Hi there,

Any idea what could cause bussy signal on those specific dialed numbers?

Thanks,