-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1
- -------- Original Message -------- Subject: [Spacewalk-list] osa-dispatcher not running or not connecting From: Joshua Roys <joshua.r...@gtri.gatech.edu> To: spacewalk-list@redhat.com <spacewalk-list@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 >> >> [r...@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 [r...@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: [r...@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=jabb...@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@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----- _______________________________________________ Spacewalk-list mailing list Spacewalk-list@redhat.com https://www.redhat.com/mailman/listinfo/spacewalk-list