[Spacewalk-list] Error accessing channels from FC12 client

Wed Dec 9 07:46:01 UTC 2009

* Miroslav Suchý <msuchy at redhat.com>:
> Stefan Förster wrote:
>> Any guidance on this? Any hint where I could start debugging?
>
> /var/log/httpd/ssl_error_log
> /var/log/httpd/error_log
> /var/log/rhn/*.log

With a debug level of 5, the latter one yielded some interesting
results (very long lines ahead):

#v+
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/apacheHandler.headerParserHandler('GET /XMLRPC/GET-REQ/fc12-base/repodata/repomd.xml HTTP/1.1',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: common/rhnApache.headerParserHandler
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: common/rhnApache.headerParserHandler('GET', '/GET-REQ/fc12-base/repodata/repomd.xml', {'Pragma': 'no-cache', 'X-RHN-Auth-Expire-Offset': '3600.0', 'X-RHN-Transport-Capability': 'follow-redirects=3', 'X-RHN-Auth': 'CFNCg3CfM7dx00A59pNH9w==', 'X-RHN-Auth-Server-Time': '
1260261295.78', 'X-RHN-Server-Id': '1000010043', 'Accept': '*/*', 'Host': 'spacewalk.domain.obfuscated
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/apacheHandler._init_request_processor
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Attempting to load module __init__.py from server.handlers /usr/share/rhn/server/handler
s',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Attempting to load module applet from server.handlers /usr/share/rhn/server/handlers',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Module applet loaded',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Attempting to load module sat from server.handlers /usr/share/rhn/server/handlers',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Module sat loaded',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Attempting to load module app from server.handlers /usr/share/rhn/server/handlers',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Module app loaded',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Attempting to load module config_mgmt from server.handlers /usr/share/rhn/server/handler
s',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Module config_mgmt loaded',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Attempting to load module __init__.pyo from server.handlers /usr/share/rhn/server/handle
rs',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Attempting to load module config from server.handlers /usr/share/rhn/server/handlers',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Module config loaded',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Attempting to load module xmlrpc from server.handlers /usr/share/rhn/server/handlers',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Module xmlrpc loaded',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Attempting to load module xp from server.handlers /usr/share/rhn/server/handlers',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Module xp loaded',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/rhnImport.load('Attempting to load module __init__.pyc from server.handlers /usr/share/rhn/server/handle
rs',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/apacheRequest.__init__('Handler classes', {'xmlrpc': <class server.handlers.xmlrpc.get_handler.GetHandle
r at 0x37038fc>, 'app': None, 'config_mgmt': None, 'applet': None, 'xp': None, 'config': None, 'sat': <class server.handlers.sat.get_handler.GetHandler at 0x
364944c>})
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/apacheRequest.__init__('Handler class', <class server.handlers.xmlrpc.get_handler.GetHandler at 0x37038f
c>, <type 'classobj'>)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: common/rhnApache._setSessionToken
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: common/rhnApache.setlang('/usr/share/rhn', 'C', 'server')
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: rhnSQL/driver_cx_Oracle._execute_wrapper('Executing SQL: "select sysdate as ID from dual" with bind params: {}'
,)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/apacheHandler.handler
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: common/rhnApache.handler
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: common/rhnApache.handler('URI', '/XMLRPC/GET-REQ/fc12-base/repodata/repomd.xml')
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: common/rhnApache.handler('CONFIG', {'PythonInterpreter': 'rhn.server', 'PythonDebug': '0', 'PythonPath': "sys.p
ath+['/usr/share/rhn']"})
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: common/rhnApache.handler('OPTIONS', {'RHNComponentType': 'server.xmlrpc', 'SERVER': 'xmlrpc', 'RootDir': '/usr/
share/rhn'})
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: common/rhnApache.handler('HEADERS', {'Pragma': 'no-cache', 'X-RHN-Auth-Expire-Offset': '3600.0', 'X-RHN-Transpo
rt-Capability': 'follow-redirects=3', 'X-RHN-Auth': 'CFNCg3CfM7dx00A59pNH9w==', 'X-RHN-Auth-Server-Time': '1260261295.78', 'X-RHN-Server-Id': '1000010043', '
Accept': '*/*', 'Host': 'spacewalk.domain.obfuscated
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: rhnSQL/driver_cx_Oracle._execute_wrapper('Executing SQL: "select label, value from rhnTemplateString" with bind
 params: {}',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/apacheHandler.handler("template strings:  {'email_account_info': '\\nAccount Information:\\n  Your Space
walk login:         <login />\\n  Your Spacewalk email address: <email-address />', 'email_footer': '--the Spacewalk Team', 'hostname': 'spacewalk.domain.obfuscated
pacewalkdmz.de'}",)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/apacheAuth.auth_client
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/apacheAuth.auth_client('Sent client signature CFNCg3CfM7dx00A59pNH9w== does not match ours p9GK5f++sMB6q
2nx0JatOA==.',)
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/apacheRequest.response
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/apacheRequest.response('Return FAULT', -33, '\nError Message:\n    Invalid client session key\nError Class Code: 33\nError Class Info: Client session token is invalid.\nExplanation: \n     An error has occurred while processing your request. If this problem\n     persists please enter a bug report at bugzilla.redhat.com.\n     If you choose to submit the bug report, please be sure to include\n     details of what you were trying to do when this error occurred and\n     details on how to reproduce this problem.\n')
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: common/rhnApache.logHandler
2009/12/08 09:34:55 +02:00 1232 192.168.254.52: server/apacheHandler.cleanupHandler
#v-

So, obviously, disabling client authentication in apacheAuth.py will
"fix" that problem - but I'd be more interested in finding out why the
problem arises at all.

Cheers
Stefan