Let me take a minute (or several minutes) to address the dreaded "No phone system connection" (alternately, the "Cannot connect to the phone system") error when you are doing Remote Call Control (RCC)
When you are integrating Office Communications Server (OCS) 2007 with Cisco Unified Presence Server (CUPS), the default, generic message on communicator is "No phone system connection". It looks like this:
Here’s what you need to know – USE THE CUPS REALTIME MONITORING TOOL (RTMT). This is the only way to troubleshoot your problem. Period. The OCS monitoring tool will not provide you enough detail, nor will the OCS client logs. The problem with those is that they only end up displaying the end result that CUPS generates, like "SIP 2.0/401 Unauthorized". That doesn’t help a whole lot. The CUPS logs show you exactly what is going on behind the scenes and why certain scenarios end in failure.
I can guarantee that no CUPS/OCS integration problem is easier to troubleshoot without the RTMT
Getting CUPS Logging going
First, turn up logging on CUPS unified serviceability page, under Trace. The main one you need to hit is the "SIP Proxy Service"
Then download the RealTime Monitoring Tool from the CUPS admin plug-in page:
Install it and connect your CUPS server.
Browse to "Trace and Log Central"
Click on "Real Time Trace"
Ok, so once you have the RTMT set up and running on your PC, log into MOC and watch the results fly by. I usually will let it run for a minute, then Ctrl+A and paste into notepad or Vi to edit.
The results can be somewhat cryptic, so I’m going to break down several common scenarios that cause the integration to fail & show the results of the RTMT for those cases.
Cases when MOC and RCC integration to CUPS fail
Case1: Here’s when it fails, using tel:+4736 in the line uri
|
1 |
10/13/2008 16:21:09.425 ESP|ID(6899) mod_sip_ua.c(857) ua_handle_invite(): INVITE request with body: <?xml version="1.0"?> |
|
2 |
<RequestSystemStatus xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><extensions> |
|
3 |
<privateData><private><lcs:line xmlns:lcs="http://schemas.microsoft.com/Lcs/2005/04/RCCExtension">tel:+4736</lcs:line></private></privateData></extensions></RequestSystemStatus> |
|
4 |
10/13/2008 16:21:09.426 ESP|PID(6899) mod_sip_ctigw.c(5059) ctigw_csta_handler:: Get CSTA request: 0 |
|
5 |
PID(6899) mod_sip_ctigw.c(5109) ctigw_csta_handler:: Get CSTA request: 0-RequestSystemStatus |
|
6 |
10/13/2008 16:21:09.432 ESP|PID(6899) mod_sip_ctigw.c(5125) ctigw_csta_handler:: line=+4736, device=, partition=, name= |
|
7 |
10/13/2008 16:21:09.432 ESP|PID(6899) mod_sip_ctigw.c(1444) Authorizing user (mmcgillen mmcgillen@pointbridge.com) DN (+4736) DEV () |
|
8 |
10/13/2008 16:21:09.587 ESP|Mon Oct 13 16:21:09 2008] PID(6899) mod_sip_ctigw.c(1153) CTIGW DB query : execute procedure spValDevice(‘mmcgillen’, ‘mmcgillen@pointbridge.com’, ‘+4736′, NULL); |
|
9 |
PID(6899) mod_sip_ctigw.c(5133) ctigw_csta_handler:: FAIL AUTHORIZED userID=mmcgillen from=mmcgillen@pointbridge.com line=+4736, device= |
|
10 |
10/13/2008 16:21:09.587 ESP|[Mon Oct 13 16:21:09 2008] PID(6899) mod_sip_ctigw.c(453) freeQCB(): QCB -1475760156 is about to be freed |
|
11 |
PID(6899) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed 474984a6 |
|
12 |
10/13/2008 16:21:09.587 ESP|[Mon Oct 13 16:21:09 2008] PID(6899) mod_sip_ua.c(1471) ua_scb_destroyed(): scb a80a9a24 key: fb0d050-9fe797f9-6df5fa1b-5d5c5824 |
|
13 |
[Mon Oct 13 16:21:09 2008] PID(32276) sip_tcp.c(2999) sip_tcp received auth state as: 0 for connid: 2 from sip_sm |
|
14 |
PID(6899) sip_sm.c(1100) Sent 675 bytes TCP packet to 10.x.x.x:1084 |
|
15 |
SIP/2.0 401 Unauthorized |
Conclusion: if you put +4736 or any number that does not match a DN of a device in CCM, it will fail. The plus sign, in this case, is hosing things up,
Case2: Using an extension, tel:1901, that exists, but is not controlled by me:
|
1 |
10/14/2008 09:51:48.719 ESP|ID(7374) mod_sip_ua.c(857) ua_handle_invite(): INVITE request with body: <?xml version="1.0"?> |
|
2 |
<RequestSystemStatus xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><extensions> |
|
3 |
<privateData><private><lcs:line xmlns:lcs="http://schemas.microsoft.com/Lcs/2005/04/RCCExtension">tel:1901</lcs:line></private></privateData></extensions></RequestSystemStatus> |
|
4 |
10/14/2008 09:51:48.720 ESP|PID(7374) mod_sip_ctigw.c(5059) ctigw_csta_handler:: Get CSTA request: 0 |
|
5 |
PID(7374) mod_sip_ctigw.c(5109) ctigw_csta_handler:: Get CSTA request: 0-RequestSystemStatus |
|
6 |
10/14/2008 09:51:48.720 ESP|PID(7374) mod_sip_ctigw.c(5125) ctigw_csta_handler:: line=1901, device=, partition=, name= |
|
7 |
10/14/2008 09:51:48.720 ESP|PID(7374) mod_sip_ctigw.c(1444) Authorizing user (mmcgillen mmcgillen@pointbridge.com) DN (1901) DEV () |
|
8 |
10/14/2008 09:51:48.884 ESP|Tue Oct 14 09:51:48 2008] PID(7374) mod_sip_ctigw.c(1153) CTIGW DB query : execute procedure spValDevice(‘mmcgillen’, ‘mmcgillen@pointbridge.com’, ‘1901’, NULL); |
|
9 |
PID(7374) mod_sip_ctigw.c(1489) Found device SEP001AE22AB338 dn:1901 part:P_1NFRANKLINInternal1 model:30018 prot:0 maxnc:4 moc:0 |
|
10 |
10/14/2008 09:51:48.884 ESP|PID(7374) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed 5557d9d4 |
|
11 |
10/14/2008 09:51:48.885 ESP|PID(7374) mod_sip_ctigw.c(1533) Found device SEP001B0C191963 dn:1901 part:P_1NFRANKLINInternal1 model:115 prot:0 maxnc:4 |
|
12 |
10/14/2008 09:51:48.885 ESP|PID(7374) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed 832df1c3 |
|
13 |
10/14/2008 09:51:48.886 ESP|PID(7374) mod_sip_ctigw.c(1573) UserId mmcgillen does not have a MOC license |
|
14 |
10/14/2008 09:51:48.886 ESP|PID(7374) mod_sip_ctigw.c(5133) ctigw_csta_handler:: FAIL AUTHORIZED userID=mmcgillen from=mmcgillen@pointbridge.com line=1901, device= |
|
15 |
10/14/2008 09:51:48.886 ESP|[Tue Oct 14 09:51:48 2008] PID(7374) mod_sip_ctigw.c(453) freeQCB(): QCB -1475760156 is about to be freed |
|
16 |
PID(7374) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed 5557d9d4 |
|
17 |
10/14/2008 09:51:48.886 ESP|PID(7374) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed 48c3ca40 |
|
18 |
10/14/2008 09:51:48.886 ESP|[Tue Oct 14 09:51:48 2008] PID(7374) mod_sip_ua.c(1471) ua_scb_destroyed(): scb a80a58ec key: 3e631d8d-edeb7cb5-35a8612b-b7a59a84 |
|
19 |
PID(7374) sip_sm.c(1100) Sent 675 bytes TCP packet to 10.x.x.x:4451 |
|
20 |
SIP/2.0 401 Unauthorized |
Conclusion: If you haven’t associated the a phone with the your user name, or have associated a phone that doesn’t have your correct extension, it fails.
cCase 3: user name mismatch: mmcgillen@pointbridge.com is my SIP name, but CUPS and CallManager have me as "matt"
MOC requires you to log in with a SIP address. For many reasons, it’s best to make this your SMTP address. But under any circumstance, for CUPS integration, the username to the left of the @ must match the user name configured in CCM and CUPS.
Here’s my MOC client:
Here’s my CCM user configuration. I’ve changed it to "matt" just to watch it break.
And in CUPS, I’m showing that the user "matt" is indeed enabled for MOC.
And the results are in…
|
1 |
10/14/2008 10:17:31.364 ESP|ID(7483) mod_sip_ua.c(857) ua_handle_invite(): INVITE request with body: <?xml version="1.0"?> |
|
2 |
<RequestSystemStatus xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> |
|
3 |
<extensions><privateData><private><lcs:line xmlns:lcs="http://schemas.microsoft.com/Lcs/2005/04/RCCExtension">tel:4736;phone-context=dialstring</lcs:line></private></privateData></extensions></RequestSystemStatus> |
|
4 |
10/14/2008 10:17:31.364 ESP|ID(7483) mod_sip_ctigw.c(5059) ctigw_csta_handler:: Get CSTA request: 0 |
|
5 |
PID(7483) mod_sip_ctigw.c(5109) ctigw_csta_handler:: Get CSTA request: 0-RequestSystemStatus |
|
6 |
10/14/2008 10:17:31.364 ESP|ID(7483) mod_sip_ctigw.c(5125) ctigw_csta_handler:: line=4736, device=, partition=, name= |
|
7 |
10/14/2008 10:17:31.364 ESP|ID(7483) mod_sip_ctigw.c(1444) Authorizing user (mmcgillen mmcgillen@pointbridge.com) DN (4736) DEV () |
|
8 |
10/14/2008 10:17:31.841 ESP|Tue Oct 14 10:17:31 2008] PID(7483) mod_sip_ctigw.c(1153) CTIGW DB query : execute procedure spValDevice(‘mmcgillen’, ‘mmcgillen@pointbridge.com’, ‘4736’, NULL); |
|
9 |
[Tue Oct 14 10:17:31 2008] PID(32276) sip_tcp.c(455) sip_tcp is now reporting pdu to state machine 10.x.x.x connid 0, sock_fd 28 0 msgs |
|
10 |
PID(6892) sip_protocol.c(5663) Received 219 bytes TCP packet from 10.x.x.x:40341 |
|
11 |
10/14/2008 10:18:16.752 ESP|[Tue Oct 14 10:18:16 2008] PID(32276) sip_tcp.c(2999) sip_tcp received auth state as: 0 for connid: 10 from sip_sm |
|
12 |
[Tue Oct 14 10:18:16 2008] PID(32276) sip_tcp.c(495) sip_tcp is now sending ok pdu 10.x.x.x connid 10, sock_fd 29 1 msgs |
|
13 |
[Tue Oct 14 10:18:16 2008] PID(6894) mod_sip_ctigw.c(3318) TCP-resp for cti-provider connection 0 |
|
14 |
[Tue Oct 14 10:18:16 2008] PID(6894) mod_sip.c(375) ipc handler 1 msgs |
|
15 |
10/14/2008 10:18:16.752 ESP|ID(32276) prefork.c(1515) server stats: 3 http: 0 udp: 0 time: 19 ptime: 0 tevents: 1 ipc: 1 ievents: 1 |
|
16 |
10/14/2008 10:18:20.904 ESP|PID(7483) mod_sip_ctigw.c(1489) Found device SEP001BD40C5162 dn:4736 part:P_1NFRANKLINInternal1 model:115 prot:0 maxnc:4 moc:0 |
|
17 |
10/14/2008 10:18:20.904 ESP|ID(7483) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed a8136625 |
|
18 |
10/14/2008 10:18:20.904 ESP|PID(7483) mod_sip_ctigw.c(1614) UserId mmcgillen does not have a MOC license or not authorized |
|
19 |
10/14/2008 10:18:20.904 ESP|ID(7483) mod_sip_ctigw.c(5133) ctigw_csta_handler:: FAIL AUTHORIZED userID=mmcgillen from=mmcgillen@pointbridge.com line=4736, device= |
|
20 |
10/14/2008 10:18:20.904 ESP|Tue Oct 14 10:18:20 2008] PID(7483) mod_sip_ctigw.c(453) freeQCB(): QCB -1475760156 is about to be freed |
|
21 |
PID(7483) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed a8136625 |
|
22 |
10/14/2008 10:18:20.904 ESP|PID(7483) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed fa0fdbad |
|
23 |
10/14/2008 10:18:20.905 ESP|Tue Oct 14 10:18:20 2008] PID(7483) mod_sip_ua.c(1471) ua_scb_destroyed(): scb a80a7adc key: a5b4d67-5b44622d-3997ba-b1f40b8d |
|
24 |
PID(7483) sip_sm.c(1100) Sent 675 bytes TCP packet to 10.x.x.x:4451 |
|
SIP/2.0 401 Unauthorized |
Conclusion: you definitely need to make sure that your SIP name to the left of the @ sign (mmcgillen) matches the username in CCM. If it doesn’t match, change it in call manager, then restart the UP Sync Agent in CUPS:
That will force CUPS to update the user list with the proper name. Log out of MOC and log back in.
Case 4: No devices associated with CCM user
In order for RCC to work, you need to make sure that the end user has a phone associated with him/her in the CCM user page. If not, you will get the dreaded error. So I’ve removed the device from my CCM user:
And, not shockingly, it bombs out:
|
1 |
10/14/2008 15:02:56.905 ESP|ID(6899) mod_sip_ua.c(857) ua_handle_invite(): INVITE request with body: <?xml version="1.0"?> |
|
2 |
<RequestSystemStatus xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><extensions><privateData><private><lcs:line xmlns:lcs="http://schemas.microsoft.com/Lcs/2005/04/RCCExtension">tel:4736;phone-context=dialstring</lcs:line></private></privateData></extensions></RequestSystemStatus> |
|
4 |
10/14/2008 15:02:56.905 ESP|PID(6899) mod_sip_ctigw.c(5059) ctigw_csta_handler:: Get CSTA request: 0 |
|
6 |
PID(6899) mod_sip_ctigw.c(5109) ctigw_csta_handler:: Get CSTA request: 0-RequestSystemStatus |
|
7 |
10/14/2008 15:02:56.907 ESP|PID(6899) mod_sip_ctigw.c(5125) ctigw_csta_handler:: line=4736, device=, partition=, name= |
|
8 |
10/14/2008 15:02:56.907 ESP|PID(6899) mod_sip_ctigw.c(1444) Authorizing user (mmcgillen mmcgillen@pointbridge.com) DN (4736) DEV () |
|
9 |
10/14/2008 15:02:57.061 ESP|Tue Oct 14 15:02:56 2008] PID(6899) mod_sip_ctigw.c(1153) CTIGW DB query : execute procedure spValDevice(‘mmcgillen’, ‘mmcgillen@pointbridge.com’, ‘4736’, NULL); |
|
10 |
PID(6899) mod_sip_ctigw.c(1489) Found device SEP001BD40C5162 dn:4736 part:P_1NFRANKLINInternal1 model:115 prot:0 maxnc:4 moc:0 |
|
12 |
10/14/2008 15:02:57.061 ESP|PID(6899) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed a8136625 |
|
13 |
10/14/2008 15:02:57.062 ESP|PID(6899) mod_sip_ctigw.c(1614) UserId mmcgillen does not have a MOC license or not authorized |
|
14 |
10/14/2008 15:02:57.062 ESP|PID(6899) mod_sip_ctigw.c(5133) ctigw_csta_handler:: FAIL AUTHORIZED userID=mmcgillen from=mmcgillen@pointbridge.com line=4736, device= |
|
15 |
10/14/2008 15:02:57.062 ESP|[Tue Oct 14 15:02:57 2008] PID(6899) mod_sip_ctigw.c(453) freeQCB(): QCB -1475760156 is about to be freed |
|
16 |
PID(6899) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed a8136625 |
|
17 |
10/14/2008 15:02:57.062 ESP|PID(6899) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed fa0fdbad |
|
18 |
10/14/2008 15:02:57.062 ESP|[Tue Oct 14 15:02:57 2008] PID(6899) mod_sip_ua.c(1471) ua_scb_destroyed(): scb a80a7adc key: d2544679-b6b71947-1b1a90ff-f9d3787b |
|
19 |
PID(6899) sip_sm.c(1100) Sent 675 bytes TCP packet to 10.x.x.x:2543 |
|
20 |
SIP/2.0 401 Unauthorized |
Conclusion: Make sure your CCM user has a phone associated in the end user configuration page.
Case 5: A user has a phone number assigned to more than 2 devices:
Here’s my extension, showing 3 different devices associated:
I’ve written a blog about this issue and how to resolve it. But I thought that I’d show the CUPS errors on the backend.
|
1 |
10/15/2008 16:05:12.987 ESP|ID(6894) mod_sip_ua.c(857) ua_handle_invite(): INVITE request with body: <?xml version="1.0"?> |
|
2 |
<RequestSystemStatus xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><extensions><privateData><private><lcs:line xmlns:lcs="http://schemas.microsoft.com/Lcs/2005/04/RCCExtension">tel:4736;phone-context=dilalstring</lcs:line></private></privateData></extensions></RequestSystemStatus> |
|
3 |
10/15/2008 16:05:12.987 ESP|PID(6894) mod_sip_ctigw.c(5059) ctigw_csta_handler:: Get CSTA request: 0 |
|
4 |
PID(6894) mod_sip_ctigw.c(5109) ctigw_csta_handler:: Get CSTA request: 0-RequestSystemStatus |
|
5 |
10/15/2008 16:05:12.990 ESP|PID(6894) mod_sip_ctigw.c(5125) ctigw_csta_handler:: line=4736, device=, partition=, name= |
|
6 |
10/15/2008 16:05:12.990 ESP|PID(6894) mod_sip_ctigw.c(1444) Authorizing user (mmcgillen mmcgillen@pointbridge.com) DN (4736) DEV () |
|
7 |
10/15/2008 16:05:13.143 ESP|Wed Oct 15 16:05:12 2008] PID(6894) mod_sip_ctigw.c(1153) CTIGW DB query : execute procedure spValDevice(‘mmcgillen’, ‘mmcgillen@pointbridge.com’, ‘4736’, NULL); |
|
8 |
PID(6894) mod_sip_ctigw.c(1489) Found device SEP001BD40C5162 dn:4736 part:P_1NFRANKLINInternal1 model:115 prot:0 maxnc:4 moc:0 |
|
9 |
10/15/2008 16:05:13.143 ESP|PID(6894) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed a8136625 |
|
10 |
10/15/2008 16:05:13.145 ESP|PID(6894) mod_sip_ctigw.c(1533) Found device SEP001ae2bcecf5 dn:4736 part:P_1NFRANKLINInternal1 model:115 prot:0 maxnc:4 |
|
11 |
10/15/2008 16:05:13.145 ESP|PID(6894) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed 45f7e14b |
|
12 |
10/15/2008 16:05:13.145 ESP|PID(6894) mod_sip_ctigw.c(1568) Error: Found more than 2 devices SEP001b0c191ab2 |
|
13 |
10/15/2008 16:05:13.146 ESP|PID(6894) mod_sip_ctigw.c(5133) ctigw_csta_handler:: FAIL AUTHORIZED userID=mmcgillen from=mmcgillen@pointbridge.com line=4736, device= |
|
14 |
10/15/2008 16:05:13.146 ESP|[Wed Oct 15 16:05:13 2008] PID(6894) mod_sip_ctigw.c(453) freeQCB(): QCB -1475764860 is about to be freed |
|
15 |
PID(6894) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed a8136625 |
|
16 |
10/15/2008 16:05:13.146 ESP|PID(6894) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed b4072494 |
|
17 |
10/15/2008 16:05:13.146 ESP|[Wed Oct 15 16:05:13 2008] PID(6894) mod_sip_ua.c(1471) ua_scb_destroyed(): scb a80a5f7c key: e1edf40b-bb36889e-5006c041-33c18e26 |
|
18 |
SIP/2.0 401 Unauthorized |
Now as I had mentioned earlier – if you just use the OCS logging tool or the MOC logs, all you see is line 18: SIP/2.0 401 Unauthorized. Isn’t it WAAAY better to see line 12: "Error Found more than 2 devices"? This is exactly why the RTMT is essential for troubleshooting.
Conclusion: If you have more than 2 devices associated with your phone number, be sure to hard-code the device in your Line URI.
Cases where MOC integration with CUPS works properly
Case 1: Here’s when it works with everything configured properly using tel:4736;phone-context=dialstring in the line uri and using the right username in MOC, matching the username in CCM and CUPS
|
1 |
10/13/2008 16:27:20.292 ESP|ID(6890) mod_sip_ua.c(857) ua_handle_invite(): INVITE request with body: <?xml version="1.0"?> |
|
2 |
<RequestSystemStatus xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><extensions> |
|
3 |
<privateData><private><lcs:line xmlns:lcs="http://schemas.microsoft.com/Lcs/2005/04/RCCExtension">tel:4736;phone-context=dialstring</lcs:line></private></privateData></extensions></RequestSystemStatus> |
|
4 |
10/13/2008 16:27:20.292 ESP|ID(6890) mod_sip_ctigw.c(5059) ctigw_csta_handler:: Get CSTA request: 0 |
|
5 |
PID(6890) mod_sip_ctigw.c(5109) ctigw_csta_handler:: Get CSTA request: 0-RequestSystemStatus |
|
6 |
10/13/2008 16:27:20.292 ESP|ID(6890) mod_sip_ctigw.c(5125) ctigw_csta_handler:: line=4736, device=, partition=, name= |
|
7 |
10/13/2008 16:27:20.292 ESP|ID(6890) mod_sip_ctigw.c(1444) Authorizing user (mmcgillen mmcgillen@pointbridge.com) DN (4736) DEV () |
|
8 |
10/13/2008 16:27:20.446 ESP|Mon Oct 13 16:27:20 2008] PID(6890) mod_sip_ctigw.c(1153) CTIGW DB query : execute procedure spValDevice(‘mmcgillen’, ‘mmcgillen@pointbridge.com’, ‘4736‘, NULL); |
|
9 |
PID(6890) mod_sip_ctigw.c(1489) Found device SEP001BD40C5162 dn:4736 part:P_1NFRANKLINInternal1 model:115 prot:0 maxnc:4 moc:0 |
|
10 |
10/13/2008 16:27:20.446 ESP|PID(6890) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed a8136625 |
|
11 |
10/13/2008 16:27:20.449 ESP|PID(6890) mod_sip_ctigw.c(5938) ctigw_csta_handler:: EXIT |
|
12 |
10/13/2008 16:27:20.449 ESP|[Mon Oct 13 16:27:20 2008] PID(32276) sip_tcp.c(2999) sip_tcp received auth state as: 0 for connid: 2 from sip_sm |
|
13 |
PID(6890) sip_sm.c(1100) Sent 1010 bytes TCP packet to 10.x.x.x:1084 |
|
14 |
SIP/2.0 200 OK |
Case 2: Using an extension, tel:4736;phone-context=dialstring, that is correct, but specifying an incorrect user "joeblow" in the server URI
This is counter-intuitive. You’d think that the username in the Server URI would keep MOC from logging in properly… but it appears to not matter. Check it out:
|
1 |
<RequestSystemStatus xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> |
|
2 |
<extensions><privateData><private><lcs:line xmlns:lcs="http://schemas.microsoft.com/Lcs/2005/04/RCCExtension">tel:4736;phone-context=dialstring</lcs:line></private></privateData></extensions></RequestSystemStatus> |
|
3 |
10/14/2008 10:06:13.290 ESP|ID(7483) sip_sm.c(1100) Sent 407 bytes TCP packet to 10.x.x.x:4451 |
|
4 |
SIP/2.0 100 Trying |
|
5 |
Call-ID: 7ea55e371e734ae28d4dc58530e2ef52 |
|
6 |
From: "Matthew McGillen"<sip:mmcgillen@pointbridge.com>;tag=48f9e7f4d7;epid=89c0e405a5 |
|
7 |
To: <sip:joeblow@CUPSserver.domain.local> |
|
8 |
CSeq: 1 INVITE |
|
9 |
Content-Length: 0 |
|
10 |
PID(7483) sip_sm.c(910) TCB_PROXIED_STATE_TIMER : 60 seconds. |
|
11 |
10/14/2008 10:06:13.291 ESP|ID(7483) mod_sip_ua.c(1676) sip_ua_handler(): scb not found for this request |
|
12 |
10/14/2008 10:06:13.291 ESP|ID(7483) mod_sip_ua.c(857) ua_handle_invite(): INVITE request with body: <?xml version="1.0"?> |
|
13 |
<RequestSystemStatus xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"> |
|
14 |
<extensions><privateData><private><lcs:line xmlns:lcs="http://schemas.microsoft.com/Lcs/2005/04/RCCExtension">tel:4736;phone-context=dialstring</lcs:line></private></privateData></extensions></RequestSystemStatus> |
|
15 |
10/14/2008 10:06:13.291 ESP|ID(7483) mod_sip_ctigw.c(4958) ctigw_csta_handler:: Session Time in config =1810 |
|
16 |
10/14/2008 10:06:13.305 ESP|PID(7483) mod_sip_ctigw.c(5059) ctigw_csta_handler:: Get CSTA request: 0 |
|
17 |
10/14/2008 10:06:13.305 ESP|[Tue Oct 14 10:06:13 2008] PID(7483) sip_sm.c(995) timer_ticks: id:a80a69cc 20 1810 |
|
18 |
PID(7483) mod_sip_ctigw.c(5109) ctigw_csta_handler:: Get CSTA request: 0-RequestSystemStatus |
|
19 |
10/14/2008 10:06:13.403 ESP|PID(7483) mod_sip_ctigw.c(5125) ctigw_csta_handler:: line=4736, device=, partition=, name= |
|
20 |
10/14/2008 10:06:13.403 ESP|PID(7483) mod_sip_ctigw.c(1444) Authorizing user (mmcgillen mmcgillen@pointbridge.com) DN (4736) DEV () |
|
21 |
10/14/2008 10:06:13.481 ESP|Tue Oct 14 10:06:13 2008] PID(7483) mod_sip_ctigw.c(1153) CTIGW DB query : execute procedure spValDevice(‘mmcgillen’, ‘mmcgillen@pointbridge.com’, ‘4736’, NULL); |
|
22 |
PID(32276) prefork.c(1511) Perf Idle server maint 7:0:5:7 |
|
23 |
10/14/2008 10:06:13.481 ESP|PID(32276) prefork.c(1515) server stats: 5 http: 0 udp: 0 time: 19 ptime: 0 tevents: 0 ipc: 1 ievents: 1 |
|
24 |
10/14/2008 10:06:13.578 ESP|PID(7483) mod_sip_ctigw.c(1489) Found device SEP001BD40C5162 dn:4736 part:P_1NFRANKLINInternal1 model:115 prot:0 maxnc:4 moc:0 |
|
25 |
10/14/2008 10:06:13.578 ESP|PID(7483) mod_sip_ua.c(208) ua_get_map_key(): SCB get failed a8136625 |
|
26 |
SIP/2.0 200 OK |
It works! CUPS ignores what’s in the server URI to the left of the @ sign. It just takes your MOC SIP login and uses that to match you up to the name in the CCM and CUPS directories. Strange but true.
