[Spacewalk-list] Problems With Jabberd on Fresh Install

Mon Mar 26 16:51:19 UTC 2012

Fixed, had to clean out the /var/lib/jabberd/db directory and then the auth
modules could actually initialize properly.  C2s and sm seemed to hang in my
previous post and would never return.  I've showed the log of what it looks
like now.  Would this have been caused by the FQDN of the spacewalk server
being incorrectly configured at the initial install and then fixed later or
the changes from ipv6 to ipv4?  In any case, it's working now, hopefully
this will be helpful to someone else in the future.  

Mar 26 12:34:43 walkin jabberd/router[9735]: starting up

Mar 26 12:34:43 walkin jabberd/router[9735]: process id is 9735, written to
/var/lib/jabberd/pid/router.pid

Mar 26 12:34:43 walkin jabberd/router[9735]: loaded user table (1 users)

Mar 26 12:34:43 walkin jabberd/router[9735]: loaded filters (0 rules)

Mar 26 12:34:43 walkin jabberd/router[9735]: [0.0.0.0, port=5347] listening
for incoming connections

Mar 26 12:34:43 walkin jabberd/sm[9742]: starting up

Mar 26 12:34:43 walkin jabberd/sm[9742]: process id is 9742, written to
/var/lib/jabberd/pid/sm.pid

Mar 26 12:34:43 walkin jabberd/sm[9742]: loading 'db' storage module

Mar 26 12:34:43 walkin jabberd/sm[9742]: initialised storage driver 'db'

Mar 26 12:34:43 walkin jabberd/sm[9742]: modules search path:
/usr/lib64/jabberd

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'status' added to chain
'sess-start' (order 0 index 0 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'status' added to chain
'sess-end' (order 0 index 0 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-last' added to chain
'sess-end' (order 1 index 1 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'validate' added to chain
'in-sess' (order 0 index 2 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'status' added to chain
'in-sess' (order 1 index 0 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'privacy' added to chain
'in-sess' (order 2 index 3 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'roster' added to chain
'in-sess' (order 3 index 4 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'vacation' added to chain
'in-sess' (order 4 index 5 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-vcard' added to chain
'in-sess' (order 5 index 6 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-ping' added to chain
'in-sess' (order 6 index 7 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-private' added to chain
'in-sess' (order 7 index 8 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'disco' added to chain
'in-sess' (order 8 index 9 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'amp' added to chain
'in-sess' (order 9 index 10 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'offline' added to chain
'in-sess' (order 10 index 11 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'announce' added to chain
'in-sess' (order 11 index 12 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'presence' added to chain
'in-sess' (order 12 index 13 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'deliver' added to chain
'in-sess' (order 13 index 14 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'session' added to chain
'in-router' (order 0 index 15 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'validate' added to chain
'in-router' (order 1 index 2 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'presence' added to chain
'in-router' (order 2 index 13 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'privacy' added to chain
'in-router' (order 3 index 3 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'privacy' added to chain
'out-router' (order 0 index 3 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-last' added to chain
'pkt-sm' (order 0 index 1 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-ping' added to chain
'pkt-sm' (order 1 index 7 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-time' added to chain
'pkt-sm' (order 2 index 16 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-version' added to chain
'pkt-sm' (order 3 index 17 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'amp' added to chain
'pkt-sm' (order 4 index 10 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'disco' added to chain
'pkt-sm' (order 5 index 9 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'announce' added to chain
'pkt-sm' (order 6 index 12 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'help' added to chain
'pkt-sm' (order 7 index 18 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'echo' added to chain
'pkt-sm' (order 8 index 19 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'status' added to chain
'pkt-sm' (order 9 index 0 seq 3)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'presence' added to chain
'pkt-sm' (order 10 index 13 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'roster' added to chain
'pkt-user' (order 0 index 4 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'presence' added to chain
'pkt-user' (order 1 index 13 seq 3)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-vcard' added to chain
'pkt-user' (order 2 index 6 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'amp' added to chain
'pkt-user' (order 3 index 10 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'deliver' added to chain
'pkt-user' (order 4 index 14 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'vacation' added to chain
'pkt-user' (order 5 index 5 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'offline' added to chain
'pkt-user' (order 6 index 11 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-last' added to chain
'pkt-user' (order 7 index 1 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'session' added to chain
'pkt-router' (order 0 index 15 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'disco' added to chain
'pkt-router' (order 1 index 9 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'active' added to chain
'user-load' (order 0 index 20 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'roster' added to chain
'user-load' (order 1 index 4 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'roster-publish' added to
chain 'user-load' (order 2 index 21 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'privacy' added to chain
'user-load' (order 3 index 3 seq 3)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'vacation' added to chain
'user-load' (order 4 index 5 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'active' added to chain
'user-create' (order 0 index 20 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'template-roster' added to
chain 'user-create' (order 1 index 22 seq 0)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'active' added to chain
'user-delete' (order 0 index 20 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'announce' added to chain
'user-delete' (order 1 index 12 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'offline' added to chain
'user-delete' (order 2 index 11 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'privacy' added to chain
'user-delete' (order 3 index 3 seq 4)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'roster' added to chain
'user-delete' (order 4 index 4 seq 3)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'vacation' added to chain
'user-delete' (order 5 index 5 seq 3)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'status' added to chain
'user-delete' (order 6 index 0 seq 4)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-last' added to chain
'user-delete' (order 7 index 1 seq 3)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-private' added to chain
'user-delete' (order 8 index 8 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-vcard' added to chain
'user-delete' (order 9 index 6 seq 2)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'iq-version' added to chain
'disco-extend' (order 0 index 17 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: module 'help' added to chain
'disco-extend' (order 1 index 18 seq 1)

Mar 26 12:34:43 walkin jabberd/sm[9742]: version: jabberd sm 2.2.14

Mar 26 12:34:43 walkin jabberd/sm[9742]: [walkin.x.x.x.x] configured

Mar 26 12:34:43 walkin jabberd/sm[9742]: [walkin.x.x.x.x] configured

Mar 26 12:34:43 walkin jabberd/sm[9742]: [walkin.x.x.x.x] configured

Mar 26 12:34:43 walkin jabberd/sm[9742]: [walkin] configured

Mar 26 12:34:43 walkin jabberd/sm[9742]: [walkin] configured

Mar 26 12:34:43 walkin jabberd/sm[9742]: [walkin] configured

Mar 26 12:34:43 walkin jabberd/sm[9742]: [walkin] configured

Mar 26 12:34:43 walkin jabberd/sm[9742]: [localhost.localdomain] configured

Mar 26 12:34:43 walkin jabberd/sm[9742]: attempting connection to router at
127.0.0.1, port=5347

Mar 26 12:34:43 walkin jabberd/router[9735]: [127.0.0.1, port=49431] connect

Mar 26 12:34:43 walkin jabberd/router[9735]: [127.0.0.1, port=49431]
authenticated as jabberd at jabberd-router

Mar 26 12:34:43 walkin jabberd/sm[9742]: connection to router established

Mar 26 12:34:43 walkin jabberd/router[9735]: [walkin.x.x.x.x] online (bound
to 127.0.0.1, port 49431)

Mar 26 12:34:43 walkin jabberd/sm[9742]: walkin.x.x.x.x ready for sessions

Mar 26 12:34:43 walkin jabberd/router[9735]: [walkin] online (bound to
127.0.0.1, port 49431)

Mar 26 12:34:43 walkin jabberd/router[9735]: [localhost.localdomain] online
(bound to 127.0.0.1, port 49431)

Mar 26 12:34:43 walkin jabberd/c2s[9749]: starting up

Mar 26 12:34:43 walkin jabberd/c2s[9749]: process id is 9749, written to
/var/lib/jabberd/pid/c2s.pid

Mar 26 12:34:43 walkin jabberd/c2s[9749]: modules search path:
/usr/lib64/jabberd

Mar 26 12:34:43 walkin jabberd/c2s[9749]: loading 'db' authreg module

Mar 26 12:34:43 walkin jabberd/c2s[9749]: initialized auth module 'db'

Mar 26 12:34:43 walkin jabberd/c2s[9749]: [walkin.x.x.x.x] configured;
realm=, registration enabled

Mar 26 12:34:43 walkin jabberd/c2s[9749]: attempting connection to router at
127.0.0.1, port=5347

Mar 26 12:34:43 walkin jabberd/router[9735]: [127.0.0.1, port=49432] connect

Mar 26 12:34:43 walkin jabberd/router[9735]: [127.0.0.1, port=49432]
authenticated as jabberd at jabberd-router

Mar 26 12:34:43 walkin jabberd/c2s[9749]: connection to router established

Mar 26 12:34:43 walkin jabberd/router[9735]: [c2s] online (bound to
127.0.0.1, port 49432)

Mar 26 12:34:43 walkin jabberd/s2s[9756]: starting up (interval=3, queue=60,
keepalive=0, idle=86400)

Mar 26 12:34:43 walkin jabberd/s2s[9756]: process id is 9756, written to
/var/lib/jabberd/pid/s2s.pid

Mar 26 12:34:43 walkin jabberd/s2s[9756]: attempting connection to router at
127.0.0.1, port=5347

Mar 26 12:34:43 walkin jabberd/router[9735]: [127.0.0.1, port=49433] connect

Mar 26 12:34:43 walkin jabberd/router[9735]: [127.0.0.1, port=49433]
authenticated as jabberd at jabberd-router

Mar 26 12:34:43 walkin jabberd/s2s[9756]: connection to router established

Mar 26 12:34:43 walkin jabberd/router[9735]: [s2s] set as default route

Mar 26 12:34:43 walkin jabberd/router[9735]: [s2s] online (bound to
127.0.0.1, port 49433)

Mar 26 12:34:43 walkin jabberd/c2s[9749]: [0.0.0.0, port=5222] listening for
connections

Mar 26 12:34:43 walkin jabberd/c2s[9749]: ready for connections

From: spacewalk-list-bounces at redhat.com
[mailto:spacewalk-list-bounces at redhat.com] On Behalf Of Glennie, Jonathan -
0443 - MITLL
Sent: Monday, March 26, 2012 10:10 AM
To: spacewalk-list at redhat.com
Subject: [Spacewalk-list] Problems With Jabberd on Fresh Install

Hello-

We've recently setup a fresh install of Spacewalk 1.6 on CentOS 6 using
Postgres.  Install went fine, set up some repos and started adding clients
but I realize osa-dispatcher wasn't working properly.  I get the following
when trying to start it:

Starting osa-dispatcher: RHN 4716 2012/03/26 09:59:09 -04:00: ('Traceback
(most recent call last):\n  File "/usr/share/rhn/osad/jabber_lib.py", line
252, in setup_connection\n    c = self._get_jabber_client(js)\n  File
"/usr/share/rhn/osad/jabber_lib.py", line 309, in _get_jabber_client\n
c.connect()\n  File "/usr/share/rhn/osad/jabber_lib.py", line 567, in
connect\n    jabber.Client.connect(self)\n  File
"/usr/lib/python2.6/site-packages/jabber/xmlstream.py", line 488, in
connect\n    raise socket.error("Unable to connect to the host and port
specified")\nerror: Unable to connect to the host and port specified\n',)

I also see the following in the logs when trying to restart jabberd:

Mar 26 09:53:13 walkin jabberd/router[3742]: shutting down

Mar 26 09:53:13 walkin jabberd/s2s[3763]: connection to router closed

Mar 26 09:53:13 walkin jabberd/router[3742]: [::1, port=35373] disconnect

Mar 26 09:53:13 walkin jabberd/router[3742]: [s2s] default route offline

Mar 26 09:53:13 walkin jabberd/router[3742]: [s2s] offline

Mar 26 09:53:13 walkin jabberd/s2s[3763]: attempting reconnect (3 left)

Mar 26 09:53:15 walkin jabberd/s2s[3763]: attempting connection to router at
::1, port=5347

Mar 26 09:53:15 walkin jabberd/s2s[3763]: [6] [router] write error:
Connection refused (111)

Mar 26 09:53:15 walkin jabberd/s2s[3763]: connection to router closed

Mar 26 09:53:15 walkin jabberd/s2s[3763]: attempting reconnect (2 left)

Mar 26 09:53:17 walkin jabberd/s2s[3763]: attempting connection to router at
::1, port=5347

Mar 26 09:53:17 walkin jabberd/s2s[3763]: [6] [router] write error:
Connection refused (111)

Mar 26 09:53:17 walkin jabberd/s2s[3763]: connection to router closed

Mar 26 09:53:17 walkin jabberd/s2s[3763]: attempting reconnect (1 left)

Mar 26 09:53:19 walkin jabberd/s2s[3763]: attempting connection to router at
::1, port=5347

Mar 26 09:53:19 walkin jabberd/s2s[3763]: [6] [router] write error:
Connection refused (111)

Mar 26 09:53:19 walkin jabberd/s2s[3763]: connection to router closed

Mar 26 09:53:19 walkin jabberd/s2s[3763]: shutting down

Mar 26 09:53:26 walkin jabberd/router[4566]: starting up

Mar 26 09:53:26 walkin jabberd/router[4566]: process id is 4566, written to
/var/lib/jabberd/pid/router.pid

Mar 26 09:53:26 walkin jabberd/router[4566]: loaded user table (1 users)

Mar 26 09:53:26 walkin jabberd/router[4566]: loaded filters (0 rules)

Mar 26 09:53:26 walkin jabberd/router[4566]: [::, port=5347] listening for
incoming connections

Mar 26 09:53:26 walkin jabberd/sm[4573]: starting up

Mar 26 09:53:26 walkin jabberd/sm[4573]: process id is 4573, written to
/var/lib/jabberd/pid/sm.pid

Mar 26 09:53:26 walkin jabberd/sm[4573]: loading 'db' storage module

Mar 26 09:53:26 walkin jabberd/c2s[4580]: starting up

Mar 26 09:53:26 walkin jabberd/c2s[4580]: process id is 4580, written to
/var/lib/jabberd/pid/c2s.pid

Mar 26 09:53:26 walkin jabberd/c2s[4580]: modules search path:
/usr/lib64/jabberd

Mar 26 09:53:26 walkin jabberd/c2s[4580]: loading 'db' authreg module

Mar 26 09:53:26 walkin jabberd/s2s[4587]: starting up (interval=3, queue=60,
keepalive=0, idle=86400)

Mar 26 09:53:26 walkin jabberd/s2s[4587]: process id is 4587, written to
/var/lib/jabberd/pid/s2s.pid

Mar 26 09:53:26 walkin jabberd/s2s[4587]: attempting connection to router at
::1, port=5347

Mar 26 09:53:26 walkin jabberd/router[4566]: [::1, port=35495] connect

Mar 26 09:53:26 walkin jabberd/router[4566]: [::1, port=35495] authenticated
as jabberd at jabberd-router

Mar 26 09:53:26 walkin jabberd/s2s[4587]: connection to router established

Mar 26 09:53:26 walkin jabberd/router[4566]: [s2s] set as default route

Mar 26 09:53:26 walkin jabberd/router[4566]: [s2s] online (bound to ::1,
port 35495)

Mar 26 09:53:26 walkin jabberd/s2s[4587]: [::, port=5269] listening for
connections

Mar 26 09:53:26 walkin jabberd/s2s[4587]: ready for connections

I've tried switching the router, s2s, c2s and sm xml files to all use IP v4
instead of IP v6 but it doesn't seem to make a difference, I still get the
same issues.  I've edited my hosts file in both cases to look like below:

127.0.0.1       localhost.localdomain   localhost

::1             localhost6.localdomain6 localhost6

x.x.x.x   walkin.x.x.x.x  walkin

I checked the names in the XML files as well as the name in the cert and the
CN is not my fqdn, even though I specified the fqdn during the spacewalk
setup.  I even re-ran the setup thinking that I must not have the first time
and let it regenerate the SSL Cert and it still is only showing the hostname
and not the FQDN.  I'm at  a loss as to why this is happening, so any
insight would be appreciated.  

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://listman.redhat.com/archives/spacewalk-list/attachments/20120326/2a0890cd/attachment.htm>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/x-pkcs7-signature
Size: 5410 bytes
Desc: not available
URL: <http://listman.redhat.com/archives/spacewalk-list/attachments/20120326/2a0890cd/attachment.bin>