Strange SIP Problem 1

[SupportDude]

Hello Group!

This one is very strange.

Details:
IPO 8.1.79
Public SIP service.
Serving switch type = Metaswitch
Inbound URI for Line Group 17 */*/*.
ICR built for all incoming DID numbers.

Problem:
Any DID, such as the customers Main Number, that is put in place to be used as the outgoing CLID, stops the number from being read in the ICR. I tried several different numbers in several ways. It doesn't matter if I configure the Outgoing CLID at the URI level or the User level, if any number is used for Outgoing CLID it will not be read from the ICR. See good a bad calls below.


Good Call (DID is not set as Outgoing CLID):
2014-02-27T18:09:06 1670351mS SIP Rx: UDP 208.85.62.103:5060 -> 10.0.0.10:5060
INVITE sip:3107611642@10.0.0.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 208.85.62.103:5060;branch=z9hG4bKbnnruv0068fgqisvh061.1
Allow-Events: message-summary, refer, dialog, line-seize, presence, call-info, as-feature-event
Max-Forwards: 69
Call-ID: 327A0C64@v.cfly.co
From: "Anonymous" <sip:anonymous@sc.cfly.co:5060>;tag=v.cfly.co+1+185b1e+5f0934f7
To: <sip:3107611642@sc.cfly.co>
CSeq: 998512877 INVITE
Expires: 180
Organization: Metaswitch Networks
Supported: resource-priority, 100rel
Content-Length: 189
Content-Type: application/sdp
Contact: "Anonymous" <sip:anonymous@208.85.62.103:5060;transport=udp>

v=0
o=- 1983944529 1983944529 IN IP4 208.85.62.103
s=-
c=IN IP4 208.85.62.103
t=0 0
m=audio 20994 RTP/AVP 0 18 101
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=ptime:20
2014-02-27T18:09:06 1670354mS Sip: Association found trunk: SIP Line (17)
2014-02-27T18:09:06 1670354mS CMCallEvt: 0.1066.0 -1 BaseEP: NEW CMEndpoint f51b4208 TOTAL NOW=1 CALL_LIST=0
2014-02-27T18:09:06 1670354mS Sip: SIPDialog f51b2d10 created, size 1
2014-02-27T18:09:06 1670355mS Sip: License, Valid 1, Available 7, Consumed 0
2014-02-27T18:09:06 1670355mS Sip: CheckLineMonitors on SIP Endpoint - KEY & LAMP for SIP Trunk!
2014-02-27T18:09:06 1670356mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) Cloned
2014-02-27T18:09:06 1670357mS Sip: SIPDialog::ExtractPortFromViaHeader remote: 208.85.62.103:5060 trunk
2014-02-27T18:09:06 1670357mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) SendSIPResponse: rport not found - 0
2014-02-27T18:09:06 1670357mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) SendSIPResponse: INVITE code 100 SENT TO 208.85.62.103 5060
2014-02-27T18:09:06 1670358mS SIP Tx: UDP 10.0.0.10:5060 -> 208.85.62.103:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 208.85.62.103:5060;branch=z9hG4bKbnnruv0068fgqisvh061.1
From: "Anonymous" <sip:anonymous@sc.cfly.co:5060>;tag=v.cfly.co+1+185b1e+5f0934f7
To: <sip:3107611642@sc.cfly.co>;tag=dd45e166a8c4a3e2
Call-ID: 327A0C64@v.cfly.co
CSeq: 998512877 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer,100rel
Server: IP Office 8.1 (79)
Content-Length: 0

2014-02-27T18:09:06 1670358mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) INVITE Received ep 17.1066.1 -1 SIPTrunk Endpoint(f51b4208), dialog f51b2d10
2014-02-27T18:09:06 1670358mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) UpdateSIPCallState SIPDialog::INITIAL(0) -> SIPDialog::INVITE_RCVD(9)
2014-02-27T18:09:06 1670359mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) UpdateSDPState SIPDialog::IDLE(0) -> SIPDialog::OFFER_RCVD(2)
2014-02-27T18:09:06 1670359mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) SdpClone
2014-02-27T18:09:06 1670359mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) SIPDialog::BuildFastStartFromSDPOffer sdpmsg f51ae210
2014-02-27T18:09:06 1670359mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) SIPDialog::FindConnectionParameters: found media proto <RTP/AVP>
2014-02-27T18:09:06 1670360mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) SIPDialog::BuildFastStartFromAudioMediaSDPOffer reinvite 0 msg f59397e8 fs in msg 1 mRTP_PType 255
2014-02-27T18:09:06 1670360mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) SetRemoteRTPAddress to 208.85.62.103:20994 (index 0)
2014-02-27T18:09:06 1670360mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) Process SIP request dialog f51b2d10, method INVITE in state SIPDialog::INVITE_RCVD(9)
2014-02-27T18:09:06 1670361mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) UpdateClone mMesg f51c4b78 smsg f51c4c98
2014-02-27T18:09:06 1670361mS Sip: 17.1066.1 -1 SIPTrunk Endpoint(f51b2d10) CMSetup forwarded to call model owner_ep 17.1066.1 -1 SIPTrunk Endpoint(f51b4208), dialog f51b2d10
2014-02-27T18:09:06 1670362mS CMCallEvt: CREATE CALL:6 (f5937a68)
2014-02-27T18:09:06 1670362mS CMCallEvt: 0.1067.0 -1 BaseEP: NEW CMEndpoint f59155b0 TOTAL NOW=2 CALL_LIST=0
2014-02-27T18:09:06 1670363mS CMCallEvt: 17.1066.1 6 SIPTrunk Endpoint: StateChange: END=A CMCSIdle->CMCSDialInitiated
[highlight #FCE94F]2014-02-27T18:09:06 1670363mS CMTARGET: 17.1066.1 6 SIPTrunk Endpoint: LOOKUP CALL ROUTE: type=100 called_party=3107611642 sub= calling=anonymous@sc.cfly.co dir=in complete=1 ses=0[/highlight]
2014-02-27T18:09:06 1670364mS CMTARGET: 17.1066.1 6 SIPTrunk Endpoint: SET BESTMATCH: length 0 vs -1 match= dest=Main
2014-02-27T18:09:06 1670364mS CMCallEvt: Priority hike: call 6 priority 0->1
2014-02-27T18:09:06 1670364mS CMTARGET: 17.1066.1 6 SIPTrunk Endpoint: LOOKUP ICR: DDI=3107611642 CGPN=anonymous@sc.cfly.co (Destination Main ) => CDPN=Main
2014-02-27T18:09:06 1670364mS CMTARGET: 17.1066.1 6 SIPTrunk Endpoint: ADD TARGET (N): number=Main type=100 depth=1 nobar=1 setorig=1 ses=0
2014-02-27T18:09:06 1670364mS CMTARGET: 17.1066.1 6 SIPTrunk Endpoint: HG(Main,200,10.0.0.10) Requires Routing To Master(1). IsLocalExecutive(1)
2014-02-27T18:09:06 1670364mS CMTARGET: 17.1066.1 6 SIPTrunk Endpoint: HG call targeting occuring here
2014-02-27T18:09:06 1670365mS CMTARGET: 17.1066.1 6 SIPTrunk Endpoint: PrimeForHGTarget: Main setorig=1 recall=0 resetExtnVars 1
2014-02-27T18:09:06 1670365mS CMCallEvt: Priority hike: call 6 priority 1->5
2014-02-27T18:09:06 1670365mS CMTARGET: 17.1066.1 6 SIPTrunk Endpoint: AddHGTarget Main (depth=1) allowq=1 type=CMNTypeDefault

Bad Call (DID is configured as Outgoing CLID): (and I guarantee there was a matching entry in the IRC!)
2014-02-27T18:06:59 1543870mS SIP Rx: UDP 208.85.62.103:5060 -> 10.0.0.10:5060
INVITE sip:3107611641@10.0.0.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 208.85.62.103:5060;branch=z9hG4bK98g88c20e87g4icg7370.1
Allow-Events: message-summary, refer, dialog, line-seize, presence, call-info, as-feature-event
Max-Forwards: 69
Call-ID: 97F7A5EB@v.cfly.co
From: "Anonymous" <sip:anonymous@sc.cfly.co:5060>;tag=v.cfly.co+1+62714+a634b37d
To: <sip:3107611641@sc.cfly.co>
CSeq: 341976184 INVITE
Expires: 180
Organization: Metaswitch Networks
Supported: resource-priority, 100rel
Content-Length: 189
Content-Type: application/sdp
Contact: "Anonymous" <sip:anonymous@208.85.62.103:5060;transport=udp>

v=0
o=- 1983818063 1983818063 IN IP4 208.85.62.103
s=-
c=IN IP4 208.85.62.103
t=0 0
m=audio 28178 RTP/AVP 0 18 101
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=ptime:20
2014-02-27T18:06:59 1543873mS Sip: Association found trunk: SIP Line (17)
2014-02-27T18:06:59 1543874mS CMCallEvt: 0.1058.0 -1 BaseEP: NEW CMEndpoint f5245968 TOTAL NOW=1 CALL_LIST=0
2014-02-27T18:06:59 1543874mS Sip: SIPDialog f5244470 created, size 1
2014-02-27T18:06:59 1543874mS Sip: License, Valid 1, Available 7, Consumed 0
2014-02-27T18:06:59 1543875mS Sip: CheckLineMonitors on SIP Endpoint - KEY & LAMP for SIP Trunk!
2014-02-27T18:06:59 1543876mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) Cloned
2014-02-27T18:06:59 1543876mS Sip: SIPDialog::ExtractPortFromViaHeader remote: 208.85.62.103:5060 trunk
2014-02-27T18:06:59 1543876mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) SendSIPResponse: rport not found - 0
2014-02-27T18:06:59 1543876mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) SendSIPResponse: INVITE code 100 SENT TO 208.85.62.103 5060
2014-02-27T18:06:59 1543877mS SIP Tx: UDP 10.0.0.10:5060 -> 208.85.62.103:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 208.85.62.103:5060;branch=z9hG4bK98g88c20e87g4icg7370.1
From: "Anonymous" <sip:anonymous@sc.cfly.co:5060>;tag=v.cfly.co+1+62714+a634b37d
To: <sip:3107611641@sc.cfly.co>;tag=5bed8bbaedd928e4
Call-ID: 97F7A5EB@v.cfly.co
CSeq: 341976184 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer,100rel
Server: IP Office 8.1 (79)
Content-Length: 0

2014-02-27T18:06:59 1543878mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) INVITE Received ep 17.1058.1 -1 SIPTrunk Endpoint(f5245968), dialog f5244470
2014-02-27T18:06:59 1543878mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) UpdateSIPCallState SIPDialog::INITIAL(0) -> SIPDialog::INVITE_RCVD(9)
2014-02-27T18:06:59 1543878mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) UpdateSDPState SIPDialog::IDLE(0) -> SIPDialog::OFFER_RCVD(2)
2014-02-27T18:06:59 1543878mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) SdpClone
2014-02-27T18:06:59 1543879mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) SIPDialog::BuildFastStartFromSDPOffer sdpmsg f51ca5a0
2014-02-27T18:06:59 1543879mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) SIPDialog::FindConnectionParameters: found media proto <RTP/AVP>
2014-02-27T18:06:59 1543879mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) SIPDialog::BuildFastStartFromAudioMediaSDPOffer reinvite 0 msg f590e988 fs in msg 1 mRTP_PType 255
2014-02-27T18:06:59 1543879mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) SetRemoteRTPAddress to 208.85.62.103:28178 (index 0)
2014-02-27T18:06:59 1543880mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) Process SIP request dialog f5244470, method INVITE in state SIPDialog::INVITE_RCVD(9)
2014-02-27T18:06:59 1543881mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) UpdateClone mMesg f51c4b18 smsg f51c49f8
2014-02-27T18:06:59 1543881mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) CMSetup forwarded to call model owner_ep 17.1058.1 -1 SIPTrunk Endpoint(f5245968), dialog f5244470
2014-02-27T18:06:59 1543881mS CMCallEvt: CREATE CALL:4 (f5937a68)
2014-02-27T18:06:59 1543881mS CMCallEvt: 0.1059.0 -1 BaseEP: NEW CMEndpoint f59155b0 TOTAL NOW=2 CALL_LIST=0
2014-02-27T18:06:59 1543883mS CMCallEvt: 17.1058.1 4 SIPTrunk Endpoint: StateChange: END=A CMCSIdle->CMCSDialInitiated
[highlight #FCE94F]2014-02-27T18:06:59 1543883mS CMTARGET: 17.1058.1 4 SIPTrunk Endpoint: LOOKUP CALL ROUTE: type=100 called_party=3107611641 sub= calling=anonymous@sc.cfly.co dir=in complete=1 ses=0
2014-02-27T18:06:59 1543885mS CMCallEvt: 17.1058.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSDialInitiated->CMCSCompleted
2014-02-27T18:06:59 1543885mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) KeepDlgOnCmCallLost SIPDialog::INVITE_RCVD
2014-02-27T18:06:59 1543885mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) Terminating dialog f5244470, state SIPDialog::INVITE_RCVD(9) for cause CMCauseUnallocatedNumber[/highlight]
2014-02-27T18:06:59 1543886mS Sip: SIPDialog::ExtractPortFromViaHeader remote: 208.85.62.103:5060 trunk
2014-02-27T18:06:59 1543886mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) SendSIPResponse: rport not found - 0
2014-02-27T18:06:59 1543886mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) SendSIPResponse: INVITE code 404 SENT TO 208.85.62.103 5060
2014-02-27T18:06:59 1543887mS SIP Tx: UDP 10.0.0.10:5060 -> 208.85.62.103:5060
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 208.85.62.103:5060;branch=z9hG4bK98g88c20e87g4icg7370.1
From: "Anonymous" <sip:anonymous@sc.cfly.co:5060>;tag=v.cfly.co+1+62714+a634b37d
To: <sip:3107611641@sc.cfly.co>;tag=5bed8bbaedd928e4
Call-ID: 97F7A5EB@v.cfly.co
CSeq: 341976184 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer,100rel
Server: IP Office 8.1 (79)
Content-Length: 0

2014-02-27T18:06:59 1543887mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) UpdateSIPCallState SIPDialog::INVITE_RCVD(9) -> SIPDialog::FINAL(27)
2014-02-27T18:06:59 1543888mS CMCallEvt: 0.1059.0 -1 BaseEP: DELETE CMEndpoint f59155b0 TOTAL NOW=1 CALL_LIST=0
2014-02-27T18:06:59 1543888mS CMCallEvt: END CALL:4 (f5937a68)
2014-02-27T18:06:59 1543951mS SIP Rx: UDP 208.85.62.103:5060 -> 10.0.0.10:5060
ACK sip:3107611641@10.0.0.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 208.85.62.103:5060;branch=z9hG4bK98g88c20e87g4icg7370.1
CSeq: 341976184 ACK
Max-Forwards: 69
Call-ID: 97F7A5EB@v.cfly.co
From: "Anonymous" <sip:anonymous@sc.cfly.co:5060>;tag=v.cfly.co+1+62714+a634b37d
To: <sip:3107611641@sc.cfly.co>;tag=5bed8bbaedd928e4
Content-Length: 0

2014-02-27T18:06:59 1543953mS Sip: Find End Point 17.1058.1 -1 SIPTrunk Endpoint (f5245968) Sip CallId 97F7A5EB@v.cfly.co
2014-02-27T18:06:59 1543953mS Sip: 17.1058.1 -1 SIPTrunk Endpoint(f5244470) Process SIP request dialog f5244470, method ACK in state SIPDialog::FINAL(27)

Anybody ever seen such a thing?


-SD-

http://www.mysupportguy.net

 

[intrigrant]

Inbound URI for Line Group 17 */*/*"
Only the first * is for inbound, the others are for outbound.
Try this in the URI and see what happens:
*/3107611641/3107611641

If you make a trace then add Call info with ARS and Shortcode messages enabled, only SIP messages is not enough.

 

[SupportDude]

Thanks for the reply Intrigrant!
I did have Call Info, Targeting, etc., turned on. You can see it in the good call. We don't see anything on the bad call because the call setup ends when it can't find a match in the ICR.
It's worth mentioning that I also had a default/catch-all route configured in the ICR for line group 17, no Incoming Number and a destination of the operator extension. But calls wouldn't go there either if the DID was configured as the outgoing CLID. If I removed it as the CLID, the call would process normally.

The inbound URI is like this;
Local URI = *
Contact = *
Display Name = *
PAI = None
Incoming Group = 17
Outgoing Group = 86

I actually had a separate URI for outbound using the SIP Credentials from the registration information from the provider.
So the outbound URI was like this;
Local URI = Use Credentials Contact
Contact = Use Credentials User Name
Display Name = Use Credentials User Name
PAI = Use Credentials User Name
Incoming Group = 86
Outgoing Group = 17

The SIP Credentials tab is populated with the information required to register with the provider. All of the fields, except the password, contained the customers billing telephone number, which was not the main telephone number.
I changed the SIP Credentials "Contact" to the customers main number (because it didn't impact the registration status), and then used it as the Local URI as seen above.
Per the provider, I had to send a PAI if we were going to use anything other than the billing telephone number for CLID.

This did work. The CLID of the main number was sent on all outgoing calls. But incoming calls to that same number would get a 404 not found ... even though it was built in the ICR.
Very strange stuff.

-SD-

http://www.mysupportguy.net

 

[intrigrant]

Always use the same incoming group ID for the SIP trunk OR create incoming call routes for all incoming IDs, that will solve your problem.

 

[SupportDude]

I did that sir, just not on the same URI.
I do see your theory and it's worth a star! I just wish I would have tested it.
I think you're saying that since I had the CLID configured on the URI I was using for outgoing,
the incoming calls to that DID may have been using the incoming group (86) for the outgoing URI?
That is possible. I didn't have anything built in the ICR for that group.
All of my ICR entries were against group 17.
I also can't find anything in the Monitor Trace that tells us what line group is being used.

At one point I configured the outgoing URI like this;(I pounded this problem for hours)
Local URI = Use Internal Data
Contact = Use Internal Data
Display Name = Use Internal Data
PAI = None
Incoming Group = 86
Outgoing Group = 17

This allowed me to set the outgoing CLID at the User level.
If I left everything on the User>SIP tab at default, incoming and outgoing were fine for all DID numbers.
But, I didn't get the correct outgoing caller ID because it was set for the Users extension.
When I changed the User>SIP tab to the CLID I wanted to send, outgoing was good and showed the correct CLID,
but calling the CLID DID inbound would get the "404 Not Found" error.

The strangest part is any DID worked fine, until it was used as the outgoing CLID.
I could break it and fix it at will.

-SD-

http://www.mysupportguy.net

 

[jlk53]

I am having the exact same issue. Tried everything with the same results. Has anybody found a work around?

 

[SupportDude]

@jlk53 ....
I never got it to work.
The provider was able to insert the main number, the outgoing CLID we wanted to send, on their side.

Have you tried any of Intrigrants suggestions?

-SD-

http://www.mysupportguy.net

 

[jlk53]

I tried them all. What I just tried was to put a "period" after the number, ex "2426779970." and it worked for me. You may want to try it.