[Spacewalk-list] taskomatic failing to start

ungah javorke ungahz at yahoo.com
Wed Jun 1 07:50:01 UTC 2011


I just installed spacewalk 1.4 on CentOS 5.6 x86_64. Everything seems to be running ok except for the taskomatic piece. Spacewalk service attempts to start it up but it eventually fails after 5 attempts. Below is an example of what im seeing in the logs:

DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[0] : /usr/bin/java
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[1] : -Dibm.dst.compatibility=true
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[2] : -Xms256m
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[3] : -Xmx512m
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[4] : -Djava.library.path=/usr/lib:/usr/lib64:/usr/lib/oracle/11.2/client64/lib:/usr/lib/oracle/11.2/client/lib
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[5] : -classpath
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[6] :
 /usr/share/java/tanukiwrapper.jar:/usr/share/rhn/classes:/usr/share/java/struts.jar:/usr/share/java/jfreechart.jar:/usr/share/java/jpam.jar:/usr/share/java/javamail.jar:/usr/share/java/axis/axis-ant.jar:/usr/share/java/quartz.jar:/usr/share/java/commons-codec.jar:/usr/share/java/commons-beanutils.jar:/usr/share/java/ojdbc14.jar:/usr/share/java/jta.jar:/usr/share/java/concurrent.jar:/usr/share/rhn/lib/spacewalk-asm.jar:/usr/share/java/axis/jaxrpc.jar:/usr/share/java/commons-collections.jar:/usr/share/java/taglibs-standard.jar:/usr/share/java/axis/axis.jar:/usr/share/java/xalan-j2.jar:/usr/share/java/commons-validator.jar:/usr/share/java/asm/asm-attrs.jar:/usr/share/java/jaf.jar:/usr/share/java/jdom.jar:/usr/share/java/oro.jar:/usr/share/rhn/lib/rhn.jar:/usr/share/java/redstone-xmlrpc.jar:/usr/share/java/oscache.jar:/usr/share/java/log4j.jar:/usr/share/java/wsdl4j.jar:/usr/share/java/jcommon.jar:/usr/share/java/commons-el.jar:/usr/share/java/taglibs-core
.jar:/usr/share/java/commons-lang.jar:/usr/share/java/commons-digester.jar:/usr/share/java/jasper5-runtime.jar:/usr/share/java/jspapi.jar:/usr/share/java/c3p0.jar:/usr/share/java/sitemesh.jar:/usr/share/java/jasper5-compiler.jar:/usr/share/java/axis/saaj.jar:/usr/share/java/commons-logging.jar:/usr/share/java/commons-discovery.jar:/usr/share/java/xml-commons-apis.jar:/usr/share/java/axis/jaxrpc.jar:/usr/share/java/commons-cli.jar:/usr/share/java/bcel.jar:/usr/share/java/antlr.jar:/usr/share/java/xerces-j2.jar:/usr/share/java/hibernate3.jar:/usr/share/java/cglib.jar:/usr/share/java/dom4j.jar:/usr/share/rhn/lib/java-branding.jar:/usr/share/java/slf4j-api.jar:/usr/share/java/slf4j-jcl.jar:/usr/share/java/simple-core.jar:/usr/share/java/commons-dbcp.jar:/usr/share/java/commons-pool.jar:/usr/share/java/quartz-oracle.jar:/usr/share/java/postgresql-jdbc.jar
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[7] : -Dwrapper.key=ZBkwgETL2Jmrn7qm
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[8] : -Dwrapper.port=32002
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[9] : -Dwrapper.jvm.port.min=31000
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[10] : -Dwrapper.jvm.port.max=31999
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[11] : -Dwrapper.debug=TRUE
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[12] : -Dwrapper.pid=12722
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[13] : -Dwrapper.version=3.2.1
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[14] : -Dwrapper.native_library=wrapper
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[15] : -Dwrapper.service=TRUE
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[16] : -Dwrapper.cpu.timeout=10
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[17] : -Dwrapper.jvmid=5
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Command[18] : com.redhat.rhn.taskomatic.core.TaskomaticDaemon
STATUS | wrapper  | 2011/05/31 23:58:28 | Launching a JVM...
INFO   | jvm 5    | 2011/05/31 23:58:28 | WrapperManager class initialized by thread: main  Using classloader: sun.misc.Launcher$AppClassLoader at 64601bb1
INFO   | jvm 5    | 2011/05/31 23:58:28 | Wrapper (Version 3.2.1) http://wrapper.tanukisoftware.org
INFO   | jvm 5    | 2011/05/31 23:58:28 | 
INFO   | jvm 5    | 2011/05/31 23:58:28 | Wrapper Manager: JVM #5
INFO   | jvm 5    | 2011/05/31 23:58:28 | Running a 64-bit JVM.
INFO   | jvm 5    | 2011/05/31 23:58:28 | Wrapper Manager: Registering shutdown hook
INFO   | jvm 5    | 2011/05/31 23:58:28 | Wrapper Manager: Using wrapper
INFO   | jvm 5    | 2011/05/31 23:58:28 | Load native library.  One or more attempts may fail if platform specific libraries do not exist.
INFO   | jvm 5    | 2011/05/31 23:58:28 | Loading native library failed: libwrapper-linux-x86-64.so  Cause: java.lang.UnsatisfiedLinkError: no wrapper-linux-x86-64 in java.library.path
INFO   | jvm 5    | 2011/05/31 23:58:28 | Loaded native library: libwrapper.so
INFO   | jvm 5    | 2011/05/31 23:58:28 | Calling native initialization method.
INFO   | jvm 5    | 2011/05/31 23:58:28 | Inside native WrapperManager initialization method
INFO   | jvm 5    | 2011/05/31 23:58:28 | Java Version   : 1.6.0_17-b17 OpenJDK 64-Bit Server VM
INFO   | jvm 5    | 2011/05/31 23:58:28 | Java VM Vendor : Sun Microsystems Inc.
INFO   | jvm 5    | 2011/05/31 23:58:28 | 
INFO   | jvm 5    | 2011/05/31 23:58:28 | WrapperManager.start(com.redhat.rhn.taskomatic.core.TaskomaticDaemon at 35960f05, args[]) called by thread: main
INFO   | jvm 5    | 2011/05/31 23:58:28 | Startup runner thread started.
INFO   | jvm 5    | 2011/05/31 23:58:28 | Control event monitor thread started.
INFO   | jvm 5    | 2011/05/31 23:58:28 | Communications runner thread started.
INFO   | jvm 5    | 2011/05/31 23:58:28 | Open socket to wrapper...Wrapper-Connection
INFO   | jvm 5    | 2011/05/31 23:58:28 | Failed attempt to bind using local port 31000
INFO   | jvm 5    | 2011/05/31 23:58:28 | Failed attempt to bind using local port 31001
INFO   | jvm 5    | 2011/05/31 23:58:28 | Opened Socket from 31002 to 32002
INFO   | jvm 5    | 2011/05/31 23:58:28 | Send a packet KEY : ZBkwgETL2Jmrn7qm
INFO   | jvm 5    | 2011/05/31 23:58:28 | handleSocket(Socket[addr=/127.0.0.1,port=32002,localport=31002])
DEBUG  | wrapperp | 2011/05/31 23:58:28 | accepted a socket from 127.0.0.1 on port 31002
DEBUG  | wrapperp | 2011/05/31 23:58:28 | read a packet KEY : ZBkwgETL2Jmrn7qm
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Got key from JVM: ZBkwgETL2Jmrn7qm
DEBUG  | wrapperp | 2011/05/31 23:58:28 | send a packet LOW_LOG_LEVEL : 1
DEBUG  | wrapperp | 2011/05/31 23:58:28 | send a packet PING_TIMEOUT : 60
DEBUG  | wrapperp | 2011/05/31 23:58:28 | send a packet PROPERTIES : (Property Values)
DEBUG  | wrapper  | 2011/05/31 23:58:28 | Start Application.
DEBUG  | wrapperp | 2011/05/31 23:58:28 | send a packet START : start
INFO   | jvm 5    | 2011/05/31 23:58:28 | Received a packet LOW_LOG_LEVEL : 1
INFO   | jvm 5    | 2011/05/31 23:58:28 | Wrapper Manager: LowLogLevel from Wrapper is 1
INFO   | jvm 5    | 2011/05/31 23:58:28 | Received a packet PING_TIMEOUT : 60
INFO   | jvm 5    | 2011/05/31 23:58:28 | PingTimeout from Wrapper is 60000
INFO   | jvm 5    | 2011/05/31 23:58:28 | Received a packet PROPERTIES : (Property Values)
INFO   | jvm 5    | 2011/05/31 23:58:28 | Received a packet START : start
INFO   | jvm 5    | 2011/05/31 23:58:28 | calling WrapperListener.start()
INFO   | jvm 5    | 2011/05/31 23:58:28 | Waiting for WrapperListener.start runner thread to complete.
INFO   | jvm 5    | 2011/05/31 23:58:28 | WrapperListener.start runner thread started.
ERROR  | wrapper  | 2011/05/31 23:58:58 | Startup failed: Timed out waiting for signal from JVM.
ERROR  | wrapper  | 2011/05/31 23:58:58 | JVM did not exit on request, terminated
DEBUG  | wrapper  | 2011/05/31 23:58:58 | Signal trapped.  Details:
DEBUG  | wrapper  | 2011/05/31 23:58:58 |   signal number=17 (SIGCHLD), source="unknown"
DEBUG  | wrapper  | 2011/05/31 23:58:58 | Received SIGCHLD, calling wait().
DEBUG  | wrapper  | 2011/05/31 23:58:58 | wait() returned, child process should be gone.
DEBUG  | wrapperp | 2011/05/31 23:58:58 | server listening on port 32002.
DEBUG  | wrapper  | 2011/05/31 23:58:58 | JVM was only running for 29 seconds leading to a failed restart count of 5.
FATAL  | wrapper  | 2011/05/31 23:58:58 | There were 5 failed launches in a row, each lasting less than 300 seconds.  Giving up.
FATAL  | wrapper  | 2011/05/31 23:58:58 |   There may be a configuration problem: please check the logs.
STATUS | wrapper  | 2011/05/31 23:58:58 | <-- Wrapper Stopped


Im not quite sure where to start looking. The server has plenty of available resources so im not sure why its timing out. Any suggestions on what i can check would be helpful.

Thanks
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://listman.redhat.com/archives/spacewalk-list/attachments/20110601/d4fe2694/attachment.htm>


More information about the Spacewalk-list mailing list