[Wrapper-user] App starts but does not respond to pings

SourceForge Headquarters 1320 Columbia Street Suite 310 San Diego, CA 92101 +1 (858) 422-6466

Hello again,

I have managed to get my App to start up and run under the Java Service 
Wrapper. It starts up very nicely, and I can see that it is running: it 
outputs to a log file and provides HTTP access to its internal "console". 
However, after 30 seconds the Java wrapper decides that it is not 
responding to pings and forcibly terminates it. I have tried setting 
wrapper.request_thread_dump_on_failed_jvm_exit=TRUE to see whether any 
threads are tangled inside the system, but unfortunately there are too 
many threads running and the thread dump has not completed before the 
Wrapper terminates the JVM, cutting the thread dump off in mid flow.

During the 30 seconds that the App is not responding to pings, CPU load 
settles down to 0 about 5 seconds after startup is completed, so that I 
know the ping is not being locked out by CPU overload.

Can anybody suggest where I should look for the missing ping?

Log from a single failed run is listed below.

        Alec Cawley

STATUS | wrapper  | 2005/12/01 14:12:31 | --> Wrapper Started as Console
DEBUG  | wrapper  | 2005/12/01 14:12:31 | Using system timer.
DEBUG  | wrapperp | 2005/12/01 14:12:31 | server listening on port 32000.
STATUS | wrapper  | 2005/12/01 14:12:31 | Launching a JVM...
DEBUG  | wrapper  | 2005/12/01 14:12:31 | command: 
"c:\j2sdk1.4.2_08\bin\java" -Xms128m -Xmx512m 
-Djava.library.path="F:\\dlls" -classpath 
"F:\\QuentinManager.jar;F:\\jars\wrapper-3.1.2.jar;F:\\jars\jacorb.jar;F:\\jars\mysql-connector-java-3.1.8-bin.jar;F:\\jars\jug-1.1.2.jar;F:\\jars\avalon-framework-4.1.5.jar;F:\\jars\logkit-1.2.jar" 
-Dwrapper.key="IcYMfSZTqNdo3jtq" -Dwrapper.port=32000 
-Dwrapper.debug="TRUE" -Dwrapper.use_system_time="TRUE" 
-Dwrapper.version="3.1.2" -Dwrapper.native_library="wrapper" 
-Dwrapper.cpu.timeout="10" -Dwrapper.jvmid=1 
com.quantel.QuentinManager.QuentinWrapper quentin.properties
DEBUG  | wrapper  | 2005/12/01 14:12:31 | JVM started (PID=2124)
INFO   | jvm 1    | 2005/12/01 14:12:32 | WrapperManager class initialized 
by thread: main  Using classloader: 
sun.misc.Launcher$AppClassLoader@e80a59
INFO   | jvm 1    | 2005/12/01 14:12:32 | Wrapper Manager: JVM #1
INFO   | jvm 1    | 2005/12/01 14:12:32 | Wrapper Manager: Registering 
shutdown hook
INFO   | jvm 1    | 2005/12/01 14:12:32 | Wrapper Manager: Using wrapper
INFO   | jvm 1    | 2005/12/01 14:12:32 | Loaded native library: 
wrapper.dll
INFO   | jvm 1    | 2005/12/01 14:12:32 | Calling native initialization 
method.
INFO   | jvm 1    | 2005/12/01 14:12:32 | Initializing WrapperManager 
native library.
INFO   | jvm 1    | 2005/12/01 14:12:32 | Java Executable: 
c:\j2sdk1.4.2_08\bin\java.exe
INFO   | jvm 1    | 2005/12/01 14:12:32 | Windows version: 5.0.2195
INFO   | jvm 1    | 2005/12/01 14:12:32 | Java Version   : 1.4.2_08-b03 
Java HotSpot(TM) Client VM
INFO   | jvm 1    | 2005/12/01 14:12:32 | Java VM Vendor : Sun 
Microsystems Inc.
INFO   | jvm 1    | 2005/12/01 14:12:32 |
INFO   | jvm 1    | 2005/12/01 14:12:32 | Wrapper (Version 3.1.2) 
http://wrapper.tanukisoftware.org
INFO   | jvm 1    | 2005/12/01 14:12:32 |
INFO   | jvm 1    | 2005/12/01 14:12:32 | 
WrapperManager.start(com.quantel.QuentinManager.QuentinWrapper@186db54, 
args["quentin.properties"]) called by thread: main
INFO   | jvm 1    | 2005/12/01 14:12:32 | Open socket to wrapper...
INFO   | jvm 1    | 2005/12/01 14:12:32 | Opened Socket
INFO   | jvm 1    | 2005/12/01 14:12:32 | Send a packet KEY : 
IcYMfSZTqNdo3jtq
INFO   | jvm 1    | 2005/12/01 14:12:32 | 
handleSocket(Socket[addr=/127.0.0.1,port=32000,localport=4003])
DEBUG  | wrapper  | 2005/12/01 14:12:32 | Pause reading child output to 
share cycles.
DEBUG  | wrapperp | 2005/12/01 14:12:32 | accepted a socket from 127.0.0.1 
on port 4003
DEBUG  | wrapperp | 2005/12/01 14:12:32 | read a packet KEY : 
IcYMfSZTqNdo3jtq
DEBUG  | wrapper  | 2005/12/01 14:12:32 | Got key from JVM: 
IcYMfSZTqNdo3jtq
DEBUG  | wrapperp | 2005/12/01 14:12:32 | send a packet LOW_LOG_LEVEL : 1
DEBUG  | wrapperp | 2005/12/01 14:12:32 | send a packet PING_TIMEOUT : 30
DEBUG  | wrapper  | 2005/12/01 14:12:32 | Start Application.
DEBUG  | wrapperp | 2005/12/01 14:12:32 | send a packet START : start
INFO   | jvm 1    | 2005/12/01 14:12:32 | Received a packet LOW_LOG_LEVEL 
: 1
INFO   | jvm 1    | 2005/12/01 14:12:33 | Wrapper Manager: LowLogLevel 
from Wrapper is 1
DEBUG  | wrapper  | 2005/12/01 14:12:33 | Pause reading child output to 
share cycles.
INFO   | jvm 1    | 2005/12/01 14:12:33 | Received a packet PING_TIMEOUT : 
30
DEBUG  | wrapper  | 2005/12/01 14:12:34 | Pause reading child output to 
share cycles.
INFO   | jvm 1    | 2005/12/01 14:12:34 | Wrapper Manager: PingTimeout 
from Wrapper is 30000
INFO   | jvm 1    | 2005/12/01 14:12:34 | Received a packet START : start
INFO   | jvm 1    | 2005/12/01 14:12:34 | calling listener.start()
INFO   | jvm 1    | 2005/12/01 14:12:34 | Not headless
INFO   | jvm 1    | 2005/12/01 14:12:34 | 01-Dec-2005 14:12:32 : SEVERE  : 
QuentinManager C3.0.18.16
INFO   | jvm 1    | 2005/12/01 14:12:34 | 01-Dec-2005 14:12:33 : SEVERE  : 
Invalid format 10000 in format table
ERROR  | wrapper  | 2005/12/01 14:13:01 | Startup failed: Timed out 
waiting for signal from JVM.
ERROR  | wrapper  | 2005/12/01 14:13:01 | JVM did not exit on request, 
terminated
DEBUG  | wrapper  | 2005/12/01 14:13:02 | JVM was only running for 30 
seconds leading to a failed restart count of 1.
FATAL  | wrapper  | 2005/12/01 14:13:02 | There were 1 failed launches in 
a row, each lasting less than 300 seconds.  Giving up.
FATAL  | wrapper  | 2005/12/01 14:13:02 |   There may be a configuration 
problem: please check the logs.
STATUS | wrapper  | 2005/12/01 14:13:02 | <-- Wrapper Stopped