SIP SRST with access to PSTN

v.matiakis · ‎05-12-2016

Hi all,

I am trying to configure SIP SRST with access to the PSTN network. Phones get registered on the gateway and internal calls work fine. When i try to call to the PSTN nothing happens and call is dropped. Below the configuration:

trunk group TRUNK

interface BRI0/2/0
trunk-group TRUNK
!
interface BRI0/2/1
trunk-group TRUNK
!
interface BRI0/3/0
trunk-group TRUNK
!
voice service voip
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
sip
bind control source-interface GigabitEthernet0/0.100
bind media source-interface GigabitEthernet0/0.100
registrar server expires max 600 min 60

!
voice class codec 1
codec preference 1 g729br8
codec preference 2 g729r8
codec preference 3 g711ulaw
codec preference 4 g711alaw
!
!
voice register global
timeouts interdigit 5
system message SRST-mode
max-dn 200
max-pool 10
!
voice register pool 10
id network x.xx.43.0 mask 255.255.255.0
dtmf-relay rtp-nte cisco-rtp sip-notify
voice-class codec 1
no vad
!
!
!
voice translation-rule 1
rule 1 /xxxxxxxxxx/ /2301/
!
!
voice translation-profile SRST_IN
translate called 1
!

dial-peer voice 1 pots
trunkgroup TRUNK
translation-profile incoming SRST_IN
incoming called-number .
direct-inward-dial
!
dial-peer voice 11 pots
trunkgroup TRUNK
description Outgoing calls in SRST mode
destination-pattern 92.........
direct-inward-dial
forward-digits 10
!
dial-peer voice 12 pots
trunkgroup TRUNK
description Outgoing calls in SRST mode
destination-pattern 96.........
direct-inward-dial
forward-digits 10
!
!
sip-ua
retry invite 3
retry bye 3
retry cancel 3
timers trying 200
registrar ipv4:x.x.43.1 expires 600

Also when debugging voice register errors/events the following message appears

030277: May 12 15:45:21.550 ATHENS: voice_reg_get_reg_expires_timer: no voice register pool found

Abhay Singh Reyal · ‎05-12-2016

Check the CCAPI debugs to see which incoming and outgoing dial peer is hit, if it hits it or not ?

Collect the CCAPI and ISDN debugs for the test call and see what do you get.

Regards

Abhay Reyal

Regards
Abhay Singh Reyal
The Only Way To Do Great Work Is To Love What You Do. If You Haven’t Found It Yet, Keep Looking. Don’t Settle

v.matiakis · ‎05-12-2016

Hi all and thank you very much for your replies.

I transferred the configuration on a lab. Below is the debug output:

SRST gateway IP : 172.16.11.100

Phone IP Address : 172.16.11.109

Configuration attached

CME-LAB#
010645: May 13 08:54:02.307 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:9@172.16.11.100;user=phone SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK391db043
From: "1000" <sip:1000@172.16.11.100>;tag=001aa16283b129d62bce7086-78a6f0cd
To: <sip:9@172.16.11.100>
Call-ID: 001aa162-83b1003f-03f1b9d0-668a8a27@172.16.11.109
Max-Forwards: 70
Date: Fri, 13 May 2016 05:54:02 GMT
CSeq: 101 INVITE
User-Agent: Cisco-CP7911G/9.4.2
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=udp>
Expires: 180
Accept: application/sdp
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE,INFO
Remote-Party-ID: "1000" <sip:1000@172.16.11.100>;party=calling;id-type=subscriber;privacy=off;screen=yes
Supported: replaces,join,sdp-anat,norefersub,resource-priority,X-cisco-srtp-fallback,X-cisco-xsi-8.5.1
Allow-Events: kpml,dialog
Content-Length: 378
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 27266 0 IN IP4 172.16.11.109
s=SIP Call
t=0 0
m=audio 25308 RTP/AVP 0 8 18 102 9 116 101
c=IN IP4 172.16.11.109
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:102 L16/16000
a=rtpmap:9 G722/8000
a=rtpmap:116 iLBC/8000
a=fmtp:116 mode=20
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv

010646: May 13 08:54:02.311 ATHENS: //-1/EDB0E0EDA3D8/CCAPI/cc_api_display_ie_subfields:
cc_api_call_setup_ind_common:
cisco-username=1000
----- ccCallInfo IE subfields -----
cisco-ani=1000
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=9
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

010647: May 13 08:54:02.311 ATHENS: //-1/EDB0E0EDA3D8/CCAPI/cc_api_call_setup_ind_common:
Interface=0x30FACD84, Call Info(
Calling Number=1000,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=9(TON=Unknown, NPI=Unknown),
Calling Translated=FALSE, Subscriber Type Str=Unknown, FinalDestinationFlag=FALSE,
Incoming Dial-peer=40002, Progress Indication=NULL(0), Calling IE Present=TRUE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=84535
010648: May 13 08:54:02.311 ATHENS: //-1/EDB0E0EDA3D8/CCAPI/ccCheckClipClir:
In: Calling Number=1000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
010649: May 13 08:54:02.311 ATHENS: //-1/EDB0E0EDA3D8/CCAPI/ccCheckClipClir:
Out: Calling Number=1000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
010650: May 13 08:54:02.311 ATHENS: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010651: May 13 08:54:02.311 ATHENS: :cc_get_feature_vsa malloc success
010652: May 13 08:54:02.311 ATHENS: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010653: May 13 08:54:02.311 ATHENS: cc_get_feature_vsa count is 1
010654: May 13 08:54:02.311 ATHENS: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010655: May 13 08:54:02.311 ATHENS: :FEATURE_VSA attributes are: feature_name:0,feature_time:740144864,feature_id:57
010656: May 13 08:54:02.311 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_setup_ind_common:
Set Up Event Sent;
Call Info(Calling Number=1000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=9(TON=Unknown, NPI=Unknown))
010657: May 13 08:54:02.311 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_process_call_setup_ind:
Event=0x2BC06190
010658: May 13 08:54:02.311 ATHENS: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:
Try with the demoted called number 9
010659: May 13 08:54:02.311 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallSetContext:
Context=0x2A7374E0
010660: May 13 08:54:02.311 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_process_call_setup_ind:
>>>>CCAPI handed cid 84535 with tag 40002 to app "_ManagedAppProcess_Default"
010661: May 13 08:54:02.311 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallSetupAck:
Call Id=84535
010662: May 13 08:54:02.311 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_set_transfer_info:
Transfer Number=, Transfer Reason=0x0
010663: May 13 08:54:02.311 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccSetDigitTimeouts:
Initial Digit Timeout=5000(ms), Inter Digit Timeout=5000(ms)
010664: May 13 08:54:02.311 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccSetDigitTimeouts:
Call Entry(Inter Digit Timeout=5000(ms), Initial Digit Timeout=5000(ms))
010665: May 13 08:54:02.311 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallReportDigits:
(callID=0x14A37, digit_event=0x1, enable=TRUE, consume=FALSE)
010666: May 13 08:54:02.311 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=84535
010667: May 13 08:54:02.315 ATHENS: //84535/EDB0E0EDA3D8/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK391db043
From: "1000" <sip:1000@172.16.11.100>;tag=001aa16283b129d62bce7086-78a6f0cd
To: <sip:9@172.16.11.100>
Date: Fri, 13 May 2016 05:54:02 GMT
Call-ID: 001aa162-83b1003f-03f1b9d0-668a8a27@172.16.11.109
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Content-Length: 0

010668: May 13 08:54:02.315 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SUBSCRIBE sip:1000@172.16.11.109:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.100:5060;branch=z9hG4bK53DF21B
From: <sip:9@172.16.11.100>;tag=56FA9B40-911
To: <sip:1000@172.16.11.109>
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 101 SUBSCRIBE
Max-Forwards: 70
Date: Fri, 13 May 2016 05:54:02 GMT
User-Agent: Cisco-SIPGateway/IOS-12.x
Event: kpml; call-id=001aa162-83b1003f-03f1b9d0-668a8a27@172.16.11.109; from-tag=001aa16283b129d62bce7086-78a6f0cd
Expires: 7200
Contact: <sip:9@172.16.11.100:5060>
Content-Type: application/kpml-request+xml
Content-Length: 357

<?xml version="1.0" encoding="UTF-8"?><kpml-request xmlns="urn:ietf:params:xml:ns:kpml-request" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="urn:ietf:params:xml:ns:kpml-request kpml-request.xsd" version="1.0"><pattern persist="persist" interdigittimer="5000"><regex tag="Backspace OK">[x#*]|bs</regex></pattern></kpml-request>

010669: May 13 08:54:02.355 ATHENS: //0/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.100:5060;branch=z9hG4bK53DF21B
From: <sip:9@172.16.11.100>;tag=56FA9B40-911
To: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:02 GMT
CSeq: 101 SUBSCRIBE
Server: Cisco-CP7911G/9.4.2
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Expires: 7200
Content-Length: 0

010670: May 13 08:54:02.359 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x30FACD84, callID=0x14A37, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)
010671: May 13 08:54:02.359 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x30FACD84, Call Id=84535
010672: May 13 08:54:02.359 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=5000(ms), Inter Digit Timeout=5000(ms))
010673: May 13 08:54:02.359 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK9c346c2a
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:02 GMT
CSeq: 1000 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 0

010674: May 13 08:54:02.363 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK9c346c2a
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:02 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1000 NOTIFY
Content-Length: 0

010675: May 13 08:54:02.479 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK6e0c571f
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:02 GMT
CSeq: 1001 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 209
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="200" text="OK" suppressed="false" forced_flush="false" digits="6" tag="Backspace OK"/>

010676: May 13 08:54:02.479 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=6, DigitBeginFlags=0x0,
Rtp Timestamp=0x0, Rtp Expiration=0x0
010677: May 13 08:54:02.479 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=6, Duration=250,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
010678: May 13 08:54:02.479 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
010679: May 13 08:54:02.483 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK6e0c571f
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:02 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1001 NOTIFY
Content-Length: 0

010680: May 13 08:54:02.631 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK7d09e768
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:02 GMT
CSeq: 1002 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 209
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="200" text="OK" suppressed="false" forced_flush="false" digits="9" tag="Backspace OK"/>

010681: May 13 08:54:02.631 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=9, DigitBeginFlags=0x0,
Rtp Timestamp=0x0, Rtp Expiration=0x0
010682: May 13 08:54:02.631 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=9, Duration=250,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
010683: May 13 08:54:02.631 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
010684: May 13 08:54:02.631 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK7d09e768
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:02 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1002 NOTIFY
Content-Length: 0

010685: May 13 08:54:02.779 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK07e63a85
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:02 GMT
CSeq: 1003 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 209
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="200" text="OK" suppressed="false" forced_flush="false" digits="3" tag="Backspace OK"/>

010686: May 13 08:54:02.779 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=3, DigitBeginFlags=0x0,
Rtp Timestamp=0x0, Rtp Expiration=0x0
010687: May 13 08:54:02.779 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=3, Duration=250,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
010688: May 13 08:54:02.779 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
010689: May 13 08:54:02.783 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK07e63a85
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:02 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1003 NOTIFY
Content-Length: 0

010690: May 13 08:54:02.935 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK2976a3de
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:03 GMT
CSeq: 1004 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 209
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="200" text="OK" suppressed="false" forced_flush="false" digits="6" tag="Backspace OK"/>

010691: May 13 08:54:02.935 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=6, DigitBeginFlags=0x0,
Rtp Timestamp=0x0, Rtp Expiration=0x0
010692: May 13 08:54:02.935 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=6, Duration=250,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
010693: May 13 08:54:02.935 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
010694: May 13 08:54:02.935 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK2976a3de
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:02 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1004 NOTIFY
Content-Length: 0

010695: May 13 08:54:03.079 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK4aea9abb
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:03 GMT
CSeq: 1005 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 209
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="200" text="OK" suppressed="false" forced_flush="false" digits="1" tag="Backspace OK"/>

010696: May 13 08:54:03.079 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=1, DigitBeginFlags=0x0,
Rtp Timestamp=0x0, Rtp Expiration=0x0
010697: May 13 08:54:03.079 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=1, Duration=250,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
010698: May 13 08:54:03.079 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
010699: May 13 08:54:03.079 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK4aea9abb
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:03 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1005 NOTIFY
Content-Length: 0

010700: May 13 08:54:03.227 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK2b6408dc
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:03 GMT
CSeq: 1006 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 209
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="200" text="OK" suppressed="false" forced_flush="false" digits="6" tag="Backspace OK"/>

010701: May 13 08:54:03.227 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=6, DigitBeginFlags=0x0,
Rtp Timestamp=0x0, Rtp Expiration=0x0
010702: May 13 08:54:03.227 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=6, Duration=250,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
010703: May 13 08:54:03.227 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
010704: May 13 08:54:03.227 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK2b6408dc
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:03 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1006 NOTIFY
Content-Length: 0

010705: May 13 08:54:03.375 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK09bdfee1
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:03 GMT
CSeq: 1007 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 209
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="200" text="OK" suppressed="false" forced_flush="false" digits="2" tag="Backspace OK"/>

010706: May 13 08:54:03.379 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=2, DigitBeginFlags=0x0,
Rtp Timestamp=0x0, Rtp Expiration=0x0
010707: May 13 08:54:03.379 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=2, Duration=250,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
010708: May 13 08:54:03.379 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
010709: May 13 08:54:03.379 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK09bdfee1
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:03 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1007 NOTIFY
Content-Length: 0

010710: May 13 08:54:03.515 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bKef5b4702
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:03 GMT
CSeq: 1008 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 209
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="200" text="OK" suppressed="false" forced_flush="false" digits="7" tag="Backspace OK"/>

010711: May 13 08:54:03.515 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=7, DigitBeginFlags=0x0,
Rtp Timestamp=0x0, Rtp Expiration=0x0
010712: May 13 08:54:03.515 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=7, Duration=250,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
010713: May 13 08:54:03.515 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
010714: May 13 08:54:03.519 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bKef5b4702
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:03 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1008 NOTIFY
Content-Length: 0

010715: May 13 08:54:03.655 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK84c30b17
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:03 GMT
CSeq: 1009 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 209
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="200" text="OK" suppressed="false" forced_flush="false" digits="5" tag="Backspace OK"/>

010716: May 13 08:54:03.655 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=5, DigitBeginFlags=0x0,
Rtp Timestamp=0x0, Rtp Expiration=0x0
010717: May 13 08:54:03.659 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=5, Duration=250,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
010718: May 13 08:54:03.659 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
010719: May 13 08:54:03.659 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK84c30b17
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:03 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1009 NOTIFY
Content-Length: 0

010720: May 13 08:54:03.999 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bKfcf9e800
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:03 GMT
CSeq: 1010 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 209
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="200" text="OK" suppressed="false" forced_flush="false" digits="5" tag="Backspace OK"/>

010721: May 13 08:54:03.999 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_begin:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=5, DigitBeginFlags=0x0,
Rtp Timestamp=0x0, Rtp Expiration=0x0
010722: May 13 08:54:03.999 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Destination Interface=0x0, Destination Mask=0x1, Destination Call Id=-1,
Source Call Id=84535, Digit=5, Duration=250,
Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF
010723: May 13 08:54:03.999 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_digit_end:
Call Entry(Handoff Depth=0)
010724: May 13 08:54:04.883 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallReportDigits:
(callID=0x14A37, digit_event=0x0, enable=FALSE, consume=FALSE)
010725: May 13 08:54:04.883 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallReportDigits:
Enabled=TRUE, Call Id=84535
010726: May 13 08:54:04.883 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_report_digits_done:
(vdbPtr=0x30FACD84, callID=0x14A37, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)
010727: May 13 08:54:04.883 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_report_digits_done:
Enabled=TRUE, Disposition=0x0, Interface=0x30FACD84, Call Id=84535
010728: May 13 08:54:04.883 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_report_digits_done:
Call Entry(Initial Digit Timeout=5000(ms), Inter Digit Timeout=5000(ms))
010729: May 13 08:54:04.883 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bKfcf9e800
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:03 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1010 NOTIFY
Content-Length: 0

010730: May 13 08:54:04.895 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SUBSCRIBE sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 172.16.11.100:5060;branch=z9hG4bK53E0DAD
From: <sip:9@172.16.11.100>;tag=56FA9B40-911
To: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 102 SUBSCRIBE
Max-Forwards: 70
Date: Fri, 13 May 2016 05:54:04 GMT
User-Agent: Cisco-SIPGateway/IOS-12.x
Event: kpml; call-id=001aa162-83b1003f-03f1b9d0-668a8a27@172.16.11.109; from-tag=001aa16283b129d62bce7086-78a6f0cd
Expires: 0
Contact: <sip:9@172.16.11.100:5060>
Content-Length: 0

010731: May 13 08:54:04.895 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bKfcf9e800
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:03 GMT
CSeq: 1010 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 209
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="200" text="OK" suppressed="false" forced_flush="false" digits="5" tag="Backspace OK"/>

010732: May 13 08:54:04.895 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallProceeding:
Progress Indication=NULL(0)
010733: May 13 08:54:04.899 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallSetupRequest:
Destination=, Calling IE Present=TRUE, Mode=0,
Outgoing Dial-peer=12, Params=0x2A734D00, Progress Indication=NULL(0)
010734: May 13 08:54:04.899 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCheckClipClir:
In: Calling Number=1000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
010735: May 13 08:54:04.899 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCheckClipClir:
Out: Calling Number=1000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed)
010736: May 13 08:54:04.899 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallSetupRequest:
Destination Pattern=96........., Called Number=96936162755, Digit Strip=TRUE
010737: May 13 08:54:04.899 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallSetupRequest:
Calling Number=1000(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=96936162755(TON=Unknown, NPI=Unknown),
Redirect Number=, Display Info=1000
Account Number=1000, Final Destination Flag=FALSE,
Guid=EDB0E0ED-1805-11E6-A3D8-A199D63EEB46, Outgoing Dial-peer=12
010738: May 13 08:54:04.899 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_display_ie_subfields:
ccCallSetupRequest:
cisco-username=1000
----- ccCallInfo IE subfields -----
cisco-ani=1000
cisco-anitype=0
cisco-aniplan=0
cisco-anipi=0
cisco-anisi=1
dest=96936162755
cisco-desttype=0
cisco-destplan=0
cisco-rdie=FFFFFFFF
cisco-rdn=
cisco-rdntype=0
cisco-rdnplan=0
cisco-rdnpi=-1
cisco-rdnsi=-1
cisco-redirectreason=-1 fwd_final_type =0
final_redirectNumber =
hunt_group_timeout =0

010739: May 13 08:54:04.899 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccIFCallSetupRequestPrivate:
Interface=0x31541E48, Interface Type=6, Destination=, Mode=0x0,
Call Params(Calling Number=1000,(Calling Name=1000)(TON=Unknown, NPI=Unknown, Screening=User, Passed, Presentation=Allowed),
Called Number=96936162755(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,
Subscriber Type Str=Unknown, FinalDestinationFlag=FALSE, Outgoing Dial-peer=12, Call Count On=FALSE,
Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)
010740: May 13 08:54:04.899 ATHENS: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010741: May 13 08:54:04.899 ATHENS: :cc_get_feature_vsa malloc success
010742: May 13 08:54:04.899 ATHENS: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010743: May 13 08:54:04.899 ATHENS: cc_get_feature_vsa count is 2
010744: May 13 08:54:04.899 ATHENS: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010745: May 13 08:54:04.899 ATHENS: :FEATURE_VSA attributes are: feature_name:0,feature_time:740144640,feature_id:58
010746: May 13 08:54:04.899 ATHENS: //84536/EDB0E0EDA3D8/CCAPI/ccIFCallSetupRequestPrivate:
SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1
010747: May 13 08:54:04.899 ATHENS: //84536/EDB0E0EDA3D8/CCAPI/ccCallSetContext:
Context=0x2A734CB0
010748: May 13 08:54:04.899 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccSaveDialpeerTag:
Outgoing Dial-peer=12
010749: May 13 08:54:04.899 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bKfcf9e800
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:03 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1010 NOTIFY
Content-Length: 0

010750: May 13 08:54:04.899 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bKe8d8f23d
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:05 GMT
CSeq: 1011 NOTIFY
Event: kpml
Subscription-State: active; expires=7200
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 219
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="423" text="Timer Expired" suppressed="false" forced_flush="false" digits="" tag="Backspace OK"/>

010751: May 13 08:54:04.903 ATHENS: //84536/EDB0E0EDA3D8/CCAPI/cc_api_call_disconnected:
Cause Value=63, Interface=0x31541E48, Call Id=84536
010752: May 13 08:54:04.903 ATHENS: //84536/EDB0E0EDA3D8/CCAPI/cc_api_call_disconnected:
Call Entry(Responsed=TRUE, Cause Value=63, Retry Count=0)
010753: May 13 08:54:04.903 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallReleaseResources:
release reserved xcoding resource.
010754: May 13 08:54:04.903 ATHENS: //84536/EDB0E0EDA3D8/CCAPI/ccCallSetAAA_Accounting:
Accounting=0, Call Id=84536
010755: May 13 08:54:04.903 ATHENS: //84536/EDB0E0EDA3D8/CCAPI/ccCallDisconnect:
Cause Value=63, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=63)
010756: May 13 08:54:04.903 ATHENS: //84536/EDB0E0EDA3D8/CCAPI/ccCallDisconnect:
Cause Value=63, Call Entry(Responsed=TRUE, Cause Value=63)
010757: May 13 08:54:04.903 ATHENS: //84536/EDB0E0EDA3D8/CCAPI/cc_api_get_transfer_info:
Transfer Number Is Null
010758: May 13 08:54:04.903 ATHENS: //84536/EDB0E0EDA3D8/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x31541E48, Tag=0x0, Call Id=84536,
Call Entry(Disconnect Cause=63, Voice Class Cause Code=0, Retry Count=0)
010759: May 13 08:54:04.903 ATHENS: //84536/EDB0E0EDA3D8/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
010760: May 13 08:54:04.903 ATHENS: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010761: May 13 08:54:04.903 ATHENS: :cc_free_feature_vsa freeing 2C1DB5F8
010762: May 13 08:54:04.903 ATHENS: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010763: May 13 08:54:04.903 ATHENS: vsacount in free is 1
010764: May 13 08:54:04.903 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallDisconnect:
Cause Value=63, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)
010765: May 13 08:54:04.903 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/ccCallDisconnect:
Cause Value=63, Call Entry(Responsed=TRUE, Cause Value=63)
010766: May 13 08:54:04.903 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 500 Internal Server Error
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bKe8d8f23d
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:04 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1011 NOTIFY
Content-Length: 0

010767: May 13 08:54:04.907 ATHENS: //84535/EDB0E0EDA3D8/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 500 Internal Server Error
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK391db043
From: "1000" <sip:1000@172.16.11.100>;tag=001aa16283b129d62bce7086-78a6f0cd
To: <sip:9@172.16.11.100>;tag=56FAA560-B8E
Date: Fri, 13 May 2016 05:54:02 GMT
Call-ID: 001aa162-83b1003f-03f1b9d0-668a8a27@172.16.11.109
CSeq: 101 INVITE
Allow-Events: telephone-event
Server: Cisco-SIPGateway/IOS-12.x
Reason: Q.850;cause=63
Content-Length: 0

010768: May 13 08:54:05.519 ATHENS: //0/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.16.11.100:5060;branch=z9hG4bK53E0DAD
From: <sip:9@172.16.11.100>;tag=56FA9B40-911
To: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:05 GMT
CSeq: 102 SUBSCRIBE
Server: Cisco-CP7911G/9.4.2
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Expires: 0
Content-Length: 0

010769: May 13 08:54:05.519 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
NOTIFY sip:9@172.16.11.100:5060 SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bKb594e036
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
Date: Fri, 13 May 2016 05:54:05 GMT
CSeq: 1012 NOTIFY
Event: kpml
Subscription-State: terminated; reason=timeout
Max-Forwards: 70
Contact: <sip:390376a4-86db-b28d-040a-15ce3eb7fc5e@172.16.11.109:5060;transport=UDP>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 222
Content-Type: application/kpml-response+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<kpml-response xmlns="urn:ietf:params:xml:ns:kpml-response" version="1.0" code="487" text="Subscription Exp" suppressed="false" forced_flush="false" digits="" tag="Backspace OK"/>

010770: May 13 08:54:05.523 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:9@172.16.11.100;user=phone SIP/2.0
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bK391db043
From: "1000" <sip:1000@172.16.11.100>;tag=001aa16283b129d62bce7086-78a6f0cd
To: <sip:9@172.16.11.100>;tag=56FAA560-B8E
Call-ID: 001aa162-83b1003f-03f1b9d0-668a8a27@172.16.11.109
Max-Forwards: 70
Date: Fri, 13 May 2016 05:54:05 GMT
CSeq: 101 ACK
Content-Length: 0

010771: May 13 08:54:05.523 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_disconnect_done:
Disposition=0, Interface=0x30FACD84, Tag=0x0, Call Id=84535,
Call Entry(Disconnect Cause=63, Voice Class Cause Code=0, Retry Count=0)
010772: May 13 08:54:05.523 ATHENS: //84535/EDB0E0EDA3D8/CCAPI/cc_api_call_disconnect_done:
Call Disconnect Event Sent
010773: May 13 08:54:05.523 ATHENS: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010774: May 13 08:54:05.523 ATHENS: :cc_free_feature_vsa freeing 2C1DB6D8
010775: May 13 08:54:05.523 ATHENS: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010776: May 13 08:54:05.523 ATHENS: vsacount in free is 0
010777: May 13 08:54:05.523 ATHENS: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 481 Subscription does not exist
Via: SIP/2.0/UDP 172.16.11.109:5060;branch=z9hG4bKb594e036
From: <sip:1000@172.16.11.109>;tag=001aa16283b129d71ef547a9-17e5fbc4
To: <sip:9@172.16.11.100>;tag=56FA9B40-911
Date: Fri, 13 May 2016 05:54:05 GMT
Call-ID: EDB219D5-180511E6-A3DDA199-D63EEB46@172.16.11.100
CSeq: 1012 NOTIFY
Content-Length: 0

Thank you in advance,

Murali Gunnam · ‎05-13-2016

Hi,

Looks like you haven't enabled debug isdn q931 as I don't see any messages on the PSTN leg. Call eventually getting failed with cause value 63 but we need to look at the ISDN messages.

Kindly enable debug isdn q931 along with other debugs you enabled earlier and attach the logs.

Regards,

Krish

v.matiakis · ‎05-13-2016

Hi.. Debug is enabled, it seems that the call isn't getting forward to the pstn. If i make the call from the PSTN the call reaches the isdn, but nothing else happens

010778: May 13 13:11:45.894 ATHENS: ISDN BR0/3/0 Q931: RX <- SETUP pd = 8 callref = 0x0A
Sending Complete
Bearer Capability i = 0x8090A3
Standard = CCITT
Transfer Capability = Speech
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0x89
Exclusive, B1
Calling Party Number i = 0x0183, '2310502020'
Plan:ISDN, Type:Unknown
Called Party Number i = 0xA1, '2310522087'
Plan:ISDN, Type:National
010779: May 13 13:11:47.050 ATHENS: ISDN BR0/3/0 Q931: RX <- CONNECT_ACK pd = 8 callref = 0x0A
010780: May 13 13:11:47.058 ATHENS: ISDN BR0/3/0 Q931: RX <- RELEASE pd = 8 callref = 0x0A
Cause i = 0x829A - Non-selected user clearing
CME-LAB#
010781: May 13 13:11:58.158 ATHENS: ISDN BR0/3/0 Q931: RX <- DISCONNECT pd = 8 callref = 0x0A
Cause i = 0x829F - Normal, unspecified
010782: May 13 13:11:58.222 ATHENS: ISDN BR0/3/0 Q931: RX <- RELEASE_COMP pd = 8 callref = 0x0A
CME-LAB#

CME-LAB#show deb

The following ISDN debugs are enabled on all DSLs:

debug isdn error is ON.
debug isdn q931 is ON. (filter is OFF)

Nadeem Ahmed · ‎05-12-2016

Looks like you have BRI can you please collect below debugs along with what Abhay has suggest. debug isdn events debug isdn standard debug bri-interface debug isdn events detail debug isdn cc detail

Br, Nadeem Please rate all useful post.

Murali Gunnam · ‎05-12-2016

Hi,

Configuration looks ok but we need to check logs in order find out cause of failure. As Nadeem and Abhay suggested, enable the below debugs and collect logs and share along with calling and called numbers.

debug isdn q931

debug voip ccapi inout

debug ccsip messages

Regards,

Krish

v.matiakis · ‎05-18-2016

Does anyone has similar problems or a working config?