[Wrapper-user] Wrapper immediately stops as service on linux

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

I've installed version 3.2.3 of the Wrapper on my Ubuntu box (Hardy 
Heron - 8.04). I configured the wrapper to start my program "harmonium", 
then configured linux to start the service at boot (I symlinked in 
/etc/init.d, then ran update-rc.d). When I 'cd' to /etc/init.d and run:

   sudo ./harmonium console

everything works as expected. I can run my program and all is well. If I 
just start the program with

   sudo ./harmonium start

everything works as expected. The program happily rolls along until I do 
a 'stop'.

If I reboot the machine, harmonium is started at boot as expected, but 
then it immediately stops. I don't know why. I'm hoping someone on here 
can give me some guidance.

I'm including DEBUG level logs below.

Thanks,

Charlie

##### BEGIN LOG #####
DEBUG  | wrapper  | 2008/07/13 17:53:16 | Spawning intermediate process...
DEBUG  | wrapper  | 2008/07/13 17:53:16 | Spawning daemon process...
STATUS | wrapper  | 2008/07/13 17:53:16 | --> Wrapper Started as Daemon
DEBUG  | wrapper  | 2008/07/13 17:53:16 | Using tick timer.
DEBUG  | wrapperp | 2008/07/13 17:53:16 | server listening on port 32000.
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[0] : java
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[1] : 
-Djava.library.path=../lib
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[2] : -classpath
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[3] : 
../lib/bananas.jar:../lib/hme.jar:../lib/commons-logging-1.1.1.jar:../lib/hme-hd-0.4.1.jar:../lib/wrapper.jar:../lib/hme-host-sample.jar:../lib/JID3.jar:../Harmonium.jar
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[4] : 
-Dwrapper.key=P6TgVO2G8iqqxuI1
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[5] : -Dwrapper.port=32000
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[6] : 
-Dwrapper.jvm.port.min=31000
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[7] : 
-Dwrapper.jvm.port.max=31999
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[8] : -Dwrapper.debug=TRUE
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[9] : -Dwrapper.pid=5902
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[10] : 
-Dwrapper.version=3.2.3
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[11] : 
-Dwrapper.native_library=wrapper
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[12] : 
-Dwrapper.service=TRUE
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[13] : 
-Dwrapper.cpu.timeout=10
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[14] : -Dwrapper.jvmid=1
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[15] : 
org.tanukisoftware.wrapper.WrapperSimpleApp
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[16] : 
com.tivo.hme.host.sample.Main
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[17] : 
org.dazeend.harmonium.Harmonium
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[18] : -musicRoot
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[19] : 
/home/ceperry/Harmonium/test_data/music
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[20] : -playlistRoot
DEBUG  | wrapper  | 2008/07/13 17:53:17 | Command[21] : 
/home/ceperry/Harmonium/test_data/playlists
STATUS | wrapper  | 2008/07/13 17:53:17 | Launching a JVM...
INFO   | jvm 1    | 2008/07/13 17:53:19 | WrapperManager class 
initialized by thread: main  Using classloader: 
sun.misc.Launcher$AppClassLoader@1ba34f2
INFO   | jvm 1    | 2008/07/13 17:53:21 | Wrapper (Version 3.2.3) 
http://wrapper.tanukisoftware.org
INFO   | jvm 1    | 2008/07/13 17:53:21 |   Copyright 1999-2006 Tanuki 
Software, Inc.  All Rights Reserved.
INFO   | jvm 1    | 2008/07/13 17:53:21 |
INFO   | jvm 1    | 2008/07/13 17:53:21 | Wrapper Manager: JVM #1
INFO   | jvm 1    | 2008/07/13 17:53:21 | Running a 32-bit JVM.
INFO   | jvm 1    | 2008/07/13 17:53:21 | Wrapper Manager: Registering 
shutdown hook
INFO   | jvm 1    | 2008/07/13 17:53:21 | Wrapper Manager: Using wrapper
INFO   | jvm 1    | 2008/07/13 17:53:21 | Load native library.  One or 
more attempts may fail if platform specific libraries do not exist.
INFO   | jvm 1    | 2008/07/13 17:53:21 | Loading native library failed: 
libwrapper-linux-x86-32.so  Cause: java.lang.UnsatisfiedLinkError: no 
wrapper-linux-x86-32 in java.library.path
INFO   | jvm 1    | 2008/07/13 17:53:21 | Loaded native library: 
libwrapper.so
INFO   | jvm 1    | 2008/07/13 17:53:21 | Calling native initialization 
method.
INFO   | jvm 1    | 2008/07/13 17:53:21 | Inside native WrapperManager 
initialization method
INFO   | jvm 1    | 2008/07/13 17:53:21 | Java Version   : 1.6.0_06-b02 
Java HotSpot(TM) Server VM
INFO   | jvm 1    | 2008/07/13 17:53:21 | Java VM Vendor : Sun 
Microsystems Inc.
INFO   | jvm 1    | 2008/07/13 17:53:21 |
INFO   | jvm 1    | 2008/07/13 17:53:21 | Startup runner thread started.
INFO   | jvm 1    | 2008/07/13 17:53:21 | Control event monitor thread 
started.
INFO   | jvm 1    | 2008/07/13 17:53:21 | 
WrapperManager.start(org.tanukisoftware.wrapper.WrapperSimpleApp@17bd6a1, 
args["org.dazeend.harmonium.Harmonium", "-musicRoot", 
"/home/ceperry/Harmonium/test_data/music", "-playlistRoot", 
"/home/ceperry/Harmonium/test_data/playlists"]) called by thread: main
INFO   | jvm 1    | 2008/07/13 17:53:21 | Communications runner thread 
started.
INFO   | jvm 1    | 2008/07/13 17:53:21 | Open socket to 
wrapper...Wrapper-Connection
INFO   | jvm 1    | 2008/07/13 17:53:21 | Opened Socket from 31000 to 32000
INFO   | jvm 1    | 2008/07/13 17:53:21 | Send a packet KEY : 
P6TgVO2G8iqqxuI1
INFO   | jvm 1    | 2008/07/13 17:53:21 | 
handleSocket(Socket[addr=/127.0.0.1,port=32000,localport=31000])
DEBUG  | wrapperp | 2008/07/13 17:53:21 | accepted a socket from 
127.0.0.1 on port 31000
DEBUG  | wrapperp | 2008/07/13 17:53:21 | read a packet KEY : 
P6TgVO2G8iqqxuI1
DEBUG  | wrapper  | 2008/07/13 17:53:21 | Got key from JVM: P6TgVO2G8iqqxuI1
DEBUG  | wrapperp | 2008/07/13 17:53:21 | send a packet LOW_LOG_LEVEL : 1
DEBUG  | wrapperp | 2008/07/13 17:53:21 | send a packet PING_TIMEOUT : 30
DEBUG  | wrapperp | 2008/07/13 17:53:21 | send a packet PROPERTIES : 
(Property Values)
DEBUG  | wrapper  | 2008/07/13 17:53:21 | Start Application.
DEBUG  | wrapperp | 2008/07/13 17:53:21 | send a packet START : start
INFO   | jvm 1    | 2008/07/13 17:53:21 | Received a packet 
LOW_LOG_LEVEL : 1
INFO   | jvm 1    | 2008/07/13 17:53:21 | Wrapper Manager: LowLogLevel 
from Wrapper is 1
INFO   | jvm 1    | 2008/07/13 17:53:21 | Received a packet PING_TIMEOUT 
: 30
INFO   | jvm 1    | 2008/07/13 17:53:21 | PingTimeout from Wrapper is 30000
INFO   | jvm 1    | 2008/07/13 17:53:21 | Received a packet PROPERTIES : 
(Property Values)
INFO   | jvm 1    | 2008/07/13 17:53:21 | Received a packet START : start
INFO   | jvm 1    | 2008/07/13 17:53:21 | calling WrapperListener.start()
INFO   | jvm 1    | 2008/07/13 17:53:21 | Waiting for 
WrapperListener.start runner thread to complete.
INFO   | jvm 1    | 2008/07/13 17:53:21 | WrapperListener.start runner 
thread started.
INFO   | jvm 1    | 2008/07/13 17:53:21 | WrapperSimpleApp: start(args) 
Will wait up to 2 seconds for the main method to complete.
INFO   | jvm 1    | 2008/07/13 17:53:21 | WrapperSimpleApp: invoking 
main method
INFO   | jvm 1    | 2008/07/13 17:53:21 | HME SDK 1.4 (TiVo, Inc.)
INFO   | jvm 1    | 2008/07/13 17:53:21 | Searching for music...
INFO   | jvm 1    | 2008/07/13 17:53:22 | Send a packet START_PENDING : 5000
DEBUG  | wrapperp | 2008/07/13 17:53:22 | read a packet START_PENDING : 5000
DEBUG  | wrapper  | 2008/07/13 17:53:22 | JVM signalled a start pending 
with waitHint of 5000 millis.
INFO   | jvm 1    | 2008/07/13 17:53:23 | Send a packet START_PENDING : 5000
INFO   | jvm 1    | 2008/07/13 17:53:23 | WrapperSimpleApp: start(args) 
end.  Main Completed=false, exitCode=null
INFO   | jvm 1    | 2008/07/13 17:53:23 | WrapperListener.start runner 
thread stopped.
INFO   | jvm 1    | 2008/07/13 17:53:23 | returned from 
WrapperListener.start()
INFO   | jvm 1    | 2008/07/13 17:53:23 | Send a packet STARTED :
INFO   | jvm 1    | 2008/07/13 17:53:23 | Startup runner thread stopped.
DEBUG  | wrapperp | 2008/07/13 17:53:23 | read a packet START_PENDING : 5000
DEBUG  | wrapper  | 2008/07/13 17:53:23 | JVM signalled a start pending 
with waitHint of 5000 millis.
DEBUG  | wrapperp | 2008/07/13 17:53:23 | read a packet STARTED :
DEBUG  | wrapper  | 2008/07/13 17:53:23 | JVM signalled that it was started.
DEBUG  | wrapperp | 2008/07/13 17:53:23 | send a packet PING : ping
INFO   | jvm 1    | 2008/07/13 17:53:23 | Received a packet PING : ping
INFO   | jvm 1    | 2008/07/13 17:53:23 | Send a packet PING : ok
DEBUG  | wrapperp | 2008/07/13 17:53:23 | read a packet PING : ok
DEBUG  | wrapper  | 2008/07/13 17:53:23 | Got ping response from JVM
DEBUG  | wrapperp | 2008/07/13 17:53:27 | send a packet PING : ping
INFO   | jvm 1    | 2008/07/13 17:53:27 | Received a packet PING : ping
INFO   | jvm 1    | 2008/07/13 17:53:27 | Send a packet PING : ok
DEBUG  | wrapperp | 2008/07/13 17:53:27 | read a packet PING : ok
DEBUG  | wrapper  | 2008/07/13 17:53:27 | Got ping response from JVM
INFO   | jvm 1    | 2008/07/13 17:53:27 | Searching for playlists...
INFO   | jvm 1    | 2008/07/13 17:53:27 | Build of music database completed
INFO   | jvm 1    | 2008/07/13 17:53:27 | LOG: added factory
INFO   | jvm 1    | 2008/07/13 17:53:27 | WrapperSimpleApp: main method 
completed
INFO   | jvm 1    | 2008/07/13 17:53:27 | Wrapper Manager: ShutdownHook 
started
INFO   | jvm 1    | 2008/07/13 17:53:27 | WrapperManager.stop(0) called 
by thread: Wrapper-Shutdown-Hook
INFO   | jvm 1    | 2008/07/13 17:53:27 | Send a packet STOP : 0
DEBUG  | wrapperp | 2008/07/13 17:53:27 | read a packet STOP : 0
DEBUG  | wrapper  | 2008/07/13 17:53:27 | JVM requested a shutdown. (0)
DEBUG  | wrapper  | 2008/07/13 17:53:27 | wrapperStopProcess(0) called.
DEBUG  | wrapper  | 2008/07/13 17:53:27 | Sending stop signal to JVM
DEBUG  | wrapperp | 2008/07/13 17:53:27 | send a packet STOP : NULL
INFO   | jvm 1    | 2008/07/13 17:53:27 | Received a packet STOP :
INFO   | jvm 1    | 2008/07/13 17:53:28 | Thread, Wrapper-Shutdown-Hook, 
handling the shutdown process.
INFO   | jvm 1    | 2008/07/13 17:53:28 | calling listener.stop()
INFO   | jvm 1    | 2008/07/13 17:53:28 | WrapperSimpleApp: stop(0)
INFO   | jvm 1    | 2008/07/13 17:53:28 | returned from listener.stop() -> 0
INFO   | jvm 1    | 2008/07/13 17:53:28 | shutdownJVM(0) 
Thread:Wrapper-Shutdown-Hook
INFO   | jvm 1    | 2008/07/13 17:53:28 | Send a packet STOPPED : 0
DEBUG  | wrapperp | 2008/07/13 17:53:28 | read a packet STOPPED : 0
DEBUG  | wrapper  | 2008/07/13 17:53:28 | JVM signalled that it was stopped.
INFO   | jvm 1    | 2008/07/13 17:53:29 | Closing socket.
DEBUG  | wrapperp | 2008/07/13 17:53:29 | socket read no code (closed?).
DEBUG  | wrapperp | 2008/07/13 17:53:29 | server listening on port 32001.
DEBUG  | wrapper  | 2008/07/13 17:53:29 | JVM exited normally.
DEBUG  | wrapper  | 2008/07/13 17:53:29 | Signal trapped.  Details:
DEBUG  | wrapper  | 2008/07/13 17:53:29 |   signal number=17 (SIGCHLD), 
source="unknown"
DEBUG  | wrapper  | 2008/07/13 17:53:29 | Received SIGCHLD, checking JVM 
process status.
DEBUG  | wrapper  | 2008/07/13 17:53:29 | JVM process exited with a code 
of 0, leaving the wrapper exit code set to 0.
INFO   | jvm 1    | 2008/07/13 17:53:29 | Wrapper Manager: ShutdownHook 
complete
INFO   | jvm 1    | 2008/07/13 17:53:29 | Server daemon shut down
STATUS | wrapper  | 2008/07/13 17:53:29 | <-- Wrapper Stopped