[Spacewalk-list] [Spacewalk 1.4] Error during OSAD startup
Pierre Casenove
pedro109 at hotmail.com
Mon Jul 4 06:21:47 UTC 2011
Hello,i'm installing Spacewalk 1.4 en a RHEL 5.6 server.Here is a message I get during spacewalk startup, with OSAD debug level set to 6:[root at tu-spa-d15 jabberd]# /usr/sbin/spacewalk-service startStarting spacewalk services...Initializing jabberd processes ...Starting router: [ OK ]Starting sm: [ OK ]Starting c2s: [ OK ]Starting s2s: [ OK ]Starting osa-dispatcher: --> <?xml version='1.0' encoding='UTF-8'?><stream:stream to='serverFQDN' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'><-- <error><host-gone xmlns = 'urn:ietf:params:xml:ns:xmpp-streams' /><text xmlns = 'urn:ietf:params:xml:ns:xmpp-streams' >session manager for requested domain is not available</text></error>RHN 7158 2011/07/04 06:03:03 -00:00: ('Server did not return a <features /> stanza',)--> <presence type='unavailable' />RHN 7158 2011/07/04 06:03:03 -00:00: ('Traceback (most recent call last):\n File "/usr/share/rhn/osad/jabber_lib.py", line 253, in setup_connection\n c = self._get_jabber_client(js)\n File "/usr/share/rhn/osad/jabber_lib.py", line 310, in _get_jabber_client\n c.connect()\n File "/usr/share/rhn/osad/jabber_lib.py", line 583, in connect\n raise SSLDisabledError\nSSLDisabledError\n',) [ OK ]Starting tomcat5: [ OK ]Waiting for tomcat to be ready ...Starting httpd: [ OK ]Starting Monitoring ...[ OK ]Starting MonitoringScout ...[ OK ]Starting rhn-search...Starting cobbler daemon: [ OK ]Starting RHN Taskomatic...Done.
Basically, everything is good, except osa-dispatcher.
When I look at /var/log/rhn/osa-dispatcher.log, here is what I got:2011/07/04 06:03:02 -00:00 7158 0.0.0.0: osad/jabber_lib.connect('Attempting to connect',)2011/07/04 06:03:02 -00:00 7158 0.0.0.0: osad/jabber_lib.process(300,)2011/07/04 06:03:02 -00:00 7158 0.0.0.0: osad/jabber_lib.process('before select(); timeout', 299.99999713897705)2011/07/04 06:03:03 -00:00 7158 0.0.0.0: osad/jabber_lib.process('select() returned',)2011/07/04 06:03:03 -00:00 7158 0.0.0.0: osad/jabber_lib._auth_dispatch(<jabber.xmlstream.Node instance at 0xb579f80>,)2011/07/04 06:03:03 -00:00 7158 0.0.0.0: osad/jabber_lib.connect('Connected',)2011/07/04 06:03:03 -00:00 7158 0.0.0.0: osad/jabber_lib.connect('Expecting features stanza, got:', <error><host-gone xmlns = 'urn:ietf:params:xml:ns:xmpp-streams' /><text xmlns = 'urn:ietf:params:xml:ns:xmpp-streams' >session manager for requested domain is not available</text></error>)2011/07/04 06:03:03 -00:00 7158 0.0.0.0: osad/jabber_lib.connect('ERROR', 'Server did not return a <features /> stanza')2011/07/04 06:03:03 -00:00 7158 0.0.0.0: osad/jabber_lib.print_message('SSLError',)2011/07/04 06:03:03 -00:00 7158 0.0.0.0: osad/jabber_lib.print_message('Could not connect to jabber server', 'serverFQDN')2011/07/04 06:03:03 -00:00 7158 0.0.0.0: osad/jabber_lib.setup_connection('ERROR', 'Traceback (most recent call last):\n File "/usr/share/rhn/osad/jabber_lib.py", line 253, in setup_connection\n c = self._get_jabber_client(js)\n File "/usr/share/rhn/osad/jabber_lib.py", line 310, in _get_jabber_client\n c.connect()\n File "/usr/share/rhn/osad/jabber_lib.py", line 583, in connect\n raise SSLDisabledError\nSSLDisabledError\n')2011/07/04 06:03:03 -00:00 7158 0.0.0.0: osad/jabber_lib.setup_connection('Could not connect to any jabber server',)2011/07/04 06:03:03 -00:00 7158 0.0.0.0: osad/jabber_lib.push_to_background('Pushing process into background',)2011/07/04 06:03:03 -00:00 7158 0.0.0.0: osad/jabber_lib.main('Unable to connect to jabber servers, sleeping 10 seconds',)2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib.check_cert('Loading cert', <X509Name object '/C=FR/ST=Dpt/L=City/O=Entreprise/OU=serverFQDN/CN=serverFQDN'>)2011/07/04 06:03:13 -00:00 7158 0.0.0.0: rhnSQL/driver_postgresql.convert_named_query_params('Converting query for PostgreSQL: select 1',)2011/07/04 06:03:13 -00:00 7158 0.0.0.0: rhnSQL/driver_postgresql.convert_named_query_params('New query: select 1',)2011/07/04 06:03:13 -00:00 7158 0.0.0.0: rhnSQL/driver_postgresql._execute_wrapper('Executing SQL: "select 1" with bind params: {}',)2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib.setup_connection('Connecting to', 'serverFQDN')2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib._get_jabber_client2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib._get_jabber_client('Connecting to', 'serverFQDN')2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib.__init__2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib.__init__2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib.check_cert('Loading cert', <X509Name object '/C=FR/ST=Dpt/L=City/O=Entreprise/OU=serverFQDN/CN=serverFQDN'>)2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib.connect2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib.connect('Attempting to connect',)2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib.process(300,)2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib.process('before select(); timeout', 299.99999499320984)2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib.process('select() returned',)2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib._auth_dispatch(<jabber.xmlstream.Node instance at 0xb58aa70>,)2011/07/04 06:03:13 -00:00 7158 0.0.0.0: osad/jabber_lib.connect('Connected',)
When running netstat, I see : tcp 0 0 10.120.224.15:33657 10.120.224.15:xmpp-client ESTABLISHEDAnd I'm able to telnet to port 5222.
When reading the logs, I understand that the first connection was unsuccessful, but, after a 10 seconds delay, it was correct.
Could someone confirm this analysis?
Thanks a lot,
FFF
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://listman.redhat.com/archives/spacewalk-list/attachments/20110704/d822e8fc/attachment.htm>
More information about the Spacewalk-list
mailing list