6 Replies Latest reply on Nov 24, 2005 3:40 AM by fudeus

Failed to start AutomaticDiscovery

oschei Nov 10, 2005 1:37 PM

Hi JBoss Community,

I have a problem to build a jboss cluster.
AutomaticDiscovery failed.
How can I solve this problem?

Here comes the server output:

=========================================================================

JBoss Bootstrap Environment

JBOSS_HOME: /opt/jboss-4.0.3SP1

JAVA: /usr/lib/j2sdk1.5-sun/bin/java

JAVA_OPTS: -server -Xms128m -Xmx128m -Dprogram.name=run.sh

CLASSPATH: /opt/jboss-4.0.3SP1/bin/run.jar:/usr/lib/j2sdk1.5-sun/lib/tools.jar

=========================================================================

18:51:26,240 INFO [Server] Starting JBoss (MX MicroKernel)...
18:51:26,242 INFO [Server] Release ID: JBoss [Zion] 4.0.3SP1 (build: CVSTag=JBo ss_4_0_3_SP1 date=200510231054)
18:51:26,249 INFO [Server] Home Dir: /opt/jboss-4.0.3SP1
18:51:26,250 INFO [Server] Home URL: file:/opt/jboss-4.0.3SP1/
18:51:26,256 INFO [Server] Patch URL: null
18:51:26,256 INFO [Server] Server Name: all
18:51:26,256 INFO [Server] Server Home Dir: /opt/jboss-4.0.3SP1/server/all
18:51:26,257 INFO [Server] Server Home URL: file:/opt/jboss-4.0.3SP1/server/all /
18:51:26,269 INFO [Server] Server Temp Dir: /opt/jboss-4.0.3SP1/server/all/tmp
18:51:26,270 INFO [Server] Root Deployment Filename: jboss-service.xml
18:51:27,092 INFO [ServerInfo] Java version: 1.5.0_04,Sun Microsystems Inc.
18:51:27,096 INFO [ServerInfo] Java VM: Java HotSpot(TM) Server VM 1.5.0_04-b05 ,Sun Microsystems Inc.
18:51:27,096 INFO [ServerInfo] OS-System: Linux 2.6.10-5-386,i386
18:51:28,759 INFO [Server] Core system initialized
18:51:36,758 INFO [WebService] Using RMI server codebase: http://ecw02:8083/
18:51:37,043 INFO [Log4jService$URLWatchTimerTask] Configuring from URL: resource:log4j.xml
18:51:38,987 INFO [NamingService] Started jndi bootstrap jnpPort=1099, rmiPort=1098, backlog=50, bindAddress=/0.0.0.0, Client SocketFactory=null, Server SocketFactory=org.jboss.net.sockets.DefaultSocketFactory@ad093076
18:51:59,545 INFO [SnmpAgentService] SNMP agent going active
18:52:02,144 INFO [DefaultPartition] Initializing
18:52:02,219 INFO [STDOUT]
-------------------------------------------------------
GMS: address is localhost:32790 (additional data: 14 bytes)
-------------------------------------------------------
18:52:04,271 INFO [DefaultPartition] Number of cluster members: 1
18:52:04,271 INFO [DefaultPartition] Other members: 0
18:52:04,271 INFO [DefaultPartition] Fetching state (will wait for 30000 milliseconds):
18:52:04,295 INFO [DefaultPartition] New cluster view for partition DefaultPartition (id: 0, delta: 0) : [127.0.0.1:1099]
18:52:04,335 INFO [DefaultPartition] I am (127.0.0.1:1099) received membershipChanged event:
18:52:04,335 INFO [DefaultPartition] Dead members: 0 ([])
18:52:04,335 INFO [DefaultPartition] New Members : 0 ([])
18:52:04,335 INFO [DefaultPartition] All Members : 1 ([127.0.0.1:1099])
18:52:04,426 INFO [HANamingService] Started ha-jndi bootstrap jnpPort=1100, backlog=50, bindAddress=/0.0.0.0
18:52:04,454 WARN [HANamingService] Failed to start AutomaticDiscovery
java.net.SocketException: bad argument for IP_MULTICAST_IF: address not bound to any interface
at java.net.PlainDatagramSocketImpl.socketSetOption(Native Method)
at java.net.PlainDatagramSocketImpl.setOption(PlainDatagramSocketImpl.java:299)
at java.net.MulticastSocket.setInterface(MulticastSocket.java:420)
at org.jboss.ha.jndi.DetachedHANamingService$AutomaticDiscovery.start(DetachedHANamingService.java:492)
at org.jboss.ha.jndi.DetachedHANamingService.startService(DetachedHANamingService.java:332)
at org.jboss.system.ServiceMBeanSupport.jbossInternalStart(ServiceMBeanSupport.java:274)
at org.jboss.system.ServiceMBeanSupport.jbossInternalLifecycle(ServiceMBeanSupport.java:230)
at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:141)
at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:72)
at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:245)
at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:644)
at org.jboss.system.ServiceController$ServiceProxy.invoke(ServiceController.java:943)
at $Proxy0.start(Unknown Source)
at org.jboss.system.ServiceController.start(ServiceController.java:428)
at org.jboss.system.ServiceController.start(ServiceController.java:446)
at sun.reflect.GeneratedMethodAccessor9.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:141)
at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:72)
at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:245)
at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:644)
at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:176)
at $Proxy4.start(Unknown Source)
at org.jboss.deployment.SARDeployer.start(SARDeployer.java:285)
at org.jboss.deployment.MainDeployer.start(MainDeployer.java:989)
at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:790)
at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:753)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:141)
at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
at org.jboss.mx.interceptor.AbstractInterceptor.invoke(AbstractInterceptor.java:118)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:74)
at org.jboss.mx.interceptor.ModelMBeanOperationInterceptor.invoke(ModelMBeanOperationInterceptor.java:127)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:74)
at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:245)
at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:644)
at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:176)
at $Proxy9.deploy(Unknown Source)
at org.jboss.deployment.scanner.URLDeploymentScanner.deploy(URLDeploymentScanner.java:319)
at org.jboss.deployment.scanner.URLDeploymentScanner.scan(URLDeploymentScanner.java:507)
at org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread.doScan(AbstractDeploymentScanner.java:192)
at org.jboss.deployment.scanner.AbstractDeploymentScanner.startService(AbstractDeploymentScanner.java:265)
at org.jboss.system.ServiceMBeanSupport.jbossInternalStart(ServiceMBeanSupport.java:274)
at org.jboss.system.ServiceMBeanSupport.jbossInternalLifecycle(ServiceMBeanSupport.java:230)
at sun.reflect.GeneratedMethodAccessor2.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:141)
at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:72)
at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:245)
at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:644)
at org.jboss.system.ServiceController$ServiceProxy.invoke(ServiceController.java:943)
at $Proxy0.start(Unknown Source)
at org.jboss.system.ServiceController.start(ServiceController.java:428)
at sun.reflect.GeneratedMethodAccessor9.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:141)
at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:72)
at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:245)
at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:644)
at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:176)
at $Proxy4.start(Unknown Source)
at org.jboss.deployment.SARDeployer.start(SARDeployer.java:285)
at org.jboss.deployment.MainDeployer.start(MainDeployer.java:989)
at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:790)
at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:753)
at org.jboss.deployment.MainDeployer.deploy(MainDeployer.java:737)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:585)
at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:141)
at org.jboss.mx.server.Invocation.dispatch(Invocation.java:80)
at org.jboss.mx.interceptor.AbstractInterceptor.invoke(AbstractInterceptor.java:118)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:74)
at org.jboss.mx.interceptor.ModelMBeanOperationInterceptor.invoke(ModelMBeanOperationInterceptor.java:127)
at org.jboss.mx.server.Invocation.invoke(Invocation.java:74)
at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:245)
at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:644)
at org.jboss.mx.util.MBeanProxyExt.invoke(MBeanProxyExt.java:176)
at $Proxy5.deploy(Unknown Source)
at org.jboss.system.server.ServerImpl.doStart(ServerImpl.java:453)
at org.jboss.system.server.ServerImpl.start(ServerImpl.java:330)
at org.jboss.Main.boot(Main.java:187)
at org.jboss.Main$1.run(Main.java:438)
at java.lang.Thread.run(Thread.java:595)
18:52:08,188 INFO [orb] ORB run
18:52:08,429 INFO [CorbaNamingService] Naming: [IOR:000000000000002B49444C3A6F6D672E6F72672F436F734E616D696E672F4E616D696E67436F6E746578744578743A312E3000000000000200000000000000D0000102000000000A3132372E302E302E31000DC8000000114A426F73732F4E616D696E672F726F6F74000000000000050000000000000008000000004A414300000000010000001C00000000000100010000000105010001000101090000000105010001000000210000004C000000000000000100000000000000240000001C0000007E00000000000000010000000A3132372E302E302E31000DC9000000000000000000000000000000000000000000000000000000000000002000000004000000000000001F0000000400000003000000010000002000000000000000020000002000000004000000000000001F0000000400000003]
18:52:08,694 INFO [CorbaTransactionService] TransactionFactory: [IOR:000000000000003049444C3A6F72672F6A626F73732F746D2F69696F702F5472616E73616374696F6E466163746F72794578743A312E30000000000200000000000000D0000102000000000A3132372E302E302E31000DC8000000144A426F73732F5472616E73616374696F6E732F46000000050000000000000008000000004A414300000000010000001C00000000000100010000000105010001000101090000000105010001000000210000004C000000000000000100000000000000240000001C0000007E00000000000000010000000A3132372E302E302E31000DC9000000000000000000000000000000000000000000000000000000000000002000000004000000000000001F0000000400000003000000010000002000000000000000020000002000000004000000000000001F0000000400000003]
18:52:12,095 INFO [MailService] Mail Service bound to java:/Mail
18:52:12,776 INFO [TreeCache] setting cluster properties from xml to: UDP(ip_mcast=true;ip_ttl=8;loopback=false;mcast_addr=230.1.2.7;mcast_port=45577;mcast_recv_buf_size=80000;mcast_send_buf_size=150000;ucast_recv_buf_size=80000;ucast_send_buf_size=150000):PING(down_thread=false;num_initial_members=3;timeout=2000;up_thread=false):MERGE2(max_interval=20000;min_interval=10000):FD_SOCK:VERIFY_SUSPECT(down_thread=false;timeout=1500;up_thread=false):pbcast.NAKACK(down_thread=false;gc_lag=50;max_xmit_size=8192;retransmit_timeout=600,1200,2400,4800;up_thread=false):UNICAST(down_thread=false;min_threshold=10;timeout=600,1200,2400;window_size=100):pbcast.STABLE(desired_avg_gossip=20000;down_thread=false;up_thread=false):FRAG(down_thread=false;frag_size=8192;up_thread=false):pbcast.GMS(join_retry_timeout=2000;join_timeout=5000;print_local_addr=true;shun=true):pbcast.STATE_TRANSFER(down_thread=true;up_thread=true)
18:52:13,389 INFO [TreeCache] interceptor chain is:
class org.jboss.cache.interceptors.CallInterceptor
class org.jboss.cache.interceptors.LockInterceptor
class org.jboss.cache.interceptors.UnlockInterceptor
class org.jboss.cache.interceptors.ReplicationInterceptor
18:52:13,390 INFO [TreeCache] cache mode is REPL_ASYNC
18:52:13,544 INFO [STDOUT]
-------------------------------------------------------
GMS: address is localhost:32793
-------------------------------------------------------
18:52:15,564 INFO [TreeCache] viewAccepted(): new members: [localhost:32793]
18:52:15,565 INFO [TreeCache] new cache is null (maybe first member in cluster)
18:52:15,565 INFO [TreeCache] state could not be retrieved (must be first member in group)
18:52:16,123 INFO [Embedded] Catalina naming disabled
18:52:17,577 INFO [Http11Protocol] Initializing Coyote HTTP/1.1 on http-0.0.0.0-8080
18:52:17,595 INFO [Catalina] Initialization processed in 1256 ms
18:52:17,614 INFO [StandardService] Starting service jboss.web
18:52:17,635 INFO [StandardEngine] Starting Servlet Engine: Apache Tomcat/5.5
18:52:17,815 INFO [StandardHost] XML validation disabled
18:52:17,875 INFO [Catalina] Server startup in 280 ms
18:52:18,588 INFO [TomcatDeployer] deploy, ctxPath=/invoker, warUrl=.../deploy/httpha-invoker.sar/invoker.war/
18:52:20,252 INFO [WebappLoader] Dual registration of jndi stream handler: factory already defined
18:52:22,548 INFO [TomcatDeployer] deploy, ctxPath=/ws4ee, warUrl=.../tmp/deploy/tmp19003jboss-ws4ee.war/
18:52:23,287 INFO [TomcatDeployer] deploy, ctxPath=/, warUrl=.../deploy/jbossweb-tomcat55.sar/ROOT.war/
18:52:23,886 INFO [TomcatDeployer] deploy, ctxPath=/juddi, warUrl=.../deploy/juddi-service.sar/juddiws.war/
18:52:24,218 INFO [RegistryServlet] Loading jUDDI configuration.
18:52:24,236 INFO [RegistryServlet] Resources loaded from: /WEB-INF/juddi.properties
18:52:24,237 INFO [RegistryServlet] Initializing jUDDI components.
18:52:25,426 INFO [TomcatDeployer] deploy, ctxPath=/web-console, warUrl=.../deploy/management/console-mgr.sar/web-console.war/
18:52:27,816 INFO [TomcatDeployer] deploy, ctxPath=/jbossmq-httpil, warUrl=.../deploy-hasingleton/jms/jbossmq-httpil.sar/jbossmq-httpil.war/
18:52:29,576 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-ha-local-jdbc.rar
18:52:29,986 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-ha-xa-jdbc.rar
18:52:31,035 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-local-jdbc.rar
18:52:31,386 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jboss-xa-jdbc.rar
18:52:32,074 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/jms/jms-ra.rar
18:52:32,538 INFO [RARDeployment] Required license terms exist, view META-INF/ra.xml in .../deploy/mail-ra.rar
18:52:36,532 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'
18:52:37,550 INFO [A] Bound to JNDI name: queue/A
18:52:37,569 INFO [B] Bound to JNDI name: queue/B
18:52:37,587 INFO [C] Bound to JNDI name: queue/C
18:52:37,606 INFO [D] Bound to JNDI name: queue/D
18:52:37,624 INFO [ex] Bound to JNDI name: queue/ex
18:52:37,697 INFO [testTopic] Bound to JNDI name: topic/testTopic
18:52:37,726 INFO [securedTopic] Bound to JNDI name: topic/securedTopic
18:52:37,745 INFO [testDurableTopic] Bound to JNDI name: topic/testDurableTopic
18:52:37,763 INFO [testQueue] Bound to JNDI name: queue/testQueue
18:52:37,879 INFO [UILServerILService] JBossMQ UIL service available at : /0.0.0.0:8093
18:52:37,987 INFO [DLQ] Bound to JNDI name: queue/DLQ
18:52:38,350 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA'
18:52:38,511 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=rubbos' to JNDI name 'java:rubbos'
18:52:38,580 INFO [TomcatDeployer] deploy, ctxPath=/jmx-console, warUrl=.../deploy/jmx-console.war/
18:52:39,411 INFO [Http11Protocol] Starting Coyote HTTP/1.1 on http-0.0.0.0-8080
18:52:39,725 INFO [ChannelSocket] JK: ajp13 listening on /0.0.0.0:8009
18:52:39,770 INFO [JkMain] Jk running ID=0 time=0/241 config=null
18:52:39,802 INFO [Server] JBoss (MX MicroKernel) [4.0.3SP1 (build: CVSTag=JBoss_4_0_3_SP1 date=200510231054)] Started in 1m:13s:519ms

Many thanks in advance

Oliver

1. Re: Failed to start AutomaticDiscovery

ben.wang Nov 10, 2005 10:35 PM (in response to oschei)

Please go to clustering wiki help page and troubleshoot your mcast first and then JGroups.

-Ben
Actions
2. Re: Failed to start AutomaticDiscovery

belaban Nov 11, 2005 4:34 AM (in response to oschei)

You bind to 127.0.0.1, you won't be able to see other members on different machines, just on the same machine
Actions

3. Re: Failed to start AutomaticDiscovery

fudeus Nov 24, 2005 2:33 AM (in response to oschei)

I saw the same (since 4.0.3sp1 - used 4.0.2 before) without binding only to loopback.
Configuration says

<attribute name="AutoDiscoveryAddress">230.0.0.4</attribute>

08:18:05,687 INFO [vakuum] I am (172.17.12.57:1099) received membershipChanged event:
08:18:05,687 INFO [vakuum] Dead members: 0 ([])
08:18:05,688 INFO [vakuum] New Members : 0 ([])
08:18:05,688 INFO [vakuum] All Members : 1 ([172.17.12.57:1099])
08:18:05,778 INFO [HANamingService] Started ha-jndi bootstrap jnpPort=1100, backlog=50, bindAddress=/0.0.0.0
08:18:05,789 WARN [HANamingService] Failed to start AutomaticDiscovery
java.net.SocketException: bad argument for IP_MULTICAST_IF: address not bound to any interface
 at java.net.PlainDatagramSocketImpl.socketSetOption(Native Method)
 at java.net.PlainDatagramSocketImpl.setOption(PlainDatagramSocketImpl.java:299)
 at java.net.MulticastSocket.setInterface(MulticastSocket.java:420)
 at org.jboss.ha.jndi.DetachedHANamingService$AutomaticDiscovery.start(DetachedHANamingService.java:492)
 at org.jboss.ha.jndi.DetachedHANamingService.startService(DetachedHANamingService.java:332)

4. Re: Failed to start AutomaticDiscovery

fudeus Nov 24, 2005 2:49 AM (in response to oschei)

Ok, not the AutoDiscoveryAddress seems to be relevant but the AutoDiscoveryBindAddress. This defaults to jboss.bind.address which defaults to 0.0.0.0. Setting this to some "real" value makes AutoDiscovery work.
I read some thread in the forum in respect to problems with the wildcard address.
For reference see
http://www.jboss.com/index.html?module=bb&op=viewtopic&t=72200
Actions
5. Re: Failed to start AutomaticDiscovery

belaban Nov 24, 2005 3:06 AM (in response to oschei)

See also http://wiki.jboss.org/wiki/Wiki.jsp?page=IPv6 for more details
Actions

6. Re: Failed to start AutomaticDiscovery

fudeus Nov 24, 2005 3:40 AM (in response to oschei)

Thx,

setting -Djava.net.preferIPv4Stack=true helped:

09:38:50,561 INFO [vakuum] Fetching state (will wait for 30000 milliseconds):
09:38:50,651 INFO [HANamingService] Started ha-jndi bootstrap jnpPort=1100, backlog=50, bindAddress=/0.0.0.0
09:38:50,660 INFO [DetachedHANamingService$AutomaticDiscovery] Listening on /0.0.0.0:1102, group=230.1.2.10, HA-JNDI address=172.17.12.57:1100

Go to original post