[Spacewalk-list] Spacewalk Upgrade Issue
Jonathan Hoser
jonathan.hoser at helmholtz-muenchen.de
Fri Oct 25 13:16:18 UTC 2013
Hi Paul,
when upgrading from sw1.8 to sw2.0,
did you actually go through 1.9?
(you only stated you followed the todos from howtoupgrade19 -- which
only does the upgrade things from sw1.9 to sw2.0)
as such, your db-scheme is probably missing some columns added in the
1.9 upgrade:
>[Thu Oct 24 08:20:32 2013] [error] ProgrammingError: column "org_id" does not exist
(^^ taken from the logs you send)
So, give it another go, but FIRST upgrade to 1.9 and then do the upgrade to 2.0.
Best
-Jonathan
On 10/24/2013 09:06 PM, Bedorf, Paul wrote:
> Hi Michael,
>
> So I have decided to take a clone of my server, and work the upgrade on the clone server. The results were as follows:
> All channels prior the upgrade are working perfectly fine, there is absolutely no issues with spacewalk 1.8 until I do the upgrade.
> As of now I have tried subscribing 2 different clients, and none of them can subscribe.
>
> This time I have NOT used the nightly repos. Performed the upgrade exactly the same as per the previous steps. Everything was successful and my spacewalk was successfully upgraded from version:
> 1.8 Nightly to Spacewalk release 2.0
>
> Unfortunately the same issue persists. I have attached the logs in this email: and also pasted other logs below:
>
> Spacewalk TRACEBACK from spacewalk.corp.mosaic.com - I get this email notification when something goes wrong with spacewalk etc...
>
> ### up2date logs ###
>
> [Thu Oct 24 07:58:11 2013] up2date logging into up2date server
> [Thu Oct 24 07:58:12 2013] up2date successfully retrieved authentication token from up2date server
> [Thu Oct 24 07:58:31 2013] up2date Updating package profile
> [Thu Oct 24 07:59:02 2013] up2date A socket error occurred: timed out, attempt #1
> [Thu Oct 24 08:01:09 2013] up2date logging into up2date server
> [Thu Oct 24 08:01:09 2013] up2date successfully retrieved authentication token from up2date server
> [Thu Oct 24 08:02:46 2013] up2date logging into up2date server
> [Thu Oct 24 08:02:46 2013] up2date successfully retrieved authentication token from up2date server
> [Thu Oct 24 08:06:38 2013] up2date Updating package profile
> [Thu Oct 24 08:12:51 2013] up2date Updating package profile
>
> ### httpd error_log ###
>
> [Thu Oct 24 07:53:18 2013] [notice] suEXEC mechanism enabled (wrapper: /usr/sbin/suexec)
> [Thu Oct 24 07:53:18 2013] [notice] SSL FIPS mode disabled
> [Thu Oct 24 07:53:18 2013] [notice] Digest: generating secret for digest authentication ...
> [Thu Oct 24 07:53:18 2013] [notice] Digest: done
> [Thu Oct 24 07:53:18 2013] [notice] mod_python: Creating 4 session mutexes based on 256 max processes and 0 max threads.
> [Thu Oct 24 07:53:19 2013] [notice] SSL FIPS mode disabled
> [Thu Oct 24 07:53:19 2013] [notice] Apache configured -- resuming normal operations
> [Thu Oct 24 07:53:41 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:53:41 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:53:45 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:53:45 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:53:49 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:53:49 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:53:53 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:53:53 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:53:57 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:53:57 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:01 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:01 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:05 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:05 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:09 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:09 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:15 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:15 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:19 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:19 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:23 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:23 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:27 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:27 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:31 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:31 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:35 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:35 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:39 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:39 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:43 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:43 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:49 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:49 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 07:54:53 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (*) failed
> [Thu Oct 24 07:54:53 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 08:14:08 2013] [notice] caught SIGTERM, shutting down
> [Thu Oct 24 08:18:02 2013] [notice] suEXEC mechanism enabled (wrapper: /usr/sbin/suexec)
> [Thu Oct 24 08:18:02 2013] [notice] SSL FIPS mode disabled
> [Thu Oct 24 08:18:03 2013] [notice] Digest: generating secret for digest authentication ...
> [Thu Oct 24 08:18:03 2013] [notice] Digest: done
> [Thu Oct 24 08:18:03 2013] [warn] mod_rewrite: Running external rewrite maps without defining a RewriteLock is DANGEROUS!
> [Thu Oct 24 08:18:03 2013] [notice] mod_python: Creating 4 session mutexes based on 256 max processes and 0 max threads.
> [Thu Oct 24 08:18:03 2013] [notice] SSL FIPS mode disabled
> [Thu Oct 24 08:18:03 2013] [notice] Apache configured -- resuming normal operations
> [Thu Oct 24 08:18:21 2013] [error] (111)Connection refused: proxy: AJP: attempt to connect to 127.0.0.1:8009 (localhost) failed
> [Thu Oct 24 08:18:21 2013] [error] ap_proxy_connect_backend disabling worker for (localhost)
> [Thu Oct 24 08:18:21 2013] [error] proxy: AJP: failed to make connection to backend: localhost
> [Thu Oct 24 08:18:25 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:18:29 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:18:33 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:18:37 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:18:41 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:18:45 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:18:41 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:18:45 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:18:49 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:18:54 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:18:58 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:19:02 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:19:06 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:19:10 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:19:14 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:19:18 2013] [error] proxy: AJP: disabled connection for (localhost)
> [Thu Oct 24 08:20:32 2013] [error] Exception reported from spacewalk.corp.mosaic.com
> [Thu Oct 24 08:20:32 2013] [error] Time: Thu Oct 24 08:20:32 2013
> [Thu Oct 24 08:20:32 2013] [error] Exception type psycopg2.ProgrammingError
> [Thu Oct 24 08:20:32 2013] [error] Exception while handling function registration.new_system
> [Thu Oct 24 08:20:32 2013] [error] Request object information:
> [Thu Oct 24 08:20:32 2013] [error] URI: /XMLRPC
> [Thu Oct 24 08:20:32 2013] [error] Remote Host: phx-vld-sandbox01.corp.mosaic.com
> [Thu Oct 24 08:20:32 2013] [error] Server Name: 10.99.30.144:80
> [Thu Oct 24 08:20:32 2013] [error] Headers passed in:
> [Thu Oct 24 08:20:32 2013] [error] \tAccept-Encoding: identity
> [Thu Oct 24 08:20:32 2013] [error] \tCONTENT_LENGTH: 1603
> [Thu Oct 24 08:20:32 2013] [error] \tCONTENT_TYPE: text/xml
> [Thu Oct 24 08:20:32 2013] [error] \tDOCUMENT_ROOT: /var/www/html
> [Thu Oct 24 08:20:32 2013] [error] \tGATEWAY_INTERFACE: CGI/1.1
> [Thu Oct 24 08:20:32 2013] [error] \tHTTP_ACCEPT_ENCODING: identity
> [Thu Oct 24 08:20:32 2013] [error] \tHTTP_HOST: 10.99.30.144
> [Thu Oct 24 08:20:32 2013] [error] \tHTTP_USER_AGENT: rhn.rpclib.py/2.5.69-1.el5
> [Thu Oct 24 08:20:32 2013] [error] \tHTTP_X_CLIENT_VERSION: 1
> [Thu Oct 24 08:20:32 2013] [error] \tHTTP_X_INFO: RPC Processor (C) Red Hat, Inc (version 2.5.69-1.el5)
> [Thu Oct 24 08:20:32 2013] [error] \tHTTP_X_RHN_CLIENT_CAPABILITY: caneatCheese(1)=1,packages.extended_profile(2)=1,reboot.reboot(1)=1,packages.verify(1)=1,packages.runTransaction(1)=1,packages.update(2)=2,packages.rollBack(1)=1
> [Thu Oct 24 08:20:32 2013] [error] \tHTTP_X_RHN_TRANSPORT_CAPABILITY: follow-redirects=3
> [Thu Oct 24 08:20:32 2013] [error] \tHTTP_X_TRANSPORT_INFO: Extended Capabilities Transport (C) Red Hat, Inc (version 2.5.69-1.el5)
> [Thu Oct 24 08:20:32 2013] [error] \tHTTP_X_UP2DATE_VERSION: 2.1.10-1.el5
> [Thu Oct 24 08:20:32 2013] [error] \tHost: 10.99.30.144
> [Thu Oct 24 08:20:32 2013] [error] \tPATH_INFO:
> [Thu Oct 24 08:20:32 2013] [error] \tQUERY_STRING:
> [Thu Oct 24 08:20:32 2013] [error] \tREMOTE_ADDR: 10.99.30.143
> [Thu Oct 24 08:20:32 2013] [error] \tREMOTE_PORT: 45727
> [Thu Oct 24 08:20:32 2013] [error] \tREQUEST_METHOD: POST
> [Thu Oct 24 08:20:32 2013] [error] \tREQUEST_URI: /XMLRPC
> [Thu Oct 24 08:20:32 2013] [error] \tSCRIPT_FILENAME: /usr/share/rhn/wsgi/xmlrpc.py
> [Thu Oct 24 08:20:32 2013] [error] \tSCRIPT_NAME: /XMLRPC
> [Thu Oct 24 08:20:32 2013] [error] \tSCRIPT_URI: http://10.99.30.144/XMLRPC
> [Thu Oct 24 08:20:32 2013] [error] \tSCRIPT_URL: /XMLRPC
> [Thu Oct 24 08:20:32 2013] [error] \tSERVER_ADDR: 10.99.30.144
> [Thu Oct 24 08:20:32 2013] [error] \tSERVER_ADMIN: root at localhost
> [Thu Oct 24 08:20:32 2013] [error] \tSERVER_NAME: 10.99.30.144
> [Thu Oct 24 08:20:32 2013] [error] \tSERVER_PORT: 80
> [Thu Oct 24 08:20:32 2013] [error] \tSERVER_PROTOCOL: HTTP/1.1
> [Thu Oct 24 08:20:32 2013] [error] \tSERVER_SIGNATURE: <address>Apache Server at 10.99.30.144 Port 80</address>
> [Thu Oct 24 08:20:32 2013] [error]
> [Thu Oct 24 08:20:32 2013] [error] \tSERVER_SOFTWARE: Apache
> [Thu Oct 24 08:20:32 2013] [error] \tUser-Agent: rhn.rpclib.py/2.5.69-1.el5
> [Thu Oct 24 08:20:32 2013] [error] \tX-Client-Version: 1
> [Thu Oct 24 08:20:32 2013] [error] \tX-Info: RPC Processor (C) Red Hat, Inc (version 2.5.69-1.el5)
> [Thu Oct 24 08:20:32 2013] [error] \tX-RHN-Client-Capability: caneatCheese(1)=1,packages.extended_profile(2)=1,reboot.reboot(1)=1,packages.verify(1)=1,packages.runTransaction(1)=1,packages.update(2)=2,packages.rollBack(1)=1
> [Thu Oct 24 08:20:32 2013] [error] \tX-RHN-Transport-Capability: follow-redirects=3
> [Thu Oct 24 08:20:32 2013] [error] \tX-Transport-Info: Extended Capabilities Transport (C) Red Hat, Inc (version 2.5.69-1.el5)
> [Thu Oct 24 08:20:32 2013] [error] \tX-Up2Date-Version: 2.1.10-1.el5
> [Thu Oct 24 08:20:32 2013] [error] \tX-Transport-Info: Extended Capabilities Transport (C) Red Hat, Inc (version 2.5.69-1.el5)
> [Thu Oct 24 08:20:32 2013] [error] \tX-Up2Date-Version: 2.1.10-1.el5
> [Thu Oct 24 08:20:32 2013] [error] \tmod_ssl.is_https: <built-in method ssl_is_https of mod_wsgi.Adapter object at 0x2ac9a5391300>
> [Thu Oct 24 08:20:32 2013] [error] \tmod_ssl.var_lookup: <built-in method ssl_var_lookup of mod_wsgi.Adapter object at 0x2ac9a5391300>
> [Thu Oct 24 08:20:32 2013] [error] \tmod_wsgi.application_group: spacewalk.corp.mosaic.com|/xmlrpc
> [Thu Oct 24 08:20:32 2013] [error] \tmod_wsgi.callable_object: application
> [Thu Oct 24 08:20:32 2013] [error] \tmod_wsgi.handler_script:
> [Thu Oct 24 08:20:32 2013] [error] \tmod_wsgi.input_chunked: 0
> [Thu Oct 24 08:20:32 2013] [error] \tmod_wsgi.listener_host:
> [Thu Oct 24 08:20:32 2013] [error] \tmod_wsgi.listener_port: 80
> [Thu Oct 24 08:20:32 2013] [error] \tmod_wsgi.process_group:
> [Thu Oct 24 08:20:32 2013] [error] \tmod_wsgi.request_handler: wsgi-script
> [Thu Oct 24 08:20:32 2013] [error] \tmod_wsgi.script_reloading: 1
> [Thu Oct 24 08:20:32 2013] [error] \tmod_wsgi.version: (3, 2)
> [Thu Oct 24 08:20:32 2013] [error] \twsgi.errors: <mod_wsgi.Log object at 0x2ac9a4f29470>
> [Thu Oct 24 08:20:32 2013] [error] \twsgi.file_wrapper: <built-in method file_wrapper of mod_wsgi.Adapter object at 0x2ac9a5391300>
> [Thu Oct 24 08:20:32 2013] [error] \twsgi.input: <mod_wsgi.Input object at 0x2ac9a4f29430>
> [Thu Oct 24 08:20:32 2013] [error] \twsgi.multiprocess: True
> [Thu Oct 24 08:20:32 2013] [error] \twsgi.multithread: False
> [Thu Oct 24 08:20:32 2013] [error] \twsgi.run_once: False
> [Thu Oct 24 08:20:32 2013] [error] \twsgi.url_scheme: http
> [Thu Oct 24 08:20:32 2013] [error] \twsgi.version: (1, 1)
> [Thu Oct 24 08:20:32 2013] [error]
> [Thu Oct 24 08:20:32 2013] [error] Exception Handler Information
> [Thu Oct 24 08:20:32 2013] [error] Traceback (most recent call last):
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/lib/python2.4/site-packages/spacewalk/server/apacheRequest.py", line 123, in call_function
> [Thu Oct 24 08:20:32 2013] [error] response = apply(func, params)
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/share/rhn/server/handlers/xmlrpc/registration.py", line 504, in new_system
> [Thu Oct 24 08:20:32 2013] [error] architecture, data)
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/share/rhn/server/handlers/xmlrpc/registration.py", line 408, in create_system
> [Thu Oct 24 08:20:32 2013] [error] newserv.use_token()
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/lib/python2.4/site-packages/spacewalk/server/rhnServer/server_class.py", line 346, in use_token
> [Thu Oct 24 08:20:32 2013] [error] tokens_obj, self.virt_type)
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/lib/python2.4/site-packages/spacewalk/server/rhnServer/server_token.py", line 1024, in process_token
> [Thu Oct 24 08:20:32 2013] [error] history["channels"] = token_channels(server, server_arch, tokens_obj)
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/lib/python2.4/site-packages/spacewalk/server/rhnServer/server_token.py", line 145, in token_channels
> [Thu Oct 24 08:20:32 2013] [error] child.load_by_id(c["id"])
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/lib/python2.4/site-packages/spacewalk/server/rhnChannel.py", line 220, in load_by_id
> [Thu Oct 24 08:20:32 2013] [error] self._load_dists()
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/lib/python2.4/site-packages/spacewalk/server/rhnChannel.py", line 251, in _load_dists
> [Thu Oct 24 08:20:32 2013] [error] dists = self._get_db_dists(channel_id)
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/lib/python2.4/site-packages/spacewalk/server/rhnChannel.py", line 264, in _get_db_dists
> [Thu Oct 24 08:20:32 2013] [error] h.execute(channel_id=channel_id)
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/lib/python2.4/site-packages/spacewalk/server/rhnSQL/sql_base.py", line 163, in execute
> [Thu Oct 24 08:20:32 2013] [error] return apply(self._execute_wrapper, (self._execute, ) + p, kw)
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/lib/python2.4/site-packages/spacewalk/server/rhnSQL/driver_postgresql.py", line 273, in _execute_wrapper
> [Thu Oct 24 08:20:32 2013] [error] retval = apply(function, p, kw)
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/lib/python2.4/site-packages/spacewalk/server/rhnSQL/sql_base.py", line 217, in _execute
> [Thu Oct 24 08:20:32 2013] [error] return self._execute_(args, kwargs)
> [Thu Oct 24 08:20:32 2013] [error] File "/usr/lib/python2.4/site-packages/spacewalk/server/rhnSQL/driver_postgresql.py", line 287, in _execute_
> [Thu Oct 24 08:20:32 2013] [error] self._real_cursor.execute(self.sql, params)
> [Thu Oct 24 08:20:32 2013] [error] ProgrammingError: column "org_id" does not exist
> [Thu Oct 24 08:20:32 2013] [error] LINE 5: and org_id is null
> [Thu Oct 24 08:20:32 2013] [error] ^
>
> ### rhn_server_xmlrpc.log ###
>
> 2013/10/24 07:58:12 -04:00 4619 10.99.30.144: xmlrpc/up2date.login(1000010045,)
> 2013/10/24 07:58:12 -04:00 4615 10.99.30.144: xmlrpc/up2date.listChannels(1000010045,)
> 2013/10/24 07:58:32 -04:00 4615 10.99.30.144: xmlrpc/registration.welcome_message('lang: None',)
> 2013/10/24 07:58:32 -04:00 4614 10.99.30.144: xmlrpc/registration.update_packages(1000010045, 'packages: 971')
> 2013/10/24 07:59:19 -04:00 4613 10.99.30.144: xmlrpc/up2date.listChannels(1000010045,)
> 2013/10/24 08:00:43 -04:00 4615 10.99.30.144: xmlrpc/up2date.listChannels(1000010045,)
> 2013/10/24 08:01:09 -04:00 4618 10.99.30.144: xmlrpc/up2date.login(1000010045,)
> 2013/10/24 08:01:09 -04:00 4613 10.99.30.144: xmlrpc/up2date.listChannels(1000010045,)
> 2013/10/24 08:01:48 -04:00 4620 10.99.30.144: xmlrpc/up2date.listChannels(1000010045,)
> 2013/10/24 08:01:55 -04:00 4616 10.99.30.144: xmlrpc/up2date.listChannels(1000010045,)
> 2013/10/24 08:02:46 -04:00 4617 10.99.30.144: xmlrpc/up2date.login(1000010045,)
> 2013/10/24 08:02:47 -04:00 4615 10.99.30.144: xmlrpc/up2date.listChannels(1000010045,)
> 2013/10/24 08:03:12 -04:00 4613 10.99.30.144: xmlrpc/up2date.listChannels(1000010045,)
> 2013/10/24 08:04:09 -04:00 4615 10.99.30.144: xmlrpc/up2date.listChannels(1000010045,)
> 2013/10/24 08:06:39 -04:00 4619 10.99.30.144: xmlrpc/registration.welcome_message('lang: None',)
> 2013/10/24 08:06:39 -04:00 4615 10.99.30.144: xmlrpc/registration.update_packages(1000010045, 'packages: 977')
> 2013/10/24 08:12:40 -04:00 4619 10.99.30.144: xmlrpc/up2date.listChannels(1000010045,)
> 2013/10/24 08:12:51 -04:00 4617 10.99.30.144: xmlrpc/registration.welcome_message('lang: None',)
> 2013/10/24 08:12:52 -04:00 4613 10.99.30.144: xmlrpc/registration.update_packages(1000010045, 'packages: 978')
> 2013/10/24 08:20:30 -04:00 4574 10.99.30.143: xmlrpc/registration.welcome_message('lang: None',)
> 2013/10/24 08:20:31 -04:00 4575 10.99.30.143: xmlrpc/registration.welcome_message('lang: None',)
> 2013/10/24 08:20:31 -04:00 5182 10.99.30.143: xmlrpc/registration.create_system("token = '1-3768ca29596c8b11e3d7a8cad4136446'", '5Server', 'x86_64-redhat-linux')
> 2013/10/24 08:20:32 -04:00 5182 10.99.30.143: rhnServer/server_token.process_token(1000010396, 'enterprise_entitled,monitoring_entitled,provisioning_entitled')
> 2013/10/24 08:34:40 -04:00 5182 10.99.30.143: rhnServer/server_certificate.valid('Server id ID-1000010396 not found in database',)
>
> ### catalina.out ###
>
> Oct 24, 2013 7:53:17 AM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
> INFO: The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/lib/amd64/server:/usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/lib/amd64:/usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/../lib/amd64:/usr/lib/oracle/11.2/client64/lib:/usr/lib/oracle/11.2/client/lib:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
> Oct 24, 2013 7:53:17 AM org.apache.coyote.http11.Http11BaseProtocol init
> INFO: Initializing Coyote HTTP/1.1 on http-127.0.0.1-8080
> Oct 24, 2013 7:53:17 AM org.apache.catalina.startup.Catalina load
> INFO: Initialization processed in 1294 ms
> Oct 24, 2013 7:53:17 AM org.apache.catalina.core.StandardService start
> INFO: Starting service Catalina
> Oct 24, 2013 7:53:17 AM org.apache.catalina.core.StandardEngine start
> INFO: Starting Servlet Engine: Apache Tomcat/5.5.23
> Oct 24, 2013 7:53:17 AM org.apache.catalina.core.StandardHost start
> INFO: XML validation disabled
> Oct 24, 2013 7:54:49 AM com.mchange.v2.log.MLog <clinit>
> INFO: MLog clients using java 1.4+ standard logging.
> Oct 24, 2013 7:54:49 AM com.mchange.v2.c3p0.C3P0Registry banner
> INFO: Initializing c3p0-0.9.0 [built 13-July-2007 10:11:26 -0400; debug? false; trace: 5]
> Oct 24, 2013 7:54:49 AM com.mchange.v2.c3p0.PoolBackedDataSource getPoolManager
> INFO: Initializing c3p0 pool... com.mchange.v2.c3p0.PoolBackedDataSource at 62d844a9 [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource at 3d3c33b7 [ acquireIncrement -> 3, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> 3d3c33b7, idleConnectionTestPeriod -> 300, initialPoolSize -> 5, maxIdleTime -> 300, maxPoolSize -> 20, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 5, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource at 7e63f09e [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> 7e63f09e, jdbcUrl -> jdbc:postgresql:spaceschema, properties -> {user=******, password=******, driver_proto=jdbc:postgresql} ], preferredTestQuery -> null, propertyCycle -> 300, testConnectionOnCheckin -> false, testConnectionOnCheckout -> true, usesTraditionalReflectiveProxies -> false ], factoryClassLocation -> null, identityToken -> 62d844a9, numHelperThreads -> 3 ]
> Oct 24, 2013 7:54:55 AM org.apache.coyote.http11.Http11BaseProtocol start
> INFO: Starting Coyote HTTP/1.1 on http-127.0.0.1-8080
> Oct 24, 2013 7:54:55 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Hosts
> Oct 24, 2013 7:54:55 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering WebModule Contexts
> Oct 24, 2013 7:54:55 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Servlets
> Oct 24, 2013 7:54:55 AM org.apache.jk.common.ChannelSocket init
> INFO: JK: ajp13 listening on /127.0.0.1:8009
> Oct 24, 2013 7:54:55 AM org.apache.jk.server.JkMain start
> INFO: Jk running ID=0 time=0/44 config=null
> Oct 24, 2013 7:54:55 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Hosts
> Oct 24, 2013 7:54:55 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering WebModule Contexts
> Oct 24, 2013 7:54:55 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Servlets
> Oct 24, 2013 7:54:55 AM org.apache.jk.common.ChannelSocket init
> INFO: JK: ajp13 listening on /0:0:0:0:0:0:0:1:8009
> Oct 24, 2013 7:54:55 AM org.apache.jk.server.JkMain start
> INFO: Jk running ID=0 time=0/3 config=null
> Oct 24, 2013 7:54:55 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Hosts
> Oct 24, 2013 7:54:55 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering WebModule Contexts
> Oct 24, 2013 7:54:55 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Servlets
> Oct 24, 2013 7:54:55 AM org.apache.catalina.storeconfig.StoreLoader load
> INFO: Find registry server-registry.xml at classpath resource
> Oct 24, 2013 7:54:56 AM org.apache.catalina.startup.Catalina start
> INFO: Server startup in 98563 ms
> Oct 24, 2013 8:06:16 AM org.apache.catalina.startup.HostConfig checkResources
> INFO: Undeploying context [/rhn]
> 2013-10-24 08:06:24,310 [ContainerBackgroundProcessor[StandardEngine[Catalina]]] ERROR com.redhat.rhn.common.hibernate.ConnectionManager - FATAL ERROR creating HibernateFactory
> INFO: Undeploying context [/rhn]
> 2013-10-24 08:06:24,310 [ContainerBackgroundProcessor[StandardEngine[Catalina]]] ERROR com.redhat.rhn.common.hibernate.ConnectionManager - FATAL ERROR creating HibernateFactory
> org.hibernate.MappingNotFoundException: resource: com/redhat/rhn/domain/server/Crashes.hbm.xml not found
> at org.hibernate.cfg.Configuration.addResource(Configuration.java:563)
> at com.redhat.rhn.common.hibernate.ConnectionManager.createSessionFactory(ConnectionManager.java:210)
> at com.redhat.rhn.common.hibernate.ConnectionManager.initialize(ConnectionManager.java:134)
> at com.redhat.rhn.common.hibernate.HibernateFactory.createSessionFactory(HibernateFactory.java:96)
> at com.redhat.rhn.webapp.RhnServletListener.startHibernate(RhnServletListener.java:94)
> at com.redhat.rhn.webapp.RhnServletListener.contextInitialized(RhnServletListener.java:116)
> at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3764)
> at org.apache.catalina.core.StandardContext.start(StandardContext.java:4212)
> at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:760)
> at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:740)
> at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:544)
> at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:634)
> at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:561)
> at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:496)
> at org.apache.catalina.startup.HostConfig.check(HostConfig.java:1261)
> at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:301)
> at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:120)
> at org.apache.catalina.core.ContainerBase.backgroundProcess(ContainerBase.java:1306)
> at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1570)
> at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1579)
> at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1559)
> at java.lang.Thread.run(Thread.java:679)
> 2013-10-24 08:06:25,416 [ContainerBackgroundProcessor[StandardEngine[Catalina]]] ERROR com.redhat.rhn.common.hibernate.ConnectionManager - FATAL ERROR creating HibernateFactory
> org.hibernate.MappingNotFoundException: resource: com/redhat/rhn/domain/server/Crashes.hbm.xml not found
> at org.hibernate.cfg.Configuration.addResource(Configuration.java:563)
> at com.redhat.rhn.common.hibernate.ConnectionManager.createSessionFactory(ConnectionManager.java:210)
> at com.redhat.rhn.common.hibernate.ConnectionManager.initialize(ConnectionManager.java:134)
> at com.redhat.rhn.common.hibernate.ConnectionManager.getSession(ConnectionManager.java:288)
> at com.redhat.rhn.common.hibernate.HibernateFactory.getSession(HibernateFactory.java:320)
> at com.redhat.rhn.manager.satellite.UpgradeCommand.store(UpgradeCommand.java:60)
> at com.redhat.rhn.webapp.RhnServletListener.executeUpgradeStep(RhnServletListener.java:126)
> at com.redhat.rhn.webapp.RhnServletListener.contextInitialized(RhnServletListener.java:120)
> at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3764)
> at org.apache.catalina.core.StandardContext.start(StandardContext.java:4212)
> at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:760)
> at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:740)
> at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:544)
> at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:634)
> at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:561)
> at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:496)
> at org.apache.catalina.startup.HostConfig.check(HostConfig.java:1261)
> at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:301)
> at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:120)
> at org.apache.catalina.core.ContainerBase.backgroundProcess(ContainerBase.java:1306)
> at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1570)
> at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1579)
> at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1559)
> at java.lang.Thread.run(Thread.java:679)
> 2013-10-24 08:06:25,427 [ContainerBackgroundProcessor[StandardEngine[Catalina]]] ERROR com.redhat.rhn.manager.satellite.UpgradeCommand - Problem upgrading!
> java.lang.NullPointerException
> at com.redhat.rhn.common.hibernate.ConnectionManager.getInternalSession(ConnectionManager.java:300)
> at com.redhat.rhn.common.hibernate.ConnectionManager.getSession(ConnectionManager.java:290)
> at com.redhat.rhn.common.hibernate.HibernateFactory.getSession(HibernateFactory.java:320)
> at com.redhat.rhn.manager.satellite.UpgradeCommand.store(UpgradeCommand.java:60)
> at com.redhat.rhn.webapp.RhnServletListener.executeUpgradeStep(RhnServletListener.java:126)
> at com.redhat.rhn.webapp.RhnServletListener.contextInitialized(RhnServletListener.java:120)
> at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3764)
> at org.apache.catalina.core.StandardContext.start(StandardContext.java:4212)
> at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3764)
> at org.apache.catalina.core.StandardContext.start(StandardContext.java:4212)
> at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:760)
> at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:740)
> at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:544)
> at org.apache.catalina.startup.HostConfig.deployDescriptor(HostConfig.java:634)
> at org.apache.catalina.startup.HostConfig.deployDescriptors(HostConfig.java:561)
> at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:496)
> at org.apache.catalina.startup.HostConfig.check(HostConfig.java:1261)
> at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:301)
> at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:120)
> at org.apache.catalina.core.ContainerBase.backgroundProcess(ContainerBase.java:1306)
> at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1570)
> at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1579)
> at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1559)
> at java.lang.Thread.run(Thread.java:679)
> Oct 24, 2013 8:14:10 AM org.apache.coyote.http11.Http11BaseProtocol pause
> INFO: Pausing Coyote HTTP/1.1 on http-127.0.0.1-8080
> Oct 24, 2013 8:14:11 AM org.apache.catalina.core.StandardService stop
> INFO: Stopping service Catalina
> 2013-10-24 08:14:11,598 [main] ERROR org.apache.catalina.core.ContainerBase.[Catalina].[localhost].[/rhn] - Exception sending context destroyed event to listener instance of class com.redhat.rhn.webapp.RhnServletListener
> java.lang.NullPointerException
> at com.redhat.rhn.common.hibernate.ConnectionManager.close(ConnectionManager.java:112)
> at com.redhat.rhn.common.hibernate.HibernateFactory.closeSessionFactory(HibernateFactory.java:80)
> at com.redhat.rhn.webapp.RhnServletListener.stopHibernate(RhnServletListener.java:99)
> at com.redhat.rhn.webapp.RhnServletListener.contextDestroyed(RhnServletListener.java:136)
> at org.apache.catalina.core.StandardContext.listenerStop(StandardContext.java:3805)
> at org.apache.catalina.core.StandardContext.stop(StandardContext.java:4319)
> at org.apache.catalina.core.ContainerBase.removeChild(ContainerBase.java:893)
> at org.apache.catalina.startup.HostConfig.undeployApps(HostConfig.java:1235)
> at org.apache.catalina.startup.HostConfig.stop(HostConfig.java:1206)
> at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)
> at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:120)
> at org.apache.catalina.core.ContainerBase.stop(ContainerBase.java:1055)
> at org.apache.catalina.core.ContainerBase.stop(ContainerBase.java:1067)
> at org.apache.catalina.core.StandardEngine.stop(StandardEngine.java:448)
> at org.apache.catalina.core.StandardService.stop(StandardService.java:510)
> at org.apache.catalina.core.StandardServer.stop(StandardServer.java:734)
> at org.apache.catalina.startup.Catalina.stop(Catalina.java:602)
> at org.apache.catalina.startup.Catalina.start(Catalina.java:577)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:616)
> at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:295)
> at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:433)
> Oct 24, 2013 8:14:11 AM org.apache.coyote.http11.Http11BaseProtocol destroy
> INFO: Stopping Coyote HTTP/1.1 on http-127.0.0.1-8080
> Oct 24, 2013 8:14:11 AM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
> INFO: Failed shutdown of Apache Portable Runtime
> Oct 24, 2013 8:18:01 AM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
> INFO: The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/lib/amd64/server:/usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/lib/amd64:/usr/lib/jvm/java-1.6.0-openjdk-1.6.0.0.x86_64/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
> Oct 24, 2013 8:18:02 AM org.apache.coyote.http11.Http11BaseProtocol init
> INFO: Initializing Coyote HTTP/1.1 on http-127.0.0.1-8080
> Oct 24, 2013 8:18:02 AM org.apache.catalina.startup.Catalina load
> INFO: Initialization processed in 1344 ms
> Oct 24, 2013 8:18:02 AM org.apache.catalina.core.StandardService start
> INFO: Starting service Catalina
> Oct 24, 2013 8:18:02 AM org.apache.catalina.core.StandardService start
> INFO: Starting service Catalina
> Oct 24, 2013 8:18:02 AM org.apache.catalina.core.StandardEngine start
> INFO: Starting Servlet Engine: Apache Tomcat/5.5.23
> Oct 24, 2013 8:18:02 AM org.apache.catalina.core.StandardHost start
> INFO: XML validation disabled
> Oct 24, 2013 8:18:12 AM com.mchange.v2.log.MLog <clinit>
> INFO: MLog clients using java 1.4+ standard logging.
> Oct 24, 2013 8:18:12 AM com.mchange.v2.c3p0.C3P0Registry banner
> INFO: Initializing c3p0-0.9.1.2 [built 06-August-2008 15:35:00; debug? false; trace: 5]
> Oct 24, 2013 8:18:12 AM com.mchange.v2.c3p0.impl.AbstractPoolBackedDataSource getPoolManager
> INFO: Initializing c3p0 pool... com.mchange.v2.c3p0.PoolBackedDataSource at fcd1faf [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource at acae7f8 [ acquireIncrement -> 3, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionCustomizerClassName -> com.redhat.rhn.common.db.RhnConnectionCustomizer, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, debugUnreturnedConnectionStackTraces -> false, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> z8kflt8x1t1d23peox5d0|4b0613aa, idleConnectionTestPeriod -> 300, initialPoolSize -> 5, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 300, maxIdleTimeExcessConnections -> 0, maxPoolSize -> 20, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 5, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource at 403195e [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> z8kflt8x1t1d23peox5d0|4b455d1c, jdbcUrl -> jdbc:postgresql:spaceschema, properties -> {user=******, password=******, driver_proto=jdbc:postgresql} ], preferredTestQuery -> select 'c3p0 ping' from dual, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> true, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false; userOverrides: {} ], dataSourceName -> null, factoryClassLocation -> null, identityToken -> z8kflt8x1t1d23peox5d0|6789b939, numHelperThreads -> 3 ]
> Oct 24, 2013 8:18:24 AM org.apache.coyote.http11.Http11BaseProtocol start
> INFO: Starting Coyote HTTP/1.1 on http-127.0.0.1-8080
> Oct 24, 2013 8:18:24 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Hosts
> Oct 24, 2013 8:18:24 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering WebModule Contexts
> Oct 24, 2013 8:18:24 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Servlets
> Oct 24, 2013 8:18:24 AM org.apache.jk.common.ChannelSocket init
> INFO: JK: ajp13 listening on /127.0.0.1:8009
> Oct 24, 2013 8:18:24 AM org.apache.jk.server.JkMain start
> INFO: Jk running ID=0 time=0/93 config=null
> Oct 24, 2013 8:18:24 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Hosts
> Oct 24, 2013 8:18:24 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering WebModule Contexts
> Oct 24, 2013 8:18:24 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Servlets
> Oct 24, 2013 8:18:24 AM org.apache.jk.common.ChannelSocket init
> INFO: JK: ajp13 listening on /0:0:0:0:0:0:0:1:8009
> Oct 24, 2013 8:18:24 AM org.apache.jk.server.JkMain start
> INFO: Jk running ID=0 time=0/3 config=null
> Oct 24, 2013 8:18:24 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Hosts
> Oct 24, 2013 8:18:24 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering WebModule Contexts
> Oct 24, 2013 8:18:24 AM org.apache.catalina.connector.MapperListener init
> INFO: Registering Servlets
> Oct 24, 2013 8:18:24 AM org.apache.catalina.storeconfig.StoreLoader load
> INFO: Find registry server-registry.xml at classpath resource
> Oct 24, 2013 8:18:24 AM org.apache.catalina.startup.Catalina start
> INFO: Server startup in 22849 ms
>
>
> __________________________________________________
> Paul Bedorf | Linux Administrator | Mosaic
> O: 905.238.8058 x 6414 | M: 416.799.4028
> mosaic.com
>
> -----Original Message-----
> From: spacewalk-list-bounces at redhat.com [mailto:spacewalk-list-bounces at redhat.com] On Behalf Of Michael Mraka
> Sent: Thursday, October 24, 2013 4:53 AM
> To: spacewalk-list at redhat.com
> Subject: Re: [Spacewalk-list] Spacewalk Upgrade Issue
>
> Bedorf, Paul wrote:
> % Description:
> %
> % Hi, in our environment we are running the following:
> %
> % spacewalk 1.8 nightly
> % centos 5.9 64bit
> % postgresql 8.4 (installed locally on the spacewalk server) % % Spacewalk works absolutely perfect, no issues what's so ever. I have decided to upgrade spacewalk to the latest version 2.0, below you will find the steps that I took:
> %
> % Upgrade:
> % a. I have followed the instructions on the following page:
> % https://fedorahosted.org/spacewalk/wiki/HowToUpgrade19
> % b. Install spacewalk-repo
> % rpm -Uvh http://yum.spacewalkproject.org/2.0/RHEL/5/x86_64/spacewalk-repo-2.0-3.el5.noarch.rpm
> % c. Enable nightly builds
> % sed -i 's/enabled=0/enabled=1/' /etc/yum.repos.d/spacewalk-nightly.repo
> % d. sed -i 's/enabled=1/enabled=0/' /etc/yum.repos.d/spacewalk.repo
>
> So you explicitly enabled nightly repo and disabled standard Spacewalk 2.0...
>
> % e. yum install cobbler-epel
> % yum upgrade
> % yum install rpmconf
> % su - postgres -c 'PGPASSWORD=spacepw; createlang pltclu spaceschema ;'
> % /usr/sbin/spacewalk-service stop
> % /usr/bin/spacewalk-schema-upgrade
> % f. All is 100% successful at this point, I keep on going:
> % spacewalk-setup --disconnected -upgrade % /usr/share/spacewalk/setup/upgrade/rhn-enable-monitoring.pl
> % /usr/sbin/spacewalk-service start
> % g. All is again 100% successful at this point, I restart the spacewalk server and can log in via admin URL and I see spacewalk has been upgraded to version 2.1 nightly
>
> That's expected according to enabled repos above. On the other hand it might be dangerous especially on production server because nightly repo can contain buggy development code and can even cause data corruption / loss.
>
> % h. All my configurations are there, and all of my systems are there.
> %
> % Issue:
> %
> % a. I log in to one of the client machine's and attempt to re-register the client to spacewalk, just to ensure it is working correctly, I issue this command:
> %
> % rhnreg_ks --force -vvv --serverUrl=http://10.99.30.144/XMLRPC --activationkey=1-3768ca29596c8b11e3d7a8cad4136446
> % D: rpcServer: Calling XMLRPC registration.welcome_message % D: opening db environment /var/lib/rpm/Packages joinenv
> % D: opening db index /var/lib/rpm/Packages rdonly mode=0x0
> % D: locked db index /var/lib/rpm/Packages
> % D: rpcServer: Calling XMLRPC registration.welcome_message
> % D: opening db index /var/lib/rpm/Providename rdonly mode=0x0
> % D: rpcServer: Calling XMLRPC registration.new_system % A protocol error occurred: Status 500 , attempt #1, % Error communicating with server. The message was:
> % Status 500
> % D: closed db index /var/lib/rpm/Providename
> % D: closed db index /var/lib/rpm/Packages
> % D: closed db environment /var/lib/rpm/Packages
> % D: May free Score board((nil))
>
> There should be more verbose error in /var/log/up2date on client and /var/log/httpd/*error_log, /var/log/rhn/*.log and /var/log/tomcat*/catalina.out on server.
>
> % b. I also receive an email with more info about this error message:
> %
> % Exception reported from spacewalk2.corp.mosaic.com % Time: Wed Oct 23 02:31:18 2013 % Exception type spacewalk.server.rhnSQL.sql_base.SQLSchemaError
> % Exception while handling function registration.new_system Request object information:
> %
> % URI: /XMLRPC
> % Remote Host: phx-vld-sandbox01.corp.mosaic.com Server Name: 10.99.30.144:80 Headers passed in:
> % Extra information about this error:
> % SQL Error generated: (99999, 'ERROR: current transaction is aborted, commands ignored until end of transaction block', '', <psycopg2.InternalError instance at 0x2ab9264c0fc8>) ...
> % history = <type 'dict'> {'channels': ["Subscribed to base channel 'cent5_64bit_CD' (cent564bitcd)", "FAILED to subscribe to channel 'cent5_64bit_NAGIOS'", "FAILED to subscribe to channel 'cent5_64bit_UPDATES'", "FAILED to subscribe to channel 'Cent5_64bit_SPACECLIENTREPO18'", "FAILED to subscribe to channel 'cent5_64bit_389'", "FAILED to subscribe to channel 'cent5_64bit_EXTRAS'"], 'entitlement': 'Entitled as a Spacewalk Provisioning Entitled Servers member'}
>
> Client can't subscribe to child channels (cent5_64bit_NAGIOS, cent5_64bit_UPDATES, Cent5_64bit_SPACECLIENTREPO18, cent5_64bit_389, cent5_64bit_EXTRAS). Is anything wrong with them? Can other clients subscribe to them?
>
>
> Regards,
>
> --
> Michael Mráka
> Satellite Engineering, Red Hat
>
> _______________________________________________
> Spacewalk-list mailing list
> Spacewalk-list at redhat.com
> https://www.redhat.com/mailman/listinfo/spacewalk-list
--
Jonathan Hoser, M.Sc.
Institute of Bioinformatics and System Biology
WWW: http://mips.helmholtz-muenchen.de
Helmholtz Zentrum München
Deutsches Forschungszentrum für Gesundheit und Umwelt (GmbH)
Ingolstädter Landstr. 1
85764 Neuherberg
www.helmholtz-muenchen.de
Aufsichtsratsvorsitzende: MinDir´in Bärbel Brumme-Bothe
Geschäftsführer: Prof. Dr. Günther Wess, Dr. Nikolaus Blum, Dr. Alfons Enhsen
Registergericht: Amtsgericht München HRB 6466
USt-IdNr: DE 129521671
More information about the Spacewalk-list
mailing list