#117 Wrapper shutdown itself thinking that JVM hung

[Anonymous]

This is a bizzare problem happens when the clock
moving back in time causing the wrapper to think the
JVM is hung.
INFO | jvm 1 | 2005/08/20 03:05:41 | Read Timed
out. (Last Ping was -86100 milliseconds ago)

Is this a bug? I don't know why the clock go back in
time and causing the wrapper ping to not getting the
response back.

INFO | jvm 1 | 2005/08/20 03:07:03 | Received a
packet PING : ping
INFO | jvm 1 | 2005/08/20 03:07:03 | Send a packet
PING : ok
DEBUG | wrapperp | 2005/08/20 03:07:03 | read a
packet PING : ok
DEBUG | wrapper | 2005/08/20 03:07:03 | Got ping
response from JVM
DEBUG | wrapperp | 2005/08/20 03:07:07 | send a
packet PING : ping
INFO | jvm 1 | 2005/08/20 03:05:11 | Received a
packet PING : ping
INFO | jvm 1 | 2005/08/20 03:05:11 | Send a packet
PING : ok
DEBUG | wrapperp | 2005/08/20 03:05:11 | read a
packet PING : ok
DEBUG | wrapper | 2005/08/20 03:05:11 | Got ping
response from JVM
INFO | jvm 1 | 2005/08/20 03:05:41 | Read Timed
out. (Last Ping was -86100 milliseconds ago)
ERROR | wrapper | 2005/08/20 03:05:45 | JVM
appears hung: Timed out waiting for signal from JVM.
ERROR | wrapper | 2005/08/20 03:05:45 | JVM did not
exit on request, terminated
DEBUG | wrapper | 2005/08/20 03:05:45 | Waiting 5
seconds before launching another JVM.
STATUS | wrapper | 2005/08/20 03:05:49 | Launching a
JVM...
DEBUG | wrapper | 2005/08/20 03:05:49 |
command: "..\\..\\common\\jre\\1.4.2\\bin\\java" -
Djava.library.path="." -
classpath "wrappertest.jar;wrapper.jar;server.jar;pimcom
mon.jar;log4j-
1.2.6.jar;parser.jar;jaxp.jar;genericdal.jar;Opta2000.jar;xe
rces.jar;mail.jar;activation.jar;soap.jar;snow.jar;common.j
ar" -Dwrapper.key="2b3XYyh8pB0doA_c" -
Dwrapper.port=32002 -Dwrapper.debug="TRUE" -
Dwrapper.use_system_time="TRUE" -
Dwrapper.version="3.1.1" -
Dwrapper.native_library="wrapper" -
Dwrapper.service="TRUE" -Dwrapper.cpu.timeout="10" -
Dwrapper.jvmid=2
org.tanukisoftware.wrapper.WrapperSimpleApp
com.mckhboc.product.hostimaging.foundation.laserarc.r
mi.LaserArcPluginServer server.properties
DEBUG | wrapper | 2005/08/20 03:05:49 | JVM started
(PID=5228)
INFO | jvm 2 | 2005/08/20 03:05:50 |
WrapperManager class initialized by thread: main
Using classloader:
sun.misc.Launcher$AppClassLoader@e80a59
INFO | jvm 2 | 2005/08/20 03:05:50 | Wrapper
Manager: JVM #2
INFO | jvm 2 | 2005/08/20 03:05:50 | Wrapper
Manager: Registering shutdown hook
INFO | jvm 2 | 2005/08/20 03:05:50 | Wrapper
Manager: Using wrapper
INFO | jvm 2 | 2005/08/20 03:05:50 | Loaded native
library: wrapper.dll
INFO | jvm 2 | 2005/08/20 03:05:50 | Calling native
initialization method.
INFO | jvm 2 | 2005/08/20 03:05:50 | Initializing
WrapperManager native library.
INFO | jvm 2 | 2005/08/20 03:05:50 | Java
Executable: E:\MCK\common\jre\1.4.2\bin\java.exe
INFO | jvm 2 | 2005/08/20 03:05:50 | Windows
version: 5.2.3790
INFO | jvm 2 | 2005/08/20 03:05:50 | Java Version :
1.4.2-b28 Java HotSpot(TM) Client VM
INFO | jvm 2 | 2005/08/20 03:05:50 | Java VM
Vendor : Sun Microsystems Inc.
INFO | jvm 2 | 2005/08/20 03:05:50 |
INFO | jvm 2 | 2005/08/20 03:05:50 | Wrapper
(Version 3.1.1) http://wrapper.tanukisoftware.org
INFO | jvm 2 | 2005/08/20 03:05:50 |
INFO | jvm 2 | 2005/08/20 03:05:50 |
WrapperManager.start
(org.tanukisoftware.wrapper.WrapperSimpleApp@1e445
7d, args["server.properties"]) called by thread: main
INFO | jvm 2 | 2005/08/20 03:05:50 | Open socket to
wrapper...
INFO | jvm 2 | 2005/08/20 03:05:50 | Opened Socket
INFO | jvm 2 | 2005/08/20 03:05:50 | Send a packet
KEY : 2b3XYyh8pB0doA_c
INFO | jvm 2 | 2005/08/20 03:05:50 | handleSocket
(Socket[addr=/127.0.0.1,port=32002,localport=1912])
DEBUG | wrapperp | 2005/08/20 03:05:50 | accepted a
socket from 127.0.0.1 on port 1912
DEBUG | wrapperp | 2005/08/20 03:05:50 | read a
packet KEY : 2b3XYyh8pB0doA_c
DEBUG | wrapper | 2005/08/20 03:05:50 | Got key
from JVM: 2b3XYyh8pB0doA_c
DEBUG | wrapperp | 2005/08/20 03:05:50 | send a
packet LOW_LOG_LEVEL : 1
DEBUG | wrapperp | 2005/08/20 03:05:50 | send a
packet PING_TIMEOUT : 30
DEBUG | wrapper | 2005/08/20 03:05:50 | Start
Application.
DEBUG | wrapperp | 2005/08/20 03:05:50 | send a
packet START : start
INFO | jvm 2 | 2005/08/20 03:05:50 | Received a
packet LOW_LOG_LEVEL : 1
INFO | jvm 2 | 2005/08/20 03:05:50 | Wrapper
Manager: LowLogLevel from Wrapper is 1
INFO | jvm 2 | 2005/08/20 03:05:50 | Received a
packet PING_TIMEOUT : 30
INFO | jvm 2 | 2005/08/20 03:05:50 | Wrapper
Manager: PingTimeout from Wrapper is 30000
INFO | jvm 2 | 2005/08/20 03:05:50 | Received a
packet START : start
INFO | jvm 2 | 2005/08/20 03:05:50 | calling
listener.start()
INFO | jvm 2 | 2005/08/20 03:05:50 |
WrapperSimpleApp: start(args)
INFO | jvm 2 | 2005/08/20 03:05:50 |
WrapperSimpleApp: invoking main method
INFO | jvm 2 | 2005/08/20 03:05:50 | [20 Aug 2005
03:05:50,755] [WrapperSimpleAppMain] -
INFO | jvm 2 | 2005/08/20 03:05:50 | Starting Server