Re: [Wrapper-user] JVM restart

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

Faysal,
The problem is that your implementation of the WrapperListener.start
method never returns. The API requires that that method returns within
a short period of time.
Unless you really need to write your own WrapperListener, I would
suggest first trying to make the WrapperSimpleApp helper class work for
you. That is integration method 1.

Cheers,
Leif

Alzabit, Faysal wrote:

> Hi,
>
> I am using org.apache.log4j.net.SocketServer as a service to
> centralize logging from different servers; the problem is that the
> SocketServer starts and goes into a loop waiting for a connection;
> when I implement the WrapperListener it seems that it starts the
> application but it does not come back even when the application is
> started as a thread; so the wrapper ends restarting the JVM !!! Any
> idea how I can get the service to start and end ?
>
> The following is the debug message I am getting :
>
> STATUS | wrapper | 2005/06/27 15:48:57 | --> Wrapper Started as Console
> ERROR | wrapper | 2005/06/27 15:48:57 | Unable to write to the
> EventLog due to: The event log file is full. (0x5de)
> ERROR | wrapper | 2005/06/27 15:48:57 | Internally setting
> wrapper.syslog.loglevel=NONE to prevent further messages.
> DEBUG | wrapper | 2005/06/27 15:48:57 | Using system timer.
> DEBUG | wrapperp | 2005/06/27 15:48:57 | server listening on port 32000.
> STATUS | wrapper | 2005/06/27 15:48:58 | Launching a JVM...
> DEBUG | wrapper | 2005/06/27 15:48:58 | command: "C:\Program
> Files\Java\jdk1.5.0\bin\java.exe" -Djava.library.path="../lib"
> -classpath "../lib/wrapper.jar;C:/logCentral/"
> -Dwrapper.key="QkmW5_uFP8Oh6kEU" -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
> org.unch.logcentral.core.CentralLoggerRapper 4321
> c:\\CentralLogger.properties c:\\
>
> DEBUG | wrapper | 2005/06/27 15:48:58 | JVM started (PID=884)
> INFO | jvm 1 | 2005/06/27 15:48:59 | fga, CentralLoggerRapper , in the
> main class of centralLoggerRapper
> INFO | jvm 1 | 2005/06/27 15:48:59 | fga, CentralLoggerWrapper 4321
> c:\\CentralLogger.properties c:\\
> INFO | jvm 1 | 2005/06/27 15:48:59 | WrapperManager class initialized
> by thread: main Using classloader: sun.misc.Launcher$ExtClassLoader@a9c85c
>
> INFO | jvm 1 | 2005/06/27 15:48:59 | Wrapper Manager: JVM #1
> INFO | jvm 1 | 2005/06/27 15:48:59 | Wrapper Manager: Registering
> shutdown hook
> INFO | jvm 1 | 2005/06/27 15:48:59 | Wrapper Manager: Using wrapper
> INFO | jvm 1 | 2005/06/27 15:48:59 | Loaded native library: wrapper.dll
> INFO | jvm 1 | 2005/06/27 15:48:59 | Calling native initialization
> method.
> INFO | jvm 1 | 2005/06/27 15:48:59 | Initializing WrapperManager
> native library.
> INFO | jvm 1 | 2005/06/27 15:48:59 | Java Executable: C:\Program
> Files\Java\jdk1.5.0\bin\java.exe
> INFO | jvm 1 | 2005/06/27 15:48:59 | Windows version: 5.1.2600
> INFO | jvm 1 | 2005/06/27 15:48:59 | Java Version : 1.5.0-b64 Java
> HotSpot(TM) Client VM
> INFO | jvm 1 | 2005/06/27 15:48:59 | Java VM Vendor : Sun Microsystems
> Inc.
> INFO | jvm 1 | 2005/06/27 15:48:59 |
> INFO | jvm 1 | 2005/06/27 15:48:59 | Wrapper (Version 3.1.2)
> _http://wrapper.tanukisoftware.org_
> INFO | jvm 1 | 2005/06/27 15:48:59 |
> INFO | jvm 1 | 2005/06/27 15:48:59 |
> WrapperManager.start(org.unch.logcentral.core.CentralLoggerRapper@a90653,
> args["4321", "c:\\CentralLogger.properties", "c:\\"]) called by
> thread: main
>
> INFO | jvm 1 | 2005/06/27 15:48:59 | Open socket to wrapper...
> INFO | jvm 1 | 2005/06/27 15:48:59 | Opened Socket
> INFO | jvm 1 | 2005/06/27 15:48:59 | Send a packet KEY : QkmW5_uFP8Oh6kEU
> INFO | jvm 1 | 2005/06/27 15:48:59 |
> handleSocket(Socket[addr=localhost/127.0.0.1,port=32000,localport=1156])
> DEBUG | wrapperp | 2005/06/27 15:48:59 | accepted a socket from
> 127.0.0.1 on port 1156
> DEBUG | wrapperp | 2005/06/27 15:48:59 | read a packet KEY :
> QkmW5_uFP8Oh6kEU
> DEBUG | wrapper | 2005/06/27 15:48:59 | Got key from JVM:
> QkmW5_uFP8Oh6kEU
> DEBUG | wrapperp | 2005/06/27 15:48:59 | send a packet LOW_LOG_LEVEL : 1
> DEBUG | wrapperp | 2005/06/27 15:48:59 | send a packet PING_TIMEOUT : 10
> DEBUG | wrapper | 2005/06/27 15:48:59 | Start Application.
> DEBUG | wrapperp | 2005/06/27 15:48:59 | send a packet START : start
> INFO | jvm 1 | 2005/06/27 15:48:59 | Received a packet LOW_LOG_LEVEL : 1
> INFO | jvm 1 | 2005/06/27 15:48:59 | Wrapper Manager: LowLogLevel from
> Wrapper is 1
> INFO | jvm 1 | 2005/06/27 15:48:59 | Received a packet PING_TIMEOUT : 10
> INFO | jvm 1 | 2005/06/27 15:48:59 | Wrapper Manager: PingTimeout from
> Wrapper is 10000
> INFO | jvm 1 | 2005/06/27 15:48:59 | Received a packet START : start
> INFO | jvm 1 | 2005/06/27 15:48:59 | calling listener.start()
> INFO | jvm 1 | 2005/06/27 15:48:59 | in the start method of
> CentralLoggerRapper
> INFO | jvm 1 | 2005/06/27 15:48:59 | fga, CentralLoggerRapper, in the
> run method
> INFO | jvm 1 | 2005/06/27 15:48:59 | CentralLogger, in the main method
> INFO | jvm 1 | 2005/06/27 15:48:59 | after the logger statment
> INFO | jvm 1 | 2005/06/27 15:48:59 | SocketServer is using logger
> named: org.unch.webcis.medications
> INFO | jvm 1 | 2005/06/27 15:48:59 | after SocketServer initilization
> in the CentralLogger start method
> INFO | jvm 1 | 2005/06/27 15:48:59 | fga, CentralLoggerargv[0]: 4321
> argv[1]: c:\\CentralLogger.properties
> INFO | jvm 1 | 2005/06/27 15:48:59 | 0 [Wrapper-Connection] INFO
> org.apache.log4j.net.SocketServer - Listening on port 4321
>
> INFO | jvm 1 | 2005/06/27 15:48:59 | 0 [Wrapper-Connection] INFO
> org.apache.log4j.net.SocketServer - Waiting to accept a new client.
>
> ERROR | wrapper | 2005/06/27 15:49:28 | Startup failed: Timed out
> waiting for signal from JVM.
> STATUS | wrapper | 2005/06/27 15:49:28 | Dumping JVM state.
> DEBUG | wrapper | 2005/06/27 15:49:28 | Sending BREAK event to process
> group 884.
> INFO | jvm 1 | 2005/06/27 15:49:28 | Full thread dump Java HotSpot(TM)
> Client VM (1.5.0-b64 mixed mode, sharing):
> INFO | jvm 1 | 2005/06/27 15:49:28 |
> INFO | jvm 1 | 2005/06/27 15:49:28 | "DestroyJavaVM" prio=5
> tid=0x00037ee8 nid=0x140 waiting on condition [0x00000000..0x0007fae8]
>
> INFO | jvm 1 | 2005/06/27 15:49:28 |
> INFO | jvm 1 | 2005/06/27 15:49:28 | "Wrapper-Connection" prio=5
> tid=0x00acf828 nid=0x3f8 runnable [0x02ecf000..0x02ecfb68]
>
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> java.net.PlainSocketImpl.socketAccept(Native Method)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> java.net.PlainSocketImpl.accept(PlainSocketImpl.java:384)
> INFO | jvm 1 | 2005/06/27 15:49:28 | - locked <0x22aaac20> (a
> java.net.SocksSocketImpl)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> java.net.ServerSocket.implAccept(ServerSocket.java:450)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> java.net.ServerSocket.accept(ServerSocket.java:421)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> org.apache.log4j.net.SocketServer.main(SocketServer.java:100)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> org.unch.logcentral.core.CentralLogger.run(CentralLogger.java:69)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> org.unch.logcentral.core.CentralLogger.start(CentralLogger.java:44)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> org.unch.logcentral.core.CentralLoggerRapper.run(CentralLoggerRapper.java:83)
>
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> org.unch.logcentral.core.CentralLoggerRapper.start(CentralLoggerRapper.java:38)
>
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> org.tanukisoftware.wrapper.WrapperManager.startInner(WrapperManager.java:1785)
>
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> org.tanukisoftware.wrapper.WrapperManager.handleSocket(WrapperManager.java:2384)
>
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> org.tanukisoftware.wrapper.WrapperManager.run(WrapperManager.java:2696)
>
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> java.lang.Thread.run(Thread.java:595)
> INFO | jvm 1 | 2005/06/27 15:49:28 |
> INFO | jvm 1 | 2005/06/27 15:49:28 | "Wrapper-Control-Event-Monitor"
> daemon prio=5 tid=0x00a91298 nid=0x6b8 waiting on condition
> [0x02e8f000..0x02e8fbe8]
>
> INFO | jvm 1 | 2005/06/27 15:49:28 | at java.lang.Thread.sleep(Native
> Method)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> org.tanukisoftware.wrapper.WrapperManager$2.run(WrapperManager.java:701)
>
> INFO | jvm 1 | 2005/06/27 15:49:28 |
> INFO | jvm 1 | 2005/06/27 15:49:28 | "Low Memory Detector" daemon
> prio=5 tid=0x00a6d980 nid=0x68c runnable [0x00000000..0x00000000]
>
> INFO | jvm 1 | 2005/06/27 15:49:28 |
> INFO | jvm 1 | 2005/06/27 15:49:28 | "CompilerThread0" daemon prio=10
> tid=0x00a6c558 nid=0x690 waiting on condition [0x00000000..0x02bcf6c0]
>
> INFO | jvm 1 | 2005/06/27 15:49:28 |
> INFO | jvm 1 | 2005/06/27 15:49:28 | "Signal Dispatcher" daemon
> prio=10 tid=0x00a6b848 nid=0x430 waiting on condition
> [0x00000000..0x00000000]
>
> INFO | jvm 1 | 2005/06/27 15:49:28 |
> INFO | jvm 1 | 2005/06/27 15:49:28 | "Finalizer" daemon prio=9
> tid=0x00a68b18 nid=0x434 in Object.wait() [0x02b4f000..0x02b4fa68]
>
> INFO | jvm 1 | 2005/06/27 15:49:28 | at java.lang.Object.wait(Native
> Method)
> INFO | jvm 1 | 2005/06/27 15:49:28 | - waiting on <0x22f61100> (a
> java.lang.ref.ReferenceQueue$Lock)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:116)
> INFO | jvm 1 | 2005/06/27 15:49:28 | - locked <0x22f61100> (a
> java.lang.ref.ReferenceQueue$Lock)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:132)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)
> INFO | jvm 1 | 2005/06/27 15:49:28 |
> INFO | jvm 1 | 2005/06/27 15:49:28 | "Reference Handler" daemon
> prio=10 tid=0x00a46770 nid=0x3d8 in Object.wait() [0x02b0f000..0x02b0fae8]
>
> INFO | jvm 1 | 2005/06/27 15:49:28 | at java.lang.Object.wait(Native
> Method)
> INFO | jvm 1 | 2005/06/27 15:49:28 | - waiting on <0x22f60ef0> (a
> java.lang.ref.Reference$Lock)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> java.lang.Object.wait(Object.java:474)
> INFO | jvm 1 | 2005/06/27 15:49:28 | at
> java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
> INFO | jvm 1 | 2005/06/27 15:49:28 | - locked <0x22f60ef0> (a
> java.lang.ref.Reference$Lock)
> INFO | jvm 1 | 2005/06/27 15:49:28 |
> INFO | jvm 1 | 2005/06/27 15:49:28 | "VM Thread" prio=10
> tid=0x00036908 nid=0x3c8 runnable
> INFO | jvm 1 | 2005/06/27 15:49:28 |
> INFO | jvm 1 | 2005/06/27 15:49:28 | "VM Periodic Task Thread" prio=10
> tid=0x00a6eb90 nid=0x5f4 waiting on condition
>
> INFO | jvm 1 | 2005/06/27 15:49:28 |
> ERROR | wrapper | 2005/06/27 15:49:32 | JVM did not exit on request,
> terminated
> DEBUG | wrapper | 2005/06/27 15:49:33 | JVM was only running for 35
> seconds leading to a failed restart count of 1.
> DEBUG | wrapper | 2005/06/27 15:49:33 | Waiting 5 seconds before
> launching another JVM.
> STATUS | wrapper | 2005/06/27 15:49:37 | Launching a JVM...
> DEBUG | wrapper | 2005/06/27 15:49:37 | command: "C:\Program
> Files\Java\jdk1.5.0\bin\java.exe" -Djava.library.path="../lib"
> -classpath "../lib/wrapper.jar;C:/logCentral/"
> -Dwrapper.key="2yqjvTR9FkXjFAvq" -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=2
> org.unch.logcentral.core.CentralLoggerRapper 4321
> c:\\CentralLogger.properties c:\\
>
> DEBUG | wrapper | 2005/06/27 15:49:37 | JVM started (PID=1000)
> INFO | jvm 2 | 2005/06/27 15:49:37 | fga, CentralLoggerRapper , in the
> main class of centralLoggerRapper
> INFO | jvm 2 | 2005/06/27 15:49:37 | fga, CentralLoggerWrapper 4321
> c:\\CentralLogger.properties c:\\
> INFO | jvm 2 | 2005/06/27 15:49:37 | WrapperManager class initialized
> by thread: main Using classloader: sun.misc.Launcher$ExtClassLoader@a9c85c
>
> INFO | jvm 2 | 2005/06/27 15:49:37 | Wrapper Manager: JVM #2
> INFO | jvm 2 | 2005/06/27 15:49:37 | Wrapper Manager: Registering
> shutdown hook
> INFO | jvm 2 | 2005/06/27 15:49:37 | Wrapper Manager: Using wrapper
> INFO | jvm 2 | 2005/06/27 15:49:37 | Loaded native library: wrapper.dll
> INFO | jvm 2 | 2005/06/27 15:49:37 | Calling native initialization
> method.
> INFO | jvm 2 | 2005/06/27 15:49:37 | Initializing WrapperManager
> native library.
> INFO | jvm 2 | 2005/06/27 15:49:37 | Java Executable: C:\Program
> Files\Java\jdk1.5.0\bin\java.exe
> INFO | jvm 2 | 2005/06/27 15:49:37 | Windows version: 5.1.2600
> INFO | jvm 2 | 2005/06/27 15:49:37 | Java Version : 1.5.0-b64 Java
> HotSpot(TM) Client VM
> INFO | jvm 2 | 2005/06/27 15:49:37 | Java VM Vendor : Sun Microsystems
> Inc.
> INFO | jvm 2 | 2005/06/27 15:49:37 |
> INFO | jvm 2 | 2005/06/27 15:49:37 | Wrapper (Version 3.1.2)
> _http://wrapper.tanukisoftware.org_
> INFO | jvm 2 | 2005/06/27 15:49:37 |
> INFO | jvm 2 | 2005/06/27 15:49:37 |
> WrapperManager.start(org.unch.logcentral.core.CentralLoggerRapper@a90653,
> args["4321", "c:\\CentralLogger.properties", "c:\\"]) called by
> thread: main
>
> INFO | jvm 2 | 2005/06/27 15:49:37 | Open socket to wrapper...
> INFO | jvm 2 | 2005/06/27 15:49:37 | Opened Socket
> INFO | jvm 2 | 2005/06/27 15:49:37 | Send a packet KEY : 2yqjvTR9FkXjFAvq
> INFO | jvm 2 | 2005/06/27 15:49:37 |
> handleSocket(Socket[addr=localhost/127.0.0.1,port=32000,localport=1157])
> DEBUG | wrapperp | 2005/06/27 15:49:37 | accepted a socket from
> 127.0.0.1 on port 1157
> DEBUG | wrapperp | 2005/06/27 15:49:37 | read a packet KEY :
> 2yqjvTR9FkXjFAvq
> DEBUG | wrapper | 2005/06/27 15:49:37 | Got key from JVM:
> 2yqjvTR9FkXjFAvq
> DEBUG | wrapperp | 2005/06/27 15:49:37 | send a packet LOW_LOG_LEVEL : 1
> DEBUG | wrapperp | 2005/06/27 15:49:37 | send a packet PING_TIMEOUT : 10
> DEBUG | wrapper | 2005/06/27 15:49:37 | Start Application.
> DEBUG | wrapperp | 2005/06/27 15:49:37 | send a packet START : start
> INFO | jvm 2 | 2005/06/27 15:49:37 | Received a packet LOW_LOG_LEVEL : 1
> INFO | jvm 2 | 2005/06/27 15:49:37 | Wrapper Manager: LowLogLevel from
> Wrapper is 1
> INFO | jvm 2 | 2005/06/27 15:49:37 | Received a packet PING_TIMEOUT : 10
> INFO | jvm 2 | 2005/06/27 15:49:37 | Wrapper Manager: PingTimeout from
> Wrapper is 10000
> INFO | jvm 2 | 2005/06/27 15:49:37 | Received a packet START : start
> INFO | jvm 2 | 2005/06/27 15:49:37 | calling listener.start()
> INFO | jvm 2 | 2005/06/27 15:49:37 | in the start method of
> CentralLoggerRapper
> INFO | jvm 2 | 2005/06/27 15:49:37 | fga, CentralLoggerRapper, in the
> run method
> INFO | jvm 2 | 2005/06/27 15:49:37 | CentralLogger, in the main method
> INFO | jvm 2 | 2005/06/27 15:49:37 | after the logger statment
> INFO | jvm 2 | 2005/06/27 15:49:37 | SocketServer is using logger
> named: org.unch.webcis.medications
> INFO | jvm 2 | 2005/06/27 15:49:37 | after SocketServer initilization
> in the CentralLogger start method
> INFO | jvm 2 | 2005/06/27 15:49:37 | fga, CentralLoggerargv[0]: 4321
> argv[1]: c:\\CentralLogger.properties
> INFO | jvm 2 | 2005/06/27 15:49:37 | 0 [Wrapper-Connection] INFO
> org.apache.log4j.net.SocketServer - Listening on port 4321
>
> INFO | jvm 2 | 2005/06/27 15:49:37 | 0 [Wrapper-Connection] INFO
> org.apache.log4j.net.SocketServer - Waiting to accept a new client.
>
> __________________________________________
> Faysal G. Al-Zabit
> APPLICATIONS ANALYST PROGRAMMER II
> Information Services Division
> 321 Meadowmont Village Circle
> Chapel Hill NC 27517
> E-Mail: al...@un...
> Tel. : 919-843-4011
> Fax. : 919-966-2110
> ____________________________________
>