Inbound caller ID passthrough

Discussion in '3CX Phone System - General' started by hells bells, Jun 20, 2012.

Thread Status:
Not open for further replies.
  1. hells bells

    Joined:
    Jun 20, 2012
    Messages:
    1
    Likes Received:
    0
    Evening All,

    Bit of a newbie question here and I know that there are numerous posts already regarding CID and the Linksys/Cisco/Sipura SPA3102, but here goes anyway! :roll:

    I'm using 3CX v10, with the SPA 3102 (Open - Firmware version 5.2.13) in gateway mode, and a few Intertel 8620 phones for a home/SOHO setup.

    When an incoming call arrives on the PSTN (UK BT), I can see that the Linksys is parsing the caller ID ok, and also that this is being passed to the 3CX as the ID is visible in the activity log and shows as
    "Incoming call from 078xxxxxxxx@(Ln.10000@BT Line)" (I've blanked the digits)

    But nothing gets passed to the endpoint(s) other than the gateway extension number. When an incoming call arrives via VOIP, the number is displayed correctly.

    This is the log showing a PSTN call followed by a VOIP call:

    Code:
    
    19:30:49.346|.\CallLeg.cpp(143)|Log5||CallLeg::onNewCall:[CM500002]: Info on incoming INVITE:
    INVITE sip:10000@192.168.1.127 SIP/2.0
    Via: SIP/2.0/UDP 192.168.1.200:5062;branch=z9hG4bK-942f0d3e
    Max-Forwards: 70
    Contact: "10000"<sip:078xxxxxxxx@192.168.1.200:5062>
    To: <sip:10000@192.168.1.127>
    From: "10000"<sip:078xxxxxxxx@192.168.1.127>;tag=339883b436416a2o1
    Call-ID: 1da4a6ef-246a677e@192.168.1.200
    CSeq: 102 INVITE
    Expires: 240
    Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
    Proxy-Authorization: Digest username="10000",realm="3CXPhoneSystem",nonce="414d535c05f2a7d941:a24fa31ba3c31b22170e8250ddb184d6",uri="sip:10000@192.168.1.127",algorithm=MD5,response="b04b7b794b5105f14d72ac0800207e84"
    Supported: x-sipura, replaces
    User-Agent: Linksys/SPA3102-5.2.13(GW002)
    Content-Length: 0
    Remote-Party-ID: 10000 <sip:078xxxxxxxx@192.168.1.127>;screen=yes;party=calling
    
    <br>
    19:30:49.362|.\Line.cpp(301)|Log5||LineCfg::getInboundTarget:Looking for inbound target: called=10000; caller=078xxxxxxxx<br>
    19:30:49.362|.\Line.cpp(352)|Log2||LineCfg::getInboundTarget:[CM503012]: Inbound any hours rule (*) for 10000 forwards to DN:901<br>
    19:30:49.393|.\CallCtrl.cpp(346)|Log2||CallCtrl::onIncomingCall:[CM503001]: Call(7): Incoming call from 078xxxxxxxx@(Ln.10000@BT Line) to <sip:901@192.168.1.127:5060><br>
    19:30:49.393|.\Line.cpp(1452)|Log2||Line::printEndpointInfo:[CM505002]: Gateway:[BT Line] Device info: Device Identified: [Man: Linksys;Mod: SPA Series;Rev: General] Capabilities:[reinvite, no-replaces, able-no-sdp, recvonly] UserAgent: [Linksys/SPA3102-5.2.13(GW002)] PBX contact: [sip:10000@192.168.1.127:5060]<br>
    19:30:49.393|.\CallLeg.cpp(1194)|Log5||CallLeg::setRemoteSdp:Remote SDP is set for legC:7.1<br>
    19:30:49.393|.\SLServer.cpp(861)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210000] C:7.1:Offer received. RTP connection: 192.168.1.200:16398(16399)<br>
    19:30:49.393|.\CallCtrl.cpp(529)|Log3||CallCtrl::onSelectRouteReq:[CM503010]: Making route(s) to <sip:901@192.168.1.127:5060><br>
    19:30:49.393|.\CallCtrl.cpp(708)|Log2||CallCtrl::onSelectRouteReq:[CM503004]: Call(7): Route 1: Ext:Ext.901@[Dev:sip:901@192.168.1.41:5060]<br>
    19:30:49.409|.\SLServer.cpp(861)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210006] C:7.2:Offer provided. Connection(by pass mode): 192.168.1.200:16398(16399)<br>
    19:30:49.409|.\Target.cpp(441)|Log2||Target::makeOneInvite:[CM503025]: Call(7): Calling Ext:Ext.901@[Dev:sip:901@192.168.1.41:5060]<br>
    19:30:49.549|.\CallLeg.cpp(315)|Log3||CallLeg::onAnswer:[CM503002]: Call(7): Alerting sip:901@192.168.1.41:5060<br>
    19:30:49.549|.\Extension.cpp(1407)|Log3||Extension::printEndpointInfo:[CM505001]: Ext.901: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Inter-Tel 8620 0.0 Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26] PBX contact: [sip:901@192.168.1.127:5060]<br>
    19:30:55.081|.\Call.cpp(1396)|Log2||Call::Terminate:[CM503008]: Call(7): Call is terminated<br>
    19:30:55.206|.\CallLeg.cpp(326)|Log2||CallLeg::onFailure:[CM503003]: Call(7): Call to sip:901@192.168.1.127:5060 has failed; Cause: 487 Request Terminated; from IP:192.168.1.41:5060<br>
    19:31:03.300|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:31:35.301|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:32:05.317|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:32:37.318|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:33:09.319|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:33:41.320|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:34:13.320|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:34:45.321|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:35:17.322|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:35:47.620|.\StunClient.cpp(356)|Log2|STUN|StunClient::onInitTests:[CM506001]: STUN request to resolve SIP external IP:port mapping is sent to STUN server [ V4 199.192.206.228:3478 UDP target domain=unspecified mFlowKey=0 ] over Transport [ V4 192.168.1.127:5060 UDP target domain=unspecified mFlowKey=0 ]<br>
    19:35:49.323|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:35:50.792|.\StunClient.cpp(411)|Log2|STUN|StunClient::onTestTout:[CM506004]: STUN request to STUN server 216.93.246.16:3478 has timed out; used Transport: [ V4 192.168.1.127:5060 UDP target domain=unspecified mFlowKey=0 ]<br>
    19:35:53.885|.\StunClient.cpp(411)|Log2|STUN|StunClient::onTestTout:[CM506004]: STUN request to STUN server 216.93.246.16:3478 has timed out; used Transport: [ V4 192.168.1.127:5060 UDP target domain=unspecified mFlowKey=0 ]<br>
    19:35:56.979|.\StunClient.cpp(411)|Log2|STUN|StunClient::onTestTout:[CM506004]: STUN request to STUN server 216.93.246.16:3478 has timed out; used Transport: [ V4 192.168.1.127:5060 UDP target domain=unspecified mFlowKey=0 ]<br>
    19:36:00.057|.\StunClient.cpp(411)|Log2|STUN|StunClient::onTestTout:[CM506004]: STUN request to STUN server 216.93.246.16:3478 has timed out; used Transport: [ V4 192.168.1.127:5060 UDP target domain=unspecified mFlowKey=0 ]<br>
    19:36:21.324|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:36:53.324|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:37:25.325|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:37:57.326|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:38:29.327|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:39:01.328|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:39:33.329|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:40:05.329|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:40:37.330|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:41:09.331|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:41:41.332|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:42:13.333|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:42:45.333|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:43:17.334|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:43:49.335|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:44:21.336|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:44:53.337|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:45:25.338|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:45:57.338|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:46:29.339|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:47:01.340|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:47:33.341|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:48:05.342|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:48:37.342|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:49:07.359|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:49:39.360|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:50:11.360|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:50:43.361|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:51:06.331|.\CallLeg.cpp(143)|Log5||CallLeg::onNewCall:[CM500002]: Info on incoming INVITE:
    INVITE sip:1492344@91.84.195.125:5060;rinstance=011f41b110dcdef5 SIP/2.0
    Via: SIP/2.0/UDP 217.10.79.23:5060;branch=z9hG4bK8b32.b274d6a2.0
    Via: SIP/2.0/UDP 172.20.40.1;branch=z9hG4bK8b32.b274d6a2.0
    Via: SIP/2.0/UDP 217.10.79.23:5060;received=217.10.68.222;branch=z9hG4bK68d325d1
    Via: SIP/2.0/UDP 217.116.117.8:5060;received=217.116.117.8;branch=z9hG4bK68d325d1;rport=5060
    Max-Forwards: 67
    Record-Route: <sip:217.10.79.23;lr;ftag=as408a56a3>
    Record-Route: <sip:172.20.40.1;lr=on>
    Record-Route: <sip:217.10.79.23;lr;ftag=as408a56a3>
    Contact: <sip:078xxxxxxxx@217.116.117.8>
    To: <sip:0044xxxxxxx171@sipgate.co.uk>
    From: "078xxxxxxxx"<sip:078xxxxxxxx@sipgate.co.uk>;tag=as408a56a3
    Call-ID: 689a59432c02c9773ce8e55d694482cf@sipgate.co.uk
    CSeq: 102 INVITE
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
    Supported: replaces, timer
    Content-Length: 0
    
    <br>
    19:51:06.346|.\Line.cpp(301)|Log5||LineCfg::getInboundTarget:Looking for inbound target: called=0044xxxxxxx171; caller=078xxxxxxxx<br>
    19:51:06.346|.\Line.cpp(357)|Log2||LineCfg::getInboundTarget:[CM503012]: Inbound out-of-office hours rule (unnamed) for 10002 forwards to DN:905<br>
    19:51:06.518|.\CallCtrl.cpp(346)|Log2||CallCtrl::onIncomingCall:[CM503001]: Call(8): Incoming call from 078xxxxxxxx@(Ln.10002@sipgate) to <sip:905@192.168.1.127:5060><br>
    19:51:06.518|.\Line.cpp(1455)|Log2||Line::printEndpointInfo:[CM505003]: Provider:[sipgate] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [] PBX contact: [sip:1492344@91.84.195.125:5060]<br>
    19:51:06.518|.\CallLeg.cpp(1194)|Log5||CallLeg::setRemoteSdp:Remote SDP is set for legC:8.1<br>
    19:51:06.518|.\SLServer.cpp(861)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210000] C:8.1:Offer received. RTP connection: 217.10.77.22:58604(58605)<br>
    19:51:06.518|.\CallCtrl.cpp(529)|Log3||CallCtrl::onSelectRouteReq:[CM503010]: Making route(s) to <sip:905@192.168.1.127:5060><br>
    19:51:06.518|.\CallCtrl.cpp(708)|Log2||CallCtrl::onSelectRouteReq:[CM503004]: Call(8): Route 1: Ext:Ext.905@[Dev:sip:905@192.168.1.45:5060]<br>
    19:51:06.534|.\SLServer.cpp(861)|Log5|MediaServer|MediaServerReporting::SetRemoteParty:[MS210002] C:8.2:Offer provided. Connection(transcoding mode): 192.168.1.127:7030(7031)<br>
    19:51:06.549|.\Target.cpp(441)|Log2||Target::makeOneInvite:[CM503025]: Call(8): Calling Ext:Ext.905@[Dev:sip:905@192.168.1.45:5060]<br>
    19:51:06.721|.\CallLeg.cpp(315)|Log3||CallLeg::onAnswer:[CM503002]: Call(8): Alerting sip:905@192.168.1.45:5060<br>
    19:51:06.721|.\Extension.cpp(1407)|Log3||Extension::printEndpointInfo:[CM505001]: Ext.905: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Inter-Tel 8620 0.0 Brcm Callctrl/1.5.1.0 MxSF/v3.2.6.26] PBX contact: [sip:905@192.168.1.127:5060]<br>
    19:51:13.596|.\Call.cpp(1396)|Log2||Call::Terminate:[CM503008]: Call(8): Call is terminated<br>
    19:51:13.753|.\CallLeg.cpp(326)|Log2||CallLeg::onFailure:[CM503003]: Call(8): Call to sip:905@192.168.1.127:5060 has failed; Cause: 487 Request Terminated; from IP:192.168.1.45:5060<br>
    19:51:15.362|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:51:47.363|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:52:19.364|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:52:51.380|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    19:53:21.397|.\Call.cpp(48)|Log5||??:Currently active calls [none]<br>
    
    
    Can anyone make out why this should be and is there anything I should try first?

    Thanks in advance
     
  2. leejor

    leejor Well-Known Member

    Joined:
    Jan 22, 2008
    Messages:
    10,362
    Likes Received:
    227
    Usually the problem is because the number is not being passed from the 3102 onto 3CX, which is not the case. The only thing I can think of is that the calling number is being "pushed aside" (set option?), before/after, being sent out to a set.
    If a PSTN caller leaves a message, is the caller number read out correctly if the call comes in from the 3102?
     
Thread Status:
Not open for further replies.