I reconfigured the VM to isolate it from the dedicated box installation
(different domain) and it's throwing the same errors (the 'connection
refused' errors I mentioned including the related java/callwatcher
'exception' errors).
Again, both are essentially new installs (patched up to date), both throwing
the same errors. Updated samples attached.
Anybody else see this, and have any ideas about it??
-----Original Message-----
From: [email protected]
[mailto:[email protected]] On Behalf Of James Walsh
Sent: May-16-11 5:23 PM
To: 'Discussion list for users of sipXecs software'
Subject: Re: [sipx-users] sipxopenfire.log Errors
The VM is showing this error (attached) several times a second. I think the
upstream DNS may be routing the VM tester to the dedicated machine, which is
on the same network...(???) SIP1 is the VM tester (192.168.215.21) ; SIP is
the dedicated box (192.168.215.20).
Which brings me back to the 'connection refused' errors on the freshly
installed (and updated) dedicated box. So I'll likely have to isolate the
tester to get any real info about the dedicated box.
Unless I'm reading it wrong, it's trying a redirect to the box named SIP,
but the IP is still SIP1...?!?!?
-----Original Message-----
From: [email protected]
[mailto:[email protected]] On Behalf Of Douglas Hubler
Sent: May-16-11 4:51 PM
To: Discussion list for users of sipXecs software
Subject: Re: [sipx-users] sipxopenfire.log Errors
On Mon, May 16, 2011 at 4:04 PM, James Walsh <[email protected]> wrote:
> A VM I made is spewing "404" errors every six seconds, and a log file
> nearly triple the size of the other machine.
check for errors in registrar.log. i think it's trying to connect to a
presence plugin _______________________________________________
sipx-users mailing list
[email protected]
List Archive: http://list.sipfoundry.org/archive/sipx-users/
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-16T22:42:41.507000Z":573:JAVA:INFO:sip.mycompany.local:Timer-40:00000000:Subscriber:"subscribeDialogCreator
timer fired"
"2011-05-16T22:42:41.507000Z":574:JAVA:INFO:sip.mycompany.local:Timer-40:00000000:SubscribeDialog:"[SubscribeDialog
FSM:org.sipfoundry.sipcallwatcher.SubscribeDialog.SubscribeDialog@27013985
null] Incoming event 'start'"
"2011-05-16T22:42:41.522000Z":575:JAVA:INFO:sip.mycompany.local:Timer-40: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-16T22:42:41.523000Z":576:JAVA:INFO:sip.mycompany.local:Timer-40: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-16T22:42:41.523000Z":577:JAVA:INFO:sip.mycompany.local:Timer-40: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-16T22:42:41.524000Z":578:JAVA:INFO:sip.mycompany.local:Timer-40: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-16T22:42:41.527000Z":579:JAVA:INFO:sip.mycompany.local:Timer-40: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-16T22:42:41.529000Z":580:JAVA:INFO:sip.mycompany.local:Timer-40: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-16T22:42:41.530000Z":581:JAVA:INFO:sip.mycompany.local:Timer-40: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-16T22:42:41.530000Z":582:JAVA:ERR:sip.mycompany.local:Timer-40:00000000:Subscriber:"Error
sending Subscribe -- tearing down dialog"
"2011-05-16T22:42:41.531000Z":583:JAVA:INFO:sip.mycompany.local:Timer-40: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-16T22:42:41.531000Z":584:JAVA:INFO:sip.mycompany.local:Timer-40: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-16T22:42:41.532000Z":585:JAVA:INFO:sip.mycompany.local:Timer-40:00000000:SubscribeDialog:"[SubscribeDialog
FSM:null] Transitioning from
org.sipfoundry.sipcallwatcher.SubscribeDialog.WaitingForStartState@565f0e7d to
org.sipfoundry.sipcallwatcher.SubscribeDialog.Moribund@7ab05cd7"
"2011-05-16T22:42:41.535000Z":586:JAVA:INFO:sip.mycompany.local:Timer-40:00000000:Subscriber:"arming
subscribeDialogCreator"
"2011-05-16T22:42:42.031000Z":587:JAVA:INFO:sip.mycompany.local: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/
