[asterisk-bugs] [Asterisk 0013996]: [patch] Presence subscription on Cisco SIP phone needs special Cisco-styled XML

Sun Sep 20 04:21:19 CDT 2009

A NOTE has been added to this issue. 
====================================================================== 
https://issues.asterisk.org/view.php?id=13996 
====================================================================== 
Reported By:                Nugget
Assigned To:                
====================================================================== 
Project:                    Asterisk
Issue ID:                   13996
Category:                   Channels/chan_sip/NewFeature
Reproducibility:            always
Severity:                   feature
Priority:                   normal
Status:                     confirmed
Target Version:             1.6.x Version Tracker
Asterisk Version:           SVN 
JIRA:                        
Regression:                 No 
SVN Branch (only for SVN checkouts, not tarball releases):  trunk 
SVN Revision (number only!):  
Request Review:              
====================================================================== 
Date Submitted:             2008-11-30 15:13 CST
Last Modified:              2009-09-20 04:21 CDT
====================================================================== 
Summary:                    [patch] Presence subscription on Cisco SIP phone
needs special Cisco-styled XML
Description: 
Cisco phones appear to be unable to parse the existing PIDF XML being
generated by Asterisk for presence notification.  I've attached a patch
which produces well-formed (but incomplete) XML which will satisfy a Cisco
phone.  The patch as supplied will successfully render a "busy"
subscription, but does not send a subsequent "available" notification, so
presence detection only half works currently.

I suspect the next step might be to watch some CallManager SIP traffic to
identify precisely what XML tags the phone is expecting in order to
properly parse an available subscription, but I'm not in a position to do
that.  I'll continue to work with this, though, and perhaps may be able to
stumble upon the precise data the Cisco phone is looking for.
====================================================================== 

---------------------------------------------------------------------- 
 (0111045) Reinhard (reporter) - 2009-09-20 04:21
 https://issues.asterisk.org/view.php?id=13996#c111045 
---------------------------------------------------------------------- 
Maybe this is important!
Here`s the log of Asterisk and the Cisco:

Asterisk:
[Sep 19 04:02:01] VERBOSE[3596] logger.c: == Parsing
'/etc/asterisk/logger.conf': [Sep 19 04:02:01] VERBOSE[3596] logger.c: ==
Found
[Sep 19 04:02:01] VERBOSE[3596] logger.c: Asterisk Event Logger restarted
[Sep 19 04:02:01] VERBOSE[3596] logger.c: Asterisk Queue Logger restarted
[Sep 19 04:02:02] VERBOSE[3596] logger.c: -- Remote UNIX connection
disconnected
[Sep 19 04:57:23] VERBOSE[3499] logger.c: -- Incoming call: Got SIP
response 500 "Internal Server Error" back from 192.168.0.213
[Sep 19 04:57:23] VERBOSE[3499] logger.c: -- Incoming call: Got SIP
response 500 "Internal Server Error" back from 192.168.0.213
[Sep 19 04:57:23] VERBOSE[3499] logger.c: -- Incoming call: Got SIP
response 500 "Internal Server Error" back from 192.168.0.213
[Sep 19 04:57:23] VERBOSE[3499] logger.c: -- Incoming call: Got SIP
response 500 "Internal Server Error" back from 192.168.0.213
[Sep 19 04:57:55] NOTICE[3277] chan_sip.c: Activity `-1` is not
implemented in CISCO_PIDF_XML
[Sep 19 04:57:55] NOTICE[3277] chan_sip.c: Activity `-1` is not
implemented in CISCO_PIDF_XML
[Sep 19 04:57:55] NOTICE[3277] chan_sip.c: Activity `-1` is not
implemented in CISCO_PIDF_XML
[Sep 19 04:57:55] NOTICE[3277] chan_sip.c: Activity `-1` is not
implemented in CISCO_PIDF_XML 

Cisco 7975:
=== Syslogd === Sat Sep 19 04:57:23 2009
====================================================
ERR 04:57:23.395702 JVM: sip_subsManager_send_protocol_error: Protocol
Error for scb=7 sub_id=770007
ERR 04:57:23.399385 JVM: decode_message_body: Error: Unable to get memory
ERR 04:57:23.401214 JVM: sip_subsManager_send_protocol_error: Protocol
Error for scb=8 sub_id=560008
ERR 04:57:23.434095 JVM: subsmanager_handle_ev_app_subscribe: Error:
Reached limit on Presence SCBs!
ERR 04:57:23.434518 JVM: SIPTaskProcessListEvent: Error:
subsmanager_handle_ev_app_subscribe() returned error.
ERR 04:57:23.437456 JVM: subsmanager_handle_ev_app_subscribe: Error:
Reached limit on Presence SCBs!
ERR 04:57:23.437873 JVM: SIPTaskProcessListEvent: Error:
subsmanager_handle_ev_app_subscribe() returned error.
ERR 04:57:23.442630 JVM: subsmanager_handle_ev_sip_response: Error: No
matching request found
ERR 04:57:23.443486 JVM: subsmanager_handle_ev_sip_response: Error: No
matching request found
ERR 04:57:23.443927 JVM: subsmanager_handle_ev_app_subscribe: Error:
Reached limit on Presence SCBs!
ERR 04:57:23.444281 JVM: SIPTaskProcessListEvent: Error:
subsmanager_handle_ev_app_subscribe() returned error.
ERR 04:57:23.447829 JVM: subsmanager_handle_ev_sip_response: Error: No
matching request found
ERR 04:57:23.469758 JVM: subsmanager_handle_ev_sip_response: Error: No
matching request found
ERR 04:57:23.470291 JVM: subsmanager_handle_ev_app_subscribe: Error:
Reached limit on Presence SCBs!
ERR 04:57:23.470648 JVM: SIPTaskProcessListEvent: Error:
subsmanager_handle_ev_app_subscribe() returned error.
ERR 04:57:23.470994 JVM:
subsmanager_handle_ev_app_subscription_terminated: no SCB for
sub_id=ffffffff or request id 34 and eventPackage 5 found
ERR 04:57:23.471201 JVM: SIPTaskProcessListEvent: Error:
subsmanager_handle_ev_app_subscription_terminated() returned error.
ERR 04:57:23.522477 JVM:
subsmanager_handle_ev_app_subscription_terminated: no SCB for
sub_id=ffffffff or request id 32 and eventPackage 5 found
ERR 04:57:23.523589 JVM: SIPTaskProcessListEvent: Error:
subsmanager_handle_ev_app_subscription_terminated() returned error.
ERR 04:57:23.524601 JVM:
subsmanager_handle_ev_app_subscription_terminated: no SCB for
sub_id=ffffffff or request id 36 and eventPackage 5 found
ERR 04:57:23.525440 JVM: SIPTaskProcessListEvent: Error:
subsmanager_handle_ev_app_subscription_terminated() returned error.
ERR 04:57:23.526453 JVM:
subsmanager_handle_ev_app_subscription_terminated: no SCB for
sub_id=ffffffff or request id 35 and eventPackage 5 found
ERR 04:57:23.527536 JVM: SIPTaskProcessListEvent: Error:
subsmanager_handle_ev_app_subscription_terminated() returned error.
ERR 04:57:55.440907 JVM: subsmanager_handle_ev_sip_subscribe_notify: No
prior subscription
ERR 04:57:55.446688 JVM: subsmanager_handle_ev_sip_subscribe_notify: No
prior subscription
ERR 04:57:55.451616 JVM: subsmanager_handle_ev_sip_subscribe_notify: No
prior subscription
ERR 04:57:55.455711 JVM: subsmanager_handle_ev_sip_subscribe_notify: No
prior subscription 

Issue History 
Date Modified    Username       Field                    Change               
====================================================================== 
2009-09-20 04:21 Reinhard       Note Added: 0111045                          
======================================================================