[Spacewalk-list] RE: Osa-dispatcher error

Fri Jun 26 14:43:50 UTC 2009

That is exactly what I see on client and server.  I've been unable to make any headway on this issue, though.  The only thing I can think is that I either have the cert set up incorrectly (hence the SSLError) or I missed a step and the user is unable to authenticate and the error is being misinterpreted by me.

From: spacewalk-list-bounces at redhat.com [mailto:spacewalk-list-bounces at redhat.com] On Behalf Of Frey, Evan
Sent: Friday, June 26, 2009 10:23 AM
To: spacewalk-list at redhat.com
Subject: RE: [Spacewalk-list] RE: Osa-dispatcher error

So I ran osad in debug 10 and this is what I see returned:

2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.check_cert('Loading cert', <X509Name object '/C=US/ST=NY/L=New York/O=MSUSA/OU=shobspwklnprd01.usi.mizuho-sc.com/CN=shobspwklnprd01.usi.mizuho-sc.com'>)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: rhnSQL/driver_cx_Oracle._execute_wrapper('Executing SQL: "select sysdate as ID from dual" with bind params: {}',)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.setup_connection('Connecting to', 'shobspwklnprd01.usi.mizuho-sc.com')
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib._get_jabber_client
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib._get_jabber_client('Connecting to', 'shobspwklnprd01.usi.mizuho-sc.com')
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.__init__
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.__init__
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.check_cert('Loading cert', <X509Name object '/C=US/ST=NY/L=New York/O=MSUSA/OU=shobspwklnprd01.usi.mizuho-sc.com/CN=shobspwklnprd01.usi.mizuho-sc.com'>)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.connect
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.connect('Attempting to connect',)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.process(300,)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.process('before select(); timeout', 299.99999690055847)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.process('select() returned',)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib._auth_dispatch(<jabber.xmlstream.Node instance at 0xb7ac722c>,)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.connect('Connected',)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.connect('Expecting features stanza, got:', <error><host-unknown xmlns = 'urn:ietf:params:xml:ns:xmpp-streams'  /><text xmlns = 'urn:ietf:params:xml:ns:xmpp-streams' >service requested for unknown domain</text></error>)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.connect('ERROR', 'Server did not return a <features /> stanza')
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.print_message('SSLError',)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.print_message('Could not connect to jabber server', 'shobspwklnprd01.usi.mizuho-sc.com')
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.setup_connection('Could not connect to any jabber server',)
2009/06/25 14:05:17 -04:00 29201 0.0.0.0: osad/jabber_lib.main('Unable to connect to jabber servers, sleeping 10 seconds',)
jabber is running fine
q
28987 ?        S      0:00 /usr/bin/router -c /etc/jabberd/router.xml
29024 ?        S      0:00 /usr/bin/sm -c /etc/jabberd/sm.xml
29060 ?        S      0:01 /usr/bin/c2s -c /etc/jabberd/c2s.xml
29096 ?        S      0:00 /usr/bin/s2s -c /etc/jabberd/s2s.xml
and these are my startup logs for jabber

Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: starting up
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: id: localhost.localdomain
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: process id is 28673, written to /var/lib/jabberd/pid/sm.pid
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: loading 'db' storage module
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: initialised storage driver 'db'
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: modules search path: /usr/lib/jabberd
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'status' added to chain 'sess-start' (order 0 index 0 seq 0)
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'status' added to chain 'sess-end' (order 0 index 0 seq 1)
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'iq-last' added to chain 'sess-end' (order 1 index 1 seq 0)
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'validate' added to chain 'in-sess' (order 0 index 2 seq 0)
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'status' added to chain 'in-sess' (order 1 index 0 seq 2)
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'privacy' added to chain 'in-sess' (order 2 index 3 seq 0)
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'roster' added to chain 'in-sess' (order 3 index 4 seq 0)
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'vacation' added to chain 'in-sess' (order 4 index 5 seq 0)
Jun 26 10:19:01 shobspwklnprd01 jabberd/c2s[28709]: starting up
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'iq-vcard' added to chain 'in-sess' (order 5 index 6 seq 0)
Jun 26 10:19:01 shobspwklnprd01 jabberd/c2s[28709]: process id is 28709, written to /var/lib/jabberd/pid/c2s.pid
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'iq-ping' added to chain 'in-sess' (order 6 index 7 seq 0)
Jun 26 10:19:01 shobspwklnprd01 jabberd/c2s[28709]: modules search path: /usr/lib/jabberd
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'iq-private' added to chain 'in-sess' (order 7 index 8 seq 0)
Jun 26 10:19:01 shobspwklnprd01 jabberd/c2s[28709]: loading 'pam' authreg module
Jun 26 10:19:01 shobspwklnprd01 jabberd/s2s[28746]: starting up (interval=60, queue=60, keepalive=0, idle=86400)
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'disco' added to chain 'in-sess' (order 8 index 9 seq 0)
Jun 26 10:19:01 shobspwklnprd01 jabberd/c2s[28709]: initialized auth module 'pam'
Jun 26 10:19:01 shobspwklnprd01 jabberd/s2s[28746]: process id is 28746, written to /var/lib/jabberd/pid/s2s.pid
Jun 26 10:19:01 shobspwklnprd01 jabberd/sm[28673]: module 'amp' added to chain 'in-sess' (order 9 index 10 seq 0)
Jun 26 10:19:02 shobspwklnprd01 jabberd/c2s[28709]: failed to load local SSL pemfile, SSL will not be available to clients
Jun 26 10:19:02 shobspwklnprd01 jabberd/s2s[28746]: attempting connection to router at 127.0.0.1, port=5347
Jun 26 10:19:02 shobspwklnprd01 jabberd/sm[28673]: module 'offline' added to chain 'in-sess' (order 10 index 11 seq 0)
Jun 26 10:19:02 shobspwklnprd01 jabberd/c2s[28709]: failed to load router SSL pemfile, channel to router will not be SSL encrypted
Jun 26 10:19:02 shobspwklnprd01 jabberd/router[28637]: [127.0.0.1, port=37270] connect
Jun 26 10:19:02 shobspwklnprd01 jabberd/sm[28673]: module 'announce' added to chain 'in-sess' (order 11 index 12 seq 0)
Jun 26 10:19:02 shobspwklnprd01 jabberd/c2s[28709]: failed to load  SSL pemfile
Jun 26 10:19:02 shobspwklnprd01 jabberd/s2s[28746]: connection to router established
Jun 26 10:19:02 shobspwklnprd01 jabberd/router[28637]: [127.0.0.1, port=37270] authenticated as jabberd at jabberd-router<mailto:jabberd at jabberd-router>
Jun 26 10:19:02 shobspwklnprd01 jabberd/sm[28673]: module 'presence' added to chain 'in-sess' (order 12 index 13 seq 0)
Jun 26 10:19:02 shobspwklnprd01 jabberd/c2s[28709]: [shobspwklnprd01.usi.mizuho-sc.com] configured; realm=, registration disabled
Jun 26 10:19:02 shobspwklnprd01 jabberd/router[28637]: [s2s] set as default route
Jun 26 10:19:02 shobspwklnprd01 jabberd/sm[28673]: module 'deliver' added to chain 'in-sess' (order 13 index 14 seq 0)
Jun 26 10:19:02 shobspwklnprd01 jabberd/c2s[28709]: attempting connection to router at 127.0.0.1, port=5347
Jun 26 10:19:02 shobspwklnprd01 jabberd/router[28637]: [s2s] online (bound to 127.0.0.1, port 37270)
Jun 26 10:19:02 shobspwklnprd01 jabberd/sm[28673]: module 'session' added to chain 'in-router' (order 0 index 15 seq 0)
Jun 26 10:19:02 shobspwklnprd01 jabberd/router[28637]: [127.0.0.1, port=37273] connect
Jun 26 10:19:02 shobspwklnprd01 jabberd/s2s[28746]: [0.0.0.0, port=5269] listening for connections
Jun 26 10:19:02 shobspwklnprd01 jabberd/sm[28673]: module 'validate' added to chain 'in-router' (order 1 index 2 seq 1)
Jun 26 10:19:02 shobspwklnprd01 jabberd/router[28637]: [127.0.0.1, port=37273] authenticated as jabberd at jabberd-router<mailto:jabberd at jabberd-router>
Jun 26 10:19:02 shobspwklnprd01 jabberd/c2s[28709]: connection to router established
Jun 26 10:19:02 shobspwklnprd01 jabberd/s2s[28746]: ready for connections
Jun 26 10:19:02 shobspwklnprd01 jabberd/sm[28673]: module 'presence' added to chain 'in-router' (order 2 index 13 seq 1)
Jun 26 10:19:02 shobspwklnprd01 jabberd/router[28637]: [c2s] online (bound to 127.0.0.1, port 37273)
Jun 26 10:19:02 shobspwklnprd01 jabberd/sm[28673]: module 'privacy' added to chain 'in-router' (order 3 index 3 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/c2s[28709]: [0.0.0.0, port=5222] listening for connections
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'privacy' added to chain 'out-router' (order 0 index 3 seq 2)
Jun 26 10:19:03 shobspwklnprd01 jabberd/c2s[28709]: ready for connections
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'iq-last' added to chain 'pkt-sm' (order 0 index 1 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'iq-ping' added to chain 'pkt-sm' (order 1 index 7 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'iq-time' added to chain 'pkt-sm' (order 2 index 16 seq 0)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'iq-version' added to chain 'pkt-sm' (order 3 index 17 seq 0)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'amp' added to chain 'pkt-sm' (order 4 index 10 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'disco' added to chain 'pkt-sm' (order 5 index 9 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'announce' added to chain 'pkt-sm' (order 6 index 12 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'help' added to chain 'pkt-sm' (order 7 index 18 seq 0)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'echo' added to chain 'pkt-sm' (order 8 index 19 seq 0)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'status' added to chain 'pkt-sm' (order 9 index 0 seq 3)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'presence' added to chain 'pkt-sm' (order 10 index 13 seq 2)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'roster' added to chain 'pkt-user' (order 0 index 4 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'presence' added to chain 'pkt-user' (order 1 index 13 seq 3)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'iq-vcard' added to chain 'pkt-user' (order 2 index 6 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'amp' added to chain 'pkt-user' (order 3 index 10 seq 2)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'deliver' added to chain 'pkt-user' (order 4 index 14 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'vacation' added to chain 'pkt-user' (order 5 index 5 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'offline' added to chain 'pkt-user' (order 6 index 11 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'disco-publish' added to chain 'pkt-user' (order 7 index 20 seq 0)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'iq-last' added to chain 'pkt-user' (order 8 index 1 seq 2)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'session' added to chain 'pkt-router' (order 0 index 15 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'disco' added to chain 'pkt-router' (order 1 index 9 seq 2)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'active' added to chain 'user-load' (order 0 index 21 seq 0)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'roster' added to chain 'user-load' (order 1 index 4 seq 2)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'roster-publish' added to chain 'user-load' (order 2 index 22 seq 0)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'privacy' added to chain 'user-load' (order 3 index 3 seq 3)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'disco-publish' added to chain 'user-load' (order 4 index 20 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'vacation' added to chain 'user-load' (order 5 index 5 seq 2)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'active' added to chain 'user-create' (order 0 index 21 seq 1)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'template-roster' added to chain 'user-create' (order 1 index 23 seq 0)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'active' added to chain 'user-delete' (order 0 index 21 seq 2)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'announce' added to chain 'user-delete' (order 1 index 12 seq 2)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'disco-publish' added to chain 'user-delete' (order 2 index 20 seq 2)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'offline' added to chain 'user-delete' (order 3 index 11 seq 2)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'privacy' added to chain 'user-delete' (order 4 index 3 seq 4)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'roster' added to chain 'user-delete' (order 5 index 4 seq 3)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'vacation' added to chain 'user-delete' (order 6 index 5 seq 3)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'status' added to chain 'user-delete' (order 7 index 0 seq 4)
Jun 26 10:19:03 shobspwklnprd01 jabberd/sm[28673]: module 'iq-last' added to chain 'user-delete' (order 8 index 1 seq 3)
Jun 26 10:19:04 shobspwklnprd01 jabberd/sm[28673]: module 'iq-private' added to chain 'user-delete' (order 9 index 8 seq 1)
Jun 26 10:19:04 shobspwklnprd01 jabberd/sm[28673]: module 'iq-vcard' added to chain 'user-delete' (order 10 index 6 seq 2)
Jun 26 10:19:04 shobspwklnprd01 jabberd/sm[28673]: module 'iq-version' added to chain 'disco-extend' (order 0 index 17 seq 1)
Jun 26 10:19:04 shobspwklnprd01 jabberd/sm[28673]: module 'help' added to chain 'disco-extend' (order 1 index 18 seq 1)
Jun 26 10:19:04 shobspwklnprd01 jabberd/sm[28673]: version: jabberd sm 2.2.8
Jun 26 10:19:04 shobspwklnprd01 jabberd/sm[28673]: attempting connection to router at 127.0.0.1, port=5347
Jun 26 10:19:04 shobspwklnprd01 jabberd/router[28637]: [127.0.0.1, port=37275] connect
Jun 26 10:19:04 shobspwklnprd01 jabberd/router[28637]: [127.0.0.1, port=37275] authenticated as jabberd at jabberd-router<mailto:jabberd at jabberd-router>
Jun 26 10:19:04 shobspwklnprd01 jabberd/sm[28673]: connection to router established
Jun 26 10:19:04 shobspwklnprd01 jabberd/router[28637]: [localhost.localdomain] online (bound to 127.0.0.1, port 37275)
Jun 26 10:19:04 shobspwklnprd01 jabberd/sm[28673]: ready for sessions
then I see tons of these messages every 10 seconds

Jun 26 10:19:40 shobspwklnprd01 jabberd/c2s[28709]: [7] [10.129.1.220, port=52847] connect
Jun 26 10:19:40 shobspwklnprd01 jabberd/c2s[28709]: [7] [10.129.1.220, port=52847] disconnect jid=unbound, packets: 0
Jun 26 10:19:50 shobspwklnprd01 jabberd/c2s[28709]: [7] [10.129.1.220, port=52856] connect
Jun 26 10:19:50 shobspwklnprd01 jabberd/c2s[28709]: [7] [10.129.1.220, port=52856] disconnect jid=unbound, packets: 0
Jun 26 10:20:00 shobspwklnprd01 jabberd/c2s[28709]: [7] [10.129.1.220, port=52864] connect
Jun 26 10:20:00 shobspwklnprd01 jabberd/c2s[28709]: [7] [10.129.1.220, port=52864] disconnect jid=unbound, packets: 0
Jun 26 10:20:10 shobspwklnprd01 jabberd/c2s[28709]: [7] [10.129.1.220, port=52888] connect
Jun 26 10:20:10 shobspwklnprd01 jabberd/c2s[28709]: [7] [10.129.1.220, port=52888] disconnect jid=unbound, packets: 0
Jun 26 10:20:20 shobspwklnprd01 jabberd/c2s[28709]: [7] [10.129.1.220, port=52890] connect
Jun 26 10:20:20 shobspwklnprd01 jabberd/c2s[28709]: [7] [10.129.1.220, port=52890] disconnect jid=unbound, packets: 0

________________________________
From: spacewalk-list-bounces at redhat.com [mailto:spacewalk-list-bounces at redhat.com] On Behalf Of Wojtak, Greg
Sent: Wednesday, June 24, 2009 4:26 PM
To: spacewalk-list at redhat.com
Subject: [Spacewalk-list] RE: Osa-dispatcher error
I have seen it before.  I've been unable to resolve it, although launching the osad client with multiple -v options also seems to indicate an SSLError and that the user cannot login.

From: spacewalk-list-bounces at redhat.com [mailto:spacewalk-list-bounces at redhat.com] On Behalf Of Frey, Evan
Sent: Wednesday, June 24, 2009 4:17 PM
To: spacewalk-list at redhat.com
Subject: [Spacewalk-list] Osa-dispatcher error

Anyone see this error before?

Starting osa-dispatcher: --> <?xml version='1.0' encoding='UTF-8'?><stream:stream to='shobspwklnprd01.usi.mizuho-sc.com' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>

<-- <error><host-unknown xmlns = 'urn:ietf:params:xml:ns:xmpp-streams'  /><text xmlns = 'urn:ietf:params:xml:ns:xmpp-streams' >service requested for unknown domain</text></error>

RHN 3437 2009/06/24 16:15:36 -04:00: ('Server did not return a <features /> stanza',)
--> <presence type='unavailable' />
________________________________
CONFIDENTIAL: This e-mail, including its contents and attachments, if any, are confidential. It is neither an offer to buy or sell, nor a solicitation of an offer to buy or sell, any securities or any related financial instruments mentioned in it. If you are not the named recipient please notify the sender and immediately delete it. You may not disseminate, distribute, or forward this e-mail message or disclose its contents to anybody else. Unless otherwise indicated, copyright and any other intellectual property rights in its contents are the sole property of Mizuho Securities USA Inc.

E-mail transmission cannot be guaranteed to be secure or error-free. The sender therefore does not accept liability for any errors or omissions in the contents of this message which arise as a result of e-mail transmission. If verification is required please request a hard-copy version.

Although we routinely screen for viruses, addressees should check this e-mail and any attachments for viruses. We make no representation or warranty as to the absence of viruses in this e-mail or any attachments. Please note that to ensure regulatory compliance and for the protection of our customers and business, we may monitor and read e-mails sent to and from our server(s).

________________________________
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://listman.redhat.com/archives/spacewalk-list/attachments/20090626/25a4270a/attachment.htm>