Voice Mail Issues

[danfrpa1]

I have a new install. All IP Phones connect to the system and can dial each extension just fine, but when I try to configure VM from a IP Phone (dialing 999), I get a message that says "This call can not be completed". I get that same response from every extention.

I can not get into the Voice Mail feature at all???

Does anybody know why?

 

[lneblett]

Do you have Vmail enabled for the extensions? What is the length of your extension numbers? Is it possible that you have an outbound rule that is somehow being confused? Does Vmail show to be a running service (green)?

 

[danfrpa1]

Yes, Vmail is enabled on all extensions.
3 is the length for the extension numbers.
No extra outbound rules are set, used the defaults since new install.
Vmail service is running (green).

 

[jpillow]

Have you taken a look at your server activity log to see if your getting any errors? if so what errors are you seeing when you dial 999? Please post a log while attempting to make a call to your vm that may shed a little light on what the problem may be, as the information you've posted may not be enough to go off.

 

[danfrpa1]

02-09-2012 11:09:30.828 Leg L:7.2[VMail] is terminated: Cause: 200 BYE from PBX
02-09-2012 11:09:30.827 [CM503008]: Call(C:7): Call is terminated
02-09-2012 11:09:30.824 Leg L:7.1[Extn] is terminated: Cause: 200 BYE from xxx.xxx.xxx.xxx:5080
02-09-2012 11:09:25.571 [CM503007]: Call(C:7): VMail:999 has joined, contact <sip:[email protected]:40600>
02-09-2012 11:09:25.571 [CM503007]: Call(C:7): Extn:101 has joined, contact <sip:[email protected]:5060>
02-09-2012 11:09:25.420 [CM503025]: Call(C:7): Calling T:VMail:999@[Dev:sip:[email protected]:40600;rinstance=39593319e0b67e04]
02-09-2012 11:09:25.372 [CM503027]: Call(C:7): From: Extn:101 ("Daniel Friend" <sip:[email protected]:5060>) to T:VMail:999@[Dev:sip:[email protected]:40600;rinstance=39593319e0b67e04]
02-09-2012 11:09:25.372 [CM503004]: Call(C:7): Route 1: T:VMail:999@[Dev:sip:[email protected]:40600;rinstance=39593319e0b67e04]
02-09-2012 11:09:25.371 [CM503001]: Call(C:7): Incoming call from Extn:101 to <sip:[email protected]:5060>

 

[craigreilly]

You have removed all the ip's. is your server remote?
Try installing the windows soft phone to see if vm works. If it is, then that at least points to the handset.

 

[craigreilly]

Also turn on verbose logging and see if it sheds any more light on this.

 

[SY]

try to open "internet browser" on your server and type http://localhost:5000/ivr/ivr_start.aspx
Do you see any errors?

 

[danfrpa1]

The server is remote on a public IP address.... here is the verbose logging


02-09-2012 12:25:03.602 [CM503007]: Call(C:13): VMail:999 has joined, contact <sip:[email protected]:40600>
02-09-2012 12:25:03.601 [CM503007]: Call(C:13): Extn:106 has joined, contact <sip:[email protected]:57028>
02-09-2012 12:25:03.601 Session 416 of leg L:13.1[Extn] is connected
02-09-2012 12:25:03.601 L:13.1[Extn] got Connected Connected Send 200/INVITE from 0.0.0.0:0 tid=c0592833150cdb46 Call-ID=YzYzMjk5M2Q0MDE5ZTg2ZTZhMTAyMjRlMTgzYTA5YzA.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx:5080;branch=z9hG4bK-d8754z-c0592833150cdb46-2---d8754z-;rport=5080;received=xxx.xxx.xxx.xxx
Via: SIP/2.0/UDP 68.238.205.242:59811;branch=z9hG4bK-d8754z-tunneltid-1---d8754z-;rport
Via: SIP/2.0/UDP 192.168.1.18:57028;branch=z9hG4bK-d8754z-c0592833150cdb46-1---d8754z-;rport=57028
Record-Route: <sip:[email protected]:5080;user=proxy;uri="68.238.205.242:59811">
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]:5060>;tag=c8536522
From: "Dan"<sip:[email protected]:5060>;tag=fa170032
Call-ID: YzYzMjk5M2Q0MDE5ZTg2ZTZhMTAyMjRlMTgzYTA5YzA.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
User-Agent: 3CXPhoneSystem 11.0.25940.0
Content-Length: 380

v=0
o=3cxPS 157907156992 477244686337 IN IP4 208.109.191.92
s=3cxPS Audio call
c=IN IP4 208.109.191.92
t=0 0
m=audio 7044 RTP/AVP 0 8 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
m=video 0 RTP/AVP 34
c=IN IP4 127.0.0.1
a=rtpmap:34 H263/90000
a=fmtp:34 QCIF=1;CIF=1;SQCIF=1;CIF4=1
a=sendrecv
02-09-2012 12:25:03.601 Connected(UAS) from "Dan"<sip:[email protected]:5060>;tag=fa170032 to <sip:[email protected]:5060>;tag=c8536522
02-09-2012 12:25:03.601 L:13.1[Extn] Sending: OnSendResp Send 200/INVITE from 0.0.0.0:0 tid=c0592833150cdb46 Call-ID=YzYzMjk5M2Q0MDE5ZTg2ZTZhMTAyMjRlMTgzYTA5YzA.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx:5080;branch=z9hG4bK-d8754z-c0592833150cdb46-2---d8754z-;rport=5080;received=xxx.xxx.xxx.xxx
Via: SIP/2.0/UDP 68.238.205.242:59811;branch=z9hG4bK-d8754z-tunneltid-1---d8754z-;rport
Via: SIP/2.0/UDP 192.168.1.18:57028;branch=z9hG4bK-d8754z-c0592833150cdb46-1---d8754z-;rport=57028
Record-Route: <sip:[email protected]:5080;user=proxy;uri="68.238.205.242:59811">
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]:5060>;tag=c8536522
From: "Dan"<sip:[email protected]:5060>;tag=fa170032
Call-ID: YzYzMjk5M2Q0MDE5ZTg2ZTZhMTAyMjRlMTgzYTA5YzA.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
Content-Length: 380

v=0
o=3cxPS 157907156992 477244686337 IN IP4 208.109.191.92
s=3cxPS Audio call
c=IN IP4 208.109.191.92
t=0 0
m=audio 7044 RTP/AVP 0 8 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
m=video 0 RTP/AVP 34
c=IN IP4 127.0.0.1
a=rtpmap:34 H263/90000
a=fmtp:34 QCIF=1;CIF=1;SQCIF=1;CIF4=1
a=sendrecv
02-09-2012 12:25:03.601 SendMsg from <sip:[email protected]:5060>;tag=c8536522 to "Dan"<sip:[email protected]:5060>;tag=fa170032
02-09-2012 12:25:03.600 ~Target=Ext:Ext.999
02-09-2012 12:25:03.600 L:13.1[Extn]: Terminating targets, reason: SIP ;cause=200 ;text="Call completed elsewhere"
02-09-2012 12:25:03.599 ~Route=Dev:sip:[email protected]:40600;rinstance=39593319e0b67e04
02-09-2012 12:25:03.599 Session 419 of leg L:13.2[VMail] is connected
02-09-2012 12:25:03.599 L:13.2[VMail] got Connected Connected Recv 200/INVITE from 127.0.0.1:40600 tid=bd4b186dfd4ddf02 Call-ID=OTViZmExMWEzNTZmNzg0ZTkxNWY0NWE3ZWQzNDU5M2Y.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-d8754z-bd4b186dfd4ddf02-1---d8754z-;rport=5060
Contact: <sip:[email protected]:40600;rinstance=39593319e0b67e04>
To: <sip:[email protected]:5060>;tag=0a5afb64
From: "Dan's Cell"<sip:[email protected]:5060>;tag=11645f6f
Call-ID: OTViZmExMWEzNTZmNzg0ZTkxNWY0NWE3ZWQzNDU5M2Y.
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Supported: replaces
User-Agent: 3CX Voice Mail Menu
Content-Length: 366

v=0
o=3cxVCE 73020675 356696430 IN IP4 127.0.0.1
s=3cxVCE Audio Call
c=IN IP4 127.0.0.1
t=0 0
m=audio 40620 RTP/AVP 0 8 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
m=video 0 RTP/AVP 34
c=IN IP4 127.0.0.1
a=rtpmap:34 H263/90000
a=fmtp:34 QCIF=1;CIF=1;SQCIF=1;CIF4=1
a=sendrecv
02-09-2012 12:25:03.599 Connected(UAC) from <sip:[email protected]:5060>;tag=0a5afb64 to "Dan's Cell"<sip:[email protected]:5060>;tag=11645f6f
02-09-2012 12:25:03.599 Remote SDP is set for leg L:13.2[VMail]
02-09-2012 12:25:03.599 AnswerSDP arrived for session 419 of Leg L:13.2[VMail]
02-09-2012 12:25:03.599 Got SDP from <sip:[email protected]:5060>;tag=0a5afb64 to "Dan's Cell"<sip:[email protected]:5060>;tag=11645f6f
02-09-2012 12:25:03.598 L:13.2[VMail] Sending: OnSendReq Send Req ACK from 0.0.0.0:0 tid=b4176820b839674e Call-ID=OTViZmExMWEzNTZmNzg0ZTkxNWY0NWE3ZWQzNDU5M2Y.:
ACK sip:[email protected]:40600;rinstance=39593319e0b67e04 SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-b4176820b839674e-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]:5060>;tag=0a5afb64
From: "Dan's Cell"<sip:[email protected]:5060>;tag=11645f6f
Call-ID: OTViZmExMWEzNTZmNzg0ZTkxNWY0NWE3ZWQzNDU5M2Y.
CSeq: 1 ACK
Content-Length: 0
02-09-2012 12:25:03.598 SendMsg from "Dan's Cell"<sip:[email protected]:5060>;tag=11645f6f to <sip:[email protected]:5060>;tag=0a5afb64
02-09-2012 12:25:03.598 L:13.1[Extn] Sending: OnSendResp Send 180/INVITE from 0.0.0.0:0 tid=c0592833150cdb46 Call-ID=YzYzMjk5M2Q0MDE5ZTg2ZTZhMTAyMjRlMTgzYTA5YzA.:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx:5080;branch=z9hG4bK-d8754z-c0592833150cdb46-2---d8754z-;rport=5080;received=xxx.xxx.xxx.xxx
Via: SIP/2.0/UDP 68.238.205.242:59811;branch=z9hG4bK-d8754z-tunneltid-1---d8754z-;rport
Via: SIP/2.0/UDP 192.168.1.18:57028;branch=z9hG4bK-d8754z-c0592833150cdb46-1---d8754z-;rport=57028
Record-Route: <sip:[email protected]:5080;user=proxy;uri="68.238.205.242:59811">
Contact: <sip:[email protected]:5060>
To: <sip:[email protected]:5060>;tag=c8536522
From: "Dan"<sip:[email protected]:5060>;tag=fa170032
Call-ID: YzYzMjk5M2Q0MDE5ZTg2ZTZhMTAyMjRlMTgzYTA5YzA.
CSeq: 2 INVITE
Content-Length: 0
02-09-2012 12:25:03.598 SendMsg from <sip:[email protected]:5060>;tag=c8536522 to "Dan"<sip:[email protected]:5060>;tag=fa170032
02-09-2012 12:25:03.598 Provisional response arrived for session 419 of Leg L:13.2[VMail]
02-09-2012 12:25:03.598 L:13.2[VMail] got Provisional Provisional Recv 180/INVITE from 127.0.0.1:40600 tid=bd4b186dfd4ddf02 Call-ID=OTViZmExMWEzNTZmNzg0ZTkxNWY0NWE3ZWQzNDU5M2Y.:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-d8754z-bd4b186dfd4ddf02-1---d8754z-;rport=5060
Contact: <sip:[email protected]:40600;rinstance=39593319e0b67e04>
02-09-2012 12:35:32.937 Endpoint Extn:106 has added contact <sip:[email protected]:57028> for device Dev(9):[sip:[email protected]:57028;rinstance=b37a9deef8c8b39e / 106]
02-09-2012 12:35:32.937 [CM504001]: Endpoint Extn:106: new contact is registered. Contact(s): [sip:[email protected]:57028 / 106]
02-09-2012 12:35:32.934 Dev(9):[sip:[email protected]:57028;rinstance=b37a9deef8c8b39e / 106] has updated source address: xxx.xxx.xxx.xxx:5080
02-09-2012 12:35:17.752 L:1.1[Extn]: Terminating targets, reason:
02-09-2012 12:35:17.751 Leg L:1.1[Extn] is terminated: Cause: 200 BYE from PBX
02-09-2012 12:35:17.751 Terminated from "Dan"<sip:[email protected]:5060>;tag=e86a2d2f to <sip:[email protected]:5060>;tag=ba565232; reason: LocalBye
02-09-2012 12:35:17.751 L:1.1[Extn] Sending: OnSendReq Send Req BYE from 0.0.0.0:0 tid=114f05442a52a733 Call-ID=MDA0YmQ5OWIwODk5NGZhYTA2NTRhN2M3MDlkOGJmNmY.:
BYE sip:[email protected]:57028;rinstance=b37a9deef8c8b39e SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-114f05442a52a733-1---d8754z-;rport
Max-Forwards: 70
Route: <sip:[email protected]:5080;user=proxy;uri="68.238.205.242:60407">
Contact: <sip:[email protected]:5060>
To: "Dan"<sip:[email protected]:5060>;tag=e86a2d2f
From: <sip:[email protected]:5060>;tag=ba565232
Call-ID: MDA0YmQ5OWIwODk5NGZhYTA2NTRhN2M3MDlkOGJmNmY.
CSeq: 2 BYE
Content-Length: 0
02-09-2012 12:35:17.751 SendMsg from <sip:[email protected]:5060>;tag=ba565232 to "Dan"<sip:[email protected]:5060>;tag=e86a2d2f
02-09-2012 12:35:17.751 [CM503008]: Call(C:1): Call is terminated
02-09-2012 12:35:17.751 L:1.1[Extn]: Terminating targets, reason: SIP ;cause=200 ;text="Call terminated on user request"
02-09-2012 12:35:17.751 Blocking refers for the Call(C:1)
02-09-2012 12:35:17.751 Call(C:1) is terminated
02-09-2012 12:35:17.747 L:1.2[VMail]: Terminating targets, reason: SIP ;cause=200 ;text="User hangup"
02-09-2012 12:35:17.747 Leg L:1.2[VMail] is terminated: Cause: 200 BYE "SIP" from 127.0.0.1:40600
02-09-2012 12:35:17.746 L:1.2[VMail] got Terminated Terminated Recv Req BYE from 127.0.0.1:40600 tid=bc6c857b3f2a221c Call-ID=YTNjNTEzMTdjYTVhOGM2NTA4ZjFkMmQ2NDEwNzEyY2Y.:
BYE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 127.0.0.1:40600;branch=z9hG4bK-d8754z-bc6c857b3f2a221c-1---d8754z-;rport=40600
Max-Forwards: 70
Contact: <sip:[email protected]:40600;rinstance=f48e0c8851e778cd>
To: "Dan's Cell"<sip:[email protected]:5060>;tag=0933ad70
From: <sip:[email protected]:5060>;tag=c16ed367
Call-ID: YTNjNTEzMTdjYTVhOGM2NTA4ZjFkMmQ2NDEwNzEyY2Y.
CSeq: 2 BYE
User-Agent: 3CX Voice Mail Menu
Reason: SIP;description="User Hung Up"
Content-Length: 0
02-09-2012 12:35:17.746 Terminated from <sip:[email protected]:5060>;tag=c16ed367 to "Dan's Cell"<sip:[email protected]:5060>;tag=0933ad70; reason: RemoteBye
02-09-2012 12:35:17.746 L:1.2[VMail] Sending: OnSendResp Send 200/BYE from 0.0.0.0:0 tid=bc6c857b3f2a221c Call-ID=YTNjNTEzMTdjYTVhOGM2NTA4ZjFkMmQ2NDEwNzEyY2Y.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 127.0.0.1:40600;branch=z9hG4bK-d8754z-bc6c857b3f2a221c-1---d8754z-;rport=40600
Contact: <sip:[email protected]:5060>
To: "Dan's Cell"<sip:[email protected]:5060>;tag=0933ad70
From: <sip:[email protected]:5060>;tag=c16ed367
Call-ID: YTNjNTEzMTdjYTVhOGM2NTA4ZjFkMmQ2NDEwNzEyY2Y.
CSeq: 2 BYE
Content-Length: 0
02-09-2012 12:35:17.746 SendMsg from "Dan's Cell"<sip:[email protected]:5060>;tag=0933ad70 to <sip:[email protected]:5060>;tag=c16ed367
02-09-2012 12:35:09.637 Session 17 of leg L:1.1[Extn] is confirmed
02-09-2012 12:35:09.637 L:1.1[Extn] got Confirmed Confirmed Recv Req ACK from xxx.xxx.xxx.xxx:5080 tid=bb612e250f0d6903 Call-ID=MDA0YmQ5OWIwODk5NGZhYTA2NTRhN2M3MDlkOGJmNmY.:
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx:5080;branch=z9hG4bK-d8754z-bb612e250f0d6903-2---d8754z-;rport=5080;received=xxx.xxx.xxx.xxx
Via: SIP/2.0/UDP 68.238.205.242:60407;branch=z9hG4bK-d8754z-tunneltid-1---d8754z-;rport
Via: SIP/2.0/UDP 192.168.1.18:57028;branch=z9hG4bK-d8754z-bb612e250f0d6903-1---d8754z-;rport=57028
Max-Forwards: 68
Record-Route: <sip:[email protected]:5080;user=proxy;uri="68.238.205.242:60407">
Contact: <sip:[email protected]:57028;rinstance=b37a9deef8c8b39e>
To: <sip:[email protected]:5060>;tag=ba565232
From: "Dan"<sip:[email protected]:5060>;tag=e86a2d2f
Call-ID: MDA0YmQ5OWIwODk5NGZhYTA2NTRhN2M3MDlkOGJmNmY.
CSeq: 2 ACK
Proxy-Authorization: Digest username="106",realm="3CXPhoneSystem",nonce="414d535c06541bbc83:4bf2da650ffa6a3cf7079f4c8457e9fc",uri="sip:[email protected]:5060",response="e06d069d86518eb07279caab07a8d00e",algorithm=MD5
User-Agent: 3CXPhone 6.0.25732.0
Content-Length: 0
02-09-2012 12:35:09.637 ConnCfmd from "Dan"<sip:[email protected]:5060>;tag=e86a2d2f to <sip:[email protected]:5060>;tag=ba565232
02-09-2012 12:35:09.247 [CM503007]: Call(C:1): VMail:999 has joined, contact <sip:[email protected]:40600>
02-09-2012 12:35:09.247 [CM503007]: Call(C:1): Extn:106 has joined, contact <sip:[email protected]:57028>
02-09-2012 12:35:09.247 Session 17 of leg L:1.1[Extn] is connected
02-09-2012 12:35:09.247 L:1.1[Extn] got Connected Connected Send 200/INVITE from 0.0.0.0:0 tid=066cfb0c3d4d8b09 Call-ID=MDA0YmQ5OWIwODk5NGZhYTA2NTRhN2M3MDlkOGJmNmY.:
SIP/2.0 200 OK
Via: SIP/2.0/UDP xxx.xxx.xxx.xxx:5080;branch=z9hG4bK-d8754z-066cfb0c3d4d8b09-2---d8754z-;rport=5080;received=xxx.xxx.xxx.xxx
Via: SIP/2.0/UDP 68.238.205.242:60407;branch=z9hG4bK-d8754z-tunneltid-1---d8754z-;rport
Via: SIP/2.0/UDP 192.168.1.18:57028;branch=z9hG4bK-d8754z-066cfb0c3d4d8b09-1---d8754z-;bK-d8754z-c0592833150cdb46-1---d8754z-;rport=57028
Max-Forwards: 68
Record-Route: <sip:[email protected]:5080;user=proxy;uri="68.238.205.242:59811">
Contact: <sip:[email protected]:57028;rinstance=b37a9deef8c8b39e>
To: <sip:[email protected]:5060>
From: "Dan"<sip:[email protected]:5060>;tag=fa170032
Call-ID: YzYzMjk5M2Q0MDE5ZTg2ZTZhMTAyMjRlMTgzYTA5YzA.
CSeq: 2 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
Content-Type: application/sdp
Proxy-Authorization: Digest username="106",realm="3CXPhoneSystem",nonce="414d535c0654195e64:08b689366cb021e0e0f7a150d40a9923",uri="sip:[email protected]:5060",response="60c68fb941bfa9e22a422a187090a501",algorithm=MD5
Supported: replaces
User-Agent: 3CXPhone 6.0.25732.0
Content-Length: 408

 

[danfrpa1]

By visiting http://localhost:5000/ivr/ivr_start.aspx I do get an error

This is the detail error message

HTTP Error 500.19 - Internal Server Error
The requested page cannot be accessed because the related configuration data for the page is invalid.

Detailed Error Information
Module CustomErrorModule
Notification SendResponse
Handler PageHandlerFactory-Integrated-4.0
Error Code 0x8007000d
Config Error Unrecognized attribute 'cacheControlMode'
Config File \\?\C:\ProgramData\3CX\Data\Http\Interface\ivr\web.config
Requested URL http://xxx.xxx.xxx.xxx:5000/ivr/ivr_start.aspx
Physical Path C:\ProgramData\3CX\Data\Http\Interface\ivr\ivr_start.aspx
Logon Method Anonymous
Logon User Anonymous

Config Source
68: <httpProtocol allowKeepAlive="false" />
69: <staticContent cacheControlMode="DisableCache" />
70: <modules>

 

[danfrpa1]

I have tried my IP Phone, the cell version for android and the Windows Soft Phone... all receiving the same results.

 

[jpillow]

Is there an extension located where the server is or install a softphone on the server and attempt to call vm from on site, if you have tried this were you able to reach vm? If no try and see what happens when on the same LAN as the server.