Voicemail after upgrade to v12

Discussion in '3CX Phone System - General' started by hillcows, Sep 4, 2013.

Thread Status:
Not open for further replies.
  1. hillcows

    Joined:
    Sep 4, 2013
    Messages:
    4
    Likes Received:
    0
    Hello -

    I just upgraded from v11 to v12 today and other than a few little hiccups, the install went great. I am getting an error when attempting to access my voicemail. When dialing the 9999, from any extension, I get a message "This call can not be completed, try you call again later"

    Any Ideas?

    03-Sep-2013 16:48:35.271 Leg L:24.2[VMail] is terminated: Cause: BYE from PBX
    03-Sep-2013 16:48:35.271 [CM503008]: Call(C:24): Call is terminated
    03-Sep-2013 16:48:35.269 Leg L:24.1[Extn] is terminated: Cause: BYE from 192.168.1.49:5060
    03-Sep-2013 16:48:29.108 [CM503007]: Call(C:24): VMail:9999 has joined, contact <sip:9999@127.0.0.1:40600>
    03-Sep-2013 16:48:29.107 [CM503007]: Call(C:24): Extn:3301 has joined, contact <sip:3301@192.168.1.49:5060>
    03-Sep-2013 16:48:29.105 L:24.2[VMail] has joined to L:24.1[Extn]
    03-Sep-2013 16:48:28.957 [CM503025]: Call(C:24): Calling T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=2cd81b728d1cb4b5] for L:24.1[Extn]
    03-Sep-2013 16:48:28.909 [CM503027]: Call(C:24): From: Extn:3301 ("Adam Smith - DKC" <sip:3301@192.168.1.133:5060>) to T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=2cd81b728d1cb4b5]
    03-Sep-2013 16:48:28.909 [CM503004]: Call(C:24): Route 1: from L:24.1[Extn] to T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=2cd81b728d1cb4b5]
    03-Sep-2013 16:48:28.909 [CM503001]: Call(C:24): Incoming call from Extn:3301 to "Voice Mail" <sip:9999@192.168.1.133:5060>
     
  2. hillcows

    Joined:
    Sep 4, 2013
    Messages:
    4
    Likes Received:
    0
    Also - I found out this is the same error anyone gets when calling in to us and it routes to voicemail.
     
  3. craigreilly

    craigreilly Well-Known Member

    Joined:
    Feb 1, 2012
    Messages:
    3,069
    Likes Received:
    201
    Does an IVR work?
    Does hold music work?

    Is media server service running?
     
    Stop hovering to collapse... Click to collapse... Hover to expand... Click to expand...
  4. leejor

    leejor Well-Known Member

    Joined:
    Jan 22, 2008
    Messages:
    10,563
    Likes Received:
    245
    Offhand, this would seem to indicate that the call was dropped from the extension. Obviously that is not the case. The logs Verbatim setting may give you some more detail about what is actually going on. Have you checked, in Windows, that all services are running?

    I'm assuming that you are running version 12 on the same machine, as the previous version?

    Do you have any issues with automated attendants or queues? I'm thinking that there might be an audio driver issue.
     
  5. hillcows

    Joined:
    Sep 4, 2013
    Messages:
    4
    Likes Received:
    0
    Good Morning -

    Thank you for the replies - This was indeed an upgrade on the same machine that v11 was running on and had been on for almost 2 years. This has me totally puzzled since there were no changes made and we did the restore from v11 after install. We do not have any auto attendants or call queues, so I am not sure if those features work, but the hold music works fine so I do not think it would be an audio driver. I did try stopping the media server and do get the same error that I had before and everything I see shows that the media server is running correctly unless I am missing something. I would like to add this is running on a Win7 Ultimate machine, if that helps any. Additionally here is what I do know:

    When the digital receptionist service is disabled, the system will instead provide a busy signal, and not the error message previously described. Here is a log clip from when DR service is off:

    04-Sep-2013 06:36:52.888 L:12.1[Extn]: Terminating targets, reason:
    04-Sep-2013 06:36:52.888 Leg L:12.1[Extn] is terminated: Cause: BYE from PBX
    04-Sep-2013 06:36:52.888 Terminated from "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=777263bb3d28aaf4o0 to <sip:9999@192.168.1.133>;tag=a219f952; reason: Rejected
    04-Sep-2013 06:36:52.888 L:12.1[Extn] Sending: OnSendResp Send 480/INVITE from 0.0.0.0:0 tid=-4313d7c4 Call-ID=3e6dc87-573ba898@192.168.1.49:
    SIP/2.0 480 Temporarily Unavailable
    Via: SIP/2.0/UDP 192.168.1.49:5060;branch=z9hG4bK-4313d7c4
    To: <sip:9999@192.168.1.133>;tag=a219f952
    From: "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=777263bb3d28aaf4o0
    Call-ID: 3e6dc87-573ba898@192.168.1.49
    CSeq: 102 INVITE
    Warning: 499 W7_Main "Not available"
    Content-Length: 0
    04-Sep-2013 06:36:52.888 SendMsg from <sip:9999@192.168.1.133>;tag=a219f952 to "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=777263bb3d28aaf4o0
    04-Sep-2013 06:36:52.847 Call(C:12) is terminated
    04-Sep-2013 06:36:52.847 [CM503020]: Call(C:12): Normal call termination. Call originator: Extn:3301. Reason: Not available
    04-Sep-2013 06:36:52.847 [CM503016]: Call(C:12): Attempt to reach <sip:9999@192.168.1.133:5060> from Extn:3301 has failed. Reason: Not Registered
    04-Sep-2013 06:36:52.847 [Flow] Endpoint VMail:9999 has no forwarding rule on reason 'Not Registered'
    04-Sep-2013 06:36:52.847 [Flow] Current call diversion path:[]
    04-Sep-2013 06:36:52.847 L:12.1[Extn] failed to reach VMail:9999, reason Not Registered
    04-Sep-2013 06:36:52.847 RerouteReq
    04-Sep-2013 06:36:52.845 [CM503016]: Call(C:12): Attempt to reach <sip:9999@192.168.1.133:5060> from Extn:3301 has failed. Reason: Not Registered
    04-Sep-2013 06:36:52.845 [CM503017]: Call(C:12): Target is not registered: VMail:9999
    04-Sep-2013 06:36:52.845 [Flow] Endpoint VMail:9999 has no forwarding rule on reason 'All calls'
    04-Sep-2013 06:36:52.845 [Flow] Call(C:12): has built target endpoint: VMail:9999 for call from L:12.1[Extn]
    04-Sep-2013 06:36:52.845 [Flow] Target endpoint for 9999 is VMail:9999
    04-Sep-2013 06:36:52.845 [Flow] Building target endpoint to 9999 from "Adam Smith - DKC" <sip:3301@192.168.1.133:5060>
    04-Sep-2013 06:36:52.845 [CM503010]: Call(C:12): Making route(s) from Extn:3301 to <sip:9999@192.168.1.133:5060>
    04-Sep-2013 06:36:52.845 Remote SDP is set for leg L:12.1[Extn]
    04-Sep-2013 06:36:52.845 OnOffer from "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=777263bb3d28aaf4o0
    04-Sep-2013 06:36:52.845 [CM505001]: Endpoint Extn:3301: Device info: Device Identified: [Man: Linksys;Mod: SPA Series;Rev: General] Capabilities:[reinvite, no-replaces, able-no-sdp, recvonly] UserAgent: [Linksys/SPA942-6.1.5(a)] PBX contact: [sip:3301@192.168.1.133:5060]
    04-Sep-2013 06:36:52.845 Inbound DID: ''; Phonebook Name: ''
    04-Sep-2013 06:36:52.845 [CM500002]: Call(C:12): Info on incoming INVITE from Extn:3301:
    Invite-IN Recv Req INVITE from 192.168.1.49:5060 tid=-4313d7c4 Call-ID=3e6dc87-573ba898@192.168.1.49:
    INVITE sip:9999@192.168.1.133:5060 SIP/2.0
    Via: SIP/2.0/UDP 192.168.1.49:5060;branch=z9hG4bK-4313d7c4
    Max-Forwards: 70
    Contact: "Adam Smith - DKC"<sip:3301@192.168.1.49:5060>
    To: <sip:9999@192.168.1.133>
    From: "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=777263bb3d28aaf4o0
    Call-ID: 3e6dc87-573ba898@192.168.1.49
    CSeq: 102 INVITE
    Expires: 240
    Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
    Content-Type: application/sdp
    Proxy-Authorization: Digest username="3301",realm="3CXPhoneSystem",nonce="414d535c0837c87400:6eae662f5d3e07a07a7392c302eb2748",uri="sip:9999@192.168.1.133:5060",algorithm=MD5,response="878c9fa4dc859d8d0e024adca8d7028d"
    Supported: replaces
    User-Agent: Linksys/SPA942-6.1.5(a)
    Content-Length: 397

    v=0
    o=- 5406964 5406964 IN IP4 192.168.1.49
    s=-
    c=IN IP4 192.168.1.49
    t=0 0
    m=audio 16444 RTP/AVP 0 8 18 2 4 96 97 98 101
    a=rtpmap:0 PCMU/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:18 G729a/8000
    a=rtpmap:2 G726-32/8000
    a=rtpmap:4 G723/8000
    a=rtpmap:96 G726-40/8000
    a=rtpmap:97 G726-24/8000
    a=rtpmap:98 G726-16/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-15
    a=ptime:20
    a=sendrecv
    04-Sep-2013 06:36:52.844 [CM503001]: Call(C:12): Incoming call from Extn:3301 to <sip:9999@192.168.1.133:5060>
    04-Sep-2013 06:36:52.838 Outbound URI is used: sip:3301@192.168.1.49:5060
    04-Sep-2013 06:36:52.838 IncomingCall: C:12 from <sip:3301@192.168.1.133:5060> to <sip:9999@192.168.1.133:5060>
    04-Sep-2013 06:36:52.838 Added leg L:C:12.1[No endpoint yet]
    04-Sep-2013 06:36:52.838 UasSession 9626 started
    04-Sep-2013 06:36:52.838 Call from "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=777263bb3d28aaf4o0 to <sip:9999@192.168.1.133>;tag=a219f952





    This is the log when I turn DR back on:

    04-Sep-2013 06:40:44.719 Endpoint Ivr:RecordFile has added contact <sip:RecordFile@127.0.0.1:40600> for device Dev(68):[sip:RecordFile@127.0.0.1:40600;rinstance=452d424f278d4513 / RecordFile]
    04-Sep-2013 06:40:44.718 [CM504001]: Endpoint Ivr:RecordFile: new contact is registered. Contact(s): [sip:RecordFile@127.0.0.1:40600 / RecordFile]
    04-Sep-2013 06:40:44.718 Endpoint Ivr:playFile has added contact <sip:playFile@127.0.0.1:40600> for device Dev(67):[sip:playFile@127.0.0.1:40600;rinstance=35c59579cec8430d / PlayFile]
    04-Sep-2013 06:40:44.718 [CM504001]: Endpoint Ivr:playFile: new contact is registered. Contact(s): [sip:playFile@127.0.0.1:40600 / PlayFile]
    04-Sep-2013 06:40:44.718 Endpoint Ivr:MakeCall has added contact <sip:MakeCall@127.0.0.1:40600> for device Dev(66):[sip:MakeCall@127.0.0.1:40600;rinstance=9e2a14b0d5e85ee2 / MakeCall]
    04-Sep-2013 06:40:44.717 [CM504001]: Endpoint Ivr:MakeCall: new contact is registered. Contact(s): [sip:MakeCall@127.0.0.1:40600 / MakeCall]
    04-Sep-2013 06:40:44.714 Endpoint Ivr:IVRForward has added contact <sip:IVRForward@127.0.0.1:40600> for device Dev(65):[sip:IVRForward@127.0.0.1:40600;rinstance=6561045bb9b7192f / IVRForward]
    04-Sep-2013 06:40:44.713 [CM504001]: Endpoint Ivr:IVRForward: new contact is registered. Contact(s): [sip:IVRForward@127.0.0.1:40600 / IVRForward]
    04-Sep-2013 06:40:44.703 Endpoint Ivr:EndCall has added contact <sip:EndCall@127.0.0.1:40600> for device Dev(64):[sip:EndCall@127.0.0.1:40600;rinstance=097ec6edd8a6be5e / EndCall]
    04-Sep-2013 06:40:44.702 [CM504001]: Endpoint Ivr:EndCall: new contact is registered. Contact(s): [sip:EndCall@127.0.0.1:40600 / EndCall]
    04-Sep-2013 06:40:44.698 Endpoint Ivr:7777 has added contact <sip:7777@127.0.0.1:40600> for device Dev(63):[sip:7777@127.0.0.1:40600;rinstance=0ef353c9e23865e3 / 7777]
    04-Sep-2013 06:40:44.698 [CM504001]: Endpoint Ivr:7777: new contact is registered. Contact(s): [sip:7777@127.0.0.1:40600 / 7777]
    04-Sep-2013 06:40:44.694 Endpoint Ivr:HOL has added contact <sip:HOL@127.0.0.1:40600> for device Dev(62):[sip:HOL@127.0.0.1:40600;rinstance=ba335c9af8d88646 / HOL]
    04-Sep-2013 06:40:44.694 [CM504001]: Endpoint Ivr:HOL: new contact is registered. Contact(s): [sip:HOL@127.0.0.1:40600 / HOL]
    04-Sep-2013 06:40:44.690 Endpoint VMail:9999 has added contact <sip:9999@127.0.0.1:40600> for device Dev(61):[sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56 / 9999]






    And here is the log when I make a call to VM after it is on:


    04-Sep-2013 06:42:05.725 L:13.2[VMail]: Terminating targets, reason:
    04-Sep-2013 06:42:05.725 Leg L:13.2[VMail] is terminated: Cause: BYE from PBX
    04-Sep-2013 06:42:05.725 Terminated from <sip:9999@127.0.0.1:5060>;tag=fa520753 to "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060; reason: LocalBye
    04-Sep-2013 06:42:05.725 L:13.2[VMail] Sending: OnSendReq Send Req BYE from 0.0.0.0:0 tid=0e49d906a03fb45b Call-ID=MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.:
    BYE sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56 SIP/2.0
    Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-0e49d906a03fb45b-1---d8754z-;rport
    Max-Forwards: 70
    Contact: <sip:3301@127.0.0.1:5060>
    To: <sip:9999@127.0.0.1:5060>;tag=fa520753
    From: "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060
    Call-ID: MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.
    CSeq: 2 BYE
    Content-Length: 0
    04-Sep-2013 06:42:05.725 SendMsg from "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060 to <sip:9999@127.0.0.1:5060>;tag=fa520753
    04-Sep-2013 06:42:05.725 [CM503008]: Call(C:13): Call is terminated
    04-Sep-2013 06:42:05.725 L:13.2[VMail]: Terminating targets, reason: SIP ;cause=200 ;text="Call terminated on user request"
    04-Sep-2013 06:42:05.725 Blocking refers for the Call(C:13)
    04-Sep-2013 06:42:05.725 Call(C:13) is terminated
    04-Sep-2013 06:42:05.723 L:13.1[Extn]: Terminating targets, reason: SIP ;cause=200 ;text="User hangup"
    04-Sep-2013 06:42:05.723 Leg L:13.1[Extn] is terminated: Cause: BYE from 192.168.1.49:5060
    04-Sep-2013 06:42:05.722 L:13.1[Extn] got Terminated Recv Req BYE from 192.168.1.49:5060 tid=-3b6f62f0 Call-ID=ea1077ce-68be651d@192.168.1.49:
    BYE sip:9999@192.168.1.133:5060 SIP/2.0
    Via: SIP/2.0/UDP 192.168.1.49:5060;branch=z9hG4bK-3b6f62f0
    Max-Forwards: 70
    To: <sip:9999@192.168.1.133>;tag=52141a02
    From: "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0
    Call-ID: ea1077ce-68be651d@192.168.1.49
    CSeq: 103 BYE
    Proxy-Authorization: Digest username="3301",realm="3CXPhoneSystem",nonce="414d535c0837c9a508:4704aef22c206333e09ae0f63644fc4a",uri="sip:9999@192.168.1.133:5060",algorithm=MD5,response="cc4a5b69e6e7723cc0515c5aa2177bd0"
    User-Agent: Linksys/SPA942-6.1.5(a)
    Content-Length: 0
    04-Sep-2013 06:42:05.722 Terminated from "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0 to <sip:9999@192.168.1.133>;tag=52141a02; reason: RemoteBye
    04-Sep-2013 06:42:05.722 L:13.1[Extn] Sending: OnSendResp Send 200/BYE from 0.0.0.0:0 tid=-3b6f62f0 Call-ID=ea1077ce-68be651d@192.168.1.49:
    SIP/2.0 200 OK
    Via: SIP/2.0/UDP 192.168.1.49:5060;branch=z9hG4bK-3b6f62f0
    Contact: <sip:9999@192.168.1.133:5060>
    To: <sip:9999@192.168.1.133>;tag=52141a02
    From: "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0
    Call-ID: ea1077ce-68be651d@192.168.1.49
    CSeq: 103 BYE
    Content-Length: 0
    04-Sep-2013 06:42:05.722 SendMsg from <sip:9999@192.168.1.133>;tag=52141a02 to "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0
    04-Sep-2013 06:41:58.142 Session 9755 of leg L:13.1[Extn] is confirmed
    04-Sep-2013 06:41:58.142 L:13.1[Extn] got Confirmed Recv Req ACK from 192.168.1.49:5060 tid=-a913b8e9 Call-ID=ea1077ce-68be651d@192.168.1.49:
    ACK sip:9999@192.168.1.133:5060 SIP/2.0
    Via: SIP/2.0/UDP 192.168.1.49:5060;branch=z9hG4bK-a913b8e9
    Max-Forwards: 70
    Contact: "Adam Smith - DKC"<sip:3301@192.168.1.49:5060>
    To: <sip:9999@192.168.1.133>;tag=52141a02
    From: "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0
    Call-ID: ea1077ce-68be651d@192.168.1.49
    CSeq: 102 ACK
    Proxy-Authorization: Digest username="3301",realm="3CXPhoneSystem",nonce="414d535c0837c9a508:4704aef22c206333e09ae0f63644fc4a",uri="sip:9999@192.168.1.133:5060",algorithm=MD5,response="225a5e200f51c2abc61420b8928f076e"
    User-Agent: Linksys/SPA942-6.1.5(a)
    Content-Length: 0
    04-Sep-2013 06:41:58.142 ConnCfmd from "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0 to <sip:9999@192.168.1.133>;tag=52141a02
    04-Sep-2013 06:41:58.030 [CM503007]: Call(C:13): VMail:9999 has joined, contact <sip:9999@127.0.0.1:40600>
    04-Sep-2013 06:41:58.029 [CM503007]: Call(C:13): Extn:3301 has joined, contact <sip:3301@192.168.1.49:5060>
    04-Sep-2013 06:41:58.029 ~Target=Ext:Ext.9999
    04-Sep-2013 06:41:58.029 Session 9755 of leg L:13.1[Extn] is connected
    04-Sep-2013 06:41:58.029 L:13.1[Extn] got Connected.UAS Send 200/INVITE from 0.0.0.0:0 tid=-1d3b36e9 Call-ID=ea1077ce-68be651d@192.168.1.49:
    SIP/2.0 200 OK
    Via: SIP/2.0/UDP 192.168.1.49:5060;branch=z9hG4bK-1d3b36e9
    Contact: <sip:9999@192.168.1.133:5060>
    To: <sip:9999@192.168.1.133>;tag=52141a02
    From: "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0
    Call-ID: ea1077ce-68be651d@192.168.1.49
    CSeq: 102 INVITE
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
    Content-Type: application/sdp
    Supported: replaces
    User-Agent: 3CXPhoneSystem 12.0.32127.322 (32093)
    Content-Length: 249

    v=0
    o=3cxPS 330846699520 34745614337 IN IP4 192.168.1.133
    s=3cxPS Audio call
    c=IN IP4 192.168.1.133
    t=0 0
    m=audio 7034 RTP/AVP 0 8 101
    a=rtpmap:0 PCMU/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-15
    a=sendrecv
    04-Sep-2013 06:41:58.029 Connected(UAS) from "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0 to <sip:9999@192.168.1.133>;tag=52141a02
    04-Sep-2013 06:41:58.029 L:13.1[Extn] Sending: OnSendResp Send 200/INVITE from 0.0.0.0:0 tid=-1d3b36e9 Call-ID=ea1077ce-68be651d@192.168.1.49:
    SIP/2.0 200 OK
    Via: SIP/2.0/UDP 192.168.1.49:5060;branch=z9hG4bK-1d3b36e9
    Contact: <sip:9999@192.168.1.133:5060>
    To: <sip:9999@192.168.1.133>;tag=52141a02
    From: "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0
    Call-ID: ea1077ce-68be651d@192.168.1.49
    CSeq: 102 INVITE
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
    Content-Type: application/sdp
    Supported: replaces
    Content-Length: 249

    v=0
    o=3cxPS 330846699520 34745614337 IN IP4 192.168.1.133
    s=3cxPS Audio call
    c=IN IP4 192.168.1.133
    t=0 0
    m=audio 7034 RTP/AVP 0 8 101
    a=rtpmap:0 PCMU/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-15
    a=sendrecv
    04-Sep-2013 06:41:58.029 SendMsg from <sip:9999@192.168.1.133>;tag=52141a02 to "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0
    04-Sep-2013 06:41:58.028 L:13.1[Extn]: Terminating targets, reason: SIP ;cause=200 ;text="Call completed elsewhere"
    04-Sep-2013 06:41:58.027 ~Route=Dev:sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56
    04-Sep-2013 06:41:58.027 L:13.2[VMail] has joined to L:13.1[Extn]
    04-Sep-2013 06:41:58.027 Session 9758 of leg L:13.2[VMail] is connected
    04-Sep-2013 06:41:58.027 L:13.2[VMail] got Connected.UAC Recv 200/INVITE from 127.0.0.1:40600 tid=f27c8924095bec63 Call-ID=MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.:
    SIP/2.0 200 OK
    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-d8754z-f27c8924095bec63-1---d8754z-;rport=5060
    Contact: <sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56>
    To: <sip:9999@127.0.0.1:5060>;tag=fa520753
    From: "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060
    Call-ID: MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.
    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: 336

    v=0
    o=3cxVCE 277083525 250097355 IN IP4 127.0.0.1
    s=3cxVCE Audio Call
    c=IN IP4 127.0.0.1
    t=0 0
    m=audio 40610 RTP/AVP 0 8 3 13 9 110 99 101
    a=rtpmap:0 PCMU/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:3 GSM/8000
    a=rtpmap:13 CN/8000
    a=rtpmap:9 G722/8000
    a=rtpmap:110 iLBC/8000
    a=rtpmap:99 SPEEX/8000
    a=rtpmap:101 telephone-event/8000
    04-Sep-2013 06:41:58.027 Connected(UAC) from <sip:9999@127.0.0.1:5060>;tag=fa520753 to "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060
    04-Sep-2013 06:41:58.027 Remote SDP is set for leg L:13.2[VMail]
    04-Sep-2013 06:41:58.027 Answer SDP arrived on Leg L:13.2[VMail];
    OnAnswer Recv 200/INVITE from 127.0.0.1:40600 tid=f27c8924095bec63 Call-ID=MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.:
    SIP/2.0 200 OK
    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-d8754z-f27c8924095bec63-1---d8754z-;rport=5060
    Contact: <sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56>
    To: <sip:9999@127.0.0.1:5060>;tag=fa520753
    From: "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060
    Call-ID: MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.
    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: 336

    v=0
    o=3cxVCE 277083525 250097355 IN IP4 127.0.0.1
    s=3cxVCE Audio Call
    c=IN IP4 127.0.0.1
    t=0 0
    m=audio 40610 RTP/AVP 0 8 3 13 9 110 99 101
    a=rtpmap:0 PCMU/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:3 GSM/8000
    a=rtpmap:13 CN/8000
    a=rtpmap:9 G722/8000
    a=rtpmap:110 iLBC/8000
    a=rtpmap:99 SPEEX/8000
    a=rtpmap:101 telephone-event/8000
    04-Sep-2013 06:41:58.027 L:13.2[VMail] Sending: OnSendReq Send Req ACK from 0.0.0.0:0 tid=81030b5c7f651d0c Call-ID=MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.:
    ACK sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56 SIP/2.0
    Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-81030b5c7f651d0c-1---d8754z-;rport
    Max-Forwards: 70
    Contact: <sip:3301@127.0.0.1:5060>
    To: <sip:9999@127.0.0.1:5060>;tag=fa520753
    From: "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060
    Call-ID: MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.
    CSeq: 1 ACK
    Content-Length: 0
    04-Sep-2013 06:41:58.027 SendMsg from "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060 to <sip:9999@127.0.0.1:5060>;tag=fa520753
    04-Sep-2013 06:41:58.026 L:13.1[Extn] Sending: OnSendResp Send 180/INVITE from 0.0.0.0:0 tid=-1d3b36e9 Call-ID=ea1077ce-68be651d@192.168.1.49:
    SIP/2.0 180 Ringing
    Via: SIP/2.0/UDP 192.168.1.49:5060;branch=z9hG4bK-1d3b36e9
    Contact: <sip:9999@192.168.1.133:5060>
    To: <sip:9999@192.168.1.133>;tag=52141a02
    From: "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0
    Call-ID: ea1077ce-68be651d@192.168.1.49
    CSeq: 102 INVITE
    Content-Length: 0
    04-Sep-2013 06:41:58.026 SendMsg from <sip:9999@192.168.1.133>;tag=52141a02 to "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0
    04-Sep-2013 06:41:58.026 Provisional response arrived for session 9758 of Leg L:13.2[VMail]
    04-Sep-2013 06:41:58.026 L:13.2[VMail] got Provisional Recv 180/INVITE from 127.0.0.1:40600 tid=f27c8924095bec63 Call-ID=MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.:
    SIP/2.0 180 Ringing
    Via: SIP/2.0/UDP 127.0.0.1:5060;branch=z9hG4bK-d8754z-f27c8924095bec63-1---d8754z-;rport=5060
    Contact: <sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56>
    To: <sip:9999@127.0.0.1:5060>;tag=fa520753
    From: "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060
    Call-ID: MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.
    CSeq: 1 INVITE
    User-Agent: 3CX Voice Mail Menu
    Content-Length: 0
    04-Sep-2013 06:41:58.026 Provisional(180) from <sip:9999@127.0.0.1:5060>;tag=fa520753 to "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060
    04-Sep-2013 06:41:58.026 [CM505001]: Endpoint VMail:9999: Device info: Device Identified: [Man: 3CX Ltd.;Mod: Voice Mail Menu;Rev: General] Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [3CX Voice Mail Menu] PBX contact: [sip:9999@127.0.0.1:5060]
    04-Sep-2013 06:41:58.026 [CM503002]: Call(C:13): Alerting VMail:9999 by contact <sip:9999@127.0.0.1:40600>
    04-Sep-2013 06:41:58.025 UacSession 9758 has formed leg L:13.2[VMail]
    04-Sep-2013 06:41:58.025 Answer from <sip:9999@127.0.0.1:5060>;tag=fa520753 to "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060
    04-Sep-2013 06:41:57.876 [CM503025]: Call(C:13): Calling T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56] for L:13.1[Extn]
    04-Sep-2013 06:41:57.876 Route to L:13.2[VMail] sends Invite-OUT Send Req INVITE from 0.0.0.0:0 tid=37495d4a50219977 Call-ID=MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.:
    INVITE sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56 SIP/2.0
    Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-37495d4a50219977-1---d8754z-;rport
    Max-Forwards: 70
    Contact: <sip:3301@127.0.0.1:5060>
    To: <sip:9999@127.0.0.1:5060>
    From: "Adam Smith - DKC"<sip:3301@127.0.0.1:5060>;tag=cd4d7060
    Call-ID: MTRiZWIzM2Y0ZThmZDE3MDE5NmE2NDVhZmYzODAzYzQ.
    CSeq: 1 INVITE
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE
    Content-Type: application/sdp
    Supported: replaces
    Content-Length: 351

    v=0
    o=3cxPS 334252474368 326081970177 IN IP4 127.0.0.1
    s=3cxPS Audio call
    c=IN IP4 127.0.0.1
    t=0 0
    m=audio 7036 RTP/AVP 0 8 3 13 9 110 99 101
    a=rtpmap:0 PCMU/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:3 GSM/8000
    a=rtpmap:13 CN/8000
    a=rtpmap:9 G722/8000
    a=rtpmap:110 iLBC/8000
    a=rtpmap:99 SPEEX/8000
    a=rtpmap:101 telephone-event/8000
    a=sendrecv
    04-Sep-2013 06:41:57.876 Loading personal phonebook for 9999: found 0 entries
    04-Sep-2013 06:41:57.873 Outbound URI is used: sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56
    04-Sep-2013 06:41:57.873 SLA is globally disabled
    04-Sep-2013 06:41:57.872 Added leg L:13.2[VMail]
    04-Sep-2013 06:41:57.830 [Flow] Call(C:13): making call from L:13.1[Extn] to T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56]
    04-Sep-2013 06:41:57.830 [CM503027]: Call(C:13): From: Extn:3301 ("Adam Smith - DKC" <sip:3301@192.168.1.133:5060>) to T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56]
    04-Sep-2013 06:41:57.830 [CM503004]: Call(C:13): Route 1: from L:13.1[Extn] to T:VMail:9999@[Dev:sip:9999@127.0.0.1:40600;rinstance=29a848283101cb56]
    04-Sep-2013 06:41:57.830 [Flow] Endpoint VMail:9999 has no forwarding rule on reason 'All calls'
    04-Sep-2013 06:41:57.830 [Flow] Call(C:13): has built target endpoint: VMail:9999 for call from L:13.1[Extn]
    04-Sep-2013 06:41:57.830 [Flow] Target endpoint for 9999 is VMail:9999
    04-Sep-2013 06:41:57.830 [Flow] Building target endpoint to 9999 from "Adam Smith - DKC" <sip:3301@192.168.1.133:5060>
    04-Sep-2013 06:41:57.830 [CM503010]: Call(C:13): Making route(s) from Extn:3301 to <sip:9999@192.168.1.133:5060>
    04-Sep-2013 06:41:57.830 Remote SDP is set for leg L:13.1[Extn]
    04-Sep-2013 06:41:57.830 OnOffer from "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0
    04-Sep-2013 06:41:57.830 [CM505001]: Endpoint Extn:3301: Device info: Device Identified: [Man: Linksys;Mod: SPA Series;Rev: General] Capabilities:[reinvite, no-replaces, able-no-sdp, recvonly] UserAgent: [Linksys/SPA942-6.1.5(a)] PBX contact: [sip:3301@192.168.1.133:5060]
    04-Sep-2013 06:41:57.829 Inbound DID: ''; Phonebook Name: ''
    04-Sep-2013 06:41:57.829 [CM500002]: Call(C:13): Info on incoming INVITE from Extn:3301:
    Invite-IN Recv Req INVITE from 192.168.1.49:5060 tid=-1d3b36e9 Call-ID=ea1077ce-68be651d@192.168.1.49:
    INVITE sip:9999@192.168.1.133:5060 SIP/2.0
    Via: SIP/2.0/UDP 192.168.1.49:5060;branch=z9hG4bK-1d3b36e9
    Max-Forwards: 70
    Contact: "Adam Smith - DKC"<sip:3301@192.168.1.49:5060>
    To: <sip:9999@192.168.1.133>
    From: "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0
    Call-ID: ea1077ce-68be651d@192.168.1.49
    CSeq: 102 INVITE
    Expires: 240
    Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
    Content-Type: application/sdp
    Proxy-Authorization: Digest username="3301",realm="3CXPhoneSystem",nonce="414d535c0837c9a508:4704aef22c206333e09ae0f63644fc4a",uri="sip:9999@192.168.1.133:5060",algorithm=MD5,response="225a5e200f51c2abc61420b8928f076e"
    Supported: replaces
    User-Agent: Linksys/SPA942-6.1.5(a)
    Content-Length: 397

    v=0
    o=- 5437460 5437460 IN IP4 192.168.1.49
    s=-
    c=IN IP4 192.168.1.49
    t=0 0
    m=audio 16446 RTP/AVP 0 8 18 2 4 96 97 98 101
    a=rtpmap:0 PCMU/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:18 G729a/8000
    a=rtpmap:2 G726-32/8000
    a=rtpmap:4 G723/8000
    a=rtpmap:96 G726-40/8000
    a=rtpmap:97 G726-24/8000
    a=rtpmap:98 G726-16/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-15
    a=ptime:20
    a=sendrecv
    04-Sep-2013 06:41:57.829 [CM503001]: Call(C:13): Incoming call from Extn:3301 to <sip:9999@192.168.1.133:5060>
    04-Sep-2013 06:41:57.822 Outbound URI is used: sip:3301@192.168.1.49:5060
    04-Sep-2013 06:41:57.822 IncomingCall: C:13 from <sip:3301@192.168.1.133:5060> to <sip:9999@192.168.1.133:5060>
    04-Sep-2013 06:41:57.822 Added leg L:C:13.1[No endpoint yet]
    04-Sep-2013 06:41:57.822 UasSession 9755 started
    04-Sep-2013 06:41:57.822 Call from "Adam Smith - DKC"<sip:3301@192.168.1.133>;tag=2f95401a66b15941o0 to <sip:9999@192.168.1.133>;tag=52141a02


    Thanks for any assistance anyone can provide.
    Adam
     
Thread Status:
Not open for further replies.