[Spacewalk-list] error jabber after upgrade 0.5 => 0.6

Thu Sep 10 19:30:34 UTC 2009

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

- -------- Original Message --------
Subject: [Spacewalk-list] error jabber after upgrade 0.5 => 0.6
From: Joshua Roys <joshua.roys at gtri.gatech.edu>
To: spacewalk-list at redhat.com <spacewalk-list at redhat.com>
Date: 9/10/2009 9:10 PM

> On 09/10/2009 03:03 PM, Michiel van Es wrote:
>> Hi Joshua,
>>
>> I still got some errors but I can not find out what, is there a way to
>> log or debug the modules (router, s2s etc.) seperatly..
>> I also found out that when I do an service jabberd restart that s2s
>> isn't correctly stopping (always a red FAILED).
>> So my guess is that at least that config file is wrong.
>> Can I post the config here?
>>
>> Thanks and kind regards,
>>
>> Michiel
>>
> 
> Michiel,
> 
> Yes, service jabberd stop, and then, in 4 terminals:
> # router -D
> # sm -D
> # c2s -D
> # s2s -D
======================================================================================

[root at devmx01 ~]# sm -D
Thu Sep 10 21:22:51 2009 [notice] starting up
Thu Sep 10 21:22:51 2009 [notice] id: devmx01.buro.info.nl
Thu Sep 10 21:22:51 2009 [info] process id is 15647, written to
/var/lib/jabberd/pid/sm.pid
Thu Sep 10 21:22:51 2009 storage.c:94 adding arbitrary types to driver 'db'
Thu Sep 10 21:22:51 2009 storage.c:117 driver not loaded, trying to init
Thu Sep 10 21:22:51 2009 [info] loading 'db' storage module
Thu Sep 10 21:22:51 2009 storage.c:139 preloaded module 'db' (not
initialized yet)
Thu Sep 10 21:22:51 2009 storage.c:158 calling driver initializer

[root at devmx01 ~]# c2s -D
Thu Sep 10 21:23:03 2009 [notice] starting up
Thu Sep 10 21:23:03 2009 [info] process id is 15701, written to
/var/lib/jabberd/pid/c2s.pid
Thu Sep 10 21:23:03 2009 [notice] modules search path: /usr/lib/jabberd/
Thu Sep 10 21:23:03 2009 [info] loading 'db' authreg module
Thu Sep 10 21:23:03 2009 authreg.c:73 preloaded module 'db' (not
initialized yet)

sx (client.c:70) stream response: to (null) from (null) version 1.0 id
li9a5d0r7y3iof5gck79uvws61xkyuhjdrtj5tac
sx (client.c:85) 6 state change from 2 to 3
sx (client.c:86) tag 6 event 4 data 0x0
Thu Sep 10 21:23:26 2009 router.c:288 read action on fd 6
sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x8a40ec0
Thu Sep 10 21:23:26 2009 router.c:44 reading from 6
Thu Sep 10 21:23:26 2009 router.c:69 read 182 bytes
sx (io.c:216) passed 182 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (182 bytes): <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 (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 6 event 6 data 0x8a41318
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 6 event 1 data 0x0
Thu Sep 10 21:23:26 2009 router.c:39 want write
Thu Sep 10 21:23:26 2009 router.c:299 write action on fd 6
sx (io.c:328) 6 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 6 event 3 data 0x8a41210
Thu Sep 10 21:23:26 2009 router.c:76 writing to 6
Thu Sep 10 21:23:26 2009 router.c:80 71 bytes written
sx (io.c:383) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:34 want read
sx (io.c:431) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:34 want read
Thu Sep 10 21:23:26 2009 router.c:288 read action on fd 6
sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x8a41210
Thu Sep 10 21:23:26 2009 router.c:44 reading from 6
Thu Sep 10 21:23:26 2009 router.c:69 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'>cmVhbG09ImphYmJlcmQtcm91dGVyIiwgbm9uY2U9Ik5ydGgvblRCZUlHQjhvM3haUGNIZnc9PSIsIHFvcD0iYXV0aCIsIGNoYXJzZXQ9dXRmLTgsIGFsZ29yaXRobT1tZDUtc2Vzcw==</challenge>
sx (io.c:92) completed nad: <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cmVhbG09ImphYmJlcmQtcm91dGVyIiwgbm9uY2U9Ik5ydGgvblRCZUlHQjhvM3haUGNIZnc9PSIsIHFvcD0iYXV0aCIsIGNoYXJzZXQ9dXRmLTgsIGFsZ29yaXRobT1tZDUtc2Vzcw==</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="Nrth/nTBeIGB8o3xZPcHfw==", 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="Nrth/nTBeIGB8o3xZPcHfw==", cnonce="2+k1qNi8xNovnXq5LBScaw==",
nc=00000001, qop=auth, digest-uri="jabberd-router/devmx01.buro.info.nl",
response=9fd8804d21560bdfb30ab9a52c611ccc, 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'>dXNlcm5hbWU9ImphYmJlcmQiLCByZWFsbT0iamFiYmVyZC1yb3V0ZXIiLCBub25jZT0iTnJ0aC9uVEJlSUdCOG8zeFpQY0hmdz09IiwgY25vbmNlPSIyK2sxcU5pOHhOb3ZuWHE1TEJTY2F3PT0iLCBuYz0wMDAwMDAwMSwgcW9wPWF1dGgsIGRpZ2VzdC11cmk9ImphYmJlcmQtcm91dGVyL2Rldm14MDEuYnVyby5pbmZvLm5sIiwgcmVzcG9uc2U9OWZkODgwNGQyMTU2MGJkZmIzMGFiOWE1MmM2MTFjY2MsIGNoYXJzZXQ9dXRmLTg=</response>
sx (io.c:256) tag 6 event 1 data 0x0
Thu Sep 10 21:23:26 2009 router.c:39 want write
Thu Sep 10 21:23:26 2009 router.c:299 write action on fd 6
sx (io.c:328) 6 ready for writing
sx (io.c:286) encoding 386 bytes for writing: <response
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>dXNlcm5hbWU9ImphYmJlcmQiLCByZWFsbT0iamFiYmVyZC1yb3V0ZXIiLCBub25jZT0iTnJ0aC9uVEJlSUdCOG8zeFpQY0hmdz09IiwgY25vbmNlPSIyK2sxcU5pOHhOb3ZuWHE1TEJTY2F3PT0iLCBuYz0wMDAwMDAwMSwgcW9wPWF1dGgsIGRpZ2VzdC11cmk9ImphYmJlcmQtcm91dGVyL2Rldm14MDEuYnVyby5pbmZvLm5sIiwgcmVzcG9uc2U9OWZkODgwNGQyMTU2MGJkZmIzMGFiOWE1MmM2MTFjY2MsIGNoYXJzZXQ9dXRmLTg=</response>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 386 bytes to write
sx (io.c:350) tag 6 event 3 data 0x8a413c8
Thu Sep 10 21:23:26 2009 router.c:76 writing to 6
Thu Sep 10 21:23:26 2009 router.c:80 386 bytes written
sx (io.c:383) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:34 want read
Thu Sep 10 21:23:26 2009 router.c:288 read action on fd 6
sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x8a5c3a8
Thu Sep 10 21:23:26 2009 router.c:44 reading from 6
Thu Sep 10 21:23:26 2009 router.c:69 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'>cnNwYXV0aD1lZjNlNjQ3NjAxNDliYzJlZDMyY2JlYjVlNTEyNzg4Zg==</challenge>
sx (io.c:92) completed nad: <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cnNwYXV0aD1lZjNlNjQ3NjAxNDliYzJlZDMyY2JlYjVlNTEyNzg4Zg==</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=ef3e64760149bc2ed32cbeb5e512788f
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 6 event 1 data 0x0
Thu Sep 10 21:23:26 2009 router.c:39 want write
Thu Sep 10 21:23:26 2009 router.c:299 write action on fd 6
sx (io.c:328) 6 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 6 event 3 data 0x8a5c3d0
Thu Sep 10 21:23:26 2009 router.c:76 writing to 6
Thu Sep 10 21:23:26 2009 router.c:80 52 bytes written
sx (io.c:383) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:34 want read
Thu Sep 10 21:23:26 2009 router.c:288 read action on fd 6
sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x8a5c3d0
Thu Sep 10 21:23:26 2009 router.c:44 reading from 6
Thu Sep 10 21:23:26 2009 router.c:69 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 6
sx (sx.c:51) allocated new sx for 6
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 6
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 6 event 1 data 0x0
Thu Sep 10 21:23:26 2009 router.c:39 want write
Thu Sep 10 21:23:26 2009 router.c:299 write action on fd 6
sx (io.c:328) 6 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 6 event 3 data 0x8a425f8
Thu Sep 10 21:23:26 2009 router.c:76 writing to 6
Thu Sep 10 21:23:26 2009 router.c:80 98 bytes written
sx (client.c:103) 6 state change from 0 to 2
sx (client.c:105) stream header sent, waiting for reply
sx (io.c:383) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:34 want read
Thu Sep 10 21:23:26 2009 router.c:288 read action on fd 6
sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x8a425f8
Thu Sep 10 21:23:26 2009 router.c:44 reading from 6
Thu Sep 10 21:23:26 2009 router.c:69 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='seugt0x6k4fe0c76e4qcz8wd0qw5k6hclctecqkw'>
sx (client.c:70) stream response: to (null) from (null) version 1.0 id
seugt0x6k4fe0c76e4qcz8wd0qw5k6hclctecqkw
sx (sx.c:130) authenticating stream (method=SASL/DIGEST-MD5;
id=jabberd at jabberd-router)
sx (sx.c:135) 6 state change from 2 to 4
sx (sx.c:136) tag 6 event 5 data 0x0
Thu Sep 10 21:23:26 2009 [notice] connection to router established
Thu Sep 10 21:23:26 2009 router.c:118 requesting component bind for 's2s'
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='s2s'><default/></bind>
sx (io.c:429) tag 6 event 1 data 0x0
Thu Sep 10 21:23:26 2009 router.c:39 want write
Thu Sep 10 21:23:26 2009 router.c:299 write action on fd 6
sx (io.c:328) 6 ready for writing
sx (io.c:286) encoding 91 bytes for writing: <bind
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'
name='s2s'><default/></bind>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 91 bytes to write
sx (io.c:350) tag 6 event 3 data 0x8a411d8
Thu Sep 10 21:23:26 2009 router.c:76 writing to 6
Thu Sep 10 21:23:26 2009 router.c:80 91 bytes written
sx (io.c:383) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:34 want read
sx (io.c:431) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:34 want read
Thu Sep 10 21:23:26 2009 router.c:288 read action on fd 6
sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x8a42560
Thu Sep 10 21:23:26 2009 router.c:44 reading from 6
Thu Sep 10 21:23:26 2009 router.c:69 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 6 event 6 data 0x8a5c270
Thu Sep 10 21:23:26 2009 router.c:171 got a packet from router, but
we're not online, dropping
Thu Sep 10 21:23:26 2009 router.c:288 read action on fd 6
sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x8a5c1e8
Thu Sep 10 21:23:26 2009 router.c:44 reading from 6
Thu Sep 10 21:23:26 2009 router.c:69 read 80 bytes
sx (io.c:216) passed 80 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (80 bytes): <bind
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'><default/></bind>
sx (io.c:92) completed nad: <bind
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'><default/></bind>
sx (chain.c:119) calling nad read chain
sx (io.c:156) tag 6 event 6 data 0x8a425f8
Thu Sep 10 21:23:26 2009 router.c:183 coming online
Thu Sep 10 21:23:26 2009 [notice] [0.0.0.0, port=5269] listening for
connections
Thu Sep 10 21:23:26 2009 [notice] ready for connections
Thu Sep 10 21:24:26 2009 main.c:795 running time checks
Thu Sep 10 21:24:26 2009 main.c:800 next time check at 1252610726

sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x9131bc0
Thu Sep 10 21:23:26 2009 router.c:525 reading from 6
Thu Sep 10 21:23:26 2009 router.c:583 read 71 bytes
sx (io.c:216) passed 71 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (71 bytes): <auth
xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='DIGEST-MD5'/>
sx (io.c:92) completed nad: <auth
xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='DIGEST-MD5'/>
sx (chain.c:119) calling nad read chain
sx (sasl_gsasl.c:412) auth request from client (mechanism=DIGEST-MD5)
sx (sasl_gsasl.c:461) sasl context initialised for 6
sx (sasl_gsasl.c:535) sasl handshake in progress (challenge:
realm="jabberd-router", nonce="Nrth/nTBeIGB8o3xZPcHfw==", qop="auth",
charset=utf-8, algorithm=md5-sess)
sx (chain.c:106) calling nad write chain
sx (io.c:406) queueing for write: <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cmVhbG09ImphYmJlcmQtcm91dGVyIiwgbm9uY2U9Ik5ydGgvblRCZUlHQjhvM3haUGNIZnc9PSIsIHFvcD0iYXV0aCIsIGNoYXJzZXQ9dXRmLTgsIGFsZ29yaXRobT1tZDUtc2Vzcw==</challenge>
sx (io.c:256) tag 6 event 1 data 0x0
Thu Sep 10 21:23:26 2009 router.c:520 want write
Thu Sep 10 21:23:26 2009 router.c:869 write action on fd 6
sx (io.c:328) 6 ready for writing
sx (io.c:286) encoding 204 bytes for writing: <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cmVhbG09ImphYmJlcmQtcm91dGVyIiwgbm9uY2U9Ik5ydGgvblRCZUlHQjhvM3haUGNIZnc9PSIsIHFvcD0iYXV0aCIsIGNoYXJzZXQ9dXRmLTgsIGFsZ29yaXRobT1tZDUtc2Vzcw==</challenge>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 204 bytes to write
sx (io.c:350) tag 6 event 3 data 0x9131b98
Thu Sep 10 21:23:26 2009 router.c:590 writing to 6
Thu Sep 10 21:23:26 2009 router.c:594 204 bytes written
sx (io.c:383) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:515 want read
Thu Sep 10 21:23:26 2009 router.c:855 read action on fd 6
sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x9131b98
Thu Sep 10 21:23:26 2009 router.c:525 reading from 6
Thu Sep 10 21:23:26 2009 router.c:583 read 386 bytes
sx (io.c:216) passed 386 read bytes
sx (chain.c:93) calling io read chain
sx (io.c:240) decoded read data (386 bytes): <response
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>dXNlcm5hbWU9ImphYmJlcmQiLCByZWFsbT0iamFiYmVyZC1yb3V0ZXIiLCBub25jZT0iTnJ0aC9uVEJlSUdCOG8zeFpQY0hmdz09IiwgY25vbmNlPSIyK2sxcU5pOHhOb3ZuWHE1TEJTY2F3PT0iLCBuYz0wMDAwMDAwMSwgcW9wPWF1dGgsIGRpZ2VzdC11cmk9ImphYmJlcmQtcm91dGVyL2Rldm14MDEuYnVyby5pbmZvLm5sIiwgcmVzcG9uc2U9OWZkODgwNGQyMTU2MGJkZmIzMGFiOWE1MmM2MTFjY2MsIGNoYXJzZXQ9dXRmLTg=</response>
sx (io.c:92) completed nad: <response
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>dXNlcm5hbWU9ImphYmJlcmQiLCByZWFsbT0iamFiYmVyZC1yb3V0ZXIiLCBub25jZT0iTnJ0aC9uVEJlSUdCOG8zeFpQY0hmdz09IiwgY25vbmNlPSIyK2sxcU5pOHhOb3ZuWHE1TEJTY2F3PT0iLCBuYz0wMDAwMDAwMSwgcW9wPWF1dGgsIGRpZ2VzdC11cmk9ImphYmJlcmQtcm91dGVyL2Rldm14MDEuYnVyby5pbmZvLm5sIiwgcmVzcG9uc2U9OWZkODgwNGQyMTU2MGJkZmIzMGFiOWE1MmM2MTFjY2MsIGNoYXJzZXQ9dXRmLTg=</response>
sx (chain.c:119) calling nad read chain
sx (sasl_gsasl.c:511) response from client (decoded: username="jabberd",
realm="jabberd-router", nonce="Nrth/nTBeIGB8o3xZPcHfw==",
cnonce="2+k1qNi8xNovnXq5LBScaw==", nc=00000001, qop=auth,
digest-uri="jabberd-router/devmx01.buro.info.nl",
response=9fd8804d21560bdfb30ab9a52c611ccc, charset=utf-8)
sx (sasl_gsasl.c:766) in _sx_sasl_gsasl_callback, property: 12
sx (sasl_gsasl.c:766) in _sx_sasl_gsasl_callback, property: 3
Thu Sep 10 21:23:26 2009 main.c:209 sx sasl callback: get pass
(authnid=jabberd, realm=jabberd-router)
sx (sasl_gsasl.c:535) sasl handshake in progress (challenge:
rspauth=ef3e64760149bc2ed32cbeb5e512788f)
sx (chain.c:106) calling nad write chain
sx (io.c:406) queueing for write: <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cnNwYXV0aD1lZjNlNjQ3NjAxNDliYzJlZDMyY2JlYjVlNTEyNzg4Zg==</challenge>
sx (io.c:256) tag 6 event 1 data 0x0
Thu Sep 10 21:23:26 2009 router.c:520 want write
Thu Sep 10 21:23:26 2009 router.c:869 write action on fd 6
sx (io.c:328) 6 ready for writing
sx (io.c:286) encoding 120 bytes for writing: <challenge
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>cnNwYXV0aD1lZjNlNjQ3NjAxNDliYzJlZDMyY2JlYjVlNTEyNzg4Zg==</challenge>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 120 bytes to write
sx (io.c:350) tag 6 event 3 data 0x9133070
Thu Sep 10 21:23:26 2009 router.c:590 writing to 6
Thu Sep 10 21:23:26 2009 router.c:594 120 bytes written
sx (io.c:383) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:515 want read
Thu Sep 10 21:23:26 2009 router.c:855 read action on fd 6
sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x9133070
Thu Sep 10 21:23:26 2009 router.c:525 reading from 6
Thu Sep 10 21:23:26 2009 router.c:583 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): <response
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
sx (io.c:92) completed nad: <response
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
sx (chain.c:119) calling nad read chain
sx (sasl_gsasl.c:511) response from client (decoded: )
sx (sasl_gsasl.c:519) sasl handshake completed
sx (chain.c:106) calling nad write chain
sx (io.c:406) queueing for write: <success
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
sx (io.c:256) tag 6 event 1 data 0x0
Thu Sep 10 21:23:26 2009 router.c:520 want write
Thu Sep 10 21:23:26 2009 router.c:869 write action on fd 6
sx (io.c:328) 6 ready for writing
sx (io.c:286) encoding 51 bytes for writing: <success
xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
sx (chain.c:79) calling io write chain
sx (io.c:349) handing app 51 bytes to write
sx (io.c:350) tag 6 event 3 data 0x9133070
Thu Sep 10 21:23:26 2009 router.c:590 writing to 6
Thu Sep 10 21:23:26 2009 router.c:594 51 bytes written
sx (chain.c:28) adding io plugin
sx (sasl_gsasl.c:396) auth completed, resetting
sx (sx.c:144) resetting stream state
sx (sx.c:68) freeing sx for 6
sx (sx.c:51) allocated new sx for 6
sx (sx.c:199) finished resetting stream state
sx (server.c:236) doing server init for sx 6
sx (server.c:251) waiting for stream header
sx (server.c:254) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:515 want read
sx (io.c:383) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:515 want read
Thu Sep 10 21:23:26 2009 router.c:855 read action on fd 6
sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x9133070
Thu Sep 10 21:23:26 2009 router.c:525 reading from 6
Thu Sep 10 21:23:26 2009 router.c:583 read 98 bytes
sx (io.c:216) passed 98 read bytes
sx (chain.c:93) calling io read chain
sx (sasl_gsasl.c:246) doing sasl decode
sx (sasl_gsasl.c:262) 98 bytes decoded from sasl channel
sx (io.c:240) decoded read data (98 bytes): <?xml
version='1.0'?><stream:stream
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'>
sx (server.c:118) stream request: to (null) from (null) version 1.0
sx (server.c:133) 6 state change from 0 to 1
sx (server.c:151) stream id is seugt0x6k4fe0c76e4qcz8wd0qw5k6hclctecqkw
sx (server.c:181) prepared stream response: <?xml
version='1.0'?><stream:stream
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'
id='seugt0x6k4fe0c76e4qcz8wd0qw5k6hclctecqkw'>
sx (io.c:256) tag 6 event 1 data 0x0
Thu Sep 10 21:23:26 2009 router.c:520 want write
Thu Sep 10 21:23:26 2009 router.c:869 write action on fd 6
sx (io.c:328) 6 ready for writing
sx (io.c:286) encoding 144 bytes for writing: <?xml
version='1.0'?><stream:stream
xmlns:stream='http://etherx.jabber.org/streams' version='1.0'
id='seugt0x6k4fe0c76e4qcz8wd0qw5k6hclctecqkw'>
sx (chain.c:79) calling io write chain
sx (sasl_gsasl.c:218) doing sasl encode
sx (sasl_gsasl.c:234) 144 bytes encoded for sasl channel
sx (io.c:349) handing app 144 bytes to write
sx (io.c:350) tag 6 event 3 data 0x91317b8
Thu Sep 10 21:23:26 2009 router.c:590 writing to 6
Thu Sep 10 21:23:26 2009 router.c:594 144 bytes written
sx (server.c:29) stream established
sx (sx.c:130) authenticating stream (method=SASL/DIGEST-MD5;
id=jabberd at jabberd-router)
sx (sx.c:135) 6 state change from 1 to 4
sx (sx.c:136) tag 6 event 5 data 0x0
Thu Sep 10 21:23:26 2009 [notice] [127.0.0.1, port=39168] authenticated
as jabberd at jabberd-router
sx (server.c:45) building features nad
sx (sasl_gsasl.c:339) already auth'd, not offering sasl mechanisms
sx (io.c:383) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:515 want read
Thu Sep 10 21:23:26 2009 router.c:855 read action on fd 6
sx (io.c:191) 6 ready for reading
sx (io.c:197) tag 6 event 2 data 0x91317b8
Thu Sep 10 21:23:26 2009 router.c:525 reading from 6
Thu Sep 10 21:23:26 2009 router.c:583 read 91 bytes
sx (io.c:216) passed 91 read bytes
sx (chain.c:93) calling io read chain
sx (sasl_gsasl.c:246) doing sasl decode
sx (sasl_gsasl.c:262) 91 bytes decoded from sasl channel
sx (io.c:240) decoded read data (91 bytes): <bind
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'
name='s2s'><default/></bind>
sx (io.c:92) completed nad: <bind
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'
name='s2s'><default/></bind>
sx (chain.c:119) calling nad read chain
sx (io.c:156) tag 6 event 6 data 0x9133190
Thu Sep 10 21:23:26 2009 aci.c:96 checking for 'jabberd' in acl 'all'
Thu Sep 10 21:23:26 2009 aci.c:96 checking for 'jabberd' in acl 'all'
Thu Sep 10 21:23:26 2009 [notice] [s2s] set as default route
Thu Sep 10 21:23:26 2009 [notice] [s2s] online (bound to 127.0.0.1, port
39168)
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'><default/></bind>
sx (io.c:429) tag 6 event 1 data 0x0
Thu Sep 10 21:23:26 2009 router.c:520 want write
Thu Sep 10 21:23:26 2009 router.c:869 write action on fd 6
sx (io.c:328) 6 ready for writing
sx (io.c:286) encoding 66 bytes for writing: <stream:features
xmlns:stream='http://etherx.jabber.org/streams'/>
sx (chain.c:79) calling io write chain
sx (sasl_gsasl.c:218) doing sasl encode
sx (sasl_gsasl.c:234) 66 bytes encoded for sasl channel
sx (io.c:349) handing app 66 bytes to write
sx (io.c:350) tag 6 event 3 data 0x9133200
Thu Sep 10 21:23:26 2009 router.c:590 writing to 6
Thu Sep 10 21:23:26 2009 router.c:594 66 bytes written
sx (io.c:383) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:515 want read
sx (io.c:431) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:515 want read
Thu Sep 10 21:23:26 2009 router.c:47 advertising s2s to all routes
(unavail=0)
sx (io.c:256) tag 6 event 1 data 0x0
Thu Sep 10 21:23:26 2009 router.c:520 want write
Thu Sep 10 21:23:26 2009 router.c:869 write action on fd 6
sx (io.c:328) 6 ready for writing
sx (io.c:286) encoding 80 bytes for writing: <bind
xmlns='http://jabberd.jabberstudio.org/ns/component/1.0'><default/></bind>
sx (chain.c:79) calling io write chain
sx (sasl_gsasl.c:218) doing sasl encode
sx (sasl_gsasl.c:234) 80 bytes encoded for sasl channel
sx (io.c:349) handing app 80 bytes to write
sx (io.c:350) tag 6 event 3 data 0x9133200
Thu Sep 10 21:23:26 2009 router.c:590 writing to 6
Thu Sep 10 21:23:26 2009 router.c:594 80 bytes written
sx (io.c:383) tag 6 event 0 data 0x0
Thu Sep 10 21:23:26 2009 router.c:515 want read
Thu Sep 10 21:23:26 2009 router.c:869 write action on fd 6
Thu Sep 10 21:23:46 2009 main.c:446 running time checks
Thu Sep 10 21:23:46 2009 main.c:451 next time check at 1252610686
Thu Sep 10 21:24:46 2009 main.c:446 running time checks
Thu Sep 10 21:24:46 2009 main.c:451 next time check at 1252610746
==========================================================================

I can not see what is wrong, I think the new db option is wrong perhaps
in the .xml files? (sm and c2s)

I can not see the jabber port listening:
netstat -an | grep 5222

(empty)

/var/log/messages:

Sep 10 21:22:37 devmx01 jabberd/router[15590]: starting up
Sep 10 21:22:37 devmx01 jabberd/router[15590]: process id is 15590,
written to /var/lib/jabberd/pid/router.pid
Sep 10 21:22:37 devmx01 jabberd/router[15590]: loaded user table (1 users)
Sep 10 21:22:37 devmx01 jabberd/router[15590]: loaded filters (0 rules)
Sep 10 21:22:37 devmx01 jabberd/router[15590]: [0.0.0.0, port=5347]
listening for incoming connections
Sep 10 21:22:51 devmx01 jabberd/sm[15647]: starting up
Sep 10 21:22:51 devmx01 jabberd/sm[15647]: id: devmx01.buro.info.nl
Sep 10 21:22:51 devmx01 jabberd/sm[15647]: process id is 15647, written
to /var/lib/jabberd/pid/sm.pid
Sep 10 21:22:51 devmx01 jabberd/sm[15647]: loading 'db' storage module
Sep 10 21:23:03 devmx01 jabberd/c2s[15701]: starting up
Sep 10 21:23:03 devmx01 jabberd/c2s[15701]: process id is 15701, written
to /var/lib/jabberd/pid/c2s.pid
Sep 10 21:23:03 devmx01 jabberd/c2s[15701]: modules search path:
/usr/lib/jabberd/
Sep 10 21:23:03 devmx01 jabberd/c2s[15701]: loading 'db' authreg module
Sep 10 21:23:26 devmx01 jabberd/s2s[15816]: starting up (interval=60,
queue=60, keepalive=0, idle=86400)
Sep 10 21:23:26 devmx01 jabberd/s2s[15816]: process id is 15816, written
to /var/lib/jabberd/pid/s2s.pid
Sep 10 21:23:26 devmx01 jabberd/s2s[15816]: attempting connection to
router at 127.0.0.1, port=5347
Sep 10 21:23:26 devmx01 jabberd/router[15590]: [127.0.0.1, port=39168]
connect
Sep 10 21:23:26 devmx01 jabberd/s2s[15816]: connection to router established
Sep 10 21:23:26 devmx01 jabberd/router[15590]: [127.0.0.1, port=39168]
authenticated as jabberd at jabberd-router
Sep 10 21:23:26 devmx01 jabberd/router[15590]: [s2s] set as default route
Sep 10 21:23:26 devmx01 jabberd/router[15590]: [s2s] online (bound to
127.0.0.1, port 39168)
Sep 10 21:23:26 devmx01 jabberd/s2s[15816]: [0.0.0.0, port=5269]
listening for connections

Kind Regards,

Michiel

> 
> See if that tells you anything, and if you need to you can attach your 
> config file and send it.  Although if it's giving a FAILED on stop, the 
> config is probably alright.  jabberd might be putting messages in 
> /var/log/messages at present if you don't want to do the -D thing above.
> 
> Good luck,
> 
> Joshua Roys
> 
> _______________________________________________
> 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 (MingW32)
Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org/

iQEcBAEBAgAGBQJKqVPaAAoJEKmnTNucqQlOpkQIAN+VYDF4+n3FtuwIvInRWecB
G+jcnHoweyyxN6y+cqoNepQT0aSjkmBg5BsIaYccPayqXCjR22fC1u4S2LorQEDH
PPcuPUVRUscdJeAH9TD2GVs8AvMnhGf6jMD3lzVPFOI5MyaWkcKS7ipvKyV4lPEE
CIfGorbHUdKBhIYB6vrliZiinnz2uo5rkBRsEhFIizVSsCMH6mIEko14Mi0Ulq+M
A9ZFlJk/UDG843gIHecSu7j5J0qOfUFEypCUk9lgdgl2+8PQpZSGJSss+uIKkFVt
gaf+KzlPICCIz9gvu/Wy9LaJDh5eOxS3yMjiyQ1mPQ6AZ1AGNeCuStEKlFLMd3A=
=TK2y
-----END PGP SIGNATURE-----
-------------- next part --------------
A non-text attachment was scrubbed...
Name: 0x9CA9094E.asc
Type: application/pgp-keys
Size: 1728 bytes
Desc: not available
URL: <http://listman.redhat.com/archives/spacewalk-list/attachments/20090910/34ef3cac/attachment.bin>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: 0x9CA9094E.asc.sig
Type: application/octet-stream
Size: 287 bytes
Desc: not available
URL: <http://listman.redhat.com/archives/spacewalk-list/attachments/20090910/34ef3cac/attachment.obj>