CUE MWI not working

happyatuni · ‎05-26-2012

Dear NetPro gurus,

My customer has recently setup a new CUE with the existing CME. Everything works and users can retreive voicemails except MWI.

I have attached the CME configs as attached. Also, i have attached the debugs as below when I initiated "MWI refresh".

ce-e03-1#
May 26 2012 09:12:58.524 UTC: //6691/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
OPTIONS sip:10.69.11.1:5060 SIP/2.0
Via: SIP/2.0/UDP 10.69.250.83:5060;branch=z9hG4bK19E6CB
From: <sip:10.69.250.83>;tag=5E916C4-222
To: <sip:10.69.11.1>
Date: Sat, 26 May 2012 09:12:58 GMT
Call-ID: D2151E26-A64911E1-9A9A84E2-464F7003@10.69.250.83
User-Agent: Cisco-SIPGateway/IOS-15.2.2.T1
Max-Forwards: 70
CSeq: 101 OPTIONS
Contact: <sip:10.69.250.83:5060>
Content-Length: 0

May 26 2012 09:12:58.524 UTC: //6692/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
OPTIONS sip:10.69.11.1:5060 SIP/2.0
Via: SIP/2.0/UDP 10.69.250.83:5060;branch=z9hG4bK19E71AC
From: <sip:10.69.250.83>;tag=5E916C4-13BB
To: <sip:10.69.11.1>
Date: Sat, 26 May 2012 09:12:58 GMT
Call-ID: D2151E26-A64911E1-9A9B84E2-464F7003@10.69.250.83
User-Agent: Cisco-SIPGateway/IOS-15.2.2.T1
Max-Forwards: 70
CSeq: 101 OPTIONS
Contact: <sip:10.69.250.83:5060>
Content-Length: 0

May 26 2012 09:12:58.528 UTC: //6693/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
OPTIONS sip:10.69.11.1:5060 SIP/2.0
Via: SIP/2.0/UDP 10.69.250.83:5060;branch=z9hG4bK19E8199
From: <sip:10.69.250.83>;tag=5E916C8-82
To: <sip:10.69.11.1>
Date: Sat, 26 May 2012 09:12:58 GMT
Call-ID: D215BAE6-A64911E1-9A9C84E2-464F7003@10.69.250.83
User-Agent: Cisco-SIPGateway/IOS-15.2.2.T1
Max-Forwards: 70
CSeq: 101 OPTIONS
Contact: <sip:10.69.250.83:5060>
Content-Length: 0

May 26 2012 09:12:58.528 UTC: //6694/000000000000/SIP/Msg/ccsipDisplayMsg:
Sent:
OPTIONS sip:10.69.11.1:5060 SIP/2.0
Via: SIP/2.0/UDP 10.69.250.83:5060;branch=z9hG4bK19E91DEF
From: <sip:10.69.250.83>;tag=5E916C8-159E
To: <sip:10.69.11.1>
Date: Sat, 26 May 2012 09:12:58 GMT
Call-ID: D215BAE6-A64911E1-9A9D84E2-464F7003@10.69.250.83
User-Agent: Cisco-SIPGateway/IOS-15.2.2.T1
Max-Forwards: 70
CSeq: 101 OPTIONS
Contact: <sip:10.69.250.83:5060>
Content-Length: 0

May 26 2012 09:12:58.544 UTC: //6691/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Date: Sat, 26 May 2012 09:12:58 GMT
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
From: <sip:10.69.250.83>;tag=5E916C4-222
Content-Length: 0
To: <sip:10.69.11.1>;tag=27483156
Call-ID: D2151E26-A64911E1-9A9A84E2-464F7003@10.69.250.83
Via: SIP/2.0/UDP 10.69.250.83:5060;branch=z9hG4bK19E6CB
CSeq: 101 OPTIONS

May 26 2012 09:12:58.544 UTC: //6692/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Date: Sat, 26 May 2012 09:12:58 GMT
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
From: <sip:10.69.250.83>;tag=5E916C4-13BB
Content-Length: 0
To: <sip:10.69.11.1>;tag=1311376281
Call-ID: D2151E26-A64911E1-9A9B84E2-464F7003@10.69.250.83
Via: SIP/2.0/UDP 10.69.250.83:5060;branch=z9hG4bK19E71AC
CSeq: 101 OPTIONS

May 26 2012 09:12:58.548 UTC: //6693/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Date: Sat, 26 May 2012 09:12:58 GMT
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
From: <sip:10.69.250.83>;tag=5E916C8-82
Content-Length: 0
To: <sip:10.69.11.1>;tag=650594590
Call-ID: D215BAE6-A64911E1-9A9C84E2-464F7003@10.69.250.83
Via: SIP/2.0/UDP 10.69.250.83:5060;branch=z9hG4bK19E8199
CSeq: 101 OPTIONS

May 26 2012 09:12:58.548 UTC: //6694/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Date: Sat, 26 May 2012 09:12:58 GMT
Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
From: <sip:10.69.250.83>;tag=5E916C8-159E
Content-Length: 0
To: <sip:10.69.11.1>;tag=1369032143
Call-ID: D215BAE6-A64911E1-9A9D84E2-464F7003@10.69.250.83
Via: SIP/2.0/UDP 10.69.250.83:5060;branch=z9hG4bK19E91DEF
CSeq: 101 OPTIONS

Cheers,

Hunt

Ayodeji Okanlawon · ‎05-26-2012

Hi Hunt Lee,

Can you check that the same number has been configured for MWI ON/OFF in CUE..

type show ccn application

check that the mwi number in

strMWI_OFF_DN:

strMWI_ON_DN:

is the same as configured on CCME.

Also when you did your mwi refresh, there is no invite coming from CUE to CCME to change the state of the MWI LAMP.

Can you ensure that the sip gateway address on your cue is the ip address of the ccme router using the command below:

sh ccn subsystem sip

Please rate all useful posts

chuckeplus · ‎05-26-2012

Add this

sip-ua

mwi-server ipv4:x.x.x.x expires 3600 port 5060 transport udp

Sent from Cisco Technical Support iPad App

chuckeplus · ‎05-26-2012

Opps also need to add under all ephone- dn

Ephone- dn

Mwi sip

Sent from Cisco Technical Support iPad App

Karthik Sivaram · ‎05-26-2012

hi Hunt,

on the cue ...do a "show ccn application"....this will show you mwi on and off numbers.

The system does not have a concept of the number of digits in the extensions; it simply places a call to the

appropriate MWI on or off number and appends the mailbox extension. The Cisco CallManager Express

system must have a dial peer with the appropriate number of dots in the destination pattern in order to route

the call properly.

Finally, be sure that the Cisco Unity Express SIP gateway IP address points to the correct Cisco CallManager express . "show ccn subsystem sip"

to check this.

Hope this helps!

Thanks,

Karthik

happyatuni · ‎05-29-2012

Hi guys,

From the CUE ISM module, i can ping the CME without any problems.

CME itself: 10.69.254.30

CUE ISM card: 10.69.254.28

There are 4 unread Voicemail on the IP Phone, but MWI just won't work...

!

interface ISM0/0

description CUE running on SRE Module

ip vrf forwarding voip

ip address 10.69.254.30 255.255.255.248

service-module ip address 10.69.254.28 255.255.255.248

!Application: CUE Running on ISM

service-module ip default-gateway 10.69.254.30

end

!

On CUE Module

se-10-69-45-253# ping 10.69.254.30
PING 10.69.254.30 (10.69.254.30) 56(84) bytes of data.
64 bytes from 10.69.254.30: icmp_seq=1 ttl=255 time=0.161 ms
64 bytes from 10.69.254.30: icmp_seq=2 ttl=255 time=0.109 ms
64 bytes from 10.69.254.30: icmp_seq=3 ttl=255 time=0.108 ms
64 bytes from 10.69.254.30: icmp_seq=4 ttl=255 time=0.108 ms
64 bytes from 10.69.254.30: icmp_seq=5 ttl=255 time=0.109 ms

--- 10.69.254.30 ping statistics ---
5 packets transmitted, 5 received, 0% packet loss, time 0ms
rtt min/avg/max/mdev = 0.108/0.119/0.161/0.021 ms, ipg/ewma 0.164/0.139 ms
se-10-69-45-253# ping 10.69.254.28
PING 10.69.254.28 (10.69.254.28) 56(84) bytes of data.
64 bytes from 10.69.254.28: icmp_seq=1 ttl=64 time=0.058 ms
64 bytes from 10.69.254.28: icmp_seq=2 ttl=64 time=0.012 ms
64 bytes from 10.69.254.28: icmp_seq=3 ttl=64 time=0.007 ms
64 bytes from 10.69.254.28: icmp_seq=4 ttl=64 time=0.007 ms
64 bytes from 10.69.254.28: icmp_seq=5 ttl=64 time=0.006 ms

--- 10.69.254.28 ping statistics ---
5 packets transmitted, 5 received, 0% packet loss, time 0ms
rtt min/avg/max/mdev = 0.006/0.018/0.058/0.020 ms, ipg/ewma 0.054/0.037 ms
se-10-69-45-253#

se-10-69-45-253# show ccn application

Name:                                   sip
Description:                            sip
Application type:                       aa
Script:                                 noscript
ID number:                              6
Enabled:                                yes
Maximum number of sessions:             8

Name:                                   promptmgmt
Description:                            promptmgmt
Application type:                       aa
Script:                                 promptmgmt.aef
ID number:                              4
Enabled:                                yes
Maximum number of sessions:             1
appManagementScript:

Name:                                   autoattendant
Description:                            autoattendant
Application type:                       aa
Script:                                 aa.aef
ID number:                              1
Enabled:                                yes
Maximum number of sessions:             10
allowExternalTransfers:                 false
busClosedPrompt:                        AABusinessClosed.wav
businessSchedule:                       systemschedule
busOpenPrompt:                          AABusinessOpen.wav
dialByExtnAnytime:                      false
dialByExtnAnytimeInputLength:           4
dialByFirstName:                        false
disconnectAfterMenu:                    false
holidayPrompt:                          AAHolidayPrompt.wav
MaxRetry:                               3
operExtn:
welcomePrompt:                          AAWelcome.wav

Name:                                   voicemail
Description:                            voicemail
Application type:                       aa
Script:                                 voicebrowser.aef
ID number:                              2
Enabled:                                yes
Maximum number of sessions:             10
logoutUri:                              http://localhost/voicemail/vxmlscripts/mbxLogout.jsp
uri:                                    http://localhost/voicemail/vxmlscripts/login.vxml

Name:                                   msgnotification
Description:                            msgnotification
Application type:                       aa
Script:                                 msgnotify.aef
ID number:                              5
Enabled:                                yes
Maximum number of sessions:             10
DelayBeforeSendDTMF:                    1
logoutUri:                              http://localhost/voicemail/vxmlscripts/mbxLogout.jsp

Name:                                   ciscomwiapplication
Description:                            ciscomwiapplication
Application type:                       aa
Script:                                 setmwi.aef
ID number:                              3
Enabled:                                yes
Maximum number of sessions:             10
CallControlGroupID:                     0
strMWI_OFF_DN:                          8280
strMWI_ON_DN:                           8281

se-10-69-45-253# sh ccn subsystem sip
SIP Gateway:                            10.69.254.30
SIP Port Number:                        5060
DTMF Relay:                             sip-notify,sub-notify
MWI Notification:                       unsolicited,sub-notify
MWI Envelope Info:                      enabled
Transfer Mode:                          bye-also
SIP RFC Compliance:                     Pre-RFC3261
se-10-69-45-253#

I have re-attached both CME & CUE configs.

Would apprecited any help on this.

Cheers,

Hunt

Abdo Ahmed Saleh Al-Kaf · ‎06-19-2013

Hi Hunt

I check yur configuratin of CUE just need to remove the unsolicited command from ccn subsystem sip "no mwi sip unsolicited sub-notify" it will work fine>>>

Thanks

asotojimenezz · ‎07-21-2016

Hello

I have the same problem, any solution?

GATEWAY 2911

TELEPHONES: 7821, 7841

CME: 172.16.1.1

CUE: 172.16.1.2

CME

USER:

voice register dn 8
number 816
call-forward b2bua busy 1000
call-forward b2bua mailbox 1000
call-forward b2bua noan 1000 timeout 10
allow watch
pickup-call any-group
pickup-group 10
name Claudio Rangel
label 816 Claudio Rangel
mwi

dial-peer voice 1000 voip
description al unity express
destination-pattern 1000
b2bua
session protocol sipv2
session target ipv4:172.16.1.2
dtmf-relay sip-notify
codec g711ulaw
no vad

sip-ua
mwi-server ipv4:172.16.1.2 expires 3600 port 5060 transport udp unsolicited

GNP_GATEWAY#show voice register global
CONFIG [Version=10.0]
========================
Version 10.0
Mode is cme
Max-pool is 55
Max-dn is 55
Outbound-proxy is enabled and will use global configured value
Security Policy: DEVICE-DEFAULT
Forced Authorization Code Refer is enabled
Source-address is 172.16.1.1 port 5060
Authenticate register
Authenticate realm lab.local
NTP Server Address is 172.16.1.1
NTP Server Mode is: directedbroadcast
Time-format is 24
Date-format is D/M/Y
Time-zone is 9
Hold-alert is disabled
Mwi stutter is enabled
Mwi registration for full E.164 is enabled

CUE

CUEGNP# show user detail username claudio
Username: claudio
Full Name: Claudio rangel
First Name: Claudio
Last Name: rangel
Nickname: Claudio rangel
Phone: 816
Phone(E.164):
Fax:
Email:
Epage:
Language: es_MX
GroupMember: <none>
GroupOwner: <none>
Privileges: <none>

CUEGNP# show ccn subsystem sip
SIP Gateway: 172.16.1.1
SIP Port Number: 5060
DTMF Relay: sip-notify,sub-notify
MWI Notification: unsolicited
MWI Envelope Info: disabled
Transfer Mode: bye-also
Transfer Timeout: 6
SIP RFC Compliance: Pre-RFC3261

CUEGNP# show ccn subsystem sip
SIP Gateway: 172.16.1.1
SIP Port Number: 5060
DTMF Relay: sip-notify,sub-notify
MWI Notification: unsolicited
MWI Envelope Info: disabled
Transfer Mode: bye-also
Transfer Timeout: 6
SIP RFC Compliance: Pre-RFC3261
CUEGNP# show ccn app
CUEGNP# show ccn application

Name: autoattendant
Description: Cisco Auto-Attendant
Application type: aa
Script: aa.aef
ID number: 1
Enabled: yes
Maximum number of sessions: 2
allowExternalTransfers: true
busClosedPrompt: AABusinessClosed.wav
businessSchedule: systemschedule
busOpenPrompt: Conmutador_aps_1.wav
dialByExtnAnytime: true
dialByExtnAnytimeInputLength: 3
dialByFirstName: false
disconnectAfterMenu: false
holidayPrompt: AAHolidayPrompt.wav
MaxRetry: 0
operExtn: 800
welcomePrompt: Conmutador_aps_3segs_1u.wav

Name: promptmgmt
Description: promptmgmt
Application type: aa
Script: promptmgmt.aef
ID number: 4
Enabled: yes
Maximum number of sessions: 1
appManagementScript:

Name: voicemail
Description: Cisco Voicemail
Application type: aa
Script: voicebrowser.aef
ID number: 2
Enabled: yes
Maximum number of sessions: 1
logoutUri: http://localhost/voicemail/vxmlscripts/mbxLogout.jsp
uri: http://localhost/voicemail/vxmlscripts/login.vxml

Name: msgnotification
Description: msgnotification
Application type: aa
Script: msgnotify.aef
ID number: 5
Enabled: yes
Maximum number of sessions: 10
DelayBeforeSendDTMF: 1
logoutUri: http://localhost/voicemail/vxmlscripts/mbxLogout.jsp

Name: ciscomwiapplication
Description: ciscomwiapplication
Application type: aa
Script: setmwi.aef
ID number: 3
Enabled: yes
Maximum number of sessions: 10
CallControlGroupID: 0
strMWI_OFF_DN: 8001
strMWI_ON_DN: 8000

I keep the following documentation

http://www.cisco.com/c/en/us/support/docs/voice-unified-communications/unity-express/60081-unity-mwi-problems.html

no trace all

trace ccn stacksip dbug.

mwi refresh

show trace buffer long

CUEGNP# show trace buffer long

Press <CTRL-C> to exit...

11456 07/21 15:48:05.840 ACCN SIPL 0 SIP NewInvitationCallback.invitation()

INVITE sip:1000@172.16.1.2:5060 SIP/2.0

Via: SIP/2.0/UDP 172.16.1.1:5060;branch=z9hG4bK722BF2228;x-route-tag="tgrp:AXTEL"

Max-Forwards: 69

To: <sip:1000@172.16.1.2>

From: <sip:5545586137@172.16.1.1>;tag=8935D068-1FEB

Call-ID: 8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1

CSeq: 101 INVITE

Content-Length: 451

Remote-Party-ID: <sip:5545586137@172.16.1.1>;party=calling;screen=no;privacy=off

Date: Thu, 21 Jul 2016 21:47:31 GMT

Supported: 100rel,timer,resource-priority,replaces,sdp-anat

Min-SE: 1800

Cisco-Guid: 2407445159-1321406950-2270663624-1320671749

User-Agent: Cisco-SIPGateway/IOS-15.3.3.M6

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER

Timestamp: 1469137651

Contact: <sip:5545586137@172.16.1.1:5060>

Call-Info: <sip:172.16.1.1:5060>;method="NOTIFY;Event=telephone-event;Duration=2000"

Diversion: <sip:816@172.16.1.1>;privacy=off;reason=no-answer;counter=1;screen=no

Expires: 180

Allow-Events: telephone-event

Content-Type: multipart/mixed;boundary=uniqueBoundary

MIME-Version: 1.0

--uniqueBoundary

Content-Type: application/sdp

Content-Disposition: session;handling=required

v=0

o=CiscoSystemsSIP-GW-UserAgent 875 8068 IN IP4 172.16.1.1

s=SIP Call

c=IN IP4 172.16.1.1

t=0 0

m=audio 16716 RTP/AVP 0

c=IN IP4 172.16.1.1

a=rtpmap:0 PCMU/8000

a=ptime:20

--uniqueBoundary

Content-Type: application/gtd

Content-Disposition: signal;handling=optional

IAM,

GCI,8f7eb2a74ec311e6875793c84eb7da05

--uniqueBoundary--

11456 07/21 15:48:05.841 ACCN SIPL 0 SIP invitation: targetNumber=1000

11456 07/21 15:48:05.843 ACCN SIPL 0 LocalLine[1000] process invitation, callId=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, globalCallId=null from SIP-Remote-MT-8282 dn=5545586137 addr=172.16.1.1:16716, dtmf=-1, codecs=[0], ptime=20, mediaStarted=false (sip:5545586137@172.16.1.1)

11456 07/21 15:48:05.843 ACCN SIPL 0 LocalLine[1000] InboundCallInfo=SIP-InboundCallInfo: source=5545586137, (sip:5545586137@172.16.1.1), destination=1000, (sip:1000@172.16.1.2), callId=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, gcId=null, lastRedirectDN=816, (sip:816@172.16.1.1), originalCalledDN=816, callingDisplayName=null, arrivalType=no-answer

11456 07/21 15:48:05.843 ACCN SIPL 0 NewInvitationCallback: creating new call

11456 07/21 15:48:05.843 ACCN SIPL 0 SIP-Call-4142:IDLE[] <init>

11456 07/21 15:48:05.843 ACCN SIPL 0 SIP-Provider: getAbstractLine: creating RemoteLineImpl for DN 5545586137

11456 07/21 15:48:05.843 ACCN SIPL 0 RemoteLine[5545586137@172.16.1.1]: created

11456 07/21 15:48:05.843 ACCN SIPL 0 Call.createConnection: this=SIP-Call-4142:IDLE[], line=RemoteLine[5545586137@172.16.1.1], state=ACTIVE, sc=null, mt=[SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=false], addToCall=true

11456 07/21 15:48:05.844 ACCN SIPL 0 [Conn-8283-5545586137] ConnectionState.<init>: state=ACTIVE

11456 07/21 15:48:05.844 ACCN SIPL 0 [SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=false] associate with [SIP-Conn-8283, dn=5545586137, call=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, state=ACTIVE]

ctrl=[CSTA-SC-8283], MT=[[SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=false]]

11456 07/21 15:48:05.844 ACCN SIPL 0 CallManager.put: callIdImpl=[8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1,null,4142]

11456 07/21 15:48:05.844 ACCN SIPL 0 [Conn-8283-5545586137] <init>: [SIP-Conn-8283, dn=5545586137, call=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, state=ACTIVE]

ctrl=[CSTA-SC-8283], MT=[[SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=false]]

11456 07/21 15:48:05.844 ACCN SIPL 0 SIP-Call-4142:ACTIVE[5545586137:ACTIVE] addCallListener com.cisco.wf.subsystems.callcontrol.AppRoutePointCallListener@6e9b6a

11456 07/21 15:48:05.844 ACCN SIPL 0 Call.createConnection: this=SIP-Call-4142:ACTIVE[5545586137:ACTIVE], line=LocalLine[1000], state=IDLE, sc=ltp8282-1000, State=IDLE: , mt=[SIP-Local-MT-8283 dn=1000 no media], addToCall=true

11456 07/21 15:48:05.844 ACCN SIPL 0 [Conn-8284-1000] ConnectionState.<init>: state=IDLE

11456 07/21 15:48:05.844 ACCN SIPL 0 [SIP-Local-MT-8283 dn=1000 no media] set connection [SIP-Conn-8284, dn=1000, call=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, state=IDLE]

ctrl=[ltp8282], MT=[[SIP-Local-MT-8283 dn=1000 no media]]

11456 07/21 15:48:05.844 ACCN SIPL 0 [Conn-8284-1000] <init>: [SIP-Conn-8284, dn=1000, call=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, state=IDLE]

ctrl=[ltp8282], MT=[[SIP-Local-MT-8283 dn=1000 no media]]

11456 07/21 15:48:05.844 ACCN SIPL 0 SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:IDLE] addCallListener com.cisco.wf.subsystems.callcontrol.AppRoutePointCallListener@6e9b6a

11456 07/21 15:48:05.844 ACCN SIPL 0 New InvitationCallback from LocalLine[1000] creates SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:IDLE]

11456 07/21 15:48:05.844 ACCN SIPL 0 NewInvitationCallback.processInvitation: id=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, callId=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, lastRedirectedDN=816, originalCalledDN=816, originalCallingDN=5545586137, calledDN=1000, parentGlobalCallId=null, type=1002, arrivalType=1002

11456 07/21 15:48:05.844 ACCN SIPL 0 LocalLine[1000] process inbound call, gcId=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1

Local: [SIP-Conn-8284, dn=1000, call=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, state=IDLE]

ctrl=[ltp8282], MT=[[SIP-Local-MT-8283 dn=1000 no media]] LocalLine[1000]

Remote: [SIP-Conn-8283, dn=5545586137, call=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, state=ACTIVE]

ctrl=[CSTA-SC-8283], MT=[[SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=false]] RemoteLine[5545586137@172.16.1.1]

11456 07/21 15:48:05.844 ACCN SIPL 0 [Conn-8284-1000] onCallConnectionOffered: state=IDLE

11456 07/21 15:48:05.844 ACCN SIPL 0 [Conn-8284-1000] setState: IDLE -> OFFERED

11456 07/21 15:48:05.845 ACCN SIPL 0 CallImpl.notifyAllListeners: ev=CALL_CONNECTION_OFFERED (1000) call=SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:OFFERED]

CALL_CONNECTION_OFFERED

EVCall=SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:OFFERED]EVConn=[SIP-Conn-8284, dn=1000, call=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, state=OFFERED]

ctrl=[ltp8282], MT=[[SIP-Local-MT-8283 dn=1000 no media]]

11456 07/21 15:48:05.845 ACCN SIPL 0 SIP-Call-4142, GCID=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1 dispatch to com.cisco.wf.subsystems.callcontrol.AppRoutePointCallListener@6e9b6a

4601 07/21 15:48:05.846 ACCN SIPL 0 SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:OFFERED] addCallListener com.cisco.wf.subsystems.callcontrol.AppCallListener@1ba4b88

4601 07/21 15:48:05.846 ACCN SIPL 0 SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:OFFERED] removeCallListener com.cisco.wf.subsystems.callcontrol.AppRoutePointCallListener@6e9b6a

4601 07/21 15:48:05.849 ACCN SIPL 0 [SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=false] - add MediaListener com.cisco.wf.subsystems.callcontrol.AppMediaTerminalListener@1532f8d

4601 07/21 15:48:05.849 ACCN SIPL 0 [SIP-Local-MT-8283 dn=1000 no media] - add MediaListener com.cisco.wf.subsystems.callcontrol.AppMediaTerminalListener@1532f8d

4601 07/21 15:48:05.852 ACCN SIPL 0 ltp8282-1000, State=ALERTED: process Invite, callId=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1

4601 07/21 15:48:05.853 ACCN SIPL 0 ltp8282-1000, State=ALERTED: sending ringing response:

SIP/2.0 180 Ringing

Via: SIP/2.0/UDP 172.16.1.1:5060;branch=z9hG4bK722BF2228;x-route-tag="tgrp:AXTEL"

To: <sip:1000@172.16.1.2>;tag=ds131e2f69

From: <sip:5545586137@172.16.1.1>;tag=8935D068-1FEB

Call-ID: 8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1

CSeq: 101 INVITE

Content-Length: 0

Contact: <sip:1000@172.16.1.2:5060;transport=udp>

Allow: INVITE, BYE, CANCEL, ACK, REFER, SUBSCRIBE, NOTIFY, INFO

Cisco-Gcid: 8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1

4601 07/21 15:48:05.853 ACCN SIPL 0 ltp8282-1000, State=ALERTED: setDialog: com.dynamicsoft.DsLibs.DsSipDialog.DsSipInviteDialog@1487e6b isConfirmed=false

4601 07/21 15:48:05.853 ACCN SIPL 0 [Conn-8284-1000] onCallConnectionRinging: state=OFFERED

4601 07/21 15:48:05.853 ACCN SIPL 0 [Conn-8284-1000] setState: OFFERED -> RINGING

4601 07/21 15:48:05.853 ACCN SIPL 0 CallImpl.notifyAllListeners: ev=CALL_CONNECTION_RINGING (1000) call=SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:RINGING]

CALL_CONNECTION_RINGING

EVCall=SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:RINGING]EVConn=[SIP-Conn-8284, dn=1000, call=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, state=RINGING]

ctrl=[ltp8282], MT=[[SIP-Local-MT-8283 dn=1000 media=172.16.1.2:21426, mediaStarted=false]]

4601 07/21 15:48:05.853 ACCN SIPL 0 SIP-Call-4142, GCID=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1 dispatch to com.cisco.wf.subsystems.callcontrol.AppCallListener@1ba4b88

4037 07/21 15:48:05.862 ACCN SIPL 0 ltp8282-1000, State=ALERTED: sending ok response for dialog

SIP/2.0 200 Ok

Via: SIP/2.0/UDP 172.16.1.1:5060;branch=z9hG4bK722BF2228;x-route-tag="tgrp:AXTEL"

To: <sip:1000@172.16.1.2>;tag=ds131e2f69

From: <sip:5545586137@172.16.1.1>;tag=8935D068-1FEB

Call-ID: 8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1

CSeq: 101 INVITE

Content-Length: 127

Contact: <sip:1000@172.16.1.2:5060;transport=udp>

Content-Type: application/sdp

Call-Info: <sip:172.16.1.2:5060>;method="NOTIFY;Event=telephone-event;Duration=2000"

Allow-Events: telephone-event

Allow: INVITE, BYE, CANCEL, ACK, REFER, SUBSCRIBE, NOTIFY, INFO

Cisco-Gcid: 8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1

v=0

o=CUE 3612371 2 IN IP4 172.16.1.2

s=SIP Call

c=IN IP4 172.16.1.2

t=0 0

m=audio 21426 RTP/AVP 0

a=rtpmap:0 PCMU/8000

4037 07/21 15:48:05.862 ACCN SIPL 0 [SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=false]: getDtmfMethodOut: sip-notify

4037 07/21 15:48:05.862 ACCN SIPL 0 ltp8282-1000, State=ALERTED: initDtmf: dtmfMethodIn=sip-notify

4037 07/21 15:48:05.862 ACCN SIPL 0 DtmfSubscription.<init>: unsolicited=true

4609 07/21 15:48:05.870 ACCN SIPL 0 ltp8282-1000, State=ALERTED: processAck Call-ID=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1 isReplacing=false, body=null

4609 07/21 15:48:05.870 ACCN SIPL 0 [Conn-8284-1000] onCallConnectionActive: state=RINGING

4609 07/21 15:48:05.870 ACCN SIPL 0 [Conn-8284-1000] setState: RINGING -> ACTIVE

4609 07/21 15:48:05.870 ACCN SIPL 0 CallImpl.notifyAllListeners: ev=CALL_CONNECTION_ACTIVE (1000) call=SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:ACTIVE]

CALL_CONNECTION_ACTIVE

EVCall=SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:ACTIVE]EVConn=[SIP-Conn-8284, dn=1000, call=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, state=ACTIVE]

ctrl=[ltp8282], MT=[[SIP-Local-MT-8283 dn=1000 media=172.16.1.2:21426, mediaStarted=false]]

, cause=ANSWERED

4609 07/21 15:48:05.870 ACCN SIPL 0 SIP-Call-4142, GCID=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1 dispatch to com.cisco.wf.subsystems.callcontrol.AppCallListener@1ba4b88

4609 07/21 15:48:05.871 ACCN SIPL 0 CallImpl.notifyAllListeners: ev=CALL_ANSWERED call=SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:ACTIVE]

CALL_ANSWERED

EVCall=SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:ACTIVE]

4609 07/21 15:48:05.871 ACCN SIPL 0 SIP-Call-4142, GCID=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1 dispatch to com.cisco.wf.subsystems.callcontrol.AppCallListener@1ba4b88

4609 07/21 15:48:05.871 ACCN SIPL 0 [SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=false]

dispatch media MEDIA_STARTED Event: [SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=false] to com.cisco.wf.subsystems.callcontrol.AppMediaTerminalListener@1532f8d

4609 07/21 15:48:05.874 ACCN SIPL 0 [SIP-Local-MT-8283 dn=1000 media=172.16.1.2:21426, mediaStarted=false]

dispatch media MEDIA_STARTED Event: [SIP-Local-MT-8283 dn=1000 media=172.16.1.2:21426, mediaStarted=false] to com.cisco.wf.subsystems.callcontrol.AppMediaTerminalListener@1532f8d

4609 07/21 15:48:27.377 ACCN SIPL 0 ltp8282-1000, State=CONNECTED: processTerminated

BYE sip:1000@172.16.1.2:5060;transport=udp SIP/2.0

Via: SIP/2.0/UDP 172.16.1.1:5060;branch=z9hG4bK722C1997;x-route-tag="tgrp:AXTEL"

Max-Forwards: 69

To: <sip:1000@172.16.1.2>;tag=ds131e2f69

From: <sip:5545586137@172.16.1.1>;tag=8935D068-1FEB

Call-ID: 8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1

CSeq: 102 BYE

Content-Length: 0

Date: Thu, 21 Jul 2016 21:47:31 GMT

User-Agent: Cisco-SIPGateway/IOS-15.3.3.M6

Timestamp: 1469137672

Reason: Q.850;cause=16

P-RTP-Stat: PS=1074,OS=171840,PR=693,OR=110403,PL=0,JI=0,LA=0,DU=21

4609 07/21 15:48:27.377 ACCN SIPL 0 ltp8282-1000, State=CONNECTED: setDisconnected processTerminated, cause=NORMAL

4609 07/21 15:48:27.377 ACCN SIPL 0 SIP-Call-4142:ACTIVE[5545586137:ACTIVE,1000:ACTIVE] clearId=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1

4609 07/21 15:48:27.377 ACCN SIPL 0 ##### ltp8282-1000, State=DISCONNECTED: ##### [SIP-Local-MT-8283 dn=1000 media=172.16.1.2:21426, mediaStarted=true] ##### [SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=true]: ##### ltp8282-1000, State=DISCONNECTED: ##### [SIP-Local-MT-8283 dn=1000 media=172.16.1.2:21426, mediaStarted=true] ##### [SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=true]

4609 07/21 15:48:27.378 ACCN SIPL 0 [SIP-Local-MT-8283 dn=1000 media=172.16.1.2:21426, mediaStarted=true]

dispatch media MEDIA_STOPPED Event: [SIP-Local-MT-8283 dn=1000 media=172.16.1.2:21426, mediaStarted=true] to com.cisco.wf.subsystems.callcontrol.AppMediaTerminalListener@1532f8d

4609 07/21 15:48:27.378 ACCN SIPL 0 [SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=true]

dispatch media MEDIA_STOPPED Event: [SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=true] to com.cisco.wf.subsystems.callcontrol.AppMediaTerminalListener@1532f8d

4609 07/21 15:48:27.378 ACCN SIPL 0 SIP-Call-4142:ACTIVE[5545586137:ACTIVE], remove connection 1000, last=false

4609 07/21 15:48:27.378 ACCN SIPL 0 [Conn-8284-1000] onCallConnectionDisconnected: state=ACTIVE, cause=NORMAL

4609 07/21 15:48:27.378 ACCN SIPL 0 [Conn-8284-1000] setState: ACTIVE -> DISCONNECTED

4609 07/21 15:48:27.378 ACCN SIPL 0 CallImpl.notifyAllListeners: ev=CALL_CONNECTION_DISCONNECTED (1000) call=SIP-Call-4142:ACTIVE[5545586137:ACTIVE]

CALL_CONNECTION_DISCONNECTED

EVCall=SIP-Call-4142:ACTIVE[5545586137:ACTIVE]EVConn=[SIP-Conn-8284, dn=1000, call=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, state=DISCONNECTED]

ctrl=[ltp8282], MT=[[SIP-Local-MT-8283 dn=1000 media=172.16.1.2:21426, mediaStarted=false]]

, cause=NORMAL

4609 07/21 15:48:27.378 ACCN SIPL 0 SIP-Call-4142, GCID=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1 dispatch to com.cisco.wf.subsystems.callcontrol.AppCallListener@1ba4b88

4609 07/21 15:48:27.378 ACCN SIPL 0 SIP-Call-4142:ACTIVE[5545586137:ACTIVE] checkCallActive

4609 07/21 15:48:27.378 ACCN SIPL 0 checkCallActive 0: isLocal=false, isMonitored=false

4609 07/21 15:48:27.378 ACCN SIPL 0 SIP-Call-4142:ACTIVE[5545586137:ACTIVE] dropped, connLen=1

4609 07/21 15:48:27.378 ACCN SIPL 0 SIP-Call-4142:ACTIVE[], remove connection 5545586137, last=true

4609 07/21 15:48:27.378 ACCN SIPL 0 SIP-Call-4142:INACTIVE[] change state to inactive, because last connection has been removed

4609 07/21 15:48:27.378 ACCN SIPL 0 [Conn-8283-5545586137] onCallConnectionDisconnected: state=ACTIVE, cause=UNKNOWN

4609 07/21 15:48:27.378 ACCN SIPL 0 [Conn-8283-5545586137] setState: ACTIVE -> DISCONNECTED

4609 07/21 15:48:27.378 ACCN SIPL 0 CallImpl.notifyAllListeners: ev=CALL_CONNECTION_DISCONNECTED (5545586137) call=SIP-Call-4142:INACTIVE[]

CALL_CONNECTION_DISCONNECTED

EVCall=SIP-Call-4142:INACTIVE[]EVConn=[SIP-Conn-8283, dn=5545586137, call=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1, state=DISCONNECTED]

ctrl=[CSTA-SC-8283], MT=[[SIP-Remote-MT-8282 dn=5545586137 media=172.16.1.1:16716, mediaStarted=false]]

, cause=UNKNOWN

4609 07/21 15:48:27.378 ACCN SIPL 0 SIP-Call-4142, GCID=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1 dispatch to com.cisco.wf.subsystems.callcontrol.AppCallListener@1ba4b88

4609 07/21 15:48:27.378 ACCN SIPL 0 CallImpl.notifyAllListeners: ev=CALL_INACTIVE call=SIP-Call-4142:INACTIVE[]

CALL_INACTIVE

EVCall=SIP-Call-4142:INACTIVE[], cause=UNKNOWN

4609 07/21 15:48:27.378 ACCN SIPL 0 SIP-Call-4142, GCID=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1 dispatch to com.cisco.wf.subsystems.callcontrol.AppCallListener@1ba4b88

4609 07/21 15:48:27.378 ACCN SIPL 0 Drop NON-monitored SIP-Call-4142:INACTIVE[] because it has zero connections

4609 07/21 15:48:27.378 ACCN SIPL 0 SIP-Call-4142:INACTIVE[] dropped, connLen=0

4609 07/21 15:48:27.378 ACCN SIPL 0 CallManager.removeCall: callIdImpl=[8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1,null,4142]

4609 07/21 15:48:27.378 ACCN SIPL 0 CallImpl.notifyAllListeners: ev=CALL_DROPPED call=SIP-Call-4142:INVALID[]

CALL_DROPPED

EVCall=SIP-Call-4142:INVALID[], cause=UNKNOWN

4609 07/21 15:48:27.378 ACCN SIPL 0 SIP-Call-4142, GCID=8F7F4ECF-4EC311E6-875B93C8-4EB7DA05@172.16.1.1 dispatch to com.cisco.wf.subsystems.callcontrol.AppCallListener@1ba4b88

4609 07/21 15:48:27.378 ACCN SIPL 0 SIP-Call-4142:INVALID[] checkCallActive

4609 07/21 15:48:27.379 ACCN SIPL 0 ltp8282-1000, State=DISCONNECTED: Drop Call because mode= SIP

6235 07/21 15:48:27.386 ACCN SIPL 0 SIP-Call-4142:INVALID[] removeCallListener com.cisco.wf.subsystems.callcontrol.AppCallListener@1ba4b88

CUEGNP#

This is mandatory ??

ephone-dn 44
number 8000...
mwi on
!
ephone-dn 45
number 8001...
mwi off

I have not configured it to be SIP phones

I need some configuration ??

which is my mistake ??

Regards