Hi all,
I don't know how big of a problem this is, or if it is related to anything
else...
I've got a dedicated box spewing "Connection refused" errors and generating
repeating errors every seven seconds or so, creating 50MB+ log files per
day.
A VM I made is spewing "404" errors every six seconds, and a log file nearly
triple the size of the other machine.
I've attached samples of the logs - perhaps someone can tell me if this is a
big deal beyond the obvious, and if there is a fix.
The only difference in the installs is that the one spewing "404" errors was
set to resolve DNS itself - the dedicated box has my gateway resolving DNS.
Otherwise, they're both fresh installs with little (dedicated box) or no
(the VM) configuration post-install.
Any thoughts???
Main SIP Machine
"2011-05-16T18:37:42.827000Z":366517:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:Subscriber:"subscribeDialogCreator
timer fired"
"2011-05-16T18:37:42.827000Z":366518:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:SubscribeDialog:"[SubscribeDialog
FSM:org.sipfoundry.sipcallwatcher.SubscribeDialog.SubscribeDialog@2d85af06
null] Incoming event 'start'"
"2011-05-16T18:37:42.832000Z":366519:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:sipxcallwatcher:"[SIPClientTransaction.java:304][SIPTransactionStack.java:1543][SIPTransactionStack.java:1521][SipProviderImpl.java:397][Subscriber.java:297][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T18:37:42.833000Z":366520:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:sipxcallwatcher:"[SIPDialog.java:1786][SIPDialog.java:1554][SIPDialog.java:596][SIPTransactionStack.java:710][SipProviderImpl.java:415][Subscriber.java:297][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T18:37:42.833000Z":366521:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:sipxcallwatcher:"[SIPDialog.java:1598][SIPDialog.java:596][SIPTransactionStack.java:710][SipProviderImpl.java:415][Subscriber.java:297][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T18:37:42.833000Z":366522:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:sipxcallwatcher:"[SIPDialog.java:601][SIPTransactionStack.java:710][SipProviderImpl.java:415][Subscriber.java:297][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T18:37:42.834000Z":366523:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:sipxcallwatcher:"[SIPTransaction.java:556][SIPClientTransaction.java:1301][SIPClientTransaction.java:462][SIPClientTransaction.java:968][Subscriber.java:300][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T18:37:42.835000Z":366524:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:sipxcallwatcher:"[SIPTransaction.java:556][SIPClientTransaction.java:1301][SIPClientTransaction.java:480][SIPClientTransaction.java:968][Subscriber.java:300][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T18:37:42.836000Z":366525:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:sipxcallwatcher:"[SIPTransaction.java:556][SIPClientTransaction.java:1301][SIPClientTransaction.java:971][Subscriber.java:300][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T18:37:42.836000Z":366526:JAVA:ERR:sip.mycompany.com:Timer-23647:00000000:Subscriber:"Error
sending Subscribe -- tearing down dialog"
"2011-05-16T18:37:42.836000Z":366527:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:sipxcallwatcher:"[SIPDialog.java:1233][SIPDialog.java:1801][Subscriber.java:304][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T18:37:42.837000Z":366528:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:WaitingForStartState:"WaitingForStartState::start
caught exception: "
org.sipfoundry.sipcallwatcher.SubscribeDialog.SubscribeDialogStateException:
org.sipfoundry.sipcallwatcher.CallWatcherException: javax.sip.SipException:
Connection refused
at
org.sipfoundry.sipcallwatcher.SubscribeDialog.SubscribeDialog.sendDialogFormingSubscribe(SubscribeDialog.java:178)
at
org.sipfoundry.sipcallwatcher.SubscribeDialog.WaitingForStartState.start(WaitingForStartState.java:19)
at
org.sipfoundry.sipcallwatcher.SubscribeDialog.SubscribeDialog.start(SubscribeDialog.java:59)
at org.sipfoundry.sipcallwatcher.Subscriber$1.run(Subscriber.java:402)
at java.util.TimerThread.mainLoop(Timer.java:534)
at java.util.TimerThread.run(Timer.java:484)
Caused by: org.sipfoundry.sipcallwatcher.CallWatcherException:
javax.sip.SipException: Connection refused
at
org.sipfoundry.sipcallwatcher.Subscriber.sendDialogFormingSubscribe(Subscriber.java:306)
at
org.sipfoundry.sipcallwatcher.SubscribeDialog.SubscribeDialog.sendDialogFormingSubscribe(SubscribeDialog.java:173)
... 5 more
Caused by: javax.sip.SipException: Connection refused
at
gov.nist.javax.sip.stack.SIPClientTransaction.sendRequest(SIPClientTransaction.java:972)
at
org.sipfoundry.sipcallwatcher.Subscriber.sendDialogFormingSubscribe(Subscriber.java:300)
... 6 more
Caused by: java.net.ConnectException: Connection refused
at java.net.PlainSocketImpl.socketConnect(Native Method)
at
java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:327)
at
java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:193)
at
java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:180)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:384)
at java.net.Socket.connect(Socket.java:546)
at java.net.Socket.connect(Socket.java:495)
at java.net.Socket.<init>(Socket.java:392)
at java.net.Socket.<init>(Socket.java:293)
at
gov.nist.core.net.DefaultNetworkLayer.createSocket(DefaultNetworkLayer.java:120)
at gov.nist.javax.sip.stack.IOHandler.sendBytes(IOHandler.java:224)
at
gov.nist.javax.sip.stack.TCPMessageChannel.sendMessage(TCPMessageChannel.java:311)
at
gov.nist.javax.sip.stack.MessageChannel.sendMessage(MessageChannel.java:255)
at
gov.nist.javax.sip.stack.SIPTransaction.sendMessage(SIPTransaction.java:745)
at
gov.nist.javax.sip.stack.SIPClientTransaction.sendMessage(SIPClientTransaction.java:476)
at
gov.nist.javax.sip.stack.SIPClientTransaction.sendRequest(SIPClientTransaction.java:968)
... 7 more
"2011-05-16T18:37:42.837000Z":366529:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:SubscribeDialog:"[SubscribeDialog
FSM:null] Transitioning from
org.sipfoundry.sipcallwatcher.SubscribeDialog.WaitingForStartState@6c41f5a7 to
org.sipfoundry.sipcallwatcher.SubscribeDialog.Moribund@5e66de77"
"2011-05-16T18:37:42.838000Z":366530:JAVA:INFO:sip.mycompany.com:Timer-23647:00000000:Subscriber:"arming
subscribeDialogCreator"
"2011-05-16T18:37:43.337000Z":366531:JAVA:INFO:sip.mycompany.com:Timer-2:00000000:sipxcallwatcher:"[SipProviderImpl.java:182][SIPTransactionStack.java:1673][SIPClientTransaction.java:231][SIPStackTimerTask.java:29][Timer.java:534][Timer.java:484]"
Second SIP Test Machine (VM)
"2011-05-16T20:15:24.072000Z":711477:JAVA:INFO:sip1.mycompany.com:Timer-2:00000000:sipxcallwatcher:"[SipProviderImpl.java:182][SIPTransactionStack.java:805][SIPDialog.java:389][SIPStackTimerTask.java:29][Timer.java:534][Timer.java:484]"
"2011-05-16T20:15:24.072000Z":711478:JAVA:INFO:sip1.mycompany.com:Timer-2:00000000:SubscribeDialog:"[SubscribeDialog
FSM:org.sipfoundry.sipcallwatcher.SubscribeDialog.SubscribeDialog@233e253b
null] Incoming event 'processDialogTerminated' for dialog:
gov.nist.javax.sip.stack.SIPDialog@15e52b53"
"2011-05-16T20:15:24.072000Z":711479:JAVA:INFO:sip1.mycompany.com:Timer-2:00000000:SubscribeDialog:"org.sipfoundry.sipcallwatcher.SubscribeDialog.UnknownTransactionException:
SubscribeDialog::validateDialog: event received for foreign dialog:
gov.nist.javax.sip.stack.SIPDialog@15e52b53; was expecting null"
"2011-05-16T20:15:25.603000Z":711480:JAVA:INFO:sip1.mycompany.com:Timer-2:00000000:sipxcallwatcher:"[SipProviderImpl.java:182][SIPTransactionStack.java:805][SIPDialog.java:389][SIPStackTimerTask.java:29][Timer.java:534][Timer.java:484]"
"2011-05-16T20:15:25.603000Z":711481:JAVA:INFO:sip1.mycompany.com:Timer-2:00000000:SubscribeDialog:"[SubscribeDialog
FSM:org.sipfoundry.sipcallwatcher.SubscribeDialog.SubscribeDialog@233e253b
null] Incoming event 'processDialogTerminated' for dialog:
gov.nist.javax.sip.stack.SIPDialog@5eabd47c"
"2011-05-16T20:15:25.604000Z":711482:JAVA:INFO:sip1.mycompany.com:Timer-2:00000000:SubscribeDialog:"org.sipfoundry.sipcallwatcher.SubscribeDialog.UnknownTransactionException:
SubscribeDialog::validateDialog: event received for foreign dialog:
gov.nist.javax.sip.stack.SIPDialog@5eabd47c; was expecting null"
"2011-05-16T20:15:27.606000Z":711483:JAVA:INFO:sip1.mycompany.com:Timer-14650:00000000:Subscriber:"subscribeDialogCreator
timer fired"
"2011-05-16T20:15:27.607000Z":711484:JAVA:INFO:sip1.mycompany.com:Timer-14650:00000000:SubscribeDialog:"[SubscribeDialog
FSM:org.sipfoundry.sipcallwatcher.SubscribeDialog.SubscribeDialog@74260899
null] Incoming event 'start'"
"2011-05-16T20:15:27.609000Z":711485:JAVA:INFO:sip1.mycompany.com:Timer-14650:00000000:sipxcallwatcher:"[SIPClientTransaction.java:304][SIPTransactionStack.java:1543][SIPTransactionStack.java:1521][SipProviderImpl.java:397][Subscriber.java:297][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T20:15:27.610000Z":711486:JAVA:INFO:sip1.mycompany.com:Timer-14650:00000000:sipxcallwatcher:"[SIPDialog.java:1786][SIPDialog.java:1554][SIPDialog.java:596][SIPTransactionStack.java:710][SipProviderImpl.java:415][Subscriber.java:297][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T20:15:27.610000Z":711487:JAVA:INFO:sip1.mycompany.com:Timer-14650:00000000:sipxcallwatcher:"[SIPDialog.java:1598][SIPDialog.java:596][SIPTransactionStack.java:710][SipProviderImpl.java:415][Subscriber.java:297][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T20:15:27.610000Z":711488:JAVA:INFO:sip1.mycompany.com:Timer-14650:00000000:sipxcallwatcher:"[SIPDialog.java:601][SIPTransactionStack.java:710][SipProviderImpl.java:415][Subscriber.java:297][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T20:15:27.611000Z":711489:JAVA:INFO:sip1.mycompany.com:Timer-14650:00000000:sipxcallwatcher:"[SIPTransaction.java:556][SIPClientTransaction.java:1301][SIPClientTransaction.java:462][SIPClientTransaction.java:968][Subscriber.java:300][SubscribeDialog.java:173][WaitingForStartState.java:19][SubscribeDialog.java:59][Subscriber.java:402][Timer.java:534][Timer.java:484]"
"2011-05-16T20:15:27.611000Z":711490:OUTGOING:INFO:sip1.mycompany.com:Timer-14650:00000000:sipxcallwatcher:"Sent
SIP Message :\n----Remote Host:192.168.215.21---- Port: 5060----\nSUBSCRIBE
sip:[email protected];transport=tcp SIP/2.0\r\nCall-ID:
[email protected]\r\nCSeq: 1 SUBSCRIBE\r\nFrom:
\"Call Watcher\"
<sip:[email protected]>;tag=2877696847686569437\r\nTo:
<sip:[email protected]>\r\nVia: SIP/2.0/TCP
192.168.215.21:5064;branch=z9hG4bKa2a8b5bc39a927d28d214791dd5a8137323636\r\nMax-Forwards:
70\r\nContact: \"~~id~xmpprlsclient\"
<sip:[email protected]:5064;transport=tcp>\r\nExpires:
3600\r\nSupported: eventlist\r\nEvent: dialog\r\nAccept:
application/dialog-info+xml,application/rlmi+xml,multipart/related\r\nContent-Length:
0\r\n\r\n--------------------END--------------------\n"
"2011-05-16T20:15:27.612000Z":711491:JAVA:INFO:sip1.mycompany.com:Timer-14650:00000000:SubscribeDialog:"New
Subscribe Dialog = gov.nist.javax.sip.stack.SIPDialog@44ede82e"
"2011-05-16T20:15:27.612000Z":711492:JAVA:INFO:sip1.mycompany.com:Timer-14650:00000000:SubscribeDialog:"[SubscribeDialog
FSM:gov.nist.javax.sip.stack.SIPDialog@44ede82e] Transitioning from
org.sipfoundry.sipcallwatcher.SubscribeDialog.WaitingForStartState@5f26475c to
org.sipfoundry.sipcallwatcher.SubscribeDialog.WaitingForSubscribeResponseState@4a1719d1"
"2011-05-16T20:15:27.629000Z":711493:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPTransaction.java:1152][SIPTransactionStack.java:1460][TCPMessageChannel.java:556][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:27.629000Z":711494:INCOMING:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"Read
SIP Message:\n----Remote Host:192.168.215.21---- Port: 5060----\nSIP/2.0 401
Unauthorized\r\nRecord-Route:
<sip:192.168.215.21:5060;lr;sipXecs-rs=%2Aauth%7E.%2Afrom%7EMjg3NzY5Njg0NzY4NjU2OTQzNw%60%60%215471d648f279a01f350e0bfe0a44c83c;x-sipX-done>\r\nFrom:
\"Call Watcher\"
<sip:[email protected]>;tag=2877696847686569437\r\nTo:
<sip:[email protected]>;tag=3o4fEg\r\nCall-ID:
[email protected]\r\nCSeq: 1 SUBSCRIBE\r\nVia:
SIP/2.0/TCP
192.168.215.21:5064;branch=z9hG4bKa2a8b5bc39a927d28d214791dd5a8137323636\r\nWWW-Authenticate:
Digest
realm=\"mycompany.com\",nonce=\"451d6b1ffdcb713d72222bb87b0bfd544dd185df\",qop=\"auth\"\r\nContact:
<sip:[email protected]:5140;transport=udp>\r\nDate:
Mon, 16 May 2011 20:15:27 GMT\r\nAllow:
INVITE,ACK,CANCEL,BYE,REFER,OPTIONS,SUBSCRIBE,MESSAGE\r\nUser-Agent:
sipXecs/4.4.0 sipXecs/rls (Linux)\r\nAccept-Language: en\r\nRequire:
eventlist\r\nContent-Length:
0\r\n\r\n====================END====================\n"
"2011-05-16T20:15:27.630000Z":711495:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPDialog.java:2635][DialogFilter.java:1287][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:27.630000Z":711496:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPDialog.java:1233][SIPDialog.java:2733][DialogFilter.java:1287][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:27.630000Z":711497:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SipProviderImpl.java:182][DialogFilter.java:1291][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:27.631000Z":711498:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:SubscribeDialog:"[SubscribeDialog
FSM:org.sipfoundry.sipcallwatcher.SubscribeDialog.SubscribeDialog@74260899
gov.nist.javax.sip.stack.SIPDialog@44ede82e] Incoming event 'processResponse'"
"2011-05-16T20:15:27.631000Z":711499:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:SubscribeDialog:"Response
received on client transaction id
gov.nist.javax.sip.stack.SIPClientTransaction@5db71dc0: 401"
"2011-05-16T20:15:27.632000Z":711500:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPClientTransaction.java:304][SIPTransactionStack.java:1543][SIPTransactionStack.java:1521][SipProviderImpl.java:397][AuthenticationHelperImpl.java:216][null:-1][SubscribeDialog.java:201][WaitingForSubscribeResponseState.java:24][SubscribeDialog.java:92][Subscriber.java:145][EventScanner.java:291][SipProviderImpl.java:192][DialogFilter.java:1291][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:27.633000Z":711501:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPDialog.java:1786][SIPDialog.java:1554][SIPDialog.java:596][SIPTransactionStack.java:706][SipProviderImpl.java:415][AuthenticationHelperImpl.java:216][null:-1][SubscribeDialog.java:201][WaitingForSubscribeResponseState.java:24][SubscribeDialog.java:92][Subscriber.java:145][EventScanner.java:291][SipProviderImpl.java:192][DialogFilter.java:1291][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:27.633000Z":711502:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPDialog.java:1598][SIPDialog.java:596][SIPTransactionStack.java:706][SipProviderImpl.java:415][AuthenticationHelperImpl.java:216][null:-1][SubscribeDialog.java:201][WaitingForSubscribeResponseState.java:24][SubscribeDialog.java:92][Subscriber.java:145][EventScanner.java:291][SipProviderImpl.java:192][DialogFilter.java:1291][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:27.634000Z":711503:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPDialog.java:601][SIPTransactionStack.java:706][SipProviderImpl.java:415][AuthenticationHelperImpl.java:216][null:-1][SubscribeDialog.java:201][WaitingForSubscribeResponseState.java:24][SubscribeDialog.java:92][Subscriber.java:145][EventScanner.java:291][SipProviderImpl.java:192][DialogFilter.java:1291][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:27.635000Z":711504:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPTransaction.java:556][SIPClientTransaction.java:1301][SIPClientTransaction.java:462][SIPClientTransaction.java:968][null:-1][SubscribeDialog.java:201][WaitingForSubscribeResponseState.java:24][SubscribeDialog.java:92][Subscriber.java:145][EventScanner.java:291][SipProviderImpl.java:192][DialogFilter.java:1291][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:27.636000Z":711505:OUTGOING:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"Sent
SIP Message :\n----Remote Host:192.168.215.21---- Port: 5060----\nSUBSCRIBE
sip:[email protected]:5060;transport=tcp;maddr=192.168.215.21
SIP/2.0\r\nCall-ID: [email protected]\r\nCSeq: 2
SUBSCRIBE\r\nFrom: \"Call Watcher\"
<sip:[email protected]>;tag=2877696847686569437\r\nTo:
<sip:[email protected]>\r\nVia: SIP/2.0/TCP
192.168.215.21:5064;branch=z9hG4bKe8d86950da7b9c12494f27dc096a7085323636\r\nMax-Forwards:
70\r\nContact: \"~~id~xmpprlsclient\"
<sip:[email protected]:5064;transport=tcp>\r\nExpires:
3600\r\nSupported: eventlist\r\nEvent: dialog\r\nAccept:
application/dialog-info+xml,application/rlmi+xml,multipart/related\r\nAuthorization:
Digest
username=\"~~id~xmpprlsclient\",realm=\"mycompany.com\",nonce=\"451d6b1ffdcb713d72222bb87b0bfd544dd185df\",uri=\"sip:[email protected]:5060;transport=tcp;maddr=192.168.215.21\",response=\"7b5448b5fb77517b70b8e1c30193bd2d\",qop=auth,cnonce=\"xyz\",nc=00000001\r\nContent-Length:
0\r\n\r\n--------------------END--------------------\n"
"2011-05-16T20:15:27.636000Z":711506:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:SubscribeDialog:"[SubscribeDialog
FSM:gov.nist.javax.sip.stack.SIPDialog@5f4ff299] Transitioning from
org.sipfoundry.sipcallwatcher.SubscribeDialog.WaitingForSubscribeResponseState@4a1719d1
to
org.sipfoundry.sipcallwatcher.SubscribeDialog.WaitingForSubscribeResponseWithChallengeState@79c04bb1"
"2011-05-16T20:15:27.636000Z":711507:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPTransaction.java:1197][SIPTransaction.java:1183][EventScanner.java:365][SipProviderImpl.java:192][DialogFilter.java:1291][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:27.636000Z":711508:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPTransaction.java:556][SIPClientTransaction.java:1301][SIPClientTransaction.java:632][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:27.637000Z":711509:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPTransaction.java:1197][SIPTransaction.java:1183][TCPMessageChannel.java:574][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:28.115000Z":711510:JAVA:INFO:sip1.mycompany.com:Timer-2:00000000:sipxcallwatcher:"[SipProviderImpl.java:182][SIPTransactionStack.java:1673][SIPClientTransaction.java:231][SIPStackTimerTask.java:29][Timer.java:534][Timer.java:484]"
"2011-05-16T20:15:29.162000Z":711511:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPTransaction.java:1152][SIPTransactionStack.java:1460][TCPMessageChannel.java:556][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:29.162000Z":711512:INCOMING:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"Read
SIP Message:\n----Remote Host:192.168.215.21---- Port: 5060----\nSIP/2.0 404
Not Found\r\nRecord-Route:
<sip:192.168.215.21:5060;lr;sipXecs-rs=%2Aauth%7E.%2Afrom%7EMjg3NzY5Njg0NzY4NjU2OTQzNw%60%60%215471d648f279a01f350e0bfe0a44c83c;x-sipX-done>\r\nFrom:
\"Call Watcher\"
<sip:[email protected]>;tag=2877696847686569437\r\nTo:
<sip:[email protected]>;tag=CK2X8e\r\nCall-ID:
[email protected]\r\nCSeq: 2 SUBSCRIBE\r\nVia:
SIP/2.0/TCP
192.168.215.21:5064;branch=z9hG4bKe8d86950da7b9c12494f27dc096a7085323636\r\nExpires:
2595\r\nContact:
<sip:[email protected]:5140;transport=udp>\r\nDate: Mon,
16 May 2011 20:15:29 GMT\r\nAllow:
INVITE,ACK,CANCEL,BYE,REFER,OPTIONS,SUBSCRIBE,MESSAGE\r\nUser-Agent:
sipXecs/4.4.0 sipXecs/rls (Linux)\r\nAccept-Language: en\r\nRequire:
eventlist\r\nContent-Length:
0\r\n\r\n====================END====================\n"
"2011-05-16T20:15:29.163000Z":711513:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPDialog.java:2635][DialogFilter.java:1287][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:29.163000Z":711514:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPDialog.java:1233][SIPDialog.java:2733][DialogFilter.java:1287][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:29.164000Z":711515:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SipProviderImpl.java:182][DialogFilter.java:1291][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:29.164000Z":711516:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:SubscribeDialog:"[SubscribeDialog
FSM:org.sipfoundry.sipcallwatcher.SubscribeDialog.SubscribeDialog@74260899
gov.nist.javax.sip.stack.SIPDialog@5f4ff299] Incoming event 'processResponse'"
"2011-05-16T20:15:29.164000Z":711517:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:SubscribeDialog:"Response
received on client transaction id
gov.nist.javax.sip.stack.SIPClientTransaction@218b8301: 404"
"2011-05-16T20:15:29.164000Z":711518:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:SubscribeDialog:"[SubscribeDialog
FSM:gov.nist.javax.sip.stack.SIPDialog@5f4ff299] Transitioning from
org.sipfoundry.sipcallwatcher.SubscribeDialog.WaitingForSubscribeResponseWithChallengeState@79c04bb1
to org.sipfoundry.sipcallwatcher.SubscribeDialog.Moribund@3e610323"
"2011-05-16T20:15:29.165000Z":711519:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPDialog.java:1233][SIPDialog.java:1801][SubscribeDialog.java:280][Moribund.java:26][SubscribeDialog.java:159][WaitingForSubscribeResponseWithChallengeState.java:20][SubscribeDialog.java:92][Subscriber.java:145][EventScanner.java:291][SipProviderImpl.java:192][DialogFilter.java:1291][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:29.165000Z":711520:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:Subscriber:"arming
subscribeDialogCreator"
"2011-05-16T20:15:29.245000Z":711521:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPTransaction.java:1197][SIPTransaction.java:1183][EventScanner.java:365][SipProviderImpl.java:192][DialogFilter.java:1291][SIPClientTransaction.java:624][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:29.245000Z":711522:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPTransaction.java:556][SIPClientTransaction.java:1301][SIPClientTransaction.java:632][SIPClientTransaction.java:537][SIPClientTransaction.java:1453][TCPMessageChannel.java:570][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:29.246000Z":711523:JAVA:INFO:sip1.mycompany.com:PipelineThread-0:00000000:sipxcallwatcher:"[SIPTransaction.java:1197][SIPTransaction.java:1183][TCPMessageChannel.java:574][PipelinedMsgParser.java:361][Thread.java:636]"
"2011-05-16T20:15:29.642000Z":711524:JAVA:INFO:sip1.mycompany.com:btpool0-2:00000000:XmlRpcPresenceProvider:"ping
received from [900-PRESENCE] SipRedirectorPresenceRouting"
"2011-05-16T20:15:29.644000Z":711525:JAVA:INFO:sip1.mycompany.com:Timer-2:00000000:sipxcallwatcher:"[SipProviderImpl.java:182][SIPTransactionStack.java:1673][SIPClientTransaction.java:231][SIPStackTimerTask.java:29][Timer.java:534][Timer.java:484]"
_______________________________________________
sipx-users mailing list
[email protected]
List Archive: http://list.sipfoundry.org/archive/sipx-users/
