[Spacewalk-list] osa-dispatcher not running or not connecting

Tue Oct 20 12:52:43 UTC 2009

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

- -------- Original Message --------
Subject: [Spacewalk-list] osa-dispatcher not running or not connecting
From: Joshua Roys <joshua.roys at gtri.gatech.edu>
To: spacewalk-list at redhat.com <spacewalk-list at redhat.com>
Date: 10/20/2009 01:51 PM

> Hello,
> 
> On 10/20/2009 01:47 AM, Michiel van Es wrote:
>> 2009/10/20 07:43:00 +02:00 3866 0.0.0.0: osad/jabber_lib.__init__
>> 2009/10/20 07:43:00 +02:00 3866 0.0.0.0:
>> osad/jabber_lib.print_message('socket error',)
>> 2009/10/20 07:43:00 +02:00 3866 0.0.0.0:
>> osad/jabber_lib.print_message('Could not connect to jabber server',
>> 'devmx01.buro.info.nl')
>> 2009/10/20 07:43:00 +02:00 3866 0.0.0.0:
>> osad/jabber_lib.setup_connection('Could not connect to any jabber server',)
>> 2009/10/20 07:43:00 +02:00 3866 0.0.0.0: osad/jabber_lib.main('Unable to
>> connect to jabber servers, sleeping 10 seconds',)
>>
>> But when I run nc -vv devmx01 5222 it is working
>>
>> [root at devmx01 ~]# nc -vv devmx01 5222
>> Connection to devmx01 5222 port [tcp/xmpp-client] succeeded!
>>
>> All the logfiles in /var/lib/jabberd/log show no errors.
>>
>> How can I check what is going wrong?
>>
> 
> I think you had a similar problem about a month ago?  Has anything 
> significant changed between then and now?  (the thread name is "error 
> jabber after upgrade 0.5 => 0.6")  You could grep /var/log/yum.log for 
> osa and jabber, etc.
> 
> I would do something like:
> 
> # kill $(pidof c2s)
> # c2s -D
> 
> and then see what
> # service osa-dispatcher stop
> # osa-dispatcher -N -vvvvvvvvvvvv
> ...gives you.

On the client I get an unable to connect:

2009-10-20 14:46:59 jabber_lib.setup_connection: Connecting to
devmx01.buro.info.nl
2009-10-20 14:46:59 jabber_lib._get_jabber_client:
2009-10-20 14:46:59 jabber_lib._get_jabber_client: Connecting to
devmx01.buro.info.nl
2009-10-20 14:46:59 jabber_lib.__init__:
2009-10-20 14:46:59 jabber_lib.__init__:
2009-10-20 14:46:59 jabber_lib.check_cert: Loading cert <X509Name object
'/C=NL/ST=Noord Holland/L=Amsterdam/O=Info.nl
HF/OU=devmx01.buro.info.nl/CN=devmx01.buro.info.nl'>
2009-10-20 14:46:59 jabber_lib.connect:
2009-10-20 14:47:06 jabber_lib.process: 300
2009-10-20 14:50:36 jabber_lib.process: None
2009-10-20 14:50:58 jabber_lib.print_message: socket error
2009-10-20 14:50:58 jabber_lib.print_message: Could not connect to
jabber server devmx01.buro.info.nl
2009-10-20 14:50:58 jabber_lib.setup_connection: Could not connect to
any jabber server
2009-10-20 14:50:58 jabber_lib.main: Unable to connect to jabber
servers, sleeping 85 seconds
[root at devgnudb01 ~]# nc -vv devmx01.buro.info.nl 5222
Connection to devmx01.buro.info.nl 5222 port [tcp/xmpp-client] succeeded!

The osa-dispatcher is only showing:

[root at devmx01 jabberd]# osa-dispatcher -N -vvvvvvvv
- --> <?xml version='1.0' encoding='UTF-8'?><stream:stream
to='devmx01.buro.info.nl' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>

- --> <?xml version='1.0' encoding='UTF-8'?><stream:stream
to='devmx01.buro.info.nl' xmlns='jabber:client'
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>

c2s -D:

ue Oct 20 14:44:44 2009 c2s.c:735 98 bytes written
sx (client.c:103) 5 state change from 0 to 2
sx (client.c:105) stream header sent, waiting for reply
sx (io.c:383) tag 5 event 0 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:689 want read
Tue Oct 20 14:44:44 2009 c2s.c:1301 read action on fd 5
sx (io.c:191) 5 ready for reading
sx (io.c:197) tag 5 event 2 data 0x8a3d1b0
Tue Oct 20 14:44:44 2009 c2s.c:699 reading from 5
Tue Oct 20 14:44:44 2009 c2s.c:724 read 326 bytes
sx (io.c:216) passed 326 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (326 bytes): <?xml
version='1.0'?><stream:stream
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'
id='yuivbsh9qr8zocahq8wrjiegzggf2tx1ofx08e9y'><stream:features
xmlns:stream='http://etherx.jabber.org/streams'><mechanisms
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>DIGEST-MD5</mechanism></mechanisms></stream:features>
sx (client.c:70) stream response: to (null) from (null) version 1.0 id
yuivbsh9qr8zocahq8wrjiegzggf2tx1ofx08e9y
sx (client.c:85) 5 state change from 2 to 3
sx (client.c:86) tag 5 event 4 data 0x0
sx (io.c:92) completed nad: <stream:features
xmlns:stream='http://etherx.jabber.org/streams'><mechanisms
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>DIGEST-MD5</mechanism></mechanisms></stream:features>
sx (chain.c:119) calling nad read chain
sx (io.c:156) tag 5 event 6 data 0x8a3df28
sx (sasl_gsasl.c:960) sending auth request to server, mech 'DIGEST-MD5':
sx (chain.c:106) calling nad write chain
sx (io.c:406) queueing for write: <auth
xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='DIGEST-MD5'/>
sx (io.c:429) tag 5 event 1 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:694 want write
Tue Oct 20 14:44:44 2009 c2s.c:1312 write action on fd 5
sx (io.c:328) 5 ready for writing
sx (io.c:286) encoding 71 bytes for writing: <auth
xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='DIGEST-MD5'/>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 71 bytes to write
sx (io.c:350) tag 5 event 3 data 0x8a3e048
Tue Oct 20 14:44:44 2009 c2s.c:731 writing to 5
Tue Oct 20 14:44:44 2009 c2s.c:735 71 bytes written
sx (io.c:383) tag 5 event 0 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:689 want read
sx (io.c:431) tag 5 event 0 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:689 want read
Tue Oct 20 14:44:44 2009 c2s.c:1301 read action on fd 5
sx (io.c:191) 5 ready for reading
sx (io.c:197) tag 5 event 2 data 0x8a3d708
Tue Oct 20 14:44:44 2009 c2s.c:699 reading from 5
Tue Oct 20 14:44:44 2009 c2s.c:724 read 204 bytes
sx (io.c:216) passed 204 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (204 bytes): <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cmVhbG09ImphYmJlcmQtcm91dGVyIiwgbm9uY2U9Ikp5ZUNuMER0U28zVm9DenJScG9oTWc9PSIsIHFvcD0iYXV0aCIsIGNoYXJzZXQ9dXRmLTgsIGFsZ29yaXRobT1tZDUtc2Vzcw==</challenge>
sx (io.c:92) completed nad: <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cmVhbG09ImphYmJlcmQtcm91dGVyIiwgbm9uY2U9Ikp5ZUNuMER0U28zVm9DenJScG9oTWc9PSIsIHFvcD0iYXV0aCIsIGNoYXJzZXQ9dXRmLTgsIGFsZ29yaXRobT1tZDUtc2Vzcw==</challenge>
sx (chain.c:119) calling nad read chain
sx (sasl_gsasl.c:564) data from client
sx (sasl_gsasl.c:570) decoded data: realm="jabberd-router",
nonce="JyeCn0DtSo3VoCzrRpohMg==", qop="auth", charset=utf-8,
algorithm=md5-sess
sx (sasl_gsasl.c:766) in _sx_sasl_gsasl_callback, property: 2
sx (sasl_gsasl.c:766) in _sx_sasl_gsasl_callback, property: 11
sx (sasl_gsasl.c:578) sasl handshake in progress (response:
username="jabberd", realm="jabberd-router",
nonce="JyeCn0DtSo3VoCzrRpohMg==", cnonce="jnKXN6QEbhsMSq7lOPQRyg==",
nc=00000001, qop=auth, digest-uri="jabberd-router/devmx01.buro.info.nl",
response=c7c3b168bef18b493a7885252cf61be7, charset=utf-8)
sx (chain.c:106) calling nad write chain
sx (io.c:406) queueing for write: <response
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>dXNlcm5hbWU9ImphYmJlcmQiLCByZWFsbT0iamFiYmVyZC1yb3V0ZXIiLCBub25jZT0iSnllQ24wRHRTbzNWb0N6clJwb2hNZz09IiwgY25vbmNlPSJqbktYTjZRRWJoc01TcTdsT1BRUnlnPT0iLCBuYz0wMDAwMDAwMSwgcW9wPWF1dGgsIGRpZ2VzdC11cmk9ImphYmJlcmQtcm91dGVyL2Rldm14MDEuYnVyby5pbmZvLm5sIiwgcmVzcG9uc2U9YzdjM2IxNjhiZWYxOGI0OTNhNzg4NTI1MmNmNjFiZTcsIGNoYXJzZXQ9dXRmLTg=</response>
sx (io.c:256) tag 5 event 1 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:694 want write
Tue Oct 20 14:44:44 2009 c2s.c:1312 write action on fd 5
sx (io.c:328) 5 ready for writing
sx (io.c:286) encoding 386 bytes for writing: <response
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>dXNlcm5hbWU9ImphYmJlcmQiLCByZWFsbT0iamFiYmVyZC1yb3V0ZXIiLCBub25jZT0iSnllQ24wRHRTbzNWb0N6clJwb2hNZz09IiwgY25vbmNlPSJqbktYTjZRRWJoc01TcTdsT1BRUnlnPT0iLCBuYz0wMDAwMDAwMSwgcW9wPWF1dGgsIGRpZ2VzdC11cmk9ImphYmJlcmQtcm91dGVyL2Rldm14MDEuYnVyby5pbmZvLm5sIiwgcmVzcG9uc2U9YzdjM2IxNjhiZWYxOGI0OTNhNzg4NTI1MmNmNjFiZTcsIGNoYXJzZXQ9dXRmLTg=</response>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 386 bytes to write
sx (io.c:350) tag 5 event 3 data 0x8a3e170
Tue Oct 20 14:44:44 2009 c2s.c:731 writing to 5
Tue Oct 20 14:44:44 2009 c2s.c:735 386 bytes written
sx (io.c:383) tag 5 event 0 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:689 want read
Tue Oct 20 14:44:44 2009 c2s.c:1301 read action on fd 5
sx (io.c:191) 5 ready for reading
sx (io.c:197) tag 5 event 2 data 0x8a3e8b8
Tue Oct 20 14:44:44 2009 c2s.c:699 reading from 5
Tue Oct 20 14:44:44 2009 c2s.c:724 read 120 bytes
sx (io.c:216) passed 120 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (120 bytes): <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cnNwYXV0aD1hYzU2MDdhNDI2MDE3YTExNTZkNDZlYTY4MjgxM2JjOQ==</challenge>
sx (io.c:92) completed nad: <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cnNwYXV0aD1hYzU2MDdhNDI2MDE3YTExNTZkNDZlYTY4MjgxM2JjOQ==</challenge>
sx (chain.c:119) calling nad read chain
sx (sasl_gsasl.c:564) data from client
sx (sasl_gsasl.c:570) decoded data: rspauth=ac5607a426017a1156d46ea682813bc9
sx (sasl_gsasl.c:578) sasl handshake in progress (response: )
sx (chain.c:106) calling nad write chain
sx (io.c:406) queueing for write: <response
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
sx (io.c:256) tag 5 event 1 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:694 want write
Tue Oct 20 14:44:44 2009 c2s.c:1312 write action on fd 5
sx (io.c:328) 5 ready for writing
sx (io.c:286) encoding 52 bytes for writing: <response
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 52 bytes to write
sx (io.c:350) tag 5 event 3 data 0x8a3e858
Tue Oct 20 14:44:44 2009 c2s.c:731 writing to 5
Tue Oct 20 14:44:44 2009 c2s.c:735 52 bytes written
sx (io.c:383) tag 5 event 0 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:689 want read
Tue Oct 20 14:44:44 2009 c2s.c:1301 read action on fd 5
sx (io.c:191) 5 ready for reading
sx (io.c:197) tag 5 event 2 data 0x8a3e858
Tue Oct 20 14:44:44 2009 c2s.c:699 reading from 5
Tue Oct 20 14:44:44 2009 c2s.c:724 read 51 bytes
sx (io.c:216) passed 51 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (51 bytes): <success
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
sx (io.c:92) completed nad: <success
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
sx (chain.c:119) calling nad read chain
sx (sasl_gsasl.c:696) sasl handshake completed, resetting
sx (sx.c:144) resetting stream state
sx (sx.c:68) freeing sx for 5
sx (sx.c:51) allocated new sx for 5
sx (sx.c:199) finished resetting stream state
sx (sasl_gsasl.c:711) restarting stream with sasl layer established
sx (client.c:122) doing client init for sx 5
sx (client.c:138) stream request: ns (null) to (null) from (null)
version 1.0
sx (client.c:168) prepared stream header: <?xml
version='1.0'?><stream:stream
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
sx (client.c:175) tag 5 event 1 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:694 want write
Tue Oct 20 14:44:44 2009 c2s.c:1312 write action on fd 5
sx (io.c:328) 5 ready for writing
sx (io.c:286) encoding 98 bytes for writing: <?xml
version='1.0'?><stream:stream
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 98 bytes to write
sx (io.c:350) tag 5 event 3 data 0x8a3df50
Tue Oct 20 14:44:44 2009 c2s.c:731 writing to 5
Tue Oct 20 14:44:44 2009 c2s.c:735 98 bytes written
sx (client.c:103) 5 state change from 0 to 2
sx (client.c:105) stream header sent, waiting for reply
sx (io.c:383) tag 5 event 0 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:689 want read
Tue Oct 20 14:44:44 2009 c2s.c:1301 read action on fd 5
sx (io.c:191) 5 ready for reading
sx (io.c:197) tag 5 event 2 data 0x8a3df50
Tue Oct 20 14:44:44 2009 c2s.c:699 reading from 5
Tue Oct 20 14:44:44 2009 c2s.c:724 read 144 bytes
sx (io.c:216) passed 144 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (144 bytes): <?xml
version='1.0'?><stream:stream
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'
id='6ixuu8clh8c1qqip7y59s3agj8hrmqqs8on3wzod'>
sx (client.c:70) stream response: to (null) from (null) version 1.0 id
6ixuu8clh8c1qqip7y59s3agj8hrmqqs8on3wzod
sx (sx.c:130) authenticating stream (method=SASL/DIGEST-MD5;
id=jabberd at jabberd-router)
sx (sx.c:135) 5 state change from 2 to 4
sx (sx.c:136) tag 5 event 5 data 0x0
Tue Oct 20 14:44:44 2009 [notice] connection to router established
Tue Oct 20 14:44:44 2009 c2s.c:771 requesting component bind for 'c2s'
sx (chain.c:106) calling nad write chain
sx (io.c:406) queueing for write: <bind
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0' name='c2s'/>
sx (io.c:429) tag 5 event 1 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:694 want write
Tue Oct 20 14:44:44 2009 c2s.c:1312 write action on fd 5
sx (io.c:328) 5 ready for writing
sx (io.c:286) encoding 75 bytes for writing: <bind
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0' name='c2s'/>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 75 bytes to write
sx (io.c:350) tag 5 event 3 data 0x8a3d5b8
Tue Oct 20 14:44:44 2009 c2s.c:731 writing to 5
Tue Oct 20 14:44:44 2009 c2s.c:735 75 bytes written
sx (io.c:383) tag 5 event 0 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:689 want read
sx (io.c:431) tag 5 event 0 data 0x0
Tue Oct 20 14:44:44 2009 c2s.c:689 want read
Tue Oct 20 14:44:44 2009 c2s.c:1301 read action on fd 5
sx (io.c:191) 5 ready for reading
sx (io.c:197) tag 5 event 2 data 0x8a3df38
Tue Oct 20 14:44:44 2009 c2s.c:699 reading from 5
Tue Oct 20 14:44:44 2009 c2s.c:724 read 66 bytes
sx (io.c:216) passed 66 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (66 bytes): <stream:features
xmlns:stream='http://etherx.jabber.org/streams'/>
sx (io.c:92) completed nad: <stream:features
xmlns:stream='http://etherx.jabber.org/streams'/>
sx (chain.c:119) calling nad read chain
sx (io.c:156) tag 5 event 6 data 0x8a3d4c0
Tue Oct 20 14:44:44 2009 c2s.c:824 got a packet from router, but we're
not online, dropping
Tue Oct 20 14:44:44 2009 c2s.c:1301 read action on fd 5
sx (io.c:191) 5 ready for reading
sx (io.c:197) tag 5 event 2 data 0x8a3d410
Tue Oct 20 14:44:44 2009 c2s.c:699 reading from 5
Tue Oct 20 14:44:44 2009 c2s.c:724 read 495 bytes
sx (io.c:216) passed 495 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (495 bytes): <bind
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'/><presence
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'
from='s2s'/><presence
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'
from='devmx01.buro.info.nl'/><route
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0' to='c2s'
from='devmx01.buro.info.nl'><iq xmlns='jabber:client' type='get'
to='c2s' from='devmx01.buro.info.nl' id='alrkbvc3'><query
xmlns='http://jabber.org/protocol/disco#info'/></iq></route>
sx (io.c:92) completed nad: <bind
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'/>
sx (chain.c:119) calling nad read chain
sx (io.c:156) tag 5 event 6 data 0x8a34468
Tue Oct 20 14:44:44 2009 c2s.c:836 coming online
Tue Oct 20 14:44:44 2009 [notice] [0.0.0.0, port=5222] listening for
connections
Tue Oct 20 14:44:44 2009 [notice] ready for connections
sx (io.c:92) completed nad: <presence
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0' from='s2s'/>
sx (chain.c:119) calling nad read chain
sx (io.c:156) tag 5 event 6 data 0x8a3ce28
Tue Oct 20 14:44:44 2009 c2s.c:640 component available from 's2s'
Tue Oct 20 14:44:44 2009 c2s.c:642 sm for serviced domain 's2s' online
sx (io.c:92) completed nad: <presence
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'
from='devmx01.buro.info.nl'/>
sx (chain.c:119) calling nad read chain
sx (io.c:156) tag 5 event 6 data 0x8a3e8e0
Tue Oct 20 14:44:44 2009 c2s.c:640 component available from
'devmx01.buro.info.nl'
Tue Oct 20 14:44:44 2009 c2s.c:642 sm for serviced domain
'devmx01.buro.info.nl' online
sx (io.c:92) completed nad: <route
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'
from='devmx01.buro.info.nl' to='c2s'><iq xmlns='jabber:client'
id='alrkbvc3' from='devmx01.buro.info.nl' to='c2s' type='get'><query
xmlns='http://jabber.org/protocol/disco#info'/></iq></route>
sx (chain.c:119) calling nad read chain
sx (io.c:156) tag 5 event 6 data 0x8a3ec50
Tue Oct 20 14:44:44 2009 c2s.c:919 not a c2s packet, dropping
Tue Oct 20 14:44:45 2009 c2s.c:562 accept action on fd 7
Tue Oct 20 14:44:45 2009 [notice] [7] [172.22.0.37, port=42796] connect
sx (sx.c:51) allocated new sx for 7
sx (server.c:236) doing server init for sx 7
sx (server.c:251) waiting for stream header
sx (server.c:254) tag 7 event 0 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:35 want read
Tue Oct 20 14:44:45 2009 c2s.c:525 read action on fd 7
sx (io.c:191) 7 ready for reading
sx (io.c:197) tag 7 event 2 data 0x8a3eb98
Tue Oct 20 14:44:45 2009 c2s.c:45 reading from 7
Tue Oct 20 14:44:45 2009 c2s.c:104 read 163 bytes
sx (io.c:216) passed 163 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (163 bytes): <?xml version='1.0'
encoding='UTF-8'?><stream:stream to='devmx01.buro.info.nl'
xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'
version='1.0'>
sx (server.c:118) stream request: to devmx01.buro.info.nl from (null)
version 1.0
sx (server.c:133) 7 state change from 0 to 1
sx (server.c:151) stream id is acv2qn3hf9ct7iwt94yn7675iktol8fvlayby1se
sx (server.c:181) prepared stream response: <?xml
version='1.0'?><stream:stream
xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client'
from='devmx01.buro.info.nl' version='1.0'
id='acv2qn3hf9ct7iwt94yn7675iktol8fvlayby1se'>
sx (io.c:256) tag 7 event 1 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:40 want write
Tue Oct 20 14:44:45 2009 c2s.c:539 write action on fd 7
sx (io.c:328) 7 ready for writing
sx (io.c:286) encoding 194 bytes for writing: <?xml
version='1.0'?><stream:stream
xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client'
from='devmx01.buro.info.nl' version='1.0'
id='acv2qn3hf9ct7iwt94yn7675iktol8fvlayby1se'>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 194 bytes to write
sx (io.c:350) tag 7 event 3 data 0x8a3d480
Tue Oct 20 14:44:45 2009 c2s.c:142 writing to 7
Tue Oct 20 14:44:45 2009 c2s.c:146 194 bytes written
sx (server.c:29) stream established
sx (server.c:39) 7 state change from 1 to 3
sx (server.c:40) tag 7 event 4 data 0x0
sx (server.c:45) building features nad
sx (sasl_gsasl.c:350) ssl not established yet but the app requires it,
not offering mechanisms
Tue Oct 20 14:44:45 2009 bind.c:38 not auth'd, offering auth and register
sx (ssl.c:194) offering starttls
sx (io.c:383) tag 7 event 0 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:35 want read
Tue Oct 20 14:44:45 2009 c2s.c:539 write action on fd 7
sx (io.c:328) 7 ready for writing
sx (io.c:286) encoding 263 bytes for writing: <stream:features
xmlns:stream='http://etherx.jabber.org/streams'><auth
xmlns='http://jabber.org/features/iq-auth'/><register
xmlns='http://jabber.org/features/iq-register'/><starttls
xmlns='urn:ietf:params:xml:ns:xmpp-tls'><required/></starttls></stream:features>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 263 bytes to write
sx (io.c:350) tag 7 event 3 data 0x8a3d480
Tue Oct 20 14:44:45 2009 c2s.c:142 writing to 7
Tue Oct 20 14:44:45 2009 c2s.c:146 263 bytes written
sx (io.c:383) tag 7 event 0 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:35 want read
Tue Oct 20 14:44:45 2009 c2s.c:525 read action on fd 7
sx (io.c:191) 7 ready for reading
sx (io.c:197) tag 7 event 2 data 0x8a3d480
Tue Oct 20 14:44:45 2009 c2s.c:45 reading from 7
Tue Oct 20 14:44:45 2009 c2s.c:104 read 52 bytes
sx (io.c:216) passed 52 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (52 bytes): <starttls
xmlns='urn:ietf:params:xml:ns:xmpp-tls' />
sx (io.c:92) completed nad: <starttls
xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
sx (chain.c:119) calling nad read chain
sx (ssl.c:118) starttls requested, setting up
sx (io.c:256) tag 7 event 1 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:40 want write
Tue Oct 20 14:44:45 2009 c2s.c:539 write action on fd 7
sx (io.c:328) 7 ready for writing
sx (io.c:286) encoding 50 bytes for writing: <proceed
xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 50 bytes to write
sx (io.c:350) tag 7 event 3 data 0x8a40468
Tue Oct 20 14:44:45 2009 c2s.c:142 writing to 7
Tue Oct 20 14:44:45 2009 c2s.c:146 50 bytes written
sx (ssl.c:71) preparing for starttls
sx (sx.c:144) resetting stream state
sx (sx.c:68) freeing sx for 7
sx (sx.c:51) allocated new sx for 7
sx (sx.c:199) finished resetting stream state
sx (server.c:236) doing server init for sx 7
sx (ssl.c:595) preparing for ssl accept for 7 to devmx01.buro.info.nl
sx (ssl.c:600) using default ssl context for 7
sx (chain.c:28) adding io plugin
sx (server.c:251) waiting for stream header
sx (server.c:254) tag 7 event 0 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:35 want read
sx (io.c:383) tag 7 event 0 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:35 want read
Tue Oct 20 14:44:45 2009 c2s.c:525 read action on fd 7
sx (io.c:191) 7 ready for reading
sx (io.c:197) tag 7 event 2 data 0x8a40468
Tue Oct 20 14:44:45 2009 c2s.c:45 reading from 7
Tue Oct 20 14:44:45 2009 c2s.c:104 read 87 bytes
sx (io.c:216) passed 87 read bytes
sx (chain.c:93) calling io read chain
sx (ssl.c:380) in _sx_ssl_rio
sx (ssl.c:384) loading 87 bytes into ssl read buffer
sx (ssl.c:210) secure channel not established, handshake in progress
sx (ssl.c:210) secure channel not established, handshake in progress
sx (io.c:234) tag 7 event 1 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:40 want write
Tue Oct 20 14:44:45 2009 c2s.c:539 write action on fd 7
sx (io.c:328) 7 ready for writing
sx (io.c:286) encoding 0 bytes for writing:
sx (chain.c:79) calling io write chain
sx (ssl.c:278) in _sx_ssl_wio
sx (ssl.c:210) secure channel not established, handshake in progress
sx (ssl.c:360) prepared 1403 ssl bytes for write
sx (io.c:349) handing app 1403 bytes to write
sx (io.c:350) tag 7 event 3 data 0x8a40468
Tue Oct 20 14:44:45 2009 c2s.c:142 writing to 7
Tue Oct 20 14:44:45 2009 c2s.c:146 1403 bytes written
sx (io.c:383) tag 7 event 0 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:35 want read
Tue Oct 20 14:44:45 2009 c2s.c:525 read action on fd 7
sx (io.c:191) 7 ready for reading
sx (io.c:197) tag 7 event 2 data 0x8a40468
Tue Oct 20 14:44:45 2009 c2s.c:45 reading from 7
Tue Oct 20 14:44:45 2009 c2s.c:104 read 326 bytes
sx (io.c:216) passed 326 read bytes
sx (chain.c:93) calling io read chain
sx (ssl.c:380) in _sx_ssl_rio
sx (ssl.c:384) loading 326 bytes into ssl read buffer
sx (ssl.c:210) secure channel not established, handshake in progress
sx (ssl.c:224) secure channel established
sx (ssl.c:229) using cipher AES256-SHA (256 bits)
sx (io.c:234) tag 7 event 1 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:40 want write
Tue Oct 20 14:44:45 2009 c2s.c:539 write action on fd 7
sx (io.c:328) 7 ready for writing
sx (io.c:286) encoding 0 bytes for writing:
sx (chain.c:79) calling io write chain
sx (ssl.c:278) in _sx_ssl_wio
sx (ssl.c:360) prepared 59 ssl bytes for write
sx (io.c:349) handing app 59 bytes to write
sx (io.c:350) tag 7 event 3 data 0x8a40468
Tue Oct 20 14:44:45 2009 c2s.c:142 writing to 7
Tue Oct 20 14:44:45 2009 c2s.c:146 59 bytes written
sx (io.c:383) tag 7 event 0 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:35 want read
Tue Oct 20 14:44:45 2009 c2s.c:525 read action on fd 7
sx (io.c:191) 7 ready for reading
sx (io.c:197) tag 7 event 2 data 0x8a40468
Tue Oct 20 14:44:45 2009 c2s.c:45 reading from 7
Tue Oct 20 14:44:45 2009 c2s.c:104 read 165 bytes
sx (io.c:216) passed 165 read bytes
sx (chain.c:93) calling io read chain
sx (ssl.c:380) in _sx_ssl_rio
sx (ssl.c:384) loading 165 bytes into ssl read buffer
sx (io.c:240) decoded read data (163 bytes): <?xml version='1.0'
encoding='UTF-8'?><stream:stream to='devmx01.buro.info.nl'
xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'
version='1.0'>
sx (server.c:118) stream request: to devmx01.buro.info.nl from (null)
version 1.0
sx (server.c:133) 7 state change from 0 to 1
sx (server.c:151) stream id is a57hn4bw99kgfsmycfmyo2u9dskcudq4iym52x2c
sx (server.c:181) prepared stream response: <?xml
version='1.0'?><stream:stream
xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client'
from='devmx01.buro.info.nl' version='1.0'
id='a57hn4bw99kgfsmycfmyo2u9dskcudq4iym52x2c'>
sx (io.c:256) tag 7 event 1 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:40 want write
Tue Oct 20 14:44:45 2009 c2s.c:539 write action on fd 7
sx (io.c:328) 7 ready for writing
sx (io.c:286) encoding 194 bytes for writing: <?xml
version='1.0'?><stream:stream
xmlns:stream='http://etherx.jabber.org/streams' xmlns='jabber:client'
from='devmx01.buro.info.nl' version='1.0'
id='a57hn4bw99kgfsmycfmyo2u9dskcudq4iym52x2c'>
sx (chain.c:79) calling io write chain
sx (ssl.c:278) in _sx_ssl_wio
sx (ssl.c:282) queueing buffer for write
sx (ssl.c:298) preparing queued buffer for write
sx (ssl.c:360) prepared 218 ssl bytes for write
sx (io.c:349) handing app 218 bytes to write
sx (io.c:350) tag 7 event 3 data 0x8a401b8
Tue Oct 20 14:44:45 2009 c2s.c:142 writing to 7
Tue Oct 20 14:44:45 2009 c2s.c:146 218 bytes written
sx (server.c:29) stream established
sx (server.c:39) 7 state change from 1 to 3
sx (server.c:40) tag 7 event 4 data 0x0
sx (server.c:45) building features nad
sx (sasl_gsasl.c:355) offering sasl mechanisms
sx (sasl_gsasl.c:766) in _sx_sasl_gsasl_callback, property: 5
sx (sasl_gsasl.c:766) in _sx_sasl_gsasl_callback, property: 6
sx (sasl_gsasl.c:375) offering mechanism: PLAIN
sx (sasl_gsasl.c:375) offering mechanism: DIGEST-MD5
Tue Oct 20 14:44:45 2009 bind.c:38 not auth'd, offering auth and register
sx (io.c:383) tag 7 event 0 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:35 want read
Tue Oct 20 14:44:45 2009 c2s.c:539 write action on fd 7
sx (io.c:328) 7 ready for writing
sx (io.c:286) encoding 318 bytes for writing: <stream:features
xmlns:stream='http://etherx.jabber.org/streams'><mechanisms
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'><mechanism>PLAIN</mechanism><mechanism>DIGEST-MD5</mechanism></mechanisms><auth
xmlns='http://jabber.org/features/iq-auth'/><register
xmlns='http://jabber.org/features/iq-register'/></stream:features>
sx (chain.c:79) calling io write chain
sx (ssl.c:278) in _sx_ssl_wio
sx (ssl.c:282) queueing buffer for write
sx (ssl.c:298) preparing queued buffer for write
sx (ssl.c:360) prepared 202 ssl bytes for write
sx (io.c:349) handing app 202 bytes to write
sx (io.c:350) tag 7 event 3 data 0x8a401b8
Tue Oct 20 14:44:45 2009 c2s.c:142 writing to 7
Tue Oct 20 14:44:45 2009 c2s.c:146 202 bytes written
sx (io.c:383) tag 7 event 0 data 0x0
Tue Oct 20 14:44:45 2009 c2s.c:35 want read
Tue Oct 20 14:44:45 2009 c2s.c:525 read action on fd 7
sx (io.c:191) 7 ready for reading
sx (io.c:197) tag 7 event 2 data 0x8a401b8
Tue Oct 20 14:44:45 2009 c2s.c:45 reading from 7
Tue Oct 20 14:44:45 2009 c2s.c:104 read 133 bytes
sx (io.c:216) passed 133 read bytes
sx (chain.c:93) calling io read chain
sx (ssl.c:380) in _sx_ssl_rio
sx (ssl.c:384) loading 133 bytes into ssl read buffer
sx (io.c:240) decoded read data (120 bytes): <iq type='get'
id='auth-get-74e0a4-0'><query xmlns = 'jabber:iq:auth'
><username>osad-da05225b95</username></query></iq>
sx (io.c:92) completed nad: <iq xmlns='jabber:client'
id='auth-get-74e0a4-0' type='get'><query
xmlns='jabber:iq:auth'><username>osad-da05225b95</username></query></iq>
sx (chain.c:119) calling nad read chain
sx (io.c:156) tag 7 event 6 data 0x8a401e0
Tue Oct 20 14:44:45 2009 authreg.c:661 auth get
Tue Oct 20 14:44:45 2009 authreg_db.c:105 fetching auth creds for user
'osad-da05225b95' realm ''
Tue Oct 20 14:44:45 2009 authreg_db.c:70 creating new db handle for realm ''

> 
> Hope to help,
> 
> Josh
> 
> _______________________________________________
> Spacewalk-list mailing list
> Spacewalk-list at redhat.com
> https://www.redhat.com/mailman/listinfo/spacewalk-list
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.9 (GNU/Linux)
Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org

iEYEARECAAYFAkrdspsACgkQSU+5fmlaNkO8jACeK0KEdfXfu6DvhqArAubbcmOE
fKcAnRkWSn2SeyADfUtAft+7jnv5lM+I
=RIvS
-----END PGP SIGNATURE-----