Thread: [Wrapper-user] Restart request causes 5 rapid retries and death

Brought to you by: maxime-tsl, mortenson, samanta-yoshida

wrapper-user

[Wrapper-user] Restart request causes 5 rapid retries and death

From: Ben C. <ple...@ho...> - 2007-03-30 20:37:47

Hi all,

I've been using Wrapper version 3.2.3 to manage my service on a Windows 2003 
Server machine.  Everything is working rather well, except occasionally when 
requesting a restart from within the app (and sometimes just during a 
"normal" restart after an error).  What will happen is that the wrapper will 
attempt to start my app and then give up and terminate the JVM within _one_ 
second!

At least, that's how it looks from the timestamps on the log output 
(below)...   am I missing (or misinterpreting) a config file setting?

Thanks,

Ben


STATUS | wrapper  | 2007/03/20 15:48:27 | JVM requested a restart.

STATUS | wrapper  | 2007/03/20 15:48:34 | Launching a JVM...

INFO | jvm 17 | 2007/03/20 15:48:34 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org

INFO   | jvm 17   | 2007/03/20 15:48:34 |   Copyright 1999-2006 Tanuki 
Software, Inc.  All Rights Reserved.

INFO   | jvm 17   | 2007/03/20 15:48:34 |

ERROR  | wrapper  | 2007/03/20 15:48:34 | Unable to send the start command 
to the JVM.

ERROR  | wrapper  | 2007/03/20 15:48:34 | JVM did not exit on request, 
terminated

STATUS | wrapper  | 2007/03/20 15:48:39 | Launching a JVM...

INFO | jvm 18 | 2007/03/20 15:48:40 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org

INFO   | jvm 18   | 2007/03/20 15:48:40 |   Copyright 1999-2006 Tanuki 
Software, Inc.  All Rights Reserved.

INFO   | jvm 18   | 2007/03/20 15:48:40 |

ERROR  | wrapper  | 2007/03/20 15:48:40 | Unable to send the start command 
to the JVM.

ERROR  | wrapper  | 2007/03/20 15:48:40 | JVM did not exit on request, 
terminated

STATUS | wrapper  | 2007/03/20 15:48:45 | Launching a JVM...

INFO | jvm 19 | 2007/03/20 15:48:45 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org

INFO   | jvm 19   | 2007/03/20 15:48:45 |   Copyright 1999-2006 Tanuki 
Software, Inc.  All Rights Reserved.

INFO   | jvm 19   | 2007/03/20 15:48:45 |

ERROR  | wrapper  | 2007/03/20 15:48:45 | Unable to send the start command 
to the JVM.

ERROR  | wrapper  | 2007/03/20 15:48:46 | JVM did not exit on request, 
terminated

STATUS | wrapper  | 2007/03/20 15:48:51 | Launching a JVM...

INFO | jvm 20 | 2007/03/20 15:48:51 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org

INFO   | jvm 20   | 2007/03/20 15:48:51 |   Copyright 1999-2006 Tanuki 
Software, Inc.  All Rights Reserved.

INFO   | jvm 20   | 2007/03/20 15:48:51 |

ERROR  | wrapper  | 2007/03/20 15:48:51 | Unable to send the start command 
to the JVM.

ERROR  | wrapper  | 2007/03/20 15:48:51 | JVM did not exit on request, 
terminated

STATUS | wrapper  | 2007/03/20 15:48:56 | Launching a JVM...

INFO | jvm 21 | 2007/03/20 15:48:57 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org

INFO   | jvm 21   | 2007/03/20 15:48:57 |   Copyright 1999-2006 Tanuki 
Software, Inc.  All Rights Reserved.

INFO   | jvm 21   | 2007/03/20 15:48:57 |

ERROR  | wrapper  | 2007/03/20 15:48:57 | Unable to send the start command 
to the JVM.

ERROR  | wrapper  | 2007/03/20 15:48:57 | JVM did not exit on request, 
terminated

FATAL  | wrapper  | 2007/03/20 15:48:57 | There were 5 failed launches in a 
row, each lasting less than 300 seconds.  Giving up.

FATAL  | wrapper  | 2007/03/20 15:48:57 |   There may be a configuration 
problem: please check the logs.

STATUS | wrapper  | 2007/03/20 15:48:58 | <-- Wrapper Stopped

_________________________________________________________________
Watch free concerts with Pink, Rod Stewart, Oasis and more. Visit MSN 
Presents today. 
http://music.msn.com/presents?icid=ncmsnpresentstagline&ocid=T002MSN03A07001

Re: [Wrapper-user] Restart request causes 5 rapid retries and death

From: Leif M. <le...@ta...> - 2007-03-31 02:45:59

Ben,
    Is this something that you are able to reproduce?  If so, could you 
set the
wrapper.debug=true property and then send me the resulting log?  I am not
able to tell the exact cause from what is in the current log.

    The JVM is being launched, the WrapperManager class is being initialized
and a socket is being opened back to the Wrapper process.  It appears to be
failing when the Wrapper process sends a packet back to the JVM.

Cheers,
Leif

Ben Cox wrote:
> Hi all,
>
> I've been using Wrapper version 3.2.3 to manage my service on a Windows 2003 
> Server machine.  Everything is working rather well, except occasionally when 
> requesting a restart from within the app (and sometimes just during a 
> "normal" restart after an error).  What will happen is that the wrapper will 
> attempt to start my app and then give up and terminate the JVM within _one_ 
> second!
>
> At least, that's how it looks from the timestamps on the log output 
> (below)...   am I missing (or misinterpreting) a config file setting?
>
> Thanks,
>
> Ben
>
>
> STATUS | wrapper  | 2007/03/20 15:48:27 | JVM requested a restart.
>
> STATUS | wrapper  | 2007/03/20 15:48:34 | Launching a JVM...
>
> INFO | jvm 17 | 2007/03/20 15:48:34 | Wrapper (Version 3.2.3) 
> http://wrapper.tanukisoftware.org
>
> INFO   | jvm 17   | 2007/03/20 15:48:34 |   Copyright 1999-2006 Tanuki 
> Software, Inc.  All Rights Reserved.
>
> INFO   | jvm 17   | 2007/03/20 15:48:34 |
>
> ERROR  | wrapper  | 2007/03/20 15:48:34 | Unable to send the start command 
> to the JVM.
>
> ERROR  | wrapper  | 2007/03/20 15:48:34 | JVM did not exit on request, 
> terminated
>
> STATUS | wrapper  | 2007/03/20 15:48:39 | Launching a JVM...
>
> INFO | jvm 18 | 2007/03/20 15:48:40 | Wrapper (Version 3.2.3) 
> http://wrapper.tanukisoftware.org
>
> INFO   | jvm 18   | 2007/03/20 15:48:40 |   Copyright 1999-2006 Tanuki 
> Software, Inc.  All Rights Reserved.
>
> INFO   | jvm 18   | 2007/03/20 15:48:40 |
>
> ERROR  | wrapper  | 2007/03/20 15:48:40 | Unable to send the start command 
> to the JVM.
>
> ERROR  | wrapper  | 2007/03/20 15:48:40 | JVM did not exit on request, 
> terminated
>
> STATUS | wrapper  | 2007/03/20 15:48:45 | Launching a JVM...
>
> INFO | jvm 19 | 2007/03/20 15:48:45 | Wrapper (Version 3.2.3) 
> http://wrapper.tanukisoftware.org
>
> INFO   | jvm 19   | 2007/03/20 15:48:45 |   Copyright 1999-2006 Tanuki 
> Software, Inc.  All Rights Reserved.
>
> INFO   | jvm 19   | 2007/03/20 15:48:45 |
>
> ERROR  | wrapper  | 2007/03/20 15:48:45 | Unable to send the start command 
> to the JVM.
>
> ERROR  | wrapper  | 2007/03/20 15:48:46 | JVM did not exit on request, 
> terminated
>
> STATUS | wrapper  | 2007/03/20 15:48:51 | Launching a JVM...
>
> INFO | jvm 20 | 2007/03/20 15:48:51 | Wrapper (Version 3.2.3) 
> http://wrapper.tanukisoftware.org
>
> INFO   | jvm 20   | 2007/03/20 15:48:51 |   Copyright 1999-2006 Tanuki 
> Software, Inc.  All Rights Reserved.
>
> INFO   | jvm 20   | 2007/03/20 15:48:51 |
>
> ERROR  | wrapper  | 2007/03/20 15:48:51 | Unable to send the start command 
> to the JVM.
>
> ERROR  | wrapper  | 2007/03/20 15:48:51 | JVM did not exit on request, 
> terminated
>
> STATUS | wrapper  | 2007/03/20 15:48:56 | Launching a JVM...
>
> INFO | jvm 21 | 2007/03/20 15:48:57 | Wrapper (Version 3.2.3) 
> http://wrapper.tanukisoftware.org
>
> INFO   | jvm 21   | 2007/03/20 15:48:57 |   Copyright 1999-2006 Tanuki 
> Software, Inc.  All Rights Reserved.
>
> INFO   | jvm 21   | 2007/03/20 15:48:57 |
>
> ERROR  | wrapper  | 2007/03/20 15:48:57 | Unable to send the start command 
> to the JVM.
>
> ERROR  | wrapper  | 2007/03/20 15:48:57 | JVM did not exit on request, 
> terminated
>
> FATAL  | wrapper  | 2007/03/20 15:48:57 | There were 5 failed launches in a 
> row, each lasting less than 300 seconds.  Giving up.
>
> FATAL  | wrapper  | 2007/03/20 15:48:57 |   There may be a configuration 
> problem: please check the logs.
>
> STATUS | wrapper  | 2007/03/20 15:48:58 | <-- Wrapper Stopped
>
> _________________________________________________________________
> Watch free concerts with Pink, Rod Stewart, Oasis and more. Visit MSN 
> Presents today. 
> http://music.msn.com/presents?icid=ncmsnpresentstagline&ocid=T002MSN03A07001
>
>
> -------------------------------------------------------------------------
> Take Surveys. Earn Cash. Influence the Future of IT
> Join SourceForge.net's Techsay panel and you'll get the chance to share your
> opinions on IT & business topics through brief surveys-and earn cash
> http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
> _______________________________________________
> Wrapper-user mailing list
> Wra...@li...
> https://lists.sourceforge.net/lists/listinfo/wrapper-user
>
>

Re: [Wrapper-user] Restart request causes 5 rapid retries and death

From: Ben C. <ple...@ho...> - 2007-04-02 21:18:09

Leif wrote:
>Ben,
>     Is this something that you are able to reproduce?  If so, could you
>set the
>wrapper.debug=true property and then send me the resulting log?  I am not
>able to tell the exact cause from what is in the current log.

Amazing what that debug output will tell you ;-)

The full output is below, but this caught my eye:

INFO   | jvm 2    | 2007/04/02 14:10:35 | Load native library.  One or more 
attempts may fail if platform specific libraries do not exist.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Loading native library failed: 
wrapper-windows-x86-32.dll  Cause: java.lang.UnsatisfiedLinkError: no 
wrapper-windows-x86-32 in java.library.path

It would appear that my wrapper.dll is not correctly being resolved.  The 
file that came with the distribution is lib/wrapper.dll, but this appears to 
be asking for wrapper-windows-x86-32.dll.  Does that sound correct?  Or is 
it a red herring?

Actually, I'd rather be using a 64-bit JVM, so if I will have to build 
something in order to get around this, perhaps you could suggest a means of 
building a 64-bit dll?

Thanks,

  Ben



DEBUG  | wrapper  | 2007/04/02 14:10:30 | Waiting 5 seconds before launching 
another JVM.

STATUS | wrapper  | 2007/04/02 14:10:34 | Launching a JVM...

DEBUG  | wrapper  | 2007/04/02 14:10:34 | command: "C:\Program Files 
(x86)\Java\jdk1.6.0\bin\java.exe" -Dcom.sun.management.jmxremote 
-Dorg.tanukisoftware.wrapper.WrapperManager.mbean=true 
-Dorg.tanukisoftware.wrapper.WrapperManager.mbean.testing=false 
-XX:PermSize=64m -XX:MaxPermSize=128m -Xms512m -Xmx1236m 
-Djava.library.path="../lib" -classpath 
"../lib/wrapper.jar;../build/classes;../lib/mule/commons-attributes-api-2.1.jar;../lib/mule/commons-beanutils-1.7.0.jar;../lib/mule/commons-codec-1.3.jar;../lib/mule/commons-collections-3.2.jar;../lib/mule/commons-dbutils-1.0.jar;../lib/mule/commons-digester-1.7.jar;../lib/mule/commons-discovery-0.2.jar;../lib/mule/commons-httpclient-3.0.1.jar;../lib/mule/commons-io-1.2.jar;../lib/mule/commons-jxpath-1.2.jar;../lib/mule/commons-lang-2.1.jar;../lib/mule/commons-logging-1.0.4.jar;../lib/mule/commons-net-1.3.0.jar;../lib/mule/commons-pool-1.3.jar;../lib/mule/backport-util-concurrent-2.2.jar;../lib/mule/jug-2.0.0-asl.jar;../lib/mule/xercesImpl-2.8.0.jar;../lib/mule/xml-apis-1.3.03.jar;../lib/mule/xstream-1.1.3.jar;../lib/mule/mule-transport-file-1.3.jar;../lib/mule/mule-module-jaas-1.3.jar;../lib/mule/mule-core-1.3.jar;../lib/mule/mule-transport-stream-1.3.jar;../lib/mule/mule-transport-axis
-1.3.jar;../lib/mule/mule-module-scripting-1.3.jar;../lib/mule/mule-transport-jms-1.3.jar;../lib/mule/mule-transport-http-1.3.jar;../lib/mule/mule-transport-vm-1.3.jar;../lib/mule/mule-transport-soap-1.3.jar;../lib/mule/mule-module-jotm-1.3.jar;../lib/mule/mule-transport-tcp-1.3.jar;../lib/mule/mule-transport-jdbc-1.3.jar;../lib/mule/mule-transport-email-1.3.jar;../lib/mule/mule-module-client-1.3.jar;../lib/mule/mule-module-xml-1.3.jar;../lib/mule/mule-module-builders-1.3.jar;../lib/mule/mule-transport-udp-1.3.jar;../lib/mule/mule-module-ognl-1.3.jar;../lib/mule/mule-transport-multicast-1.3.jar;../lib/mule/mule-module-management-1.3.jar;../lib/mule/mule-module-i18n-1.3.jar;../lib/mule/mule-transport-rmi-1.3.jar;../lib/mule/mule-module-spring-1.3.jar;../../../amos/site/j2ee.jar;../lib/tomcat/catalina-optional.jar;../lib/tomcat/catalina.jar;../lib/tomcat/commons-el.jar;../lib/tomcat/common
s-logging.jar;../lib/tomcat/commons-modeler.jar;../lib/tomcat/jasper-compiler-jdt.jar;../lib/tomcat/jasper-compiler.jar;../lib/tomcat/jasper-runtime.jar;../lib/tomcat/jsp-api.jar;../lib/tomcat/naming-factory.jar;../lib/tomcat/naming-resources.jar;../lib/tomcat/servlet-api.jar;../lib/tomcat/servlets-default.jar;../lib/tomcat/tomcat-coyote.jar;../lib/tomcat/tomcat-http.jar;../lib/tomcat/tomcat-util.jar;../../../amos/site/jamon.jar;../lib/jms/jbossall-client.jar;../lib/log4j-1.2.13.jar;../../../amos/amos_sub_projects/query/classes;../../../amos/amos_sub_projects/util/classes;../../../amos/site/ftpbean.jar;../../../amos/site/je.jar;../../../amos/site/jai_core.jar;../../../amos/site/jxl.jar;../../../amos/site/parser.jar;../../../amos/site/mail.jar;../../../amos/site/xercesImpl.jar;../../../amos/site/castor.jar;../../../amos/site/gnu-regexp-1.0.8.jar;../../../amos/site/jakarta-regexp.jar;../..
/../amos/site/gtftps.jar;../../../amos/amos_sub_projects/webstart_common/classes;../../../amos/site/js.jar;../../../amos/site/axis.jar;../../../amos/site/lucene.jar;../../../amos/site/forms_rt.jar;../../../amos/site/jaxrpc.jar;../../../amos/site/commons-logging.jar;../../../amos/site/commons-discovery.jar;../../../amos/amos_sub_projects/amos_app_server_common/amos_app_server_common.jar;../../../amos/site/Opta.jar;../../../amos/site/xbean.jar;../../../amos/site/jsr173_1.0_api.jar;../../../amos/site/forms.jar;../../../amos/site/classes;../../../amos/site/ss_css2.jar;../../../amos/site/commons-el.jar;../../../amos/site/activation.jar;../../../amos/site/cos.jar;../../../amos/site/JimiProClasses.zip;../../../amos/site/iioprt.jar;../../../amos/site/itext-0.70.jar;../../../amos/site/jacob.jar;../../../amos/site/jai_codec.jar;../../../amos/site/jaxp.jar;../../../amos/site/jcert.jar;../../../amos
/site/jdom.jar;../../../amos/site/jndi.jar;../../../amos/site/sqljdbc.jar;../../../amos/site/jta-spec1_0_1.jar;../../../amos/site/soap.jar;../../../amos/site/Verisign.jar;../../../amos/site/jnet.jar;../../../amos/site/jsse.jar;../../../amos/site/jdbc2_0-stdext.jar;../../../amos/site/aqcmsdk25.jar;../../../amos/site/J2TextPrinter.jar;../../../amos/site/wsdl4j.jar;../../../amos/site/ubs.jar;../../../amos/site/JLinkPointTxn.jar;../../../amos/site/saaj.jar;../../../amos/site/jh.jar;../../../amos/site/JLblexts.jar;../../../amos/site/FDXParser.jar;../../../amos/site/FedExAPI.jar;../../../amos/site/GIF.jar;../../../amos/site/sunjce_provider.jar;../../../amos/site/local_policy.jar;../../../amos/site/US_export_policy.jar;../../../amos/site/edtftpj.jar;../../../amos/site/cryptix32.jar;../../../amos/site/poi.jar;../../../amos/amos_sub_projects/amos_cache_server/classes;../../../amos/amos_sub_projec
ts/amos_cache_server/amos_cache_server.jar;../../../amos/site/commons-cli-1.0.jar;../../../amos/site/secureStream.jar;../../../amos/site/junit.jar;../../../amos/site/dbunit.jar;../../../amos/site/xjr-1.3.jar;../../../amos/amos_sub_projects/stateserver/classes;../../../amos/amos_sub_projects/encryption/classes;../../../amos/site/gdata-base.jar;../../../amos/site/gdata-client.jar;../../../amos/site/jniwrap.jar;../../../amos/site/winpack.jar;../../../amos/site/commons-net.jar;../lib/spring.jar" 
-Dwrapper.key="y8ZnOQTSANjXVuSC" -Dwrapper.port=32001 
-Dwrapper.jvm.port.min=31000 -Dwrapper.jvm.port.max=31999 
-Dwrapper.debug="TRUE" -Dwrapper.pid=1768 -Dwrapper.version="3.2.3" 
-Dwrapper.native_library="wrapper" -Dwrapper.service="TRUE" 
-Dwrapper.cpu.timeout="10" -Dwrapper.jvmid=2 
org.tanukisoftware.wrapper.WrapperSimpleApp MyApplication

DEBUG  | wrapper  | 2007/04/02 14:10:34 | JVM started (PID=4928)

INFO   | jvm 2    | 2007/04/02 14:10:35 | WrapperManager class initialized 
by thread: main  Using classloader: sun.misc.Launcher$AppClassLoader@1ba34f2

INFO   | jvm 2    | 2007/04/02 14:10:35 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org

INFO   | jvm 2    | 2007/04/02 14:10:35 |   Copyright 1999-2006 Tanuki 
Software, Inc.  All Rights Reserved.

INFO   | jvm 2    | 2007/04/02 14:10:35 |

INFO   | jvm 2    | 2007/04/02 14:10:35 | Wrapper Manager: JVM #2

INFO   | jvm 2    | 2007/04/02 14:10:35 | Running a 32-bit JVM.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Wrapper Manager: Registering 
shutdown hook

INFO   | jvm 2    | 2007/04/02 14:10:35 | Wrapper Manager: Using wrapper

INFO   | jvm 2    | 2007/04/02 14:10:35 | Registered MBean with Platform 
MBean Server: org.tanukisoftware.wrapper:type=WrapperManager

INFO   | jvm 2    | 2007/04/02 14:10:35 | Load native library.  One or more 
attempts may fail if platform specific libraries do not exist.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Loading native library failed: 
wrapper-windows-x86-32.dll  Cause: java.lang.UnsatisfiedLinkError: no 
wrapper-windows-x86-32 in java.library.path

INFO   | jvm 2    | 2007/04/02 14:10:35 | Loaded native library: wrapper.dll

INFO   | jvm 2    | 2007/04/02 14:10:35 | Calling native initialization 
method.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Initializing WrapperManager native 
library.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Java Executable: C:\Program Files 
(x86)\Java\jdk1.6.0\bin\java.exe

INFO   | jvm 2    | 2007/04/02 14:10:35 | Windows version: 5.2.3790

INFO   | jvm 2    | 2007/04/02 14:10:35 | Java Version   : 1.6.0-b105 Java 
HotSpot(TM) Client VM

INFO   | jvm 2    | 2007/04/02 14:10:35 | Java VM Vendor : Sun Microsystems 
Inc.

INFO   | jvm 2    | 2007/04/02 14:10:35 |

INFO   | jvm 2    | 2007/04/02 14:10:35 | Control event monitor thread 
started.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Startup runner thread started.

INFO   | jvm 2    | 2007/04/02 14:10:35 | 
WrapperManager.start(org.tanukisoftware.wrapper.WrapperSimpleApp@170888e, 
args[]) called by thread: main

INFO   | jvm 2    | 2007/04/02 14:10:35 | Communications runner thread 
started.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Open socket to 
wrapper...Wrapper-Connection

INFO   | jvm 2    | 2007/04/02 14:10:35 | Failed attempt to bind using local 
port 31000

INFO   | jvm 2    | 2007/04/02 14:10:35 | Opened Socket from 31001 to 32001

INFO   | jvm 2    | 2007/04/02 14:10:35 | Send a packet KEY : 
y8ZnOQTSANjXVuSC

INFO   | jvm 2    | 2007/04/02 14:10:35 | 
handleSocket(Socket[addr=/127.0.0.1,port=32001,localport=31001])

DEBUG  | wrapperp | 2007/04/02 14:10:35 | accepted a socket from 127.0.0.1 
on port 31001

DEBUG  | wrapperp | 2007/04/02 14:10:35 | read a packet KEY : 
y8ZnOQTSANjXVuSC

DEBUG  | wrapper  | 2007/04/02 14:10:35 | Got key from JVM: y8ZnOQTSANjXVuSC

DEBUG  | wrapperp | 2007/04/02 14:10:35 | send a packet LOW_LOG_LEVEL : 1

DEBUG  | wrapperp | 2007/04/02 14:10:35 | send a packet PING_TIMEOUT : 30

DEBUG  | wrapperp | 2007/04/02 14:10:35 | send a packet PROPERTIES : 
(Property Values)

DEBUG  | wrapper  | 2007/04/02 14:10:35 | Start Application.

DEBUG  | wrapperp | 2007/04/02 14:10:35 | send a packet START : start

DEBUG  | wrapperp | 2007/04/02 14:10:35 | socket send failed. (10035)

ERROR  | wrapper  | 2007/04/02 14:10:35 | Unable to send the start command 
to the JVM.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Received a packet LOW_LOG_LEVEL : 
1

INFO   | jvm 2    | 2007/04/02 14:10:35 | Wrapper Manager: LowLogLevel from 
Wrapper is 1

INFO   | jvm 2    | 2007/04/02 14:10:35 | Received a packet PING_TIMEOUT : 
30

INFO   | jvm 2    | 2007/04/02 14:10:35 | PingTimeout from Wrapper is 30000

INFO   | jvm 2    | 2007/04/02 14:10:35 | Received a packet PROPERTIES : 
(Property Values)

INFO   | jvm 2    | 2007/04/02 14:10:35 | Closing socket.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Open socket to 
wrapper...Wrapper-Connection

INFO   | jvm 2    | 2007/04/02 14:10:35 | Failed attempt to bind using local 
port 31000

INFO   | jvm 2    | 2007/04/02 14:10:35 | Opened Socket from 31001 to 32001

INFO   | jvm 2    | 2007/04/02 14:10:35 | Send a packet KEY : 
y8ZnOQTSANjXVuSC

INFO   | jvm 2    | 2007/04/02 14:10:35 | 
handleSocket(Socket[addr=/127.0.0.1,port=32001,localport=31001])

DEBUG  | wrapperp | 2007/04/02 14:10:35 | server listening on port 32000.

ERROR  | wrapper  | 2007/04/02 14:10:35 | JVM did not exit on request, 
terminated

DEBUG  | wrapper  | 2007/04/02 14:10:35 | JVM was only running for 1 seconds 
leading to a failed restart count of 2.

DEBUG  | wrapper  | 2007/04/02 14:10:35 | Waiting 5 seconds before launching 
another JVM.

STATUS | wrapper  | 2007/04/02 14:10:40 | Launching a JVM...

_________________________________________________________________
Mortgage refinance is hot 1) Rates near 30-yr lows 2) Good credit get 
intro-rate 4.625%* 
https://www2.nextag.com/goto.jsp?product=100000035&url=%2fst.jsp&tm=y&search=mortgage_text_links_88_h2a5f&s=4056&p=5117&disc=y&vers=743

Re: [Wrapper-user] Restart request causes 5 rapid retries and death

From: Hubert F. <hub...@ab...> - 2007-04-03 07:12:24

Ben,

<The full output is below, but this caught my eye:
<INFO   | jvm 2    | 2007/04/02 14:10:35 | Loading native library
failed: 
<wrapper-windows-x86-32.dll  Cause: java.lang.UnsatisfiedLinkError: no

<wrapper-windows-x86-32 in java.library.path

To my knowledge this is by design. This is the normal behaviour of
wrapper and not a bug.

In WrapperManager.java, you can see what happens: wrapper first tries
to load the wrapper.dll as 32 bit platform specific version with 
extension "-windows-x86-32" in the file name. If that fails, wrapper
tries to load the library as "wrapper.dll".

So in the log you should see "jvm X    | WrapperManager Debug: Loaded
native library: wrapper.dll"

That would mean, that this part is OK.

It sounds silly, but just to be on the safe side: check the timeouts
you use in config file. Avoid using a timeout of 1 second, increase it.
This is little work and avoids wasting time, if it just a simple thing
like this, you never know...

hth
Hubert


>>> "Ben Cox" <ple...@ho...> 02.04.2007 23:17 >>>
Leif wrote:
>Ben,
>     Is this something that you are able to reproduce?  If so, could
you
>set the
>wrapper.debug=true property and then send me the resulting log?  I am
not
>able to tell the exact cause from what is in the current log.

Amazing what that debug output will tell you ;-)

The full output is below, but this caught my eye:

INFO   | jvm 2    | 2007/04/02 14:10:35 | Load native library.  One or
more 
attempts may fail if platform specific libraries do not exist.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Loading native library
failed: 
wrapper-windows-x86-32.dll  Cause: java.lang.UnsatisfiedLinkError: no 
wrapper-windows-x86-32 in java.library.path

It would appear that my wrapper.dll is not correctly being resolved. 
The 
file that came with the distribution is lib/wrapper.dll, but this
appears to 
be asking for wrapper-windows-x86-32.dll.  Does that sound correct?  Or
is 
it a red herring?

Actually, I'd rather be using a 64-bit JVM, so if I will have to build

something in order to get around this, perhaps you could suggest a
means of 
building a 64-bit dll?

Thanks,

  Ben



DEBUG  | wrapper  | 2007/04/02 14:10:30 | Waiting 5 seconds before
launching 
another JVM.

STATUS | wrapper  | 2007/04/02 14:10:34 | Launching a JVM...

DEBUG  | wrapper  | 2007/04/02 14:10:34 | command: "C:\Program Files 
(x86)\Java\jdk1.6.0\bin\java.exe" -Dcom.sun.management.jmxremote 
-Dorg.tanukisoftware.wrapper.WrapperManager.mbean=true 
-Dorg.tanukisoftware.wrapper.WrapperManager.mbean.testing=false 
-XX:PermSize=64m -XX:MaxPermSize=128m -Xms512m -Xmx1236m 
-Djava.library.path="../lib" -classpath 
"../lib/wrapper.jar;../build/classes;../lib/mule/commons-attributes-api-2.1.jar;../lib/mule/commons-beanutils-1.7.0.jar;../lib/mule/commons-codec-1.3.jar;../lib/mule/commons-collections-3.2.jar;../lib/mule/commons-dbutils-1.0.jar;../lib/mule/commons-digester-1.7.jar;../lib/mule/commons-discovery-0.2.jar;../lib/mule/commons-httpclient-3.0.1.jar;../lib/mule/commons-io-1.2.jar;../lib/mule/commons-jxpath-1.2.jar;../lib/mule/commons-lang-2.1.jar;../lib/mule/commons-logging-1.0.4.jar;../lib/mule/commons-net-1.3.0.jar;../lib/mule/commons-pool-1.3.jar;../lib/mule/backport-util-concurrent-2.2.jar;../lib/mule/jug-2.0.0-asl.jar;../lib/mule/xercesImpl-2.8.0.jar;../lib/mule/xml-apis-1.3.03.jar;../lib/mule/xstream-1.1.3.jar;../lib/mule/mule-transport-file-1.3.jar;../lib/mule/mule-module-jaas-1.3.jar;../lib/mule/mule-core-1.3.jar;../lib/mule/mule-transport-stream-1.3.jar;../lib/mule/mule-transport-axis
-1.3.jar;../lib/mule/mule-module-scripting-1.3.jar;../lib/mule/mule-transport-jms-1.3.jar;../lib/mule/mule-transport-http-1.3.jar;../lib/mule/mule-transport-vm-1.3.jar;../lib/mule/mule-transport-soap-1.3.jar;../lib/mule/mule-module-jotm-1.3.jar;../lib/mule/mule-transport-tcp-1.3.jar;../lib/mule/mule-transport-jdbc-1.3.jar;../lib/mule/mule-transport-email-1.3.jar;../lib/mule/mule-module-client-1.3.jar;../lib/mule/mule-module-xml-1.3.jar;../lib/mule/mule-module-builders-1.3.jar;../lib/mule/mule-transport-udp-1.3.jar;../lib/mule/mule-module-ognl-1.3.jar;../lib/mule/mule-transport-multicast-1.3.jar;../lib/mule/mule-module-management-1.3.jar;../lib/mule/mule-module-i18n-1.3.jar;../lib/mule/mule-transport-rmi-1.3.jar;../lib/mule/mule-module-spring-1.3.jar;../../../amos/site/j2ee.jar;../lib/tomcat/catalina-optional.jar;../lib/tomcat/catalina.jar;../lib/tomcat/commons-el.jar;../lib/tomcat/common
s-logging.jar;../lib/tomcat/commons-modeler.jar;../lib/tomcat/jasper-compiler-jdt.jar;../lib/tomcat/jasper-compiler.jar;../lib/tomcat/jasper-runtime.jar;../lib/tomcat/jsp-api.jar;../lib/tomcat/naming-factory.jar;../lib/tomcat/naming-resources.jar;../lib/tomcat/servlet-api.jar;../lib/tomcat/servlets-default.jar;../lib/tomcat/tomcat-coyote.jar;../lib/tomcat/tomcat-http.jar;../lib/tomcat/tomcat-util.jar;../../../amos/site/jamon.jar;../lib/jms/jbossall-client.jar;../lib/log4j-1.2.13.jar;../../../amos/amos_sub_projects/query/classes;../../../amos/amos_sub_projects/util/classes;../../../amos/site/ftpbean.jar;../../../amos/site/je.jar;../../../amos/site/jai_core.jar;../../../amos/site/jxl.jar;../../../amos/site/parser.jar;../../../amos/site/mail.jar;../../../amos/site/xercesImpl.jar;../../../amos/site/castor.jar;../../../amos/site/gnu-regexp-1.0.8.jar;../../../amos/site/jakarta-regexp.jar;../..
/../amos/site/gtftps.jar;../../../amos/amos_sub_projects/webstart_common/classes;../../../amos/site/js.jar;../../../amos/site/axis.jar;../../../amos/site/lucene.jar;../../../amos/site/forms_rt.jar;../../../amos/site/jaxrpc.jar;../../../amos/site/commons-logging.jar;../../../amos/site/commons-discovery.jar;../../../amos/amos_sub_projects/amos_app_server_common/amos_app_server_common.jar;../../../amos/site/Opta.jar;../../../amos/site/xbean.jar;../../../amos/site/jsr173_1.0_api.jar;../../../amos/site/forms.jar;../../../amos/site/classes;../../../amos/site/ss_css2.jar;../../../amos/site/commons-el.jar;../../../amos/site/activation.jar;../../../amos/site/cos.jar;../../../amos/site/JimiProClasses.zip;../../../amos/site/iioprt.jar;../../../amos/site/itext-0.70.jar;../../../amos/site/jacob.jar;../../../amos/site/jai_codec.jar;../../../amos/site/jaxp.jar;../../../amos/site/jcert.jar;../../../amos
/site/jdom.jar;../../../amos/site/jndi.jar;../../../amos/site/sqljdbc.jar;../../../amos/site/jta-spec1_0_1.jar;../../../amos/site/soap.jar;../../../amos/site/Verisign.jar;../../../amos/site/jnet.jar;../../../amos/site/jsse.jar;../../../amos/site/jdbc2_0-stdext.jar;../../../amos/site/aqcmsdk25.jar;../../../amos/site/J2TextPrinter.jar;../../../amos/site/wsdl4j.jar;../../../amos/site/ubs.jar;../../../amos/site/JLinkPointTxn.jar;../../../amos/site/saaj.jar;../../../amos/site/jh.jar;../../../amos/site/JLblexts.jar;../../../amos/site/FDXParser.jar;../../../amos/site/FedExAPI.jar;../../../amos/site/GIF.jar;../../../amos/site/sunjce_provider.jar;../../../amos/site/local_policy.jar;../../../amos/site/US_export_policy.jar;../../../amos/site/edtftpj.jar;../../../amos/site/cryptix32.jar;../../../amos/site/poi.jar;../../../amos/amos_sub_projects/amos_cache_server/classes;../../../amos/amos_sub_projec
ts/amos_cache_server/amos_cache_server.jar;../../../amos/site/commons-cli-1.0.jar;../../../amos/site/secureStream.jar;../../../amos/site/junit.jar;../../../amos/site/dbunit.jar;../../../amos/site/xjr-1.3.jar;../../../amos/amos_sub_projects/stateserver/classes;../../../amos/amos_sub_projects/encryption/classes;../../../amos/site/gdata-base.jar;../../../amos/site/gdata-client.jar;../../../amos/site/jniwrap.jar;../../../amos/site/winpack.jar;../../../amos/site/commons-net.jar;../lib/spring.jar"

-Dwrapper.key="y8ZnOQTSANjXVuSC" -Dwrapper.port=32001 
-Dwrapper.jvm.port.min=31000 -Dwrapper.jvm.port.max=31999 
-Dwrapper.debug="TRUE" -Dwrapper.pid=1768 -Dwrapper.version="3.2.3" 
-Dwrapper.native_library="wrapper" -Dwrapper.service="TRUE" 
-Dwrapper.cpu.timeout="10" -Dwrapper.jvmid=2 
org.tanukisoftware.wrapper.WrapperSimpleApp MyApplication

DEBUG  | wrapper  | 2007/04/02 14:10:34 | JVM started (PID=4928)

INFO   | jvm 2    | 2007/04/02 14:10:35 | WrapperManager class
initialized 
by thread: main  Using classloader:
sun.misc.Launcher$AppClassLoader@1ba34f2

INFO   | jvm 2    | 2007/04/02 14:10:35 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org 

INFO   | jvm 2    | 2007/04/02 14:10:35 |   Copyright 1999-2006 Tanuki

Software, Inc.  All Rights Reserved.

INFO   | jvm 2    | 2007/04/02 14:10:35 |

INFO   | jvm 2    | 2007/04/02 14:10:35 | Wrapper Manager: JVM #2

INFO   | jvm 2    | 2007/04/02 14:10:35 | Running a 32-bit JVM.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Wrapper Manager: Registering

shutdown hook

INFO   | jvm 2    | 2007/04/02 14:10:35 | Wrapper Manager: Using
wrapper

INFO   | jvm 2    | 2007/04/02 14:10:35 | Registered MBean with
Platform 
MBean Server: org.tanukisoftware.wrapper:type=WrapperManager

INFO   | jvm 2    | 2007/04/02 14:10:35 | Load native library.  One or
more 
attempts may fail if platform specific libraries do not exist.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Loading native library
failed: 
wrapper-windows-x86-32.dll  Cause: java.lang.UnsatisfiedLinkError: no 
wrapper-windows-x86-32 in java.library.path

INFO   | jvm 2    | 2007/04/02 14:10:35 | Loaded native library:
wrapper.dll

INFO   | jvm 2    | 2007/04/02 14:10:35 | Calling native initialization

method.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Initializing WrapperManager
native 
library.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Java Executable: C:\Program
Files 
(x86)\Java\jdk1.6.0\bin\java.exe

INFO   | jvm 2    | 2007/04/02 14:10:35 | Windows version: 5.2.3790

INFO   | jvm 2    | 2007/04/02 14:10:35 | Java Version   : 1.6.0-b105
Java 
HotSpot(TM) Client VM

INFO   | jvm 2    | 2007/04/02 14:10:35 | Java VM Vendor : Sun
Microsystems 
Inc.

INFO   | jvm 2    | 2007/04/02 14:10:35 |

INFO   | jvm 2    | 2007/04/02 14:10:35 | Control event monitor thread

started.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Startup runner thread
started.

INFO   | jvm 2    | 2007/04/02 14:10:35 | 
WrapperManager.start(org.tanukisoftware.wrapper.WrapperSimpleApp@170888e,

args[]) called by thread: main

INFO   | jvm 2    | 2007/04/02 14:10:35 | Communications runner thread

started.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Open socket to 
wrapper...Wrapper-Connection

INFO   | jvm 2    | 2007/04/02 14:10:35 | Failed attempt to bind using
local 
port 31000

INFO   | jvm 2    | 2007/04/02 14:10:35 | Opened Socket from 31001 to
32001

INFO   | jvm 2    | 2007/04/02 14:10:35 | Send a packet KEY : 
y8ZnOQTSANjXVuSC

INFO   | jvm 2    | 2007/04/02 14:10:35 | 
handleSocket(Socket[addr=/127.0.0.1,port=32001,localport=31001])

DEBUG  | wrapperp | 2007/04/02 14:10:35 | accepted a socket from
127.0.0.1 
on port 31001

DEBUG  | wrapperp | 2007/04/02 14:10:35 | read a packet KEY : 
y8ZnOQTSANjXVuSC

DEBUG  | wrapper  | 2007/04/02 14:10:35 | Got key from JVM:
y8ZnOQTSANjXVuSC

DEBUG  | wrapperp | 2007/04/02 14:10:35 | send a packet LOW_LOG_LEVEL :
1

DEBUG  | wrapperp | 2007/04/02 14:10:35 | send a packet PING_TIMEOUT :
30

DEBUG  | wrapperp | 2007/04/02 14:10:35 | send a packet PROPERTIES : 
(Property Values)

DEBUG  | wrapper  | 2007/04/02 14:10:35 | Start Application.

DEBUG  | wrapperp | 2007/04/02 14:10:35 | send a packet START : start

DEBUG  | wrapperp | 2007/04/02 14:10:35 | socket send failed. (10035)

ERROR  | wrapper  | 2007/04/02 14:10:35 | Unable to send the start
command 
to the JVM.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Received a packet
LOW_LOG_LEVEL : 
1

INFO   | jvm 2    | 2007/04/02 14:10:35 | Wrapper Manager: LowLogLevel
from 
Wrapper is 1

INFO   | jvm 2    | 2007/04/02 14:10:35 | Received a packet
PING_TIMEOUT : 
30

INFO   | jvm 2    | 2007/04/02 14:10:35 | PingTimeout from Wrapper is
30000

INFO   | jvm 2    | 2007/04/02 14:10:35 | Received a packet PROPERTIES
: 
(Property Values)

INFO   | jvm 2    | 2007/04/02 14:10:35 | Closing socket.

INFO   | jvm 2    | 2007/04/02 14:10:35 | Open socket to 
wrapper...Wrapper-Connection

INFO   | jvm 2    | 2007/04/02 14:10:35 | Failed attempt to bind using
local 
port 31000

INFO   | jvm 2    | 2007/04/02 14:10:35 | Opened Socket from 31001 to
32001

INFO   | jvm 2    | 2007/04/02 14:10:35 | Send a packet KEY : 
y8ZnOQTSANjXVuSC

INFO   | jvm 2    | 2007/04/02 14:10:35 | 
handleSocket(Socket[addr=/127.0.0.1,port=32001,localport=31001])

DEBUG  | wrapperp | 2007/04/02 14:10:35 | server listening on port
32000.

ERROR  | wrapper  | 2007/04/02 14:10:35 | JVM did not exit on request,

terminated

DEBUG  | wrapper  | 2007/04/02 14:10:35 | JVM was only running for 1
seconds 
leading to a failed restart count of 2.

DEBUG  | wrapper  | 2007/04/02 14:10:35 | Waiting 5 seconds before
launching 
another JVM.

STATUS | wrapper  | 2007/04/02 14:10:40 | Launching a JVM...

_________________________________________________________________
Mortgage refinance is hot 1) Rates near 30-yr lows 2) Good credit get 
intro-rate 4.625%* 
https://www2.nextag.com/goto.jsp?product=100000035&url=%2fst.jsp&tm=y&search=mortgage_text_links_88_h2a5f&s=4056&p=5117&disc=y&vers=743



-------------------------------------------------------------------------
Take Surveys. Earn Cash. Influence the Future of IT
Join SourceForge.net's Techsay panel and you'll get the chance to share
your
opinions on IT & business topics through brief surveys-and earn cash
http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV

_______________________________________________
Wrapper-user mailing list
Wra...@li... 
https://lists.sourceforge.net/lists/listinfo/wrapper-user

Re: [Wrapper-user] Restart request causes 5 rapid retries and death

From: Leif M. <le...@ta...> - 2007-04-12 06:26:12

Ben,
    There were a bunch of posts around this.  I wanted to make sure you 
are not still waiting
for a reply.  Were you able to reproduce this with wrapper.debug=true set?

Cheers,
Leif

Leif Mortenson wrote:
> Ben,
>     Is this something that you are able to reproduce?  If so, could you 
> set the
> wrapper.debug=true property and then send me the resulting log?  I am not
> able to tell the exact cause from what is in the current log.
>
>     The JVM is being launched, the WrapperManager class is being initialized
> and a socket is being opened back to the Wrapper process.  It appears to be
> failing when the Wrapper process sends a packet back to the JVM.
>
> Cheers,
> Leif
>
> Ben Cox wrote:
>   
>> Hi all,
>>
>> I've been using Wrapper version 3.2.3 to manage my service on a Windows 2003 
>> Server machine.  Everything is working rather well, except occasionally when 
>> requesting a restart from within the app (and sometimes just during a 
>> "normal" restart after an error).  What will happen is that the wrapper will 
>> attempt to start my app and then give up and terminate the JVM within _one_ 
>> second!
>>
>> At least, that's how it looks from the timestamps on the log output 
>> (below)...   am I missing (or misinterpreting) a config file setting?
>>
>> Thanks,
>>
>> Ben
>>
>>
>> STATUS | wrapper  | 2007/03/20 15:48:27 | JVM requested a restart.
>>
>> STATUS | wrapper  | 2007/03/20 15:48:34 | Launching a JVM...
>>
>> INFO | jvm 17 | 2007/03/20 15:48:34 | Wrapper (Version 3.2.3) 
>> http://wrapper.tanukisoftware.org
>>
>> INFO   | jvm 17   | 2007/03/20 15:48:34 |   Copyright 1999-2006 Tanuki 
>> Software, Inc.  All Rights Reserved.
>>
>> INFO   | jvm 17   | 2007/03/20 15:48:34 |
>>
>> ERROR  | wrapper  | 2007/03/20 15:48:34 | Unable to send the start command 
>> to the JVM.
>>
>> ERROR  | wrapper  | 2007/03/20 15:48:34 | JVM did not exit on request, 
>> terminated
>>
>> STATUS | wrapper  | 2007/03/20 15:48:39 | Launching a JVM...
>>
>> INFO | jvm 18 | 2007/03/20 15:48:40 | Wrapper (Version 3.2.3) 
>> http://wrapper.tanukisoftware.org
>>
>> INFO   | jvm 18   | 2007/03/20 15:48:40 |   Copyright 1999-2006 Tanuki 
>> Software, Inc.  All Rights Reserved.
>>
>> INFO   | jvm 18   | 2007/03/20 15:48:40 |
>>
>> ERROR  | wrapper  | 2007/03/20 15:48:40 | Unable to send the start command 
>> to the JVM.
>>
>> ERROR  | wrapper  | 2007/03/20 15:48:40 | JVM did not exit on request, 
>> terminated
>>
>> STATUS | wrapper  | 2007/03/20 15:48:45 | Launching a JVM...
>>
>> INFO | jvm 19 | 2007/03/20 15:48:45 | Wrapper (Version 3.2.3) 
>> http://wrapper.tanukisoftware.org
>>
>> INFO   | jvm 19   | 2007/03/20 15:48:45 |   Copyright 1999-2006 Tanuki 
>> Software, Inc.  All Rights Reserved.
>>
>> INFO   | jvm 19   | 2007/03/20 15:48:45 |
>>
>> ERROR  | wrapper  | 2007/03/20 15:48:45 | Unable to send the start command 
>> to the JVM.
>>
>> ERROR  | wrapper  | 2007/03/20 15:48:46 | JVM did not exit on request, 
>> terminated
>>
>> STATUS | wrapper  | 2007/03/20 15:48:51 | Launching a JVM...
>>
>> INFO | jvm 20 | 2007/03/20 15:48:51 | Wrapper (Version 3.2.3) 
>> http://wrapper.tanukisoftware.org
>>
>> INFO   | jvm 20   | 2007/03/20 15:48:51 |   Copyright 1999-2006 Tanuki 
>> Software, Inc.  All Rights Reserved.
>>
>> INFO   | jvm 20   | 2007/03/20 15:48:51 |
>>
>> ERROR  | wrapper  | 2007/03/20 15:48:51 | Unable to send the start command 
>> to the JVM.
>>
>> ERROR  | wrapper  | 2007/03/20 15:48:51 | JVM did not exit on request, 
>> terminated
>>
>> STATUS | wrapper  | 2007/03/20 15:48:56 | Launching a JVM...
>>
>> INFO | jvm 21 | 2007/03/20 15:48:57 | Wrapper (Version 3.2.3) 
>> http://wrapper.tanukisoftware.org
>>
>> INFO   | jvm 21   | 2007/03/20 15:48:57 |   Copyright 1999-2006 Tanuki 
>> Software, Inc.  All Rights Reserved.
>>
>> INFO   | jvm 21   | 2007/03/20 15:48:57 |
>>
>> ERROR  | wrapper  | 2007/03/20 15:48:57 | Unable to send the start command 
>> to the JVM.
>>
>> ERROR  | wrapper  | 2007/03/20 15:48:57 | JVM did not exit on request, 
>> terminated
>>
>> FATAL  | wrapper  | 2007/03/20 15:48:57 | There were 5 failed launches in a 
>> row, each lasting less than 300 seconds.  Giving up.
>>
>> FATAL  | wrapper  | 2007/03/20 15:48:57 |   There may be a configuration 
>> problem: please check the logs.
>>
>> STATUS | wrapper  | 2007/03/20 15:48:58 | <-- Wrapper Stopped
>>

Re: [Wrapper-user] Restart request causes 5 rapid retries and death

From: Hubert F. <hub...@ab...> - 2007-04-02 08:07:40

Ben,

Just an assumption: 
check the comment on
http://wrapper.tanukisoftware.org/doc/english/integrate-listener.html
 on the bottom of the site, about 
 WrapperManager.signalStarting(int millis) and 
WrapperManager.signalStopping(int millis).

I noticed the following:
Calling WrapperManager.signalStopping(1000); I expected that the value
is added to the TimeToWait defined in the conf file. But it seams that
it is not added but replaced. This is the new time to wait. And if you
call it on Windows with value 1000, it causes or can cause a quite
immediate exit. 

Perhaps your problems have something to do with this.

hth 
Hubert

btw 

on the bottom of the mentioned site you read:

<BTW Great Job Leif!

Yes, indeed!


>>> "Ben Cox" <ple...@ho...> 30.03.2007 22:37 >>>
Hi all,

I've been using Wrapper version 3.2.3 to manage my service on a Windows
2003 
Server machine.  Everything is working rather well, except occasionally
when 
requesting a restart from within the app (and sometimes just during a 
"normal" restart after an error).  What will happen is that the wrapper
will 
attempt to start my app and then give up and terminate the JVM within
_one_ 
second!

At least, that's how it looks from the timestamps on the log output 
(below)...   am I missing (or misinterpreting) a config file setting?

Thanks,

Ben


STATUS | wrapper  | 2007/03/20 15:48:27 | JVM requested a restart.

STATUS | wrapper  | 2007/03/20 15:48:34 | Launching a JVM...

INFO | jvm 17 | 2007/03/20 15:48:34 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org 

INFO   | jvm 17   | 2007/03/20 15:48:34 |   Copyright 1999-2006 Tanuki

Software, Inc.  All Rights Reserved.

INFO   | jvm 17   | 2007/03/20 15:48:34 |

ERROR  | wrapper  | 2007/03/20 15:48:34 | Unable to send the start
command 
to the JVM.

ERROR  | wrapper  | 2007/03/20 15:48:34 | JVM did not exit on request,

terminated

STATUS | wrapper  | 2007/03/20 15:48:39 | Launching a JVM...

INFO | jvm 18 | 2007/03/20 15:48:40 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org 

INFO   | jvm 18   | 2007/03/20 15:48:40 |   Copyright 1999-2006 Tanuki

Software, Inc.  All Rights Reserved.

INFO   | jvm 18   | 2007/03/20 15:48:40 |

ERROR  | wrapper  | 2007/03/20 15:48:40 | Unable to send the start
command 
to the JVM.

ERROR  | wrapper  | 2007/03/20 15:48:40 | JVM did not exit on request,

terminated

STATUS | wrapper  | 2007/03/20 15:48:45 | Launching a JVM...

INFO | jvm 19 | 2007/03/20 15:48:45 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org 

INFO   | jvm 19   | 2007/03/20 15:48:45 |   Copyright 1999-2006 Tanuki

Software, Inc.  All Rights Reserved.

INFO   | jvm 19   | 2007/03/20 15:48:45 |

ERROR  | wrapper  | 2007/03/20 15:48:45 | Unable to send the start
command 
to the JVM.

ERROR  | wrapper  | 2007/03/20 15:48:46 | JVM did not exit on request,

terminated

STATUS | wrapper  | 2007/03/20 15:48:51 | Launching a JVM...

INFO | jvm 20 | 2007/03/20 15:48:51 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org 

INFO   | jvm 20   | 2007/03/20 15:48:51 |   Copyright 1999-2006 Tanuki

Software, Inc.  All Rights Reserved.

INFO   | jvm 20   | 2007/03/20 15:48:51 |

ERROR  | wrapper  | 2007/03/20 15:48:51 | Unable to send the start
command 
to the JVM.

ERROR  | wrapper  | 2007/03/20 15:48:51 | JVM did not exit on request,

terminated

STATUS | wrapper  | 2007/03/20 15:48:56 | Launching a JVM...

INFO | jvm 21 | 2007/03/20 15:48:57 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org 

INFO   | jvm 21   | 2007/03/20 15:48:57 |   Copyright 1999-2006 Tanuki

Software, Inc.  All Rights Reserved.

INFO   | jvm 21   | 2007/03/20 15:48:57 |

ERROR  | wrapper  | 2007/03/20 15:48:57 | Unable to send the start
command 
to the JVM.

ERROR  | wrapper  | 2007/03/20 15:48:57 | JVM did not exit on request,

terminated

FATAL  | wrapper  | 2007/03/20 15:48:57 | There were 5 failed launches
in a 
row, each lasting less than 300 seconds.  Giving up.

FATAL  | wrapper  | 2007/03/20 15:48:57 |   There may be a
configuration 
problem: please check the logs.

STATUS | wrapper  | 2007/03/20 15:48:58 | <-- Wrapper Stopped

_________________________________________________________________
Watch free concerts with Pink, Rod Stewart, Oasis and more. Visit MSN 
Presents today. 
http://music.msn.com/presents?icid=ncmsnpresentstagline&ocid=T002MSN03A07001



-------------------------------------------------------------------------
Take Surveys. Earn Cash. Influence the Future of IT
Join SourceForge.net's Techsay panel and you'll get the chance to share
your
opinions on IT & business topics through brief surveys-and earn cash
http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV

_______________________________________________
Wrapper-user mailing list
Wra...@li... 
https://lists.sourceforge.net/lists/listinfo/wrapper-user

Re: [Wrapper-user] Restart request causes 5 rapid retries and death

From: Ben C. <ple...@ho...> - 2007-04-02 21:19:31

Thanks, Hubert - this is a good clue.  However, I'm using the simplest 
integration method, so there's no WrapperManager.signalStarting() call in my 
code.

  Ben

>From: "Hubert Felber" <hub...@ab...>
>Reply-To: wra...@li...
>To: <wra...@li...>
>Subject: Re: [Wrapper-user] Restart request causes 5 rapid retries 
>and	death
>Date: Mon, 02 Apr 2007 10:07:02 +0200
>
>Ben,
>
>Just an assumption:
>check the comment on
>http://wrapper.tanukisoftware.org/doc/english/integrate-listener.html
>  on the bottom of the site, about
>  WrapperManager.signalStarting(int millis) and
>WrapperManager.signalStopping(int millis).
>
>I noticed the following:
>Calling WrapperManager.signalStopping(1000); I expected that the value
>is added to the TimeToWait defined in the conf file. But it seams that
>it is not added but replaced. This is the new time to wait. And if you
>call it on Windows with value 1000, it causes or can cause a quite
>immediate exit.
>
>Perhaps your problems have something to do with this.
>
>hth
>Hubert
>
>btw
>
>on the bottom of the mentioned site you read:
>
><BTW Great Job Leif!
>
>Yes, indeed!
>
>
> >>> "Ben Cox" <ple...@ho...> 30.03.2007 22:37 >>>
>Hi all,
>
>I've been using Wrapper version 3.2.3 to manage my service on a Windows
>2003
>Server machine.  Everything is working rather well, except occasionally
>when
>requesting a restart from within the app (and sometimes just during a
>"normal" restart after an error).  What will happen is that the wrapper
>will
>attempt to start my app and then give up and terminate the JVM within
>_one_
>second!
>
>At least, that's how it looks from the timestamps on the log output
>(below)...   am I missing (or misinterpreting) a config file setting?
>
>Thanks,
>
>Ben
>
>
>STATUS | wrapper  | 2007/03/20 15:48:27 | JVM requested a restart.
>
>STATUS | wrapper  | 2007/03/20 15:48:34 | Launching a JVM...
>
>INFO | jvm 17 | 2007/03/20 15:48:34 | Wrapper (Version 3.2.3)
>http://wrapper.tanukisoftware.org
>
>INFO   | jvm 17   | 2007/03/20 15:48:34 |   Copyright 1999-2006 Tanuki
>
>Software, Inc.  All Rights Reserved.
>
>INFO   | jvm 17   | 2007/03/20 15:48:34 |
>
>ERROR  | wrapper  | 2007/03/20 15:48:34 | Unable to send the start
>command
>to the JVM.
>
>ERROR  | wrapper  | 2007/03/20 15:48:34 | JVM did not exit on request,
>
>terminated
>
>STATUS | wrapper  | 2007/03/20 15:48:39 | Launching a JVM...
>
>INFO | jvm 18 | 2007/03/20 15:48:40 | Wrapper (Version 3.2.3)
>http://wrapper.tanukisoftware.org
>
>INFO   | jvm 18   | 2007/03/20 15:48:40 |   Copyright 1999-2006 Tanuki
>
>Software, Inc.  All Rights Reserved.
>
>INFO   | jvm 18   | 2007/03/20 15:48:40 |
>
>ERROR  | wrapper  | 2007/03/20 15:48:40 | Unable to send the start
>command
>to the JVM.
>
>ERROR  | wrapper  | 2007/03/20 15:48:40 | JVM did not exit on request,
>
>terminated
>
>STATUS | wrapper  | 2007/03/20 15:48:45 | Launching a JVM...
>
>INFO | jvm 19 | 2007/03/20 15:48:45 | Wrapper (Version 3.2.3)
>http://wrapper.tanukisoftware.org
>
>INFO   | jvm 19   | 2007/03/20 15:48:45 |   Copyright 1999-2006 Tanuki
>
>Software, Inc.  All Rights Reserved.
>
>INFO   | jvm 19   | 2007/03/20 15:48:45 |
>
>ERROR  | wrapper  | 2007/03/20 15:48:45 | Unable to send the start
>command
>to the JVM.
>
>ERROR  | wrapper  | 2007/03/20 15:48:46 | JVM did not exit on request,
>
>terminated
>
>STATUS | wrapper  | 2007/03/20 15:48:51 | Launching a JVM...
>
>INFO | jvm 20 | 2007/03/20 15:48:51 | Wrapper (Version 3.2.3)
>http://wrapper.tanukisoftware.org
>
>INFO   | jvm 20   | 2007/03/20 15:48:51 |   Copyright 1999-2006 Tanuki
>
>Software, Inc.  All Rights Reserved.
>
>INFO   | jvm 20   | 2007/03/20 15:48:51 |
>
>ERROR  | wrapper  | 2007/03/20 15:48:51 | Unable to send the start
>command
>to the JVM.
>
>ERROR  | wrapper  | 2007/03/20 15:48:51 | JVM did not exit on request,
>
>terminated
>
>STATUS | wrapper  | 2007/03/20 15:48:56 | Launching a JVM...
>
>INFO | jvm 21 | 2007/03/20 15:48:57 | Wrapper (Version 3.2.3)
>http://wrapper.tanukisoftware.org
>
>INFO   | jvm 21   | 2007/03/20 15:48:57 |   Copyright 1999-2006 Tanuki
>
>Software, Inc.  All Rights Reserved.
>
>INFO   | jvm 21   | 2007/03/20 15:48:57 |
>
>ERROR  | wrapper  | 2007/03/20 15:48:57 | Unable to send the start
>command
>to the JVM.
>
>ERROR  | wrapper  | 2007/03/20 15:48:57 | JVM did not exit on request,
>
>terminated
>
>FATAL  | wrapper  | 2007/03/20 15:48:57 | There were 5 failed launches
>in a
>row, each lasting less than 300 seconds.  Giving up.
>
>FATAL  | wrapper  | 2007/03/20 15:48:57 |   There may be a
>configuration
>problem: please check the logs.
>
>STATUS | wrapper  | 2007/03/20 15:48:58 | <-- Wrapper Stopped
>
>_________________________________________________________________
>Watch free concerts with Pink, Rod Stewart, Oasis and more. Visit MSN
>Presents today.
>http://music.msn.com/presents?icid=ncmsnpresentstagline&ocid=T002MSN03A07001
>
>
>
>-------------------------------------------------------------------------
>Take Surveys. Earn Cash. Influence the Future of IT
>Join SourceForge.net's Techsay panel and you'll get the chance to share
>your
>opinions on IT & business topics through brief surveys-and earn cash
>http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
>
>_______________________________________________
>Wrapper-user mailing list
>Wra...@li...
>https://lists.sourceforge.net/lists/listinfo/wrapper-user
>
>-------------------------------------------------------------------------
>Take Surveys. Earn Cash. Influence the Future of IT
>Join SourceForge.net's Techsay panel and you'll get the chance to share 
>your
>opinions on IT & business topics through brief surveys-and earn cash
>http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
>_______________________________________________
>Wrapper-user mailing list
>Wra...@li...
>https://lists.sourceforge.net/lists/listinfo/wrapper-user

_________________________________________________________________
Watch free concerts with Pink, Rod Stewart, Oasis and more. Visit MSN 
Presents today. 
http://music.msn.com/presents?icid=ncmsnpresentstagline&ocid=T002MSN03A07001

Re: [Wrapper-user] Restart request causes 5 rapid retries and death

From: Leif M. <le...@ta...> - 2007-04-03 08:54:41

Hubert,
    Thanks for pointing this out.  The behavior of replacing the 
remaining timeout with the
new one was historical.  In the first versions of the Wrapper, the 
internal timeouts were
not nearly so configurable and the application took much more control of 
the startup
and shutdown of the JVM.

    The way the Wrapper is now used however, this no longer makes any 
sense.  It is
only the way it still because I had not given it any thought.

    I have modified both the signalStarting and signalStopping methods 
so they will
now attempt to set a new timeout "millis" milliseconds in the future.  
If the existing
timeout is already longer then the new timeout will be ignored.  Ie, it 
will only have
any meaning if the result will be to extend the timeout.

    This is checked in to SVN and will be in the next release.

    Cheers,
    Leif

Hubert Felber wrote:
> Ben,
>
> Just an assumption: 
> check the comment on
> http://wrapper.tanukisoftware.org/doc/english/integrate-listener.html
>  on the bottom of the site, about 
>  WrapperManager.signalStarting(int millis) and 
> WrapperManager.signalStopping(int millis).
>
> I noticed the following:
> Calling WrapperManager.signalStopping(1000); I expected that the value
> is added to the TimeToWait defined in the conf file. But it seams that
> it is not added but replaced. This is the new time to wait. And if you
> call it on Windows with value 1000, it causes or can cause a quite
> immediate exit. 
>
> Perhaps your problems have something to do with this.
>
> hth 
> Hubert
>
> btw 
>
> on the bottom of the mentioned site you read:
>
> <BTW Great Job Leif!
>
> Yes, indeed!
>
>
>   
>>>> "Ben Cox" <ple...@ho...> 30.03.2007 22:37 >>>
>>>>         
> Hi all,
>
> I've been using Wrapper version 3.2.3 to manage my service on a Windows
> 2003 
> Server machine.  Everything is working rather well, except occasionally
> when 
> requesting a restart from within the app (and sometimes just during a 
> "normal" restart after an error).  What will happen is that the wrapper
> will 
> attempt to start my app and then give up and terminate the JVM within
> _one_ 
> second!
>
> At least, that's how it looks from the timestamps on the log output 
> (below)...   am I missing (or misinterpreting) a config file setting?
>
> Thanks,
>
> Ben
>
>
> STATUS | wrapper  | 2007/03/20 15:48:27 | JVM requested a restart.
>
> STATUS | wrapper  | 2007/03/20 15:48:34 | Launching a JVM...
>
> INFO | jvm 17 | 2007/03/20 15:48:34 | Wrapper (Version 3.2.3) 
> http://wrapper.tanukisoftware.org 
>
> INFO   | jvm 17   | 2007/03/20 15:48:34 |   Copyright 1999-2006 Tanuki
>
> Software, Inc.  All Rights Reserved.
>
> INFO   | jvm 17   | 2007/03/20 15:48:34 |
>
> ERROR  | wrapper  | 2007/03/20 15:48:34 | Unable to send the start
> command 
> to the JVM.
>
> ERROR  | wrapper  | 2007/03/20 15:48:34 | JVM did not exit on request,
>
> terminated
>
> STATUS | wrapper  | 2007/03/20 15:48:39 | Launching a JVM...
>
> INFO | jvm 18 | 2007/03/20 15:48:40 | Wrapper (Version 3.2.3) 
> http://wrapper.tanukisoftware.org 
>
> INFO   | jvm 18   | 2007/03/20 15:48:40 |   Copyright 1999-2006 Tanuki
>
> Software, Inc.  All Rights Reserved.
>
> INFO   | jvm 18   | 2007/03/20 15:48:40 |
>
> ERROR  | wrapper  | 2007/03/20 15:48:40 | Unable to send the start
> command 
> to the JVM.
>
> ERROR  | wrapper  | 2007/03/20 15:48:40 | JVM did not exit on request,
>
> terminated
>
> STATUS | wrapper  | 2007/03/20 15:48:45 | Launching a JVM...
>
> INFO | jvm 19 | 2007/03/20 15:48:45 | Wrapper (Version 3.2.3) 
> http://wrapper.tanukisoftware.org 
>
> INFO   | jvm 19   | 2007/03/20 15:48:45 |   Copyright 1999-2006 Tanuki
>
> Software, Inc.  All Rights Reserved.
>
> INFO   | jvm 19   | 2007/03/20 15:48:45 |
>
> ERROR  | wrapper  | 2007/03/20 15:48:45 | Unable to send the start
> command 
> to the JVM.
>
> ERROR  | wrapper  | 2007/03/20 15:48:46 | JVM did not exit on request,
>
> terminated
>
> STATUS | wrapper  | 2007/03/20 15:48:51 | Launching a JVM...
>
> INFO | jvm 20 | 2007/03/20 15:48:51 | Wrapper (Version 3.2.3) 
> http://wrapper.tanukisoftware.org 
>
> INFO   | jvm 20   | 2007/03/20 15:48:51 |   Copyright 1999-2006 Tanuki
>
> Software, Inc.  All Rights Reserved.
>
> INFO   | jvm 20   | 2007/03/20 15:48:51 |
>
> ERROR  | wrapper  | 2007/03/20 15:48:51 | Unable to send the start
> command 
> to the JVM.
>
> ERROR  | wrapper  | 2007/03/20 15:48:51 | JVM did not exit on request,
>
> terminated
>
> STATUS | wrapper  | 2007/03/20 15:48:56 | Launching a JVM...
>
> INFO | jvm 21 | 2007/03/20 15:48:57 | Wrapper (Version 3.2.3) 
> http://wrapper.tanukisoftware.org 
>
> INFO   | jvm 21   | 2007/03/20 15:48:57 |   Copyright 1999-2006 Tanuki
>
> Software, Inc.  All Rights Reserved.
>
> INFO   | jvm 21   | 2007/03/20 15:48:57 |
>
> ERROR  | wrapper  | 2007/03/20 15:48:57 | Unable to send the start
> command 
> to the JVM.
>
> ERROR  | wrapper  | 2007/03/20 15:48:57 | JVM did not exit on request,
>
> terminated
>
> FATAL  | wrapper  | 2007/03/20 15:48:57 | There were 5 failed launches
> in a 
> row, each lasting less than 300 seconds.  Giving up.
>
> FATAL  | wrapper  | 2007/03/20 15:48:57 |   There may be a
> configuration 
> problem: please check the logs.
>
> STATUS | wrapper  | 2007/03/20 15:48:58 | <-- Wrapper Stopped
>
> _________________________________________________________________
> Watch free concerts with Pink, Rod Stewart, Oasis and more. Visit MSN 
> Presents today. 
> http://music.msn.com/presents?icid=ncmsnpresentstagline&ocid=T002MSN03A07001
>
>
>
> -------------------------------------------------------------------------
> Take Surveys. Earn Cash. Influence the Future of IT
> Join SourceForge.net's Techsay panel and you'll get the chance to share
> your
> opinions on IT & business topics through brief surveys-and earn cash
> http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
>
> _______________________________________________
> Wrapper-user mailing list
> Wra...@li... 
> https://lists.sourceforge.net/lists/listinfo/wrapper-user
>
> -------------------------------------------------------------------------
> Take Surveys. Earn Cash. Influence the Future of IT
> Join SourceForge.net's Techsay panel and you'll get the chance to share your
> opinions on IT & business topics through brief surveys-and earn cash
> http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
> _______________________________________________
> Wrapper-user mailing list
> Wra...@li...
> https://lists.sourceforge.net/lists/listinfo/wrapper-user
>
>