UC540 SCCP to SIP call failing

Unanswered Question
Dec 14th, 2010
Hi,

I am trying to configure UC540 with Cisco 508G and SIP phones from Syspine IP 310 and a softphone from 3CX.

Extensions are:
Cisco 508G = 203
Syspine IP 310 = 603
3CX SIP soft phone = 604

All phones are in common Vlan and same network space.

Exten 603 and 604 can call Cisco. They can also call Unity voicemail.

Calling from Cisco to SIP phones do not work (it looks phone is trying but no ring tone).
Also SIP to the outside world says destination busy.
Cisco phone can dial out using the same pattern.

Any suggestions ?

Thanks
-Azher


Config:
============
voice service voip
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
no supplementary-service h450.2
no supplementary-service h450.3
supplementary-service h450.12
no supplementary-service sip moved-temporarily
no supplementary-service sip refer
sip
  registrar server expires max 3600 min 3600
  localhost dns:sipconnect.lax0.cbeyond.net
   outbound-proxy dns:sip-proxy.lax0.cbeyond.net
  no update-callerid
!
voice class codec 1
codec preference 1 g711ulaw
!
voice class cause-code 1
no-circuit
!
voice register global
mode cme
source-address 10.1.1.1 port 5060
max-dn 20
max-pool 10
authenticate register
time-format 24
date-format D/M/Y
voicemail 400
file text
create profile sync 0008741421053914
!
voice register dn  1
number 603
allow watch
name 603
label 603
mwi
!
voice register dn  2
number 604
allow watch
name 604
label 604
mwi
!
voice register pool  1
id mac 0023.8B0B.02A9
number 1 dn 1
dtmf-relay sip-notify
username 603 password 123456
codec g711ulaw
!
voice register pool  2
id mac 0050.5B04.A74C
number 1 dn 2
dtmf-relay sip-notify
username 604 password 123456
codec g711ulaw

.......
ephone  5
device-security-mode none
video
mac-address 8843.E157.DC4B
ephone-template 16
max-calls-per-button 2
username "UserOne" password 123456
type 508G
button  1:10

ephone-dn  10  dual-line
number 201 secondary 1111111111 no-reg both
label 201
description User One
name 1111111111
call-forward busy 400
call-forward noan 400 timeout 20


Debug:
=========

UC_540#
017170: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=201, Called Number=, Voice-Interface=0x87E9DA1C,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOICE, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
017171: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=201
017172: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
017173: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=201T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
017174: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
017175: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5621
017176: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=201
017177: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
017178: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=201T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
017179: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=20002 Is Matched
017180: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5621
017181: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=20002
017182: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerSPI:exit@6175
017183: //-1/FF6D56C48267/CCAPI/cc_api_display_ie_subfields:
   cc_api_call_setup_ind_common:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=201
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=0
   dest=
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-lastrdn=
   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

017184: //-1/FF6D56C48267/CCAPI/cc_api_call_setup_ind_common:
   Interface=0x87E9DA1C, Call Info(
   Calling Number=201,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=(TON=Unknown, NPI=Unknown),
   Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,
   Incoming Dial-peer=20002, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1
017185: //-1/FF6D56C48267/CCAPI/cc_api_call_setup_ind_common:
   Interface Type=26, Protocol=0
017186: //-1/FF6D56C48267/CCAPI/ccCheckClipClir:
   In: Calling Number=201(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
017187: //-1/FF6D56C48267/CCAPI/ccCheckClipClir:
   Calling Party Number Is User Provided
017188: //-1/FF6D56C48267/CCAPI/ccCheckClipClir:
   Out: Calling Number=201(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
017189: //1478/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:
   Total Call Count=1, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)
017190: //1478/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:
   Total Call Count=2
017191: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

017192: :cc_get_feature_vsa malloc success
017193: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

017194:  cc_get_feature_vsa count is 3
017195: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

017196: :FEATURE_VSA attributes are: feature_name:0,feature_time:2310518440,feature_id:181
017197: //-1/xxxxxxxxxxxx/CCAPI/cc_init_call_entry:

017198:  call_init_entry calle is 89B71CF0,callid is 1478
017199: //1478/FF6D56C48267/CCAPI/cc_insert_guid_pod_entry:
   Incoming=TRUE, Call Id=1478
017200: //1478/FF6D56C48267/CCAPI/cc_api_call_setup_ind_common:
   Set Up Event Sent;
   Call Info(Calling Number=201(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=(TON=Unknown, NPI=Unknown))
017201: //1478/FF6D56C48267/CCAPI/cc_process_call_setup_ind:
   Event=0x87831FC0
017202: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup:
   Matching Parameters; Called Number=, Call Transfer Consult Id=
017203: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
   Searching Node;
   Called Number=, Call Transfer Consult Id=
017204: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:

017205: Comparing SSOpCode; 0x50 and 0x0
017206: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:

017207: Comparing SSOpCode; 0x12 and 0x0
017208: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup:
   No Matching Node
017209: //1478/FF6D56C48267/CCAPI/ccCallSetContext:
   Context=0x89BA9784
017210: //1478/FF6D56C48267/CCAPI/cc_process_call_setup_ind:
   >>>>CCAPI handed cid 1478 with tag 20002 to app "_ManagedAppProcess_Default"
017211: //1478/FF6D56C48267/CCAPI/ccCallSetupAck:
   Call Id=1478
017212: //1478/FF6D56C48267/CCAPI/cc_api_set_transfer_info:
   Transfer Number=, Transfer Reason=0x0
017213: //1478/FF6D56C48267/CCAPI/cc_api_set_transfer_info:
   Call Transfer Reset
017214: //1478/FF6D56C48267/CCAPI/ccGenerateToneInfo:
   Stop Tone On Digit=TRUE, Tone=Dial Tone,
   Tone Direction=Network, Params=0x0, Call Id=1478
017215: //1478/FF6D56C48267/CCAPI/ccSetDigitTimeouts:
   Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)
017216: //1478/FF6D56C48267/CCAPI/ccSetDigitTimeouts:
   Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))
017217: //1478/xxxxxxxxxxxx/CCAPI/ccCallReportDigits:
   (callID=0x5C6, digit_event=0x1, enable=TRUE, consume=FALSE)
017218: //1478/FF6D56C48267/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=1478
017219: //1478/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x87E9DA1C, callID=0x5C6, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
017220: //1478/FF6D56C48267/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x87E9DA1C, Call Id=1478
017221: //1478/FF6D56C48267/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
017222: //1478/FF6D56C48267/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=1478, Digit=6, DigitBeginFlags=0x0,
   Rtp Timestamp=0x3F9940, Rtp Expiration=0x0
017223: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:
   CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found
017224: //1478/FF6D56C48267/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=1478, Digit=6, Duration=100,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
017225: //1478/FF6D56C48267/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
017226: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:
   CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found
017227: //1478/FF6D56C48267/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=1478, Digit=0, DigitBeginFlags=0x0,
   Rtp Timestamp=0x401640, Rtp Expiration=0x0
017228: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:
   CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found
017229: //1478/FF6D56C48267/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=1478, Digit=0, Duration=100,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
017230: //1478/FF6D56C48267/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
017231: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:
   CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found
017232: //1478/FF6D56C48267/CCAPI/cc_api_call_digit_begin:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=1478, Digit=3, DigitBeginFlags=0x0,
   Rtp Timestamp=0x409340, Rtp Expiration=0x0
017233: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:
   CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found
017234: //1478/FF6D56C48267/CCAPI/cc_api_call_digit_end:
   Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,
   Source Call Id=1478, Digit=3, Duration=100,
   Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
017235: //1478/FF6D56C48267/CCAPI/cc_api_call_digit_end:
   Call Entry(Handoff Depth=0)
017236: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:
   CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found
017237: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=6, Peer Info Type=DIALPEER_INFO_SPEECH
017238: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=6
017239: //-1/FF6D56C48267/DPM/dpMatchCore:
   Dial String=6, Expanded String=6, Calling Number=
   Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
017240: //-1/FF6D56C48267/DPM/MatchNextPeer:
   Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=20003
017241: //-1/FF6D56C48267/DPM/dpMatchCore:
   Result=1
017242: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
017243: //-1/FF6D56C48267/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
017244: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=60, Peer Info Type=DIALPEER_INFO_SPEECH
017245: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=60
017246: //-1/FF6D56C48267/DPM/dpMatchCore:
   Dial String=60, Expanded String=60, Calling Number=
   Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
017247: //-1/FF6D56C48267/DPM/MatchNextPeer:
   Result=MORE_DIGITS_NEEDED(1); Outgoing Dial-peer=20041
017248: //-1/FF6D56C48267/DPM/dpMatchCore:
   Result=1
017249: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Result=Partial Matches(1) after DP_MATCH_DEST
017250: //-1/FF6D56C48267/DPM/dpMatchPeersMoreArg:
   Result=MORE_DIGITS_NEEDED(1)
017251: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=603, Peer Info Type=DIALPEER_INFO_SPEECH
017252: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=603
017253: //-1/FF6D56C48267/DPM/dpMatchCore:
   Dial String=603, Expanded String=603, Calling Number=
   Timeout=FALSE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
017254: //-1/FF6D56C48267/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=40001 Is Matched
017255: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
017256: //-1/FF6D56C48267/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=40001
017257: //1478/xxxxxxxxxxxx/CCAPI/ccCallReportDigits:
   (callID=0x5C6, digit_event=0x0, enable=FALSE, consume=FALSE)
017258: //1478/FF6D56C48267/CCAPI/ccCallReportDigits:
   Enabled=TRUE, Call Id=1478
017259: //1478/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done:
   (vdbPtr=0x87E9DA1C, callID=0x5C6, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
017260: //1478/FF6D56C48267/CCAPI/cc_api_call_report_digits_done:
   Enabled=TRUE, Disposition=0x0, Interface=0x87E9DA1C, Call Id=1478
017261: //1478/FF6D56C48267/CCAPI/cc_api_call_report_digits_done:
   Call Entry(Initial Digit Timeout=15000(ms), Inter Digit Timeout=5000(ms))
017262: //1478/FF6D56C48267/CCAPI/ccCallProceeding:
   Progress Indication=NULL(0)
017263: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=603, Peer Info Type=DIALPEER_INFO_SPEECH
017264: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=603
017265: //-1/FF6D56C48267/DPM/dpMatchCore:
   Dial String=603, Expanded String=603, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
017266: //-1/FF6D56C48267/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=40001 Is Matched
017267: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
017268: //-1/FF6D56C48267/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=40001
017269: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=603, Peer Info Type=DIALPEER_INFO_SPEECH
017270: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=603
017271: //-1/FF6D56C48267/DPM/dpMatchCore:
   Dial String=603, Expanded String=603, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
017272: //-1/FF6D56C48267/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=40001 Is Matched
017273: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
017274: //-1/FF6D56C48267/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=40001
017275: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=603, Called Number=603, Peer Info Type=DIALPEER_INFO_SPEECH
017276: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=603
017277: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=603, Expanded String=603, Calling Number=603T
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
017278: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=40001 Is Matched
017279: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
017280: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=40001
017281: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
017282: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
017283: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6221
017284: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=603, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
017285: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=603
017286: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
017287: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=603T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
017288: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
017289: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5621
017290: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=603
017291: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
017292: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=603T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
017293: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=40001 Is Matched
017294: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5621
017295: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=Success(0) after DP_MATCH_ORIGINATE; Incoming Dial-peer=40001
017296: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6221
017297: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=603, Peer Info Type=DIALPEER_INFO_SPEECH
017298: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=603
017299: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=603, Expanded String=603, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
017300: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=40001 Is Matched
017301: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
017302: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=40001
017303: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Calling Number=, Called Number=603, Peer Info Type=DIALPEER_INFO_SPEECH
017304: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Match Rule=DP_MATCH_DEST; Called Number=603
017305: //-1/FF6D56C48267/DPM/dpMatchCore:
   Dial String=603, Expanded String=603, Calling Number=
   Timeout=TRUE, Is Incoming=FALSE, Peer Info Type=DIALPEER_INFO_SPEECH
017306: //-1/FF6D56C48267/DPM/MatchNextPeer:
   Result=Success(0); Outgoing Dial-peer=40001 Is Matched
017307: //-1/FF6D56C48267/DPM/dpMatchPeersCore:
   Result=Success(0) after DP_MATCH_DEST
017308: //-1/FF6D56C48267/DPM/dpMatchPeersMoreArg:
   Result=SUCCESS(0)
   List of Matched Outgoing Dial-peer(s):
     1: Dial-peer Tag=40001
017309: //1478/FF6D56C48267/CCAPI/ccCallSetupRequest:
   Destination=, Calling IE Present=TRUE, Mode=0,
   Outgoing Dial-peer=40001, Params=0x89BA4BB4, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)
017310: //1478/FF6D56C48267/CCAPI/ccCheckClipClir:
   In: Calling Number=201(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
017311: //1478/FF6D56C48267/CCAPI/ccCheckClipClir:
   Calling Party Number Is User Provided
017312: //1478/FF6D56C48267/CCAPI/ccCheckClipClir:
   Out: Calling Number=201(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)
017313: //1478/FF6D56C48267/CCAPI/ccCallSetupRequest:
   Destination Pattern=603, Called Number=603, Digit Strip=FALSE
017314: //1478/FF6D56C48267/CCAPI/ccCallSetupRequest:
   Calling Number=201(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=603(TON=Unknown, NPI=Unknown),
   Redirect Number=, Display Info=6264325990
   Account Number=, Final Destination Flag=FALSE,
   Guid=FF6D56C4-0721-11E0-8267-BE77E019D069, Outgoing Dial-peer=40001
017315: //1478/FF6D56C48267/CCAPI/cc_api_display_ie_subfields:
   ccCallSetupRequest:
   cisco-username=
   ----- ccCallInfo IE subfields -----
   cisco-ani=201
   cisco-anitype=0
   cisco-aniplan=0
   cisco-anipi=0
   cisco-anisi=0
   dest=603
   cisco-desttype=0
   cisco-destplan=0
   cisco-rdie=FFFFFFFF
   cisco-rdn=
   cisco-lastrdn=
   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

017316: //1478/FF6D56C48267/CCAPI/ccIFCallSetupRequestPrivate:
   Interface=0x871DCAA4, Interface Type=3, Destination=, Mode=0x0,
   Call Params(Calling Number=201,(Calling Name=6264325990)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),
   Called Number=603(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
   Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=40001, Call Count On=FALSE,
   Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
017317: //1479/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:
   Total Call Count=2, Call Entry(Call Count On=FALSE, Incoming Call=FALSE)
017318: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

017319: :cc_get_feature_vsa malloc success
017320: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

017321:  cc_get_feature_vsa count is 4
017322: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

017323: :FEATURE_VSA attributes are: feature_name:0,feature_time:2310521128,feature_id:182
017324: //-1/xxxxxxxxxxxx/CCAPI/cc_init_call_entry:

017325:  call_init_entry calle is 89B74078,callid is 1479
017326: //1478/FF6D56C48267/CCAPI/cc_peer_bind:
   Bind=TRUE, Binder Call Id=1478, Bindee Call Id=1479
017327: //1479/FF6D56C48267/CCAPI/cc_insert_guid_pod_entry:
   Incoming=FALSE, Call Id=1479
017328: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=201, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
017329: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=201
017330: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
017331: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=201T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
017332: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
017333: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5621
017334: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=201
017335: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
017336: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=201T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
017337: //-1/xxxxxxxxxxxx/DPM/MatchNextPeer:
   Result=Success(0); Incoming Dial-peer=20002 Is Matched
017338: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5621
017339: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
017340: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6221
017341: //1479/FF6D56C48267/CCAPI/ccIFCallSetupRequestPrivate:
   SPI Call Setup Request Is Success; Interface Type=3, FlowMode=1
017342: //1478/FF6D56C48267/CCAPI/ccCallSetupRequest:exit@2521
017343: //1479/FF6D56C48267/CCAPI/ccCallSetContext:
   Context=0x89BA4B64
017344: //1478/FF6D56C48267/CCAPI/ccSaveDialpeerTag:
   Outgoing Dial-peer=40001
017345: //1479/FF6D56C48267/CCAPI/cc_api_call_proceeding:
   Interface=0x871DCAA4, Progress Indication=NULL(0)
017346: //1479/FF6D56C48267/CCAPI/cc_api_call_proceeding:exit@7009
017347: //1479/FF6D56C48267/CCAPI/cc_incr_if_call_volume:
   CALL_ERROR_INFORMATIONAL; Hwidb Is Not Found; Remote IP Address=0.0.0.0
017348: //1478/FF6D56C48267/CCAPI/cc_api_call_disconnected:
   Cause Value=16, Interface=0x87E9DA1C, Call Id=1478
017349: //1478/FF6D56C48267/CCAPI/cc_api_call_disconnected:
   Call Entry(Responsed=FALSE, Cause Value=16, Retry Count=0)
017350: //1479/FF6D56C48267/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
017351: //1479/FF6D56C48267/CCAPI/ccCallDisconnect:
   Start Calling Accounting;
   Call Entry(Incoming=FALSE)
017352: //1479/FF6D56C48267/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Disconnect Cause=0)
017353: //1479/FF6D56C48267/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=FALSE, Cause Value=16)
017354: //1478/FF6D56C48267/CCAPI/ccCallDisconnect:
   Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)
017355: //1478/FF6D56C48267/CCAPI/ccCallDisconnect:
   Start Calling Accounting;
   Call Entry(Incoming=TRUE)
017356: //1478/FF6D56C48267/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Disconnect Cause=16)
017357: //1478/FF6D56C48267/CCAPI/ccCallDisconnect:
   Call Entry(Disconnect Cause=16)
017358: //1478/FF6D56C48267/CCAPI/ccCallDisconnect:
   Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)
017359: //1478/FF6D56C48267/CCAPI/cc_api_get_transfer_info:
   Transfer Number Is Null
017360: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
017361: //-1/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   CALL_ERROR_INFORMATIONAL; Call Id Is Invalid=-1
017362: //1478/xxxxxxxxxxxx/CCAPI/cc_api_request_rt_packet_stats_done:
   CALL_ERROR_INFORMATIONAL; tag not properly returned from SPI
017363: //1478/FF6D56C48267/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x87E9DA1C, Tag=0x0, Call Id=1478,
   Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)
017364: //1478/FF6D56C48267/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent
017365: //1478/FF6D56C48267/CCAPI/cc_delete_guid_pod_entry:
   Incoming=TRUE
017366: //1478/FF6D56C48267/CCAPI/cc_delete_call_entry:
   ccFreeRawMsgInfo=0x8783E3C8
017367: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

017368: :cc_free_feature_vsa freeing 89B7B6A0
017369: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

017370:  vsacount in free is 3
017371: //1478/FF6D56C48267/CCAPI/cc_delete_call_entry:
   Total Call Count=2, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)
017372: //1478/FF6D56C48267/CCAPI/cc_delete_call_entry:
   Total Call Count=1
017373: //1478/FF6D56C48267/CCAPI/cc_delete_call_entry:
   Deleting profileTable[0x860309BC]
017374: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Calling Number=201$, Called Number=, Voice-Interface=0x0,
   Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE,
   Peer Info Type=DIALPEER_INFO_SPEECH
017375: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ANSWER; Calling Number=201$
017376: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
017377: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=201$T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
017378: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
017379: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5621
017380: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Match Rule=DP_MATCH_ORIGINATE; Calling Number=201$
017381: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:
   Is Incoming=TRUE, Number Expansion=FALSE
017382: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Dial String=, Expanded String=, Calling Number=201$T
   Timeout=TRUE, Is Incoming=TRUE, Peer Info Type=DIALPEER_INFO_SPEECH
017383: //-1/xxxxxxxxxxxx/DPM/dpMatchCore:
   Result=-1
017384: //-1/xxxxxxxxxxxx/DPM/dpMatchPeertype:exit@5621
017385: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore:
   Result=NO_MATCH(-1) After All Match Rules Attempt
017386: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeer:exit@6221
017387: //0/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:
   Call Entry Is Not Found
017388: //0/xxxxxxxxxxxx/CCAPI/cc_api_call_preempt_done:
   CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found


Some output:
===============
UC_540#show dial-peer voice summary
...
20002  pots  up   up             201$             
0                           50/0/10
...
40001  voip  up   up             603                0  syst
ipv4:10.1.1.100:5060
40002  voip  up   up             604                0  syst
ipv4:10.1.1.200:6121


UC_540#show voice register global
CONFIG [Version=7.1]
========================
  Version 7.1
  Mode is cme
  Max-pool is 10
  Max-dn is 20
  Outbound-proxy is enabled and will use global configured value
  Source-address is 10.1.1.1 port 5060
  Authenticate register
  Time-format is 24
  Date-format is D/M/Y
  Time-zone is 5
  Hold-alert is disabled
  Mwi stutter is disabled
  Mwi registration for full E.164 is disabled
  Forwarding local is enabled
  Privacy is enabled
  Privacy-on-hold is disabled
  Dst auto adjust is enabled
    start at Apr week 1 day Sun time 02:00
    stop  at Oct week 8 day Sun time 02:00
  Voicemail number is 400
  Max redirect number is 5
  IP QoS DSCP:
    ef (the MS 6 bits, 46, in ToS, 0xB8) for media
    cs3 (the MS 6 bits, 24, in ToS, 0x60) for signal
    af41 (the MS 6 bits, 34, in ToS, 0x88) for video
    default (the MS 6 bits, 0, in ToS, 0x0) for service
  Telnet Level: 0
  Tftp path is system:/cme/sipphone
  Generate text file is enabled
  Tftp files are created, current syncinfo 0008741421053914
  OS79XX.TXT is not created
  timeout interdigit 10


UC_540#show voice register pool 1
Pool Tag 1
Config:
  Mac address is 0023.8B0B.02A9
  Number list 1 : DN 1
  Proxy Ip address is 0.0.0.0
  DTMF Relay is enabled, sip-notify
  Call Waiting is enabled
  DnD is disabled
  Busy trigger per button value is 0
  keep-conference is enabled
  username 603 password 123456
  Transport type is udp
  service-control mechanism is not supported
  registration Call ID is
64639f84-939fb617-b5ea564c-73677063@10.1.1.100-3e35
  Privacy feature is not configured.
  Privacy button is disabled
  active primary line is: 203

  contact IP address: 10.1.1.100 port 5060


Dialpeers created:

dial-peer voice 40001 voip
destination-pattern 603
session target ipv4:10.1.1.100:5060
session protocol sipv2
dtmf-relay sip-notify
codec  g711ulaw bytes 160
  after-hours-exempt   FALSE

Statistics:
  Active registrations  : 1

  Total SIP phones registered: 1
  Total Registration Statistics
    Registration requests  : 3
    Registration success   : 3
    Registration failed    : 0
    unRegister requests    : 2
    unRegister success     : 2
    unRegister failed      : 0


UC_540#show voice register pool 2
Pool Tag 2
Config:
  Mac address is 0050.5B04.A74C
  Number list 1 : DN 2
  Proxy Ip address is 0.0.0.0
  DTMF Relay is enabled, sip-notify
  Call Waiting is enabled
  DnD is disabled
  Busy trigger per button value is 0
  keep-conference is enabled
  username 604 password 123456
  Transport type is udp
  service-control mechanism is not supported
  registration Call ID is NGE2NWI3NWEwZTA5OWUxNmU3MWI3ZDZlMjhkZTg2MWM.
  Privacy feature is not configured.
  Privacy button is disabled
  active primary line is: 604

  contact IP address: 10.1.1.200 port 61210


Dialpeers created:

dial-peer voice 40002 voip
destination-pattern 604
session target ipv4:10.1.1.200:61210
session protocol sipv2
dtmf-relay sip-notify
codec  g711ulaw bytes 160
  after-hours-exempt   FALSE

Statistics:
  Active registrations  : 1

  Total SIP phones registered: 1
  Total Registration Statistics
    Registration requests  : 2
    Registration success   : 2
    Registration failed    : 0
    unRegister requests    : 1
    unRegister success     : 1
    unRegister failed      : 0

I have this problem too.
0 votes
  • 1
  • 2
  • 3
  • 4
  • 5
Overall Rating: 0 (0 ratings)
lusandi Tue, 12/14/2010 - 17:37

Hello,

Can you send me this:

debug ccsip messages

Use this command in order to verify why the call it is failling.

Regards,

Luis Sandi

azher.amin Tue, 12/14/2010 - 17:46

Hi,

Nothing is appearing in this debug. I have term monit enabled.

Also calling from Exten 201 (SPA508G) to 603 says on the screen "Ring out" and there is a silence.

Any other debug you need, or a like a full config ?

-Azher

azher.amin Tue, 12/14/2010 - 18:05

UC540 Configuration attached.

-Azher

Attachment: 
azher.amin Tue, 12/14/2010 - 19:52

Hi Luis,

Do you have any suggestions to look for this issue ?

Thanks

-Azher

Actions

Login or Register to take actions

This Discussion

Posted December 14, 2010 at 5:34 PM
Stats:
Replies:4 Overall Rating:
Views:1249 Votes:0
Shares:0
Tags: No tags.