Skip to main content

DarkStar server won't start

9 replies [Last post]
celestesea
Offline
Joined: 2009-03-17

My darkstar server suddenly won't start and the same problem exists even after I check out the code from svn again. When execute ant run-server, I get the following IO exception:

[java] Can't load log handler "java.util.logging.FileHandler"
[java] java.io.IOException: Couldn't get lock for C:Documents and Settingscelestesea.wonderland-server0.5-devlog/web_ser
ver.log
[java] java.io.IOException: Couldn't get lock for C:Documents and Settingscelestesea.wonderland-server0.5-devlog/web_ser
ver.log
[java] at java.util.logging.FileHandler.openFiles(FileHandler.java:372)
[java] at java.util.logging.FileHandler.(FileHandler.java:208)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at java.lang.Class.newInstance0(Class.java:355)
[java] at java.lang.Class.newInstance(Class.java:308)
[java] at java.util.logging.LogManager$7.run(LogManager.java:911)
[java] at java.security.AccessController.doPrivileged(Native Method)
[java] at java.util.logging.LogManager.initializeGlobalHandlers(LogManager.java:903)
[java] at java.util.logging.LogManager.access$900(LogManager.java:129)
[java] at java.util.logging.LogManager$RootLogger.getHandlers(LogManager.java:1010)
[java] at java.util.logging.Logger.log(Logger.java:454)
[java] at java.util.logging.Logger.doLog(Logger.java:480)
[java] at java.util.logging.Logger.log(Logger.java:503)
[java] at java.util.logging.Logger.info(Logger.java:1022)
[java] at org.jdesktop.wonderland.webserver.launcher.WebServerLauncher$KillSwitch.run(WebServerLauncher.java:40
9)
[java] at java.lang.Thread.run(Thread.java:619)

The web sever and voice bridge server are running while darkstar server is not. Here is the log for darkstar server.

Executing: [C:\Program Files\Java\jdk1.6.0_12\jre\bin\java, -cp, C:\Documents and Settings\celestesea\.wonderland-server\0.5-dev\run\darkstar_server\run\lib\ant\ant-launcher.jar, -Dant.home=C:\Documents and Settings\celestesea\.wonderland-server\0.5-dev\run\darkstar_server\run\lib\ant, org.apache.tools.ant.launch.Launcher, -Dsgs.services=:com.sun.mpk20.voicelib.impl.service.voice.VoiceServiceImpl:org.jdesktop.wonderland.modules.security.server.service.SecurityService:org.jdesktop.wonderland.modules.security.server.service.CellResourceService, -Dwonderland.web.server.url=http://127.0.0.1:8080/, -Dsgs.managers=:com.sun.mpk20.voicelib.impl.app.VoiceManagerImpl:org.jdesktop.wonderland.modules.security.server.service.SecurityManagerImpl:org.jdesktop.wonderland.modules.security.server.service.CellResourceManagerImpl, -Drunner.location=localhost, -Dsgs.port=1139, -Drunner.name=Darkstar Server, -Dsgs.coldstart=true, -Dsgs.wfs.root=worlds/gardenarches-wfs, -f, C:\Documents and Settings\celestesea\.wonderland-server\0.5-dev\run\darkstar_server\run\run.xml]
Buildfile: C:\Documents and Settings\celestesea\.wonderland-server\0.5-dev\run\darkstar_server\run\run.xml

-run-setup:

-run-coldstart:

core-clean-server:

-run-server:

-core-server-setup-bdb-platform:

-core-server-setup-assertions:

-core-server-setup-no-assertions:

-core-server-setup-debug:

-core-server-setup-classpath:

-core-setup-server:
[mkdir] Created dir: C:\Documents and Settings\celestesea\.wonderland-server\0.5-dev\run\darkstar_server\run\core\data\sgs
[mkdir] Created dir: C:\Documents and Settings\celestesea\.wonderland-server\0.5-dev\run\darkstar_server\run\core\data\sgs\dsdb
[copy] Copying 1 file to C:\Documents and Settings\celestesea\.wonderland-server\0.5-dev\run\darkstar_server\run\core\data\sgs
[copy] Copying 1 file to C:\Documents and Settings\celestesea\.wonderland-server\0.5-dev\run\darkstar_server\run\core\data\sgs

core-run-server:
[java] Feb 9, 2010 9:24:55 AM org.jdesktop.wonderland.server.auth.WebServiceAuthenticator
[java] INFO: Loading WebServie authenticator
[java] Feb 9, 2010 9:24:55 AM com.sun.sgs.impl.kernel.Kernel
[java] INFO: The Kernel is ready, version: 0.9.10
[java] Feb 9, 2010 9:25:07 AM com.sun.sgs.impl.service.watchdog.WatchdogServerImpl registerNode
[java] INFO: node:com.sun.sgs.impl.service.watchdog.NodeImpl[1,alive,backup:(none)]@ed-allen-pc9 registered
[java] Feb 9, 2010 9:25:08 AM com.sun.sgs.impl.transport.tcp.TcpTransport
[java] WARNING: acceptor failed to listen on 0.0.0.0/0.0.0.0:1139
[java] java.net.BindException: Address already in use: bind
[java] at sun.nio.ch.Net.bind(Native Method)
[java] at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:119)
[java] at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:59)
[java] at com.sun.sgs.impl.nio.AsyncServerSocketChannelImpl.bind(AsyncServerSocketChannelImpl.java:133)
[java] at com.sun.sgs.impl.nio.AsyncServerSocketChannelImpl.bind(AsyncServerSocketChannelImpl.java:54)
[java] at com.sun.sgs.impl.transport.tcp.TcpTransport.(TcpTransport.java:176)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstance(PropertiesWrapper.java:371)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstanceProperty(PropertiesWrapper.java:343)
[java] at com.sun.sgs.impl.protocol.simple.SimpleSgsProtocolAcceptor.(SimpleSgsProtocolAcceptor.java:215)
[java] at com.sun.sgs.impl.protocol.simple.SimpleSgsProtocolAcceptor.(SimpleSgsProtocolAcceptor.java:178)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstance(PropertiesWrapper.java:371)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstanceProperty(PropertiesWrapper.java:343)
[java] at com.sun.sgs.impl.service.session.ClientSessionServiceImpl.(ClientSessionServiceImpl.java:376)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.kernel.Kernel.createService(Kernel.java:682)
[java] at com.sun.sgs.impl.kernel.Kernel.setupServiceNoManager(Kernel.java:626)
[java] at com.sun.sgs.impl.kernel.Kernel.fetchServices(Kernel.java:576)
[java] at com.sun.sgs.impl.kernel.Kernel.createServices(Kernel.java:445)
[java] at com.sun.sgs.impl.kernel.Kernel.createAndStartApplication(Kernel.java:419)
[java] at com.sun.sgs.impl.kernel.Kernel.(Kernel.java:353)
[java] at com.sun.sgs.impl.kernel.Kernel.main(Kernel.java:1147)
[java] Feb 9, 2010 9:25:08 AM com.sun.sgs.impl.kernel.Kernel createServices
[java] SEVERE: Wonderland: failed to create services
[java] java.lang.reflect.InvocationTargetException
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.kernel.Kernel.createService(Kernel.java:682)
[java] at com.sun.sgs.impl.kernel.Kernel.setupServiceNoManager(Kernel.java:626)
[java] at com.sun.sgs.impl.kernel.Kernel.fetchServices(Kernel.java:576)
[java] at com.sun.sgs.impl.kernel.Kernel.createServices(Kernel.java:445)
[java] at com.sun.sgs.impl.kernel.Kernel.createAndStartApplication(Kernel.java:419)
[java] at com.sun.sgs.impl.kernel.Kernel.(Kernel.java:353)
[java] at com.sun.sgs.impl.kernel.Kernel.main(Kernel.java:1147)
[java] Caused by: java.lang.RuntimeException: java.net.BindException: Address already in use: bind
[java] at com.sun.sgs.impl.transport.tcp.TcpTransport.(TcpTransport.java:201)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstance(PropertiesWrapper.java:371)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstanceProperty(PropertiesWrapper.java:343)
[java] at com.sun.sgs.impl.protocol.simple.SimpleSgsProtocolAcceptor.(SimpleSgsProtocolAcceptor.java:215)
[java] at com.sun.sgs.impl.protocol.simple.SimpleSgsProtocolAcceptor.(SimpleSgsProtocolAcceptor.java:178)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstance(PropertiesWrapper.java:371)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstanceProperty(PropertiesWrapper.java:343)
[java] at com.sun.sgs.impl.service.session.ClientSessionServiceImpl.(ClientSessionServiceImpl.java:376)
[java] ... 11 more
[java] Caused by: java.net.BindException: Address already in use: bind
[java] at sun.nio.ch.Net.bind(Native Method)
[java] at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:119)
[java] at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:59)
[java] at com.sun.sgs.impl.nio.AsyncServerSocketChannelImpl.bind(AsyncServerSocketChannelImpl.java:133)
[java] at com.sun.sgs.impl.nio.AsyncServerSocketChannelImpl.bind(AsyncServerSocketChannelImpl.java:54)
[java] at com.sun.sgs.impl.transport.tcp.TcpTransport.(TcpTransport.java:176)
[java] ... 26 more
[java] Feb 9, 2010 9:25:08 AM com.sun.sgs.impl.kernel.Kernel
[java] SEVERE: Failed on Kernel boot
[java] java.lang.reflect.InvocationTargetException
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.kernel.Kernel.createService(Kernel.java:682)
[java] at com.sun.sgs.impl.kernel.Kernel.setupServiceNoManager(Kernel.java:626)
[java] at com.sun.sgs.impl.kernel.Kernel.fetchServices(Kernel.java:576)
[java] at com.sun.sgs.impl.kernel.Kernel.createServices(Kernel.java:445)
[java] at com.sun.sgs.impl.kernel.Kernel.createAndStartApplication(Kernel.java:419)
[java] at com.sun.sgs.impl.kernel.Kernel.(Kernel.java:353)
[java] at com.sun.sgs.impl.kernel.Kernel.main(Kernel.java:1147)
[java] Caused by: java.lang.RuntimeException: java.net.BindException: Address already in use: bind
[java] at com.sun.sgs.impl.transport.tcp.TcpTransport.(TcpTransport.java:201)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstance(PropertiesWrapper.java:371)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstanceProperty(PropertiesWrapper.java:343)
[java] at com.sun.sgs.impl.protocol.simple.SimpleSgsProtocolAcceptor.(SimpleSgsProtocolAcceptor.java:215)
[java] at com.sun.sgs.impl.protocol.simple.SimpleSgsProtocolAcceptor.(SimpleSgsProtocolAcceptor.java:178)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstance(PropertiesWrapper.java:371)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstanceProperty(PropertiesWrapper.java:343)
[java] at com.sun.sgs.impl.service.session.ClientSessionServiceImpl.(ClientSessionServiceImpl.java:376)
[java] ... 11 more
[java] Caused by: java.net.BindException: Address already in use: bind
[java] at sun.nio.ch.Net.bind(Native Method)
[java] at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:119)
[java] at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:59)
[java] at com.sun.sgs.impl.nio.AsyncServerSocketChannelImpl.bind(AsyncServerSocketChannelImpl.java:133)
[java] at com.sun.sgs.impl.nio.AsyncServerSocketChannelImpl.bind(AsyncServerSocketChannelImpl.java:54)
[java] at com.sun.sgs.impl.transport.tcp.TcpTransport.(TcpTransport.java:176)
[java] ... 26 more
[java] Exception in thread "main" java.lang.reflect.InvocationTargetException
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.kernel.Kernel.createService(Kernel.java:682)
[java] at com.sun.sgs.impl.kernel.Kernel.setupServiceNoManager(Kernel.java:626)
[java] at com.sun.sgs.impl.kernel.Kernel.fetchServices(Kernel.java:576)
[java] at com.sun.sgs.impl.kernel.Kernel.createServices(Kernel.java:445)
[java] at com.sun.sgs.impl.kernel.Kernel.createAndStartApplication(Kernel.java:419)
[java] at com.sun.sgs.impl.kernel.Kernel.(Kernel.java:353)
[java] at com.sun.sgs.impl.kernel.Kernel.main(Kernel.java:1147)
[java] Caused by: java.lang.RuntimeException: java.net.BindException: Address already in use: bind
[java] at com.sun.sgs.impl.transport.tcp.TcpTransport.(TcpTransport.java:201)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstance(PropertiesWrapper.java:371)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstanceProperty(PropertiesWrapper.java:343)
[java] at com.sun.sgs.impl.protocol.simple.SimpleSgsProtocolAcceptor.(SimpleSgsProtocolAcceptor.java:215)
[java] at com.sun.sgs.impl.protocol.simple.SimpleSgsProtocolAcceptor.(SimpleSgsProtocolAcceptor.java:178)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
[java] at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:39)
[java] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:27)
[java] at java.lang.reflect.Constructor.newInstance(Constructor.java:513)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstance(PropertiesWrapper.java:371)
[java] at com.sun.sgs.impl.sharedutil.PropertiesWrapper.getClassInstanceProperty(PropertiesWrapper.java:343)
[java] at com.sun.sgs.impl.service.session.ClientSessionServiceImpl.(ClientSessionServiceImpl.java:376)
[java] ... 11 more
[java] Caused by: java.net.BindException: Address already in use: bind
[java] at sun.nio.ch.Net.bind(Native Method)
[java] at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:119)
[java] at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:59)
[java] at com.sun.sgs.impl.nio.AsyncServerSocketChannelImpl.bind(AsyncServerSocketChannelImpl.java:133)
[java] at com.sun.sgs.impl.nio.AsyncServerSocketChannelImpl.bind(AsyncServerSocketChannelImpl.java:54)
[java] at com.sun.sgs.impl.transport.tcp.TcpTransport.(TcpTransport.java:176)
[java] ... 26 more
[java] Java Result: 1

run:

BUILD SUCCESSFUL
Total time: 1 minute 15 seconds
Process exitted, return value: 0

Anybody has similar experience?

Reply viewing options

Select your preferred way to display the comments and click "Save settings" to activate your changes.
jagwire
Offline
Joined: 2008-06-21

Michel,

Celestea said she resolved the issues she was having by multiple restarts and removing the .wonderland-server directory. Can you confirm whether any of that helps?

jW

micheldenis
Offline
Joined: 2007-12-10

Hi jagwire,

Initially my darkstar server also did not start and I removed .wonderland-server - after a new restart of the webserver then darkstar accepted to start, like it also does now anytime. So WL is functioning correctly, except that it always prints (only on Windows !) this exception, maybe due to some "\" vs "/" parsing or formatting.

Nina also seems to see this behavior.

Thanks,
-michel

nnjones
Offline
Joined: 2006-09-26

Same on my windows install. But it doesn't prevent the server from starting. Just the logging. (Which is kinda' problematic, but not deadly.) I did try using forward slashes, but that gave me the same result, which didn't make sense as far as "typical" windows <--> unix filename parsing. (That usually works for me.) Don't know if that helps narrow it down....

celestesea
Offline
Joined: 2009-03-17

false alarm, the darkstar server runs now, my bad... Thank you Bernard.

However, I am still getting the web server log file exception. Was it always there?

[java] Can't load log handler "java.util.logging.FileHandler"
[java] java.io.IOException: Couldn't get lock for C:Documents and Settingscelestesea.wonderland-server0.5-devlog/web_ser
ver.log
[java] java.io.IOException: Couldn't get lock for C:Documents and Settingscelestesea.wonderland-server0.5-devlog/web_ser
ver.log

For some reason, the path is missing all the "/".

Message was edited by: celestesea

Message was edited by: celestesea

bernard_horan
Offline
Joined: 2007-06-04

This usually happens if the \ isn't escaped. Are you using your own my.run.properties file?

Bernard

micheldenis
Offline
Joined: 2007-12-10

Bernard,

I'm also getting the same problem as celestea above.
No my.run.properties used.
WL server and client in local,
Win Vista
Java 6 upd 16
Latest trunk of today.

Any idea ?

Thanks,
-michel

BTW: this does not happen on Ubuntu.

Don't you think there's a formatting bug due to \ vs / on Windows ?

Message was edited by: micheldenis

bernard_horan
Offline
Joined: 2007-06-04

Michel

is this a bind exception?

B

micheldenis
Offline
Joined: 2007-12-10

Bernard,

It is exactly the same exception as the initial post here, but of course as Nina says it is not dramatic, except logging.

-michel

bernard_horan
Offline
Joined: 2007-06-04

Hi

looks like you've already got an instance of darkstar running.

"[java] WARNING: acceptor failed to listen on 0.0.0.0/0.0.0.0:1139
[java] java.net.BindException: Address already in use: bind"

I recommend killing all your java processes (or as this is Windows, your best bet might be to reboot) and trying again.

hope this helps

Bernard