May be a lot of junk here, but these are anomalies that I saw in the log files. Maybe they identify an issue for a Developer. I haven't done anything to fix my lack of registration in case someone wants specific tests ran on something in particular.
This is what I find in the sipregistrar log - "2012-07-19T03:22:35.800754Z":3776:XMLRPC:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"XmlRpcRequest::execute XML-RPC received valid response = \n<?xml version=\"1.0\" encoding=\"UTF-8\"?><methodResponse xmlns:ex=\"http://ws.apache.org/xmlrpc/namespaces/extensions\";><params><para m><value><struct><member><name>status-code</name><value>ok</value></member>< member><name>instance-handle</name><value>of:8791809103762886072</value></me mber></struct></value></param></params></methodResponse>" "2012-07-19T03:22:59.578943Z":3777:HTTP:ERR:rage.ragesip.com:OsTimer-10:7f4a 1cd63700:SipRegistrar:"HttpMessage::get[4] read timed out - closing" "2012-07-19T03:22:59.808318Z":3778:KERNEL:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"OsConnectionSocket::~" "2012-07-19T03:22:59.989430Z":3779:XMLRPC:ERR:rage.ragesip.com:OsTimer-10:7f 4a1cd63700:SipRegistrar:"XmlRpcRequest::execute http connection failed" "2012-07-19T03:22:59.989490Z":3780:NAT:ERR:rage.ragesip.com:OsTimer-10:7f4a1 cd63700:SipRegistrar:"[900-PRESENCE] SipRedirectorPresenceRouting::signal: ping to openfire failed - trying to reconnect" "2012-07-19T03:23:16.184233Z":3781:KERNEL:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"OsConnectionSocket::_[5] connected 10 to 192.168.5.252:9094" "2012-07-19T03:23:19.184677Z":3782:HTTP:ERR:rage.ragesip.com:OsTimer-10:7f4a 1cd63700:SipRegistrar:"HttpMessage::get[4] read timed out - closing" "2012-07-19T03:23:19.900131Z":3783:KERNEL:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"OsConnectionSocket::~" "2012-07-19T03:23:20.094723Z":3784:XMLRPC:ERR:rage.ragesip.com:OsTimer-10:7f 4a1cd63700:SipRegistrar:"XmlRpcRequest::execute http connection failed" "2012-07-19T03:23:20.094767Z":3785:NAT:ERR:rage.ragesip.com:OsTimer-10:7f4a1 cd63700:SipRegistrar:"SipRedirectorPresenceRouting::registerPresenceMonitorR equestWithOpenfire failed to execute() request: -6 : Connection Failed" "2012-07-19T03:23:20.094801Z":3786:NAT:ERR:rage.ragesip.com:OsTimer-10:7f4a1 cd63700:SipRegistrar:"[900-PRESENCE] SipRedirectorPresenceRouting::signal: failed to reconnect with openfire - retrying later" "2012-07-19T03:23:35.799296Z":3787:KERNEL:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"OsConnectionSocket::_[5] connected 10 to 192.168.5.252:9094" "2012-07-19T03:23:38.802327Z":3788:HTTP:ERR:rage.ragesip.com:OsTimer-10:7f4a 1cd63700:SipRegistrar:"HttpMessage::get[4] read timed out - closing" "2012-07-19T03:23:38.802407Z":3789:KERNEL:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"OsConnectionSocket::~" "2012-07-19T03:23:38.802472Z":3790:XMLRPC:ERR:rage.ragesip.com:OsTimer-10:7f 4a1cd63700:SipRegistrar:"XmlRpcRequest::execute http connection failed" "2012-07-19T03:23:38.802506Z":3791:NAT:ERR:rage.ragesip.com:OsTimer-10:7f4a1 cd63700:SipRegistrar:"SipRedirectorPresenceRouting::registerPresenceMonitorR equestWithOpenfire failed to execute() request: -6 : Connection Failed" "2012-07-19T03:23:38.802532Z":3792:NAT:ERR:rage.ragesip.com:OsTimer-10:7f4a1 cd63700:SipRegistrar:"[900-PRESENCE] SipRedirectorPresenceRouting::signal: failed to reconnect with openfire - retrying later" "2012-07-19T03:23:55.799320Z":3793:KERNEL:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"OsConnectionSocket::_[5] connected 10 to 192.168.5.252:9094" "2012-07-19T03:23:58.928120Z":3794:HTTP:ERR:rage.ragesip.com:OsTimer-10:7f4a 1cd63700:SipRegistrar:"HttpMessage::get[4] read timed out - closing" "2012-07-19T03:23:58.928192Z":3795:KERNEL:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"OsConnectionSocket::~" "2012-07-19T03:23:58.928256Z":3796:XMLRPC:ERR:rage.ragesip.com:OsTimer-10:7f 4a1cd63700:SipRegistrar:"XmlRpcRequest::execute http connection failed" "2012-07-19T03:23:58.928285Z":3797:NAT:ERR:rage.ragesip.com:OsTimer-10:7f4a1 cd63700:SipRegistrar:"SipRedirectorPresenceRouting::registerPresenceMonitorR equestWithOpenfire failed to execute() request: -6 : Connection Failed" "2012-07-19T03:23:58.928310Z":3798:NAT:ERR:rage.ragesip.com:OsTimer-10:7f4a1 cd63700:SipRegistrar:"[900-PRESENCE] SipRedirectorPresenceRouting::signal: failed to reconnect with openfire - retrying later" "2012-07-19T03:24:15.799314Z":3799:KERNEL:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"OsConnectionSocket::_[5] connected 10 to 192.168.5.252:9094" "2012-07-19T03:24:17.910262Z":3800:XMLRPC:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"XmlRpcRequest::execute XML-RPC received valid response = \n<?xml version=\"1.0\" encoding=\"UTF-8\"?><methodResponse xmlns:ex=\"http://ws.apache.org/xmlrpc/namespaces/extensions\";><params><para m><value><struct><member><name>status-code</name><value>ok</value></member>< member><name>instance-handle</name><value>of:8791809103762886072</value></me mber></struct></value></param></params></methodResponse>" "2012-07-19T03:24:17.910370Z":3801:NAT:INFO:rage.ragesip.com:OsTimer-10:7f4a 1cd63700:SipRegistrar:"[900-PRESENCE] SipRedirectorPresenceRouting::signal: reconnected with openfire" "2012-07-19T03:24:35.800870Z":3802:XMLRPC:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"XmlRpcRequest::execute XML-RPC received valid response = \n<?xml version=\"1.0\" encoding=\"UTF-8\"?><methodResponse xmlns:ex=\"http://ws.apache.org/xmlrpc/namespaces/extensions\";><params><para m><value><struct><member><name>status-code</name><value>ok</value></member>< member><name>instance-handle</name><value>of:8791809103762886072</value></me mber></struct></value></param></params></methodResponse>" "2012-07-19T03:24:58.894894Z":3803:HTTP:ERR:rage.ragesip.com:OsTimer-10:7f4a 1cd63700:SipRegistrar:"HttpMessage::get[4] read timed out - closing" "2012-07-19T03:24:59.241443Z":3804:KERNEL:INFO:rage.ragesip.com:OsTimer-10:7 f4a1cd63700:SipRegistrar:"OsConnectionSocket::~" I see this in the sipstatuslog - "2012-07-18T21:04:12.725508Z":201:HTTP:ERR:rage.ragesip.com:SubscribeServerT hread:7f45466fa700:SipStatus:"HttpMessage::get[4] read timed out - closing" "2012-07-18T21:04:12.799165Z":202:SIP:WARNING:rage.ragesip.com:SubscribeServ erThread:7f45466fa700:SipStatus:"MwiPlugin::handleSubscribeRequest() - voicemailCGI GET failed with -1." "2012-07-18T21:04:19.846277Z":203:HTTP:ERR:rage.ragesip.com:SubscribeServerT hread:7f45466fa700:SipStatus:"HttpMessage::get[4] read timed out - closing" "2012-07-18T21:04:19.846410Z":204:SIP:WARNING:rage.ragesip.com:SubscribeServ erThread:7f45466fa700:SipStatus:"MwiPlugin::handleSubscribeRequest() - voicemailCGI GET failed with -1." "2012-07-18T21:04:58.315781Z":205:HTTP:ERR:rage.ragesip.com:SubscribeServerT hread:7f45466fa700:SipStatus:"HttpMessage::get[4] read timed out - closing" "2012-07-18T21:04:58.315902Z":206:SIP:WARNING:rage.ragesip.com:SubscribeServ erThread:7f45466fa700:SipStatus:"MwiPlugin::handleSubscribeRequest() - voicemailCGI GET failed with -1." "2012-07-18T21:05:20.014293Z":207:HTTP:ERR:rage.ragesip.com:SubscribeServerT hread:7f45466fa700:SipStatus:"HttpMessage::get[4] read timed out - closing" "2012-07-18T21:05:20.014429Z":208:SIP:WARNING:rage.ragesip.com:SubscribeServ erThread:7f45466fa700:SipStatus:"MwiPlugin::handleSubscribeRequest() - voicemailCGI GET failed with -1." "2012-07-18T21:11:05.440989Z":209:SIP:WARNING:rage.ragesip.com:SubscribeServ erThread:7f45466fa700:SipStatus:"SubscribeServerThread::removeErrorSubscript ion [email protected]" "2012-07-18T21:22:08.404152Z":210:SIP:WARNING:rage.ragesip.com:SubscribeServ erThread:7f45466fa700:SipStatus:"SubscribeServerThread::removeErrorSubscript ion [email protected]" "2012-07-18T21:29:36.015760Z":211:SIP:WARNING:rage.ragesip.com:SubscribeServ erThread:7f45466fa700:SipStatus:"SubscribeServerThread::removeErrorSubscript ion [email protected]" Along with this - "2012-07-18T23:10:19.096543Z":230:SIP:WARNING:rage.ragesip.com:SubscribeServ erThread:7f45466fa700:SipStatus:"SubscribeServerThread::removeErrorSubscript ion [email protected]" "2012-07-18T23:13:43.306257Z":231:SIP:ERR:rage.ragesip.com:HttpServer-0:7f45 468fc700:SipStatus:"Url::parseString error - expected end of url or field parameter ';name=' at offset 15 in '[email protected]\r\nMessages-Waiting: yes\r\nMessage-Account: sip:[email protected]\r\nVoice-Message: 1/0 (0/0)'" "2012-07-18T23:15:22.715799Z":232:SIP:WARNING:rage.ragesip.com:SubscribeServ erThread:7 Sipxproxylog "2012-07-19T03:29:38.108801Z":29670:XMLRPC:INFO:rage.ragesip.com:OsTimer-11: 7ff6bbf26700:SipXProxy:"XmlRpcRequest::execute XML-RPC received valid response = \n<?xml version=\"1.0\" encoding=\"UTF-8\"?><methodResponse xmlns:ex=\"http://ws.apache.org/xmlrpc/namespaces/extensions\";><params><para m><value><struct><member><name>status-code</name><value>ok</value></member>< member><name>instance-handle</name><value>sipxbridge:2967043324395525669</va lue></member></struct></value></param></params></methodResponse>" "2012-07-19T03:29:38.109270Z":29671:XMLRPC:INFO:rage.ragesip.com:OsTimer-11: 7ff6bbf26700:SipXProxy:"XmlRpcRequest::execute XML-RPC received valid response = \n<?xml version=\"1.0\" encoding=\"UTF-8\"?><methodResponse xmlns:ex=\"http://ws.apache.org/xmlrpc/namespaces/extensions\";><params><para m><value><struct><member><name>status-code</name><value>ok</value></member>< member><name>instance-handle</name><value>sipxbridge:2967043324395525669</va lue></member></struct></value></param></params></methodResponse>" "2012-07-19T03:29:38.115245Z":29672:NAT:CRIT:rage.ragesip.com:OsTimer-11:7ff 6bbf26700:SipXProxy:"ALARM_PROXY_MEDIA_RELAY_RECONNECTED" "2012-07-19T03:30:03.397694Z":29673:KERNEL:INFO:rage.ragesip.com:AsynchMedia RelayRequestSender-14:7ff6b852b700:SipXProxy:"OsConnectionSocket::read[4] error or EOF on fd 11, errno = 115 Operation now in progress" "2012-07-19T03:30:03.397800Z":29674:KERNEL:INFO:rage.ragesip.com:AsynchMedia RelayRequestSender-14:7ff6b852b700:SipXProxy:"OsConnectionSocket::~" "2012-07-19T03:30:03.397923Z":29675:KERNEL:INFO:rage.ragesip.com:AsynchMedia RelayRequestSender-14:7ff6b852b700:SipXProxy:"OsConnectionSocket::_[5] connected 11 to 192.168.5.252:9090" "2012-07-19T03:30:03.534378Z":29676:XMLRPC:INFO:rage.ragesip.com:AsynchMedia RelayRequestSender-14:7ff6b852b700:SipXProxy:"XmlRpcRequest::execute XML-RPC received valid response = \n<?xml version=\"1.0\" encoding=\"UTF-8\"?><methodResponse xmlns:ex=\"http://ws.apache.org/xmlrpc/namespaces/extensions\";><params><para m><value><struct><member><name>status-code</name><value>ok</value></member>< member><name>nbridges</name><value>5169</value></member><member><name>instan ce-handle</name><value>sipxbridge:2967043324395525669</value></member></stru ct></value></param></params></methodResponse>" "2012-07-19T03:30:33.391313Z":29677:KERNEL:INFO:rage.ragesip.com:AsynchMedia RelayRequestSender-14:7ff6b852b700:SipXProxy:"OsConnectionSocket::read[4] error or EOF on fd 11, errno = 115 Operation now in progress" "2012-07-19T03:30:33.391407Z":29678:KERNEL:INFO:rage.ragesip.com:AsynchMedia RelayRequestSender-14:7ff6b852b700:SipXProxy:"OsConnectionSocket::~" From: [email protected] [mailto:[email protected]] On Behalf Of Todd Hodgen Sent: Wednesday, July 18, 2012 9:52 PM To: sipXecs developer discussions Subject: [sipx-dev] Loss of registrations with 4.6 Installation Two days in a row now I have lost all reservations - Polycom and Audiocodes phones, for all extensions on the phones. This is a new install of 4.6, began with minimal install, 4.6 RPM, with no issues in installation. May be something I've misconfigured, or modified, but nothing is jumping out at me at the moment. Running behind the same router configuration I ran 4.4 from for a long time. Server is dual core, 8gb memory, yawning with just two phones and a few extensions. I'm going to dig into things, but wanted to be sure there weren't any known issues, or specific logs to check for some clues. I suspect with all the changes there are some new areas of concern to check on. I'll dig through the usual suspects now. Any suggestions would be appreciated.
_______________________________________________ sipx-dev mailing list [email protected] List Archive: http://list.sipfoundry.org/archive/sipx-dev/
