[OpenSIPS-Users] NOTIFY results in SIP/2.0 400 Missing or invalid message-summary

[Alexander N. Lunev]

Hello everyone!

We're using some Chinese GPON CPE with SIP clients in it with OpenSIPS 
2.2.3 as a server, and we're having a problem: after successful REGISTER 
client starts SUBSCRIBE process for event "message-summary", and after 
authorization pass, server sends NOTIFY with "Event: message-summary", 
but client respond to it with "SIP/2.0 400 Missing or invalid 
message-summary" message.

On which side is error?

In opensips.cfg SUBSCRIBE is handling by this part:


if (is_method("SUBSCRIBE") {
	route(handle_presence);
}
....

route[handle_presence]
{
     if (!t_newtran())
     {
         sl_reply_error();
         exit;
     }

     if(is_method("PUBLISH"))
     {
         handle_publish();
     }
     else
     if( is_method("SUBSCRIBE"))
     {
         handle_subscribe();
     }

     exit;
}

Here's dump of SIP messages:

 >>>>> Sent 605 bytes from 10.21.1.2:5060 to 0.0.0.0:0 >>>>>	
At time Tue Sep 12 14:28:42 2017  	
SUBSCRIBE sip:657001 at 10.21.0.2:5060 SIP/2.0  	
Accept: application/simple-message-summary  	
Via: SIP/2.0/UDP 10.21.1.2;branch=z9hG4bK662336e9e06246136  	
Max-Forwards: 70  	
From: 657001 <sip:657001 at 10.21.0.2>;tag=43c0b60eec  	
To: 657001 <sip:657001 at 10.21.0.2>  	
Call-ID: 1e990b4daf3ddeb6  	
CSeq: 1092576850 SUBSCRIBE  	
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, UPDATE,  	
PRACK, INFO  	
Contact: 657001 <sip:657001 at 10.21.1.2:5060;transport=udp>  	
Event: message-summary  	
Expires: 86400  	
P-Preferred-Identity: 657001 <sip:657001 at 10.21.0.2>  	
Privacy: none  	
User-Agent: ZNID (S3.0.738)  	
Content-Length: 0  	
	
	<<<<< Recv 455 bytes from 10.21.0.2:5060 to 10.21.1.2:5060 <<<<<
	At time Tue Sep 12 14:28:42 2017
	SIP/2.0 407 Proxy Authentication Required
	Via: SIP/2.0/UDP 10.21.1.2;branch=z9hG4bK662336e9e06246136
	From: 657001 <sip:657001 at 10.21.0.2>;tag=43c0b60eec
	To: 657001 <sip:657001 at 10.21.0.2>;tag=cb7e6cbdb3294d3b7deb218a
	8feda606.aa32
	Call-ID: 1e990b4daf3ddeb6
	CSeq: 1092576850 SUBSCRIBE
	Proxy-Authenticate: Digest realm="10.21.0.2", nonce="59b7c50a0000
	00b6a8b943071cdddea6b36dbd77ce0f8f50"
	Server: OpenSIPS (2.2.3 (x86_64/freebsd))
	Content-Length: 0
	
 >>>>> Sent 750 bytes from 10.21.1.2:5060 to 0.0.0.0:0 >>>>>	
At time Tue Sep 12 14:28:42 2017  	
SUBSCRIBE sip:657001 at 10.21.0.2:5060 SIP/2.0  	
Accept: application/simple-message-summary  	
Via: SIP/2.0/UDP 10.21.1.2;branch=z9hG4bK2b0addd013eb79b20  	
Proxy-Authorization: Digest username="657001",realm="10.21.0.2",n  	
once="59b7c50a000000b6a8b943071cdddea6b36dbd77ce0f8f50",uri="sip:  	
657001 at 10.21.0.2:5060",response="94ab0651c654a01e3e4cc0dbfcf8f245  	
"  	
Max-Forwards: 70  	
From: 657001 <sip:657001 at 10.21.0.2>;tag=43c0b60eec  	
To: 657001 <sip:657001 at 10.21.0.2>  	
Call-ID: 1e990b4daf3ddeb6  	
CSeq: 1092576851 SUBSCRIBE  	
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, UPDATE,  	
PRACK, INFO  	
Contact: 657001 <sip:657001 at 10.21.1.2:5060;transport=udp>  	
Event: message-summary  	
Expires: 86400  	
Privacy: none  	
User-Agent: ZNID (S3.0.738)  	
Content-Length: 0  	
	
	<<<<< Recv 369 bytes from 10.21.0.2:5060 to 10.21.1.2:5060 <<<<<
	At time Tue Sep 12 14:28:42 2017
	SIP/2.0 200 OK
	Via: SIP/2.0/UDP 10.21.1.2;branch=z9hG4bK2b0addd013eb79b20
	From: 657001 <sip:657001 at 10.21.0.2>;tag=43c0b60eec
	To: 657001 <sip:657001 at 10.21.0.2>;tag=0aed321d9ce27cab43e7bf96
	f48bb735-64c7
	Call-ID: 1e990b4daf3ddeb6
	CSeq: 1092576851 SUBSCRIBE
	Expires: 600
	Contact: <sip:10.21.0.2:5060>
	Server: OpenSIPS (2.2.3 (x86_64/freebsd))
	Content-Length: 0
	
	<<<<< Recv 458 bytes from 10.21.0.2:5060 to 10.21.1.2:5060 <<<<<
	At time Tue Sep 12 14:28:42 2017
	NOTIFY sip:657001 at 10.21.1.2:5060;transport=udp SIP/2.0
	Via: SIP/2.0/UDP 10.21.0.2:5060;branch=z9hG4bKc327.b06d2882.0
	To: <sip:657001 at 10.21.0.2>;tag=43c0b60eec
	From: <sip:657001 at 10.21.0.2>;tag=0aed321d9ce27cab43e7bf96f48bb
	735-64c7
	CSeq: 1 NOTIFY
	Call-ID: 1e990b4daf3ddeb6
	Max-Forwards: 70
	Content-Length: 0
	User-Agent: OpenSIPS (2.2.3 (x86_64/freebsd))
	Event: message-summary
	Contact: <sip:10.21.0.2:5060>
	Subscription-State: active;expires=600
	
 >>>>> Sent 384 bytes from 10.21.1.2:5060 to 0.0.0.0:0 >>>>>	
At time Tue Sep 12 14:28:42 2017  	
SIP/2.0 400 Missing or invalid message-summary  	
Via: SIP/2.0/UDP 10.21.0.2:5060;branch=z9hG4bKc327.b06d2882.0  	
From: <sip:657001 at 10.21.0.2>;tag=0aed321d9ce27cab43e7bf96f48bb  	
735-64c7  	
To: <sip:657001 at 10.21.0.2>;tag=43c0b60eec  	
Call-ID: 1e990b4daf3ddeb6  	
CSeq: 1 NOTIFY  	
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, OPTIONS, UPDATE,  	
PRACK, INFO  	
Privacy: none  	
Content-Length: 0  	


.... after 10 minutes client receive from OpenSIPS:

	<<<<< Recv 465 bytes from 10.21.0.2:5060 to 10.21.1.2:5060 <<<<<
	At time Tue Sep 12 14:40:04 2017
	NOTIFY sip:657001 at 10.21.1.2:5060;transport=udp SIP/2.0
	Via: SIP/2.0/UDP 10.21.0.2:5060;branch=z9hG4bK9327.e9896c44.0
	To: <sip:657001 at 10.21.0.2>;tag=43c0b60eec
	From: <sip:657001 at 10.21.0.2>;tag=0aed321d9ce27cab43e7bf96f48bb
	735-64c7
	CSeq: 2 NOTIFY
	Call-ID: 1e990b4daf3ddeb6
	Max-Forwards: 70
	Content-Length: 0
	User-Agent: OpenSIPS (2.2.3 (x86_64/freebsd))
	Event: message-summary
	Contact: <sip:10.21.0.2:5060>
	Subscription-State: terminated;reason=timeout


  - and after this SIP client stop working, and need to be reload manually.


-- 
best regards
Alexander Lunev