I am seeing some interesting stuff in the sip status log relating to expired nonces:
"2011-09-20T18:14:38.106745Z":309204:INCOMING:INFO:choicevoip.ev.ithaca.ny.us:SipClientTcp-18:41FBC940:SipStatus:"Read SIP message:\n----Local Host:192.168.48.2---- Port: 5060----\n----Remote Host:192.168.48.2---- Port: 43072----\nSUBSCRIBE sip:[email protected] SIP/2.0\r\nRecord-Route: <sip:192.168.48.2:5060;lr>\r\nRoute: <sip:192.168.48.2:5110;transport=tcp;lr>\r\nVia: SIP/2.0/TCP 192.168.48.2;branch=z9hG4bK-XX-3aa2rXY1NAXi44jS6BEy0QWc`g\r\nVia: SIP/2.0/UDP 192.168.51.254;branch=z9hG4bKd8af82ceC52A3D09\r\nFrom: \"Gilmore Jeffrey\" <sip:[email protected]>;tag=4DC87961-BDA2F56C\r\nTo: <sip:[email protected]>;tag=7kRvqy\r\nCseq: 7 SUBSCRIBE\r\nCall-Id: [email protected]\r\nContact: <sip:[email protected];x-sipX-nonat>\r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nEvent: message-summary\r\nUser-Agent: PolycomSoundPointIP-SPIP_320-UA/3.2.4.0267\r\nAccept-Language: en\r\nAccept: application/si mple-message-summary\r\nAuthorization: Digest username=\"101/0004f2223a3e\", realm=\"ev.ithaca.ny.us\", nonce=\"33723d0ac1e8ac03d7d2e5dae504fe4c4e78d395\", qop=auth, cnonce=\"OydGmnmp8O0bAGz\", nc=00000002, uri=\"sip:[email protected]\", response=\"bcfd2b6e57f1ac8e28f966e89e6c8116\", algorithm=MD5\r\nMax-Forwards: 20\r\nExpires: 0\r\nContent-Length: 0\r\nDate: Tue, 20 Sep 2011 18:14:38 GMT\r\n\r\n====================END====================" "2011-09-20T18:14:38.108055Z":309205:SIP:INFO:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"SipNonceDB::isNonceValid expired nonce '33723d0ac1e8ac03d7d2e5dae504fe4c4e78d395': created 1316541333+300 < 1316542478" "2011-09-20T18:14:38.108100Z":309206:AUTH:INFO:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"SubscribeServerThread::isAuthenticated() Invalid NONCE: '33723d0ac1e8ac03d7d2e5dae504fe4c4e78d395' found for mailboxUrl 'sip:[email protected]' realm: 'ev.ithaca.ny.us' user: '101/0004f2223a3e' cnonce: 'OydGmnmp8O0bAGz' nc: '00000002' qop: 'auth' expiration: 300" "2011-09-20T18:14:38.108908Z":309207:OUTGOING:INFO:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"SipUserAgent::sendTcp TCP SIP User Agent sent message:\n----Local Host:192.168.48.2---- Port: -1----\n----Remote Host:192.168.48.2---- Port: 5060----\nSIP/2.0 401 Unauthorized\r\nFrom: \"Gilmore Jeffrey\" <sip:[email protected]>;tag=4DC87961-BDA2F56C\r\nTo: <sip:[email protected]>;tag=7kRvqy\r\nCall-Id: [email protected]\r\nCseq: 7 SUBSCRIBE\r\nVia: SIP/2.0/TCP 192.168.48.2;branch=z9hG4bK-XX-3aa2rXY1NAXi44jS6BEy0QWc`g\r\nVia: SIP/2.0/UDP 192.168.51.254;branch=z9hG4bKd8af82ceC52A3D09\r\nRecord-Route: <sip:192.168.48.2:5060;lr>\r\nWww-Authenticate: Digest realm=\"ev.ithaca.ny.us\", nonce=\"59839a52e5373ae2ad99e305322372034e78d80e\", qop=\"auth\"\r\nUser-Agent: sipXecs/4.4.0 sipXecs/publisher (Linux)\r\nDate: Tue, 20 Sep 2011 18:14:38 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE\r\nAccept-Language: en\r\n Supported: sip-cc-01, timer\r\nContent-Length: 0\r\n\r\n--------------------END--------------------" "2011-09-20T18:14:38.134527Z":309208:INCOMING:INFO:choicevoip.ev.ithaca.ny.us:SipClientTcp-18:41FBC940:SipStatus:"Read SIP message:\n----Local Host:192.168.48.2---- Port: 5060----\n----Remote Host:192.168.48.2---- Port: 43072----\nSUBSCRIBE sip:[email protected] SIP/2.0\r\nRecord-Route: <sip:192.168.48.2:5060;lr>\r\nRoute: <sip:192.168.48.2:5110;transport=tcp;lr>\r\nVia: SIP/2.0/TCP 192.168.48.2;branch=z9hG4bK-XX-3aa6IO2TwwKRH0rHkAEkevtuVw\r\nVia: SIP/2.0/UDP 192.168.51.254;branch=z9hG4bK9bc5c39aE339D15\r\nFrom: \"Gilmore Jeffrey\" <sip:[email protected]>;tag=4DC87961-BDA2F56C\r\nTo: <sip:[email protected]>;tag=7kRvqy\r\nCseq: 8 SUBSCRIBE\r\nCall-Id: [email protected]\r\nContact: <sip:[email protected];x-sipX-nonat>\r\nAllow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER\r\nEvent: message-summary\r\nUser-Agent: PolycomSoundPointIP-SPIP_320-UA/3.2.4.0267\r\nAccept-Language: en\r\nAccept: application/sim ple-message-summary\r\nAuthorization: Digest username=\"101/0004f2223a3e\", realm=\"ev.ithaca.ny.us\", nonce=\"59839a52e5373ae2ad99e305322372034e78d80e\", qop=auth, cnonce=\"D2+UCsmEqxENiqn\", nc=00000001, uri=\"sip:[email protected]\", response=\"b448b1e9460934d55d9b84a8d19b43fb\", algorithm=MD5\r\nMax-Forwards: 20\r\nExpires: 0\r\nContent-Length: 0\r\nDate: Tue, 20 Sep 2011 18:14:38 GMT\r\n\r\n====================END====================" "2011-09-20T18:14:38.137279Z":309209:KERNEL:INFO:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"OsConnectionSocket::_[5] connected 20 to localhost:8085" "2011-09-20T18:14:38.138950Z":309210:KERNEL:INFO:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"OsConnectionSocket::read[4] error or EOF on fd 20, errno = 115 Operation now in progress" "2011-09-20T18:14:38.139060Z":309211:HTTP:ERR:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"HttpMessage::get[4] Receiving failed on persistent connection on try 0" "2011-09-20T18:14:38.139092Z":309212:KERNEL:INFO:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"OsConnectionSocket::~" "2011-09-20T18:14:38.139337Z":309213:KERNEL:INFO:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"OsConnectionSocket::_[5] connected 20 to localhost:8085" "2011-09-20T18:14:38.140722Z":309214:KERNEL:INFO:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"OsConnectionSocket::read[4] error or EOF on fd 20, errno = 115 Operation now in progress" "2011-09-20T18:14:38.140839Z":309215:HTTP:ERR:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"HttpMessage::get[4] Receiving failed on persistent connection on try 1" "2011-09-20T18:14:38.140868Z":309216:KERNEL:INFO:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"OsConnectionSocket::~" "2011-09-20T18:14:38.140943Z":309217:SIP:WARNING:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"MwiPlugin::handleSubscribeRequest() - voicemailCGI GET failed with -1." "2011-09-20T18:14:38.142019Z":309218:OUTGOING:INFO:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"SipUserAgent::sendTcp TCP SIP User Agent sent message:\n----Local Host:192.168.48.2---- Port: -1----\n----Remote Host:192.168.48.2---- Port: 5060----\nSIP/2.0 202 Accepted\r\nFrom: \"Gilmore Jeffrey\" <sip:[email protected]>;tag=4DC87961-BDA2F56C\r\nTo: <sip:[email protected]>;tag=7kRvqy\r\nCall-Id: [email protected]\r\nCseq: 8 SUBSCRIBE\r\nVia: SIP/2.0/TCP 192.168.48.2;branch=z9hG4bK-XX-3aa6IO2TwwKRH0rHkAEkevtuVw\r\nVia: SIP/2.0/UDP 192.168.51.254;branch=z9hG4bK9bc5c39aE339D15\r\nRecord-Route: <sip:192.168.48.2:5060;lr>\r\nExpires: 652\r\nContact: sip:[email protected]\r\nUser-Agent: sipXecs/4.4.0 sipXecs/publisher (Linux)\r\nDate: Tue, 20 Sep 2011 18:14:38 GMT\r\nAllow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE\r\nAccept-Language: en\r\nSupported: sip-cc-01, timer\r\nContent-Length: 0\r\n\r\n----------------- ---END--------------------" "2011-09-20T18:14:42.347643Z":309220:SIP:ERR:choicevoip.ev.ithaca.ny.us:SubscribeServerThread:41DBA940:SipStatus:"SipNonceDB::isNonceValid nonce signature check failed 'd91927a5eaf2f835124b3a991832acb74e78d7f4'" On Sep 20, 2011, at 3:16 PM, George Niculae wrote: > On Tue, Sep 20, 2011 at 10:02 PM, Jeff Gilmore <[email protected]> wrote: >> MWI was previously working great on my system, but starting (I think) with >> an update to 4.4 a week ago, I have stopped seeing MWI, at least for some >> users. >> > > Would be great if you could tell us what version you were on and what > is the current one > > Thanks, > George > _______________________________________________ > sipx-users mailing list > [email protected] > List Archive: http://list.sipfoundry.org/archive/sipx-users/ _______________________________________________ sipx-users mailing list [email protected] List Archive: http://list.sipfoundry.org/archive/sipx-users/
