[Beginners Corner] - Re: How can I find out from command line if JBoss is current
by PeterJ
That is the expected response if the server is not running. Twiddle connects to the jbossas server and queries an bean and returns a response. Thus, if the server is running, you get
Started=true
But if jbossas is not running, twiddle cannot connect to the server and thus you get an exception. It is impossible to get "Started=false".
If yoo need a "yes" or "no" response, you need to wrap twiddle within a script that examines the response and then replies with yes or no.
My caveat was more about the timing window. In other words, what do you mean by "if JBoss is crrently running"? Here is a hypothetical timeline for starting jbossas:
1 - not yet running
2 - you enter run.bat, and java.exe starts
3 - various services are initialized
4 - JNDI port is opened and JMX is usable
5 - more services and applications start up
6 - ready to accept outside input (e.g. HTTP requests)
By "jbossas is running", do you mean states 2-6, or just state 6? Either way, the "twiddle" solution yields a incorrect result for a certain period of time. For example, if "jbossas is running" means state 6, them you have a false positive all during states 4 and 5. The question is, can you live with that timing window?
View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4143529#4143529
Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4143529
18 years, 2 months
[Persistence, JBoss/CMP, Hibernate, Database] - Re: Referenced datasource not found with JBoss 5.0 (Beta4)
by depryf
There is a message about the connection being bound to the JNDI name, so I know this is successful and done before the exception:
| 15:27:26,446 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=app/datasource' to JNDI name 'java:/app/datasource'
|
Here is the full log (I removed some EJB messages because we have many of them).
| 15:26:29,785 INFO [ServerImpl] Starting JBoss (Microcontainer)...
| 15:26:29,785 INFO [ServerImpl] Release ID: JBoss [Morpheus] 5.0.0.Beta4 (build: SVNTag=JBoss_5_0_0_Beta4 date=200802091115)
| 15:26:29,785 INFO [ServerImpl] Home Dir: C:\dev\jboss-5.0.0.Beta4
| 15:26:29,785 INFO [ServerImpl] Home URL: file:/C:/dev/jboss-5.0.0.Beta4/
| 15:26:29,785 INFO [ServerImpl] Library URL: file:/C:/dev/jboss-5.0.0.Beta4/lib/
| 15:26:29,785 INFO [ServerImpl] Patch URL: null
| 15:26:29,785 INFO [ServerImpl] Server Name: default
| 15:26:29,785 INFO [ServerImpl] Server Home Dir: C:\dev\jboss-5.0.0.Beta4\server\default
| 15:26:29,785 INFO [ServerImpl] Server Home URL: file:/C:/dev/jboss-5.0.0.Beta4/server/default/
| 15:26:29,785 INFO [ServerImpl] Server Data Dir: C:\dev\jboss-5.0.0.Beta4\server\default\data
| 15:26:29,785 INFO [ServerImpl] Server Temp Dir: C:\dev\jboss-5.0.0.Beta4\server\default\tmp
| 15:26:29,785 INFO [ServerImpl] Server Config URL: file:/C:/dev/jboss-5.0.0.Beta4/server/default/conf/
| 15:26:29,785 INFO [ServerImpl] Server Library URL: file:/C:/dev/jboss-5.0.0.Beta4/server/default/lib/
| 15:26:29,785 INFO [ServerImpl] Root Deployment Filename: jboss-service.xml
| 15:26:30,241 INFO [ServerImpl] Starting Microcontainer, bootstrapURL=file:/C:/dev/jboss-5.0.0.Beta4/server/default/conf/bootstrap-beans.xml
| 15:26:32,851 INFO [ProfileImpl] Using profile root:C:\dev\jboss-5.0.0.Beta4\server\default
| 15:26:33,669 INFO [ServerInfo] Java version: 1.6.0_05,Sun Microsystems Inc.
| 15:26:33,669 INFO [ServerInfo] Java VM: Java HotSpot(TM) Server VM 10.0-b19,Sun Microsystems Inc.
| 15:26:33,684 INFO [ServerInfo] OS-System: Windows XP 5.1,x86
| 15:26:33,732 INFO [JMXKernel] Legacy JMX core initialized
| 15:26:59,012 INFO [WebService] Using RMI server codebase: http://127.0.0.1:8083/
| 15:27:00,018 INFO [TransactionManagerService] JBossTS Transaction Service (JTA version) - JBoss Inc.
| 15:27:00,018 INFO [TransactionManagerService] Setting up property manager MBean and JMX layer
| 15:27:00,317 INFO [TransactionManagerService] Starting recovery manager
| 15:27:00,443 INFO [TransactionManagerService] Recovery manager started
| 15:27:00,443 INFO [TransactionManagerService] Binding TransactionManager JNDI Reference
| 15:27:14,938 INFO [AspectDeployer] Deploying xml into org.jboss.aop.AspectManager@980b32 for BaseClassLoader@14536a5{vfsfile:/C:/dev/jboss-5.0.0.Beta4/server/default/deploy/ejb3-i
| nterceptors-aop.xml}
| 15:27:17,045 INFO [RARDeployment] Required license terms exist, view vfsfile:/C:/dev/jboss-5.0.0.Beta4/server/default/deploy/jboss-local-jdbc.rar/META-INF/ra.xml
| 15:27:17,045 INFO [RARDeployment] Required license terms exist, view vfsfile:/C:/dev/jboss-5.0.0.Beta4/server/default/deploy/jboss-xa-jdbc.rar/META-INF/ra.xml
| 15:27:17,123 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'
| 15:27:17,579 INFO [SimpleThreadPool] Job execution threads will use class loader of thread: main
| 15:27:17,642 INFO [QuartzScheduler] Quartz Scheduler v.1.5.2 created.
| 15:27:17,642 INFO [JobStoreCMT] Using db table-based data access locking (synchronization).
| 15:27:17,674 INFO [JobStoreCMT] Removed 0 Volatile Trigger(s).
| 15:27:17,674 INFO [JobStoreCMT] Removed 0 Volatile Job(s).
| 15:27:17,674 INFO [JobStoreCMT] JobStoreCMT initialized.
| 15:27:17,674 INFO [StdSchedulerFactory] Quartz scheduler 'JBossEJB3QuartzScheduler' initialized from an externally provided properties instance.
| 15:27:17,674 INFO [StdSchedulerFactory] Quartz scheduler version: 1.5.2
| 15:27:17,689 INFO [JobStoreCMT] Freed 0 triggers from 'acquired' / 'blocked' state.
| 15:27:17,689 INFO [JobStoreCMT] Recovering 0 jobs that were in-progress at the time of the last shut-down.
| 15:27:17,689 INFO [JobStoreCMT] Recovery complete.
| 15:27:17,689 INFO [JobStoreCMT] Removed 0 'complete' triggers.
| 15:27:17,689 INFO [JobStoreCMT] Removed 0 stale fired job entries.
| 15:27:17,689 INFO [QuartzScheduler] Scheduler JBossEJB3QuartzScheduler_$_NON_CLUSTERED started.
| 15:27:19,497 INFO [TomcatDeployment] deploy, ctxPath=/invoker, vfsUrl=http-invoker.sar/invoker.war
| 15:27:21,541 INFO [NativeServerConfig] JBoss Web Services - Native
| 15:27:21,557 INFO [NativeServerConfig] jbossws-native-2.0.3.GA (build=200801241020)
| 15:27:22,547 INFO [TomcatDeployment] deploy, ctxPath=/jbossws, vfsUrl=jbossws.sar/jbossws-context.war
| 15:27:22,642 INFO [RARDeployment] Required license terms exist, view vfsfile:/C:/dev/jboss-5.0.0.Beta4/server/default/deploy/jms-ra.rar/META-INF/ra.xml
| 15:27:22,689 INFO [TomcatDeployment] deploy, ctxPath=/jmx-console, vfsUrl=jmx-console.war
| 15:27:24,088 INFO [JMXConnectorServerService] JMX Connector server: service:jmx:rmi://127.0.0.1/jndi/rmi://127.0.0.1:1090/jmxconnector
| 15:27:24,151 INFO [RARDeployment] Required license terms exist, view vfsfile:/C:/dev/jboss-5.0.0.Beta4/server/default/deploy/mail-ra.rar/META-INF/ra.xml
| 15:27:24,261 INFO [MailService] Mail Service bound to java:/Mail
| 15:27:24,450 INFO [TomcatDeployment] deploy, ctxPath=/web-console, vfsUrl=management/console-mgr.sar/web-console.war
| 15:27:25,487 WARN [SecurityMetadataStore] WARNING! POTENTIAL SECURITY RISK. It has been detected that the MessageSucker component which sucks messages from one node to another has
| not had its password changed from the installation default. Please see the JBoss Messaging user guide for instructions on how to do this.
| 15:27:25,975 INFO [ServerPeer] JBoss Messaging 1.4.1.Beta1 server [0] started
| 15:27:26,053 INFO [QueueService] Queue[/queue/DLQ] started, fullSize=200000, pageSize=2000, downCacheSize=2000
| 15:27:26,163 INFO [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
| 15:27:26,163 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@14b052 started
| 15:27:26,163 WARN [ConnectionFactoryJNDIMapper] supportsFailover attribute is true on connection factory: jboss.messaging.connectionfactory:service=ClusteredConnectionFactory but
| post office is non clustered. So connection factory will *not* support failover
| 15:27:26,163 WARN [ConnectionFactoryJNDIMapper] supportsLoadBalancing attribute is true on connection factory: jboss.messaging.connectionfactory:service=ClusteredConnectionFactory
| but post office is non clustered. So connection factory will *not* support load balancing
| 15:27:26,163 INFO [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
| 15:27:26,179 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@1737301 started
| 15:27:26,179 INFO [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
| 15:27:26,179 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@3540ce started
| 15:27:26,179 INFO [QueueService] Queue[/queue/ExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
| 15:27:26,179 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA'
| 15:27:26,211 INFO [RARDeployment] Required license terms exist, view vfsfile:/C:/dev/jboss-5.0.0.Beta4/server/default/deploy/quartz-ra.rar/META-INF/ra.xml
| 15:27:26,226 INFO [SimpleThreadPool] Job execution threads will use class loader of thread: main
| 15:27:26,226 INFO [QuartzScheduler] Quartz Scheduler v.1.5.2 created.
| 15:27:26,226 INFO [RAMJobStore] RAMJobStore initialized.
| 15:27:26,226 INFO [StdSchedulerFactory] Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
| 15:27:26,226 INFO [StdSchedulerFactory] Quartz scheduler version: 1.5.2
| 15:27:26,226 INFO [QuartzScheduler] Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.
| 15:27:26,446 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=app/datasource' to JNDI name 'java:/app/datasource'
| 15:27:29,025 INFO [MCKernelAbstraction] installing bean: jboss.j2ee:ear=seerdms.ear,jar=seerdms.jar,name=ConsolidationApiSessionBean,service=EJB3 with dependencies:
| 15:27:29,025 INFO [MCKernelAbstraction] and demands:
| 15:27:29,025 INFO [MCKernelAbstraction] jboss.ejb:service=EJBTimerService
| 15:27:29,025 INFO [MCKernelAbstraction] and supplies:
| 15:27:29,025 INFO [MCKernelAbstraction] Class:com.imsweb.seerdms.shared.api.ConsolidationApi
| 15:27:29,764 INFO [EJBContainer] STARTED EJB: com.imsweb.seerdms.app.consolidation.buslogic.ConsolidationApiSessionBean ejbName: ConsolidationApiSessionBean
|
| ... (many other EJBs)
|
| 15:27:39,385 INFO [MCKernelAbstraction] installing bean: jboss.j2ee:ear=seerdms.ear,jar=seerdms.jar,name=WorkflowWebBean,service=EJB3 with dependencies:
| 15:27:39,385 INFO [MCKernelAbstraction] and demands:
| 15:27:39,385 INFO [MCKernelAbstraction] jboss.ejb:service=EJBTimerService
| 15:27:39,385 INFO [MCKernelAbstraction] and supplies:
| 15:27:39,385 INFO [MCKernelAbstraction] Class:com.imsweb.seerdms.shared.api.WorkflowApi
| 15:27:39,810 INFO [EJBContainer] STARTED EJB: com.imsweb.seerdms.app.workflow.buslogic.WorkflowWebBean ejbName: WorkflowWebBean
| 15:27:57,528 INFO [TomcatDeployment] deploy, ctxPath=/seerdms, vfsUrl=seerdms.ear/seerdms.war
| 15:28:05,405 INFO [ConfigurationApiSessionBean] Loaded local configuration
| 15:28:05,641 INFO [ConfigurationServlet] Velocity engine initialized
| 15:28:14,013 INFO [QuartzScheduler] Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.
| 15:28:14,028 INFO [ConfigurationServlet] Quartz scheduler initialized
| 15:28:14,122 INFO [LookupConfigLoader] Lookup definitions loaded in 94ms
| 15:28:14,138 INFO [ReportConfigurator] Reporting engine initialized.
| 15:28:14,185 INFO [MatchingFactoryFinder] Initializing matching factory...
| 15:28:14,248 INFO [FunctionFactoryFinder] Initializing matching user-defined function factory...
| 15:28:14,248 INFO [ExpressionFactoryFinder] Initializing matching user-defined function factory...
| 15:28:14,765 INFO [PropertyMappingsUtils] Starting property mapping initialization
| 15:28:14,797 ERROR [[/seerdms]] StandardWrapper.Throwable
| javax.ejb.EJBException: com.imsweb.seerdms.shared.exception.SeerInvalidStateException: Unable to get connection.
| at org.jboss.ejb3.tx.Ejb3TxPolicy.handleExceptionInOurTx(Ejb3TxPolicy.java:63)
| at org.jboss.aspects.tx.TxPolicy.invokeInOurTx(TxPolicy.java:83)
| at org.jboss.aspects.tx.TxInterceptor$Required.invoke(TxInterceptor.java:193)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
| at org.jboss.aspects.tx.TxPropagationInterceptor.invoke(TxPropagationInterceptor.java:76)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
| at org.jboss.ejb3.stateless.StatelessInstanceInterceptor.invoke(StatelessInstanceInterceptor.java:64)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
| at org.jboss.ejb3.tx.NullInterceptor.invoke(NullInterceptor.java:42)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
| at org.jboss.ejb3.security.Ejb3AuthenticationInterceptorv2.invoke(Ejb3AuthenticationInterceptorv2.java:166)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
| at org.jboss.ejb3.ENCPropagationInterceptor.invoke(ENCPropagationInterceptor.java:46)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
| at org.jboss.ejb3.asynchronous.AsynchronousInterceptor.invoke(AsynchronousInterceptor.java:106)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
| at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:249)
| at org.jboss.ejb3.stateless.StatelessContainer.localInvoke(StatelessContainer.java:214)
| at org.jboss.ejb3.stateless.StatelessLocalProxy.invoke(StatelessLocalProxy.java:86)
| at $Proxy109.getDbColumnInfo(Unknown Source)
| at com.imsweb.seerdms.shared.util.PropertyMappingsUtils.initialize(PropertyMappingsUtils.java:139)
| at com.imsweb.seerdms.shared.util.PropertyMappingsUtils.initialize(PropertyMappingsUtils.java:111)
| at com.imsweb.seerdms.web.shared.configuration.ConfigurationServlet.init(ConfigurationServlet.java:158)
| at javax.servlet.GenericServlet.init(GenericServlet.java:212)
| at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1048)
| at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:950)
| at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4072)
| at org.apache.catalina.core.StandardContext.start(StandardContext.java:4377)
| at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
| at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)
| at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:525)
| 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:597)
| at org.apache.tomcat.util.modeler.BaseModelMBean.invoke(BaseModelMBean.java:297)
| at org.jboss.mx.server.RawDynamicInvoker.invoke(RawDynamicInvoker.java:164)
| at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:668)
| at org.apache.catalina.core.StandardContext.init(StandardContext.java:5357)
| at org.jboss.web.tomcat.service.deployers.TomcatDeployment.performDeployInternal(TomcatDeployment.java:346)
| at org.jboss.web.tomcat.service.deployers.TomcatDeployment.performDeploy(TomcatDeployment.java:142)
| at org.jboss.web.deployers.AbstractWarDeployment.start(AbstractWarDeployment.java:347)
| at org.jboss.web.deployers.WebModule.startModule(WebModule.java:89)
| at org.jboss.web.deployers.WebModule.start(WebModule.java:67)
| 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:597)
| at org.jboss.mx.interceptor.ReflectedDispatcher.invoke(ReflectedDispatcher.java:157)
| at org.jboss.mx.server.Invocation.dispatch(Invocation.java:96)
| at org.jboss.mx.server.Invocation.invoke(Invocation.java:88)
| at org.jboss.mx.server.AbstractMBeanInvoker.invoke(AbstractMBeanInvoker.java:264)
| at org.jboss.mx.server.MBeanServerImpl.invoke(MBeanServerImpl.java:668)
| at org.jboss.system.microcontainer.ServiceProxy.invoke(ServiceProxy.java:184)
| at $Proxy5.start(Unknown Source)
| at org.jboss.system.microcontainer.StartStopLifecycleAction.installAction(StartStopLifecycleAction.java:42)
| at org.jboss.system.microcontainer.StartStopLifecycleAction.installAction(StartStopLifecycleAction.java:37)
| at org.jboss.dependency.plugins.action.SimpleControllerContextAction.simpleInstallAction(SimpleControllerContextAction.java:62)
| at org.jboss.dependency.plugins.action.AccessControllerContextAction.install(AccessControllerContextAction.java:71)
| at org.jboss.dependency.plugins.AbstractControllerContextActions.install(AbstractControllerContextActions.java:51)
| at org.jboss.dependency.plugins.AbstractControllerContext.install(AbstractControllerContext.java:327)
| at org.jboss.system.microcontainer.ServiceControllerContext.install(ServiceControllerContext.java:255)
| at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:1309)
| at org.jboss.dependency.plugins.AbstractController.incrementState(AbstractController.java:734)
| at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:862)
| at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:784)
| at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:622)
| at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:411)
| at org.jboss.system.ServiceController.doChange(ServiceController.java:659)
| at org.jboss.system.ServiceController.start(ServiceController.java:431)
| at org.jboss.system.deployers.ServiceDeployer.start(ServiceDeployer.java:150)
| at org.jboss.system.deployers.ServiceDeployer.deploy(ServiceDeployer.java:108)
| at org.jboss.system.deployers.ServiceDeployer.deploy(ServiceDeployer.java:46)
| at org.jboss.deployers.spi.deployer.helpers.AbstractSimpleRealDeployer.internalDeploy(AbstractSimpleRealDeployer.java:65)
| at org.jboss.deployers.spi.deployer.helpers.AbstractRealDeployer.deploy(AbstractRealDeployer.java:50)
| at org.jboss.deployers.plugins.deployers.DeployerWrapper.deploy(DeployerWrapper.java:169)
| at org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFirst(DeployersImpl.java:853)
| at org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFirst(DeployersImpl.java:874)
| at org.jboss.deployers.plugins.deployers.DeployersImpl.doInstallParentFirst(DeployersImpl.java:906)
| at org.jboss.deployers.plugins.deployers.DeployersImpl.install(DeployersImpl.java:794)
| at org.jboss.dependency.plugins.AbstractControllerContext.install(AbstractControllerContext.java:327)
| at org.jboss.dependency.plugins.AbstractController.install(AbstractController.java:1309)
| at org.jboss.dependency.plugins.AbstractController.incrementState(AbstractController.java:734)
| at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:862)
| at org.jboss.dependency.plugins.AbstractController.resolveContexts(AbstractController.java:784)
| at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:622)
| at org.jboss.dependency.plugins.AbstractController.change(AbstractController.java:411)
| at org.jboss.deployers.plugins.deployers.DeployersImpl.process(DeployersImpl.java:498)
| at org.jboss.deployers.plugins.main.MainDeployerImpl.process(MainDeployerImpl.java:506)
| at org.jboss.system.server.profileservice.ProfileServiceBootstrap.loadProfile(ProfileServiceBootstrap.java:246)
| at org.jboss.system.server.profileservice.ProfileServiceBootstrap.start(ProfileServiceBootstrap.java:131)
| at org.jboss.bootstrap.AbstractServerImpl.start(AbstractServerImpl.java:408)
| at org.jboss.Main.boot(Main.java:208)
| at org.jboss.Main$1.run(Main.java:534)
| at java.lang.Thread.run(Thread.java:619)
| Caused by: com.imsweb.seerdms.shared.exception.SeerInvalidStateException: Unable to get connection.
| at com.imsweb.seerdms.app.shared.dao.hibernate.HibernateCommonDataDAO.getDbColumnInfo(HibernateCommonDataDAO.java:125)
| at com.imsweb.seerdms.app.shared.buslogic.CommonDataApiSessionBean.getDbColumnInfo(CommonDataApiSessionBean.java:143)
| 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:597)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeTarget(MethodInvocation.java:121)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:110)
| at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:166)
| at com.imsweb.seerdms.app.shared.buslogic.SessionInterceptor.aroundInvoke(SessionInterceptor.java:45)
| at sun.reflect.GeneratedMethodAccessor96.invoke(Unknown Source)
| at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
| at java.lang.reflect.Method.invoke(Method.java:597)
| at org.jboss.ejb3.interceptor.InvocationContextImpl.proceed(InvocationContextImpl.java:118)
| at org.jboss.ejb3.interceptor.EJB3InterceptorsInterceptor.invoke(EJB3InterceptorsInterceptor.java:63)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
| at org.jboss.ejb3.entity.TransactionScopedEntityManagerInterceptor.invoke(TransactionScopedEntityManagerInterceptor.java:54)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
| at org.jboss.ejb3.AllowedOperationsInterceptor.invoke(AllowedOperationsInterceptor.java:47)
| at org.jboss.aop.joinpoint.MethodInvocation.invokeNext(MethodInvocation.java:101)
| at org.jboss.aspects.tx.TxPolicy.invokeInOurTx(TxPolicy.java:79)
| ... 93 more
| Caused by: javax.naming.NameNotFoundException: app-specific not bound
| at org.jnp.server.NamingServer.getBinding(NamingServer.java:542)
| at org.jnp.server.NamingServer.getBinding(NamingServer.java:550)
| at org.jnp.server.NamingServer.getObject(NamingServer.java:556)
| at org.jnp.server.NamingServer.lookup(NamingServer.java:267)
| at org.jnp.server.NamingServer.lookup(NamingServer.java:270)
| at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:669)
| at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:776)
| at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:629)
| at javax.naming.InitialContext.lookup(InitialContext.java:392)
| at com.imsweb.seerdms.shared.api.ServiceLocator.lookup(ServiceLocator.java:631)
| at com.imsweb.seerdms.shared.api.ServiceLocator.getConnection(ServiceLocator.java:597)
| at com.imsweb.seerdms.app.shared.dao.hibernate.HibernateCommonDataDAO.getDbColumnInfo(HibernateCommonDataDAO.java:97)
| ... 113 more
| 15:28:21,635 INFO [ServerImpl] JBoss (Microcontainer) [5.0.0.Beta4 (build: SVNTag=JBoss_5_0_0_Beta4 date=200802091115)] Started in 1m:51s:834ms
|
View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4143528#4143528
Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4143528
18 years, 2 months
[Beginners Corner] - Re: How can I find out from command line if JBoss is current
by thoste
"PeterJ" wrote : You could run a twiddle command to check the value of an attribute of an mbean, such as:
|
| twiddle get "jboss.system:type=Server" Started
|
| This is not a perfect solution because this command will yield "Started=true" as soon as JMX and JNDI are running, but HTTP and thus web applications are usually not available until a few seconds later.
Your suggestion implies that if JBoss is not running the above command returns a statement
Started=false
But this is not the case. Instead I am getting an exception (see below).
Is there another solution?
D:\JBoss\v4\bin>twiddle get "jboss.system:type=Server" Started
11:49:28,781 ERROR [Twiddle] Exec failed
org.jboss.util.NestedRuntimeException: Could not obtain connection to any of these urls: localhost:1099 and discovery failed with error: javax.naming
at org.jboss.console.twiddle.Twiddle$1.getServer(Twiddle.java:143)
at org.jboss.console.twiddle.command.MBeanServerCommand.getMBeanServer(MBeanServerCommand.java:59)
at org.jboss.console.twiddle.command.GetCommand.execute(GetCommand.java:149)
at org.jboss.console.twiddle.Twiddle.main(Twiddle.java:305)
Caused by: javax.naming.CommunicationException: Could not obtain connection to any of these urls: localhost:1099 and discovery failed with error: jav
at org.jnp.interfaces.NamingContext.checkRef(NamingContext.java:1562)
at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:634)
at org.jnp.interfaces.NamingContext.lookup(NamingContext.java:627)
at javax.naming.InitialContext.lookup(InitialContext.java:351)
at org.jboss.console.twiddle.Twiddle.createMBeanServerConnection(Twiddle.java:250)
at org.jboss.console.twiddle.Twiddle.connect(Twiddle.java:268)
at org.jboss.console.twiddle.Twiddle.access$300(Twiddle.java:62)
at org.jboss.console.twiddle.Twiddle$1.getServer(Twiddle.java:139)
... 3 more
Caused by: javax.naming.CommunicationException: Failed to connect to server localhost:1099 [Root exception is javax.naming.ServiceUnavailableExceptio
at org.jnp.interfaces.NamingContext.getServer(NamingContext.java:274)
at org.jnp.interfaces.NamingContext.checkRef(NamingContext.java:1533)
... 10 more
Caused by: javax.naming.ServiceUnavailableException: Failed to connect to server localhost:1099 [Root exception is java.net.ConnectException: Connect
at org.jnp.interfaces.NamingContext.getServer(NamingContext.java:248)
... 11 more
Caused by: java.net.ConnectException: Connection refused: connect
at java.net.PlainSocketImpl.socketConnect(Native Method)
at java.net.PlainSocketImpl.doConnect(PlainSocketImpl.java:333)
at java.net.PlainSocketImpl.connectToAddress(PlainSocketImpl.java:195)
at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:182)
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:366)
at java.net.Socket.connect(Socket.java:507)
at java.net.Socket.connect(Socket.java:457)
at java.net.Socket.(Socket.java:365)
at java.net.Socket.(Socket.java:265)
at org.jnp.interfaces.TimedSocketFactory.createSocket(TimedSocketFactory.java:84)
at org.jnp.interfaces.TimedSocketFactory.createSocket(TimedSocketFactory.java:77)
at org.jnp.interfaces.NamingContext.getServer(NamingContext.java:244)
... 11 more
D:\JBoss\v4\bin>
View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4143523#4143523
Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4143523
18 years, 2 months
[JBoss Cache: Core Edition] - Need helps with initial state transfer in production
by jiafan1
Hi,
We have four production machines running as a cluster and we use JBoss Cache version 1.4.0 as data cache (In memory Tree cache only and no cache loader). The application worked fine. 2 days ago, our infrastructure people patched one of the production machine (10.128.0.114) for PCI compliance (The changes are basically securing the server by enforcing passwords of a certain length and changing the service accounts to only be suable and not logging in directly). After that, the JBoss Cache cannot join the existing cluster with the following exception:
| Invocation of init method failed; nested e
| xception is org.jboss.cache.CacheException: Initial state transfer failed: Channel.getState() returned false
| Caused by:
| org.jboss.cache.CacheException: Initial state transfer failed: Channel.getState() returned false
| at org.jboss.cache.TreeCache.fetchStateOnStartup(TreeCache.java:3191)
| at org.jboss.cache.TreeCache.startService(TreeCache.java:1429)
| at com.jtv.core.resource.impl.spring.cache.JBossCacheManagerFactoryBean.createCache(JBossCacheManagerFactoryBean.java
| :102)
| at com.jtv.core.resource.impl.spring.cache.JBossCacheManagerFactoryBean.afterPropertiesSet(JBossCacheManagerFactoryBe
| an.java:112)
| at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCap
| ableBeanFactory.java:1198)
| at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapabl
| eBeanFactory.java:1167)
| at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBea
| nFactory.java:427)
|
The exception may not really related to the patch. We changed the log level to debug for JBoss Cache and JGroup and found the new node (10.128.0.114) responsed to replicate requests during the initial state transfer, which sounds really wired since the new node should not react to any replicate requests until the tree cache service starts. Also, not sure after the replication procedure, the node resumed state transfer or re-started state transfer and why the lock on the new node did not take effect. We doubled the initial start timeout limit but got the same result. Seems to us the new node lost the lock for the cache during the initial state transfer state and was interrupted by the replication requests and finally led to timeout and failed. Really appreciate any helps on this.
Thanks in advance,
Jian
The configuration is as:
| <mbean code="org.jboss.cache.TreeCache" name="jboss.cache:service=TreeCache">
|
| <depends>jboss:service=Naming</depends>
|
| <depends>jboss:service=TransactionManager</depends>
|
| <!-- Configure the TransactionManager -->
| <!-- org.jboss.cache.DummyTransactionManagerLookup-->
| <attribute name="TransactionManagerLookupClass">com.jtv.core.resource.impl.spring.cache.JBossCacheTransaction
| ManagerLookup</attribute>
|
| <!--
| Node locking scheme:
| OPTIMISTIC
| PESSIMISTIC (default)
| -->
| <attribute name="NodeLockingScheme">PESSIMISTIC</attribute>
|
|
|
| <!--
| Node locking level : SERIALIZABLE
| REPEATABLE_READ (default)
| READ_COMMITTED
| READ_UNCOMMITTED
| NONE
| -->
| <attribute name="IsolationLevel">REPEATABLE_READ</attribute>
|
| <!--
| Valid modes are LOCAL
| REPL_ASYNC
| REPL_SYNC
| INVALIDATION_ASYNC
| INVALIDATION_SYNC
| -->
| <attribute name="CacheMode">REPL_SYNC</attribute>
| <!-- Name of cluster. Needs to be the same for all clusters, in order
| to find each other
| -->
| <attribute name="ClusterName">JTVCORE-Cluster</attribute>
|
| <!-- JGroups protocol stack properties. Can also be a URL,
| e.g. file:/home/bela/default.xml
| <attribute name="ClusterProperties"></attribute>
| -->
| <attribute name="ClusterConfig">
| <config>
| <UDP mcast_send_buf_size="32000"
| mcast_port="&mcast-port;"
| ucast_recv_buf_size="64000"
| mcast_addr="&mcast-addr;"
| bind_addr="&mcast-listen-iface-addr;"
| loopback="false"
| mcast_recv_buf_size="64000"
| max_bundle_size="60000"
| max_bundle_timeout="30"
| use_incoming_packet_handler="false"
| use_outgoing_packet_handler="false"
| ucast_send_buf_size="32000"
| ip_ttl="32"
| enable_bundling="false"/>
| <PING timeout="&startup-group-join-wait-time;"
| num_initial_members="4"
| down_thread="true"
| up_thread="true"/>
| <MERGE2 max_interval="10000"
| min_interval="5000"/>
| <FD timeout="2000"
| max_tries="3"
| shun="true"/>
| <VERIFY_SUSPECT timeout="1500"/>
| <pbcast.NAKACK max_xmit_size="8192"
| use_mcast_xmit="false"
| gc_lag="50"
| retransmit_timeout="600,1200,2400,4800"/>
| <UNICAST timeout="1200,2400,3600"/>
| -->
| <pbcast.STABLE stability_delay="1000"
| desired_avg_gossip="20000"
| max_bytes="0"/>
| <FRAG frag_size="8192"
| down_thread="false"
| up_thread="false"/>
| <VIEW_SYNC avg_send_interval="60000" down_thread="false" up_thread="false" />
| <pbcast.GMS print_local_addr="true"
| join_timeout="3000"
| join_retry_timeout="2000"
| shun="true"/><!--test what happens here -->
| <pbcast.STATE_TRANSFER
| down_thread="true"
| up_thread="true"/>
| </config>
| </attribute>
|
| <!--
| Whether or not to fetch state on joining a cluster
| NOTE this used to be called FetchStateOnStartup and has been renamed to be more descriptive.
| -->
| <!-- -->
| <attribute name="FetchInMemoryState">true</attribute>
| <!-- -->
|
| <!--
| The max amount of time (in milliseconds) we wait until the
| initial state (ie. the contents of the cache) are retrieved from
| existing members in a clustered environment
| -->
| <attribute name="InitialStateRetrievalTimeout">20000</attribute>
|
| <!--
| Number of milliseconds to wait until all responses for a
| synchronous call have been received.
| -->
| <attribute name="SyncReplTimeout">20000</attribute>
|
| <!-- Max number of milliseconds to wait for a lock acquisition -->
| <attribute name="LockAcquisitionTimeout">15000</attribute>
| <!-- Name of the eviction policy class. -->
| <!--attribute name="EvictionPolicyClass"></attribute-->
|
| <!--
| <attribute name="EvictionPolicyClass">org.jboss.cache.eviction.LRUPolicy</attribute>
| -->
|
| <!-- Specific eviction policy configurations. This is LRU -->
| <attribute name="EvictionPolicyConfig">
| <config>
| <attribute name="wakeUpIntervalSeconds">5</attribute>
| <!-- Cache wide default /com/jtv/core-->
| <region name="_default_" policyClass= "org.jboss.cache.eviction.LRUPolicy">
| <attribute name="maxNodes">0</attribute>
| <attribute name="timeToLiveSeconds">0</attribute>
| </region>
| <region name="/com/jtv/core/tgt" policyClass= "org.jboss.cache.eviction.LRUPolicy">
| <attribute name="maxNodes">20000</attribute>
| <attribute name="timeToLiveSeconds">0</attribute>
| </region><!--org.jboss.cache.eviction.LFUPolicy-->
| <region name="/com/jtv/core/st" policyClass= "org.jboss.cache.eviction.LRUPolicy">
| <attribute name="maxNodes">20000</attribute>
| <attribute name="timeToLiveSeconds">0</attribute>
| </region>
| </config>
| </attribute>
|
| <!--
| Indicate whether to use region based marshalling or not. Set this to true if you are running under a scoped
| class loader, e.g., inside an application server. Default is "false".
| -->
| <attribute name="UseRegionBasedMarshalling">false</attribute>
|
|
and the debug info is as follows,
| 2008-04-08 16:59:27,848 (main) org.jboss.cache.factories.InterceptorChainFactory.createPessimisticInterceptorChain
| INFO : interceptor chain is:
| class org.jboss.cache.interceptors.CallInterceptor
| class org.jboss.cache.interceptors.EvictionInterceptor
| class org.jboss.cache.interceptors.PessimisticLockInterceptor
| class org.jboss.cache.interceptors.UnlockInterceptor
| class org.jboss.cache.interceptors.ReplicationInterceptor
| class org.jboss.cache.interceptors.TxInterceptor
| class org.jboss.cache.interceptors.CacheMgmtInterceptor
|
| 2008-04-08 16:59:27,869 (main) org.jboss.cache.TreeCache._createService
| DEBUG: cache mode is REPL_SYNC
|
| 2008-04-08 16:59:28,047 (main) org.jgroups.conf.ClassConfigurator.init
| DEBUG: mapping is:
| 1: class org.jgroups.stack.IpAddress
| 2: class org.jgroups.protocols.CAUSAL$CausalHeader
| 3: class org.jgroups.protocols.FD$FdHeader
| 4: class org.jgroups.protocols.FD_PID$FdHeader
| 5: class org.jgroups.protocols.FD_PROB$FdHeader
| 6: class org.jgroups.protocols.FD_SOCK$FdHeader
| 7: class org.jgroups.protocols.FragHeader
| 8: class org.jgroups.protocols.MERGE$MergeHeader
| 9: class org.jgroups.protocols.NakAckHeader
| 10: class org.jgroups.protocols.PARTITIONER$PartitionerHeader
| 11: class org.jgroups.protocols.PerfHeader
| 12: class org.jgroups.protocols.PIGGYBACK$PiggybackHeader
| 13: class org.jgroups.protocols.PingHeader
| 14: class org.jgroups.protocols.TcpHeader
| 15: class org.jgroups.protocols.TOTAL$Header
| 16: class org.jgroups.protocols.TOTAL_OLD$TotalHeader
| 17: class org.jgroups.protocols.TOTAL_TOKEN$TotalTokenHeader
| 18: class org.jgroups.protocols.TOTAL_TOKEN$RingTokenHeader
| 19: class org.jgroups.protocols.TunnelHeader
| 20: class org.jgroups.protocols.UdpHeader
| 21: class org.jgroups.protocols.UNICAST$UnicastHeader
| 22: class org.jgroups.protocols.VERIFY_SUSPECT$VerifyHeader
| 23: class org.jgroups.protocols.WANPIPE$WanPipeHeader
| 24: class org.jgroups.protocols.pbcast.GMS$GmsHeader
| 25: class org.jgroups.protocols.pbcast.NakAckHeader
| 26: class org.jgroups.protocols.pbcast.PbcastHeader
| 27: class org.jgroups.protocols.pbcast.STABLE$StableHeader
| 28: class org.jgroups.protocols.pbcast.STATE_TRANSFER$StateHeader
| 29: class org.jgroups.protocols.SMACK$SmackHeader
| 30: class org.jgroups.Message
| 31: class org.jgroups.View
| 32: class org.jgroups.ViewId
| 33: class org.jgroups.util.List
| 34: interface org.jgroups.Address
| 35: class org.jgroups.blocks.RequestCorrelator$Header
| 36: class org.jgroups.protocols.PingRsp
| 37: class [Ljava.lang.Object;
| 38: class java.util.Vector
| 39: class org.jgroups.protocols.pbcast.JoinRsp
| 40: class org.jgroups.protocols.pbcast.Digest
| 41: class java.util.Hashtable
| 53: class org.jgroups.protocols.COMPRESS$CompressHeader
| 54: class org.jgroups.protocols.FC$FcHeader
| 55: class org.jgroups.protocols.WanPipeAddress
| 56: class org.jgroups.protocols.TpHeader
| 57: class org.jgroups.protocols.ENCRYPT$EncryptHeader
|
|
| 2008-04-08 16:59:28,077 (main) org.jgroups.protocols.AUTOCONF.senseMaxFragSize
| DEBUG: frag_size=64000
|
| 2008-04-08 16:59:28,146 (main) org.jgroups.protocols.pbcast.GMS.setImpl
| DEBUG: changed role to org.jgroups.protocols.pbcast.ClientGmsImpl
|
| 2008-04-08 16:59:28,194 (main) org.jboss.cache.marshall.VersionAwareMarshaller.<init>
| DEBUG: Initialised with version 1.4.0 and versionInt 14
|
| 2008-04-08 16:59:28,195 (main) org.jboss.cache.marshall.VersionAwareMarshaller.<init>
| DEBUG: Using default marshaller class org.jboss.cache.marshall.TreeCacheMarshaller140
|
| 2008-04-08 16:59:28,205 (DownHandler (STABLE)) org.jgroups.protocols.pbcast.STABLE.startStableTask
| DEBUG: stable task started
|
| 2008-04-08 16:59:28,210 (DownHandler (UDP)) org.jgroups.protocols.UDP.start
| DEBUG: creating sockets and starting threads
|
| 2008-04-08 16:59:28,211 (DownHandler (UDP)) org.jgroups.protocols.UDP.createSockets
| INFO : sockets will use interface 10.128.0.114
|
| 2008-04-08 16:59:28,233 (DownHandler (UDP)) org.jgroups.protocols.UDP.createSockets
| INFO : socket information:
| local_addr=10.128.0.114:32783, mcast_addr=239.0.0.101:45567, bind_addr=/10.128.0.114, ttl=32
| sock: bound to 10.128.0.114:32783, receive buffer size=64000, send buffer size=32000
| mcast_recv_sock: bound to 10.128.0.114:45567, send buffer size=32000, receive buffer size=64000
| mcast_send_sock: bound to 10.128.0.114:32784, send buffer size=32000, receive buffer size=64000
|
| 2008-04-08 16:59:28,237 (DownHandler (UDP)) org.jgroups.protocols.UDP.bindToInterfaces
| DEBUG: joined /224.0.0.75:7500 on eth0
|
| 2008-04-08 16:59:28,238 (DownHandler (UDP)) org.jgroups.protocols.UDP.bindToInterfaces
| DEBUG: joined /224.0.0.75:7500 on lo
|
| 2008-04-08 16:59:28,241 (DownHandler (UDP)) org.jgroups.protocols.UDP.startThreads
| DEBUG: created unicast receiver thread
|
| 2008-04-08 16:59:28,241 (UpHandler (STATE_TRANSFER)) org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.passUp
| DEBUG: setting local_addr (null) to 10.128.0.114:32783
|
| 2008-04-08 16:59:28,244 (PingWaiter) org.jgroups.protocols.PingWaiter.findInitialMembers
| DEBUG: waiting for initial members: time_to_wait=20000, got 0 rsps
|
| 2008-04-08 16:59:28,245 (PingSender) org.jgroups.protocols.PingSender.run
| DEBUG: sending GET_MBRS_REQ
|
| 2008-04-08 16:59:28,250 (DownHandler (UDP)) org.jgroups.protocols.UDP.down
| DEBUG: sending msg to null (src=10.128.0.114:32783), headers are {PING=[PING: type=GET_MBRS_REQ, arg=null], UDP=[channel_name=JTVCORE-Cluster]}
|
| 2008-04-08 16:59:28,253 (UDP mcast receiver) org.jgroups.protocols.UDP.receive
| DEBUG: received (mcast)57 bytes from 10.128.0.114:32784
|
| 2008-04-08 16:59:28,253 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.receive
| DEBUG: received (ucast) 84 bytes from 10.128.0.113:32837
|
| 2008-04-08 16:59:28,256 (UDP mcast receiver) org.jgroups.protocols.UDP.handleIncomingMessage
| DEBUG: message is [dst: 239.0.0.101:45567, src: 10.128.0.114:32783 (2 headers), size = 0 bytes], headers are {UDP=[channel_name=JTVCORE-Cluster], PING=[PING: type=GET_MBRS_REQ, arg=null]}
|
|
|
part omitted because too long and seems fine
| 2008-04-08 16:59:48,296 (DownHandler (UDP)) org.jgroups.protocols.UDP.down
| DEBUG: sending msg to 10.128.0.111:32777 (src=10.128.0.114:32783), headers are {UDP=[channel_name=JTVCORE-Cluster], UNICAST=[UNICAST: DATA, seqno=3], STATE_TRANSFER=[StateHeader: type=STATE_REQ, sender=10.128.0.114:32783 id=#1}
|
| 2008-04-08 16:59:48,297 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.receive
| DEBUG: received (ucast) 67 bytes from 10.128.0.111:32777
|
| 2008-04-08 16:59:48,297 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.handleIncomingMessage
| DEBUG: message is [dst: 10.128.0.114:32783, src: 10.128.0.111:32777 (2 headers), size = 0 bytes], headers are {UNICAST=[UNICAST: ACK, seqno=2], UDP=[channel_name=JTVCORE-Cluster]}
|
| 2008-04-08 16:59:48,298 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.receive
| DEBUG: received (ucast) 67 bytes from 10.128.0.111:32777
|
| 2008-04-08 16:59:48,298 (UpHandler (FD)) org.jgroups.protocols.FD.up
| DEBUG: received msg from 10.128.0.111:32777 (counts as ack)
|
| 2008-04-08 16:59:48,299 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.handleIncomingMessage
| DEBUG: message is [dst: 10.128.0.114:32783, src: 10.128.0.111:32777 (2 headers), size = 0 bytes], headers are {UNICAST=[UNICAST: ACK, seqno=3], UDP=[channel_name=JTVCORE-Cluster]}
|
| 2008-04-08 16:59:48,299 (UpHandler (UNICAST)) org.jgroups.protocols.UNICAST.handleAckReceived
| DEBUG: 10.128.0.114:32783 <-- ACK(10.128.0.111:32777: #2)
|
| 2008-04-08 16:59:48,299 (UpHandler (FD)) org.jgroups.protocols.FD.up
| DEBUG: received msg from 10.128.0.111:32777 (counts as ack)
|
| 2008-04-08 16:59:48,300 (UpHandler (UNICAST)) org.jgroups.protocols.UNICAST.handleAckReceived
| DEBUG: 10.128.0.114:32783 <-- ACK(10.128.0.111:32777: #3)
|
| 2008-04-08 16:59:48,329 (UDP mcast receiver) org.jgroups.protocols.UDP.receive
| DEBUG: received (mcast)2250 bytes from 10.128.0.112:32822
|
| 2008-04-08 16:59:48,330 (UDP mcast receiver) org.jgroups.protocols.UDP.handleIncomingMessage
| DEBUG: message is [dst: 239.0.0.101:45567, src: 10.128.0.112:32821 (3 headers), size = 2082 bytes], headers are {NAKACK=[MSG, seqno=123620], MessageDispatcher=[Header: name=MessageDispatcher, type=REQ, id=1207688388340, rsp_expected=true], dest_mbrs=[10.128.0.111:32777, 10.128.0.113:32837, 10.128.0.114:32783], UDP=[channel_name=JTVCORE-Cluster]}
|
| 2008-04-08 16:59:48,330 (UpHandler (NAKACK)) org.jgroups.protocols.pbcast.NAKACK.handleMessage
| DEBUG: 10.128.0.114:32783] received 10.128.0.112:32821#123620
|
| 2008-04-08 16:59:48,332 (UpHandler (STATE_TRANSFER)) org.jgroups.blocks.RequestCorrelator.handleRequest
| DEBUG: calling (org.jgroups.blocks.RpcDispatcher) with request 1207688388340
|
| 2008-04-08 16:59:48,463 (UpHandler (STATE_TRANSFER)) org.jgroups.blocks.RpcDispatcher.handle
| DEBUG: [sender=10.128.0.112:32821], method_call: _replicate(_put(null, /com/jtv/core/st/ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV, ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket@a7f3234e, true))
|
| 2008-04-08 16:59:48,464 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.TxInterceptor.invoke
| DEBUG: (10.128.0.114:32783) call on method [_put(null, /com/jtv/core/st/ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV, ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket@a7f3234e, true)]
|
| 2008-04-08 16:59:48,465 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.PessimisticLockInterceptor.invoke
| DEBUG: PessimisticLockInterceptor invoked for method _put(null, /com/jtv/core/st/ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV, ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket@a7f3234e, true)
|
| 2008-04-08 16:59:48,465 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.PessimisticLockInterceptor.lock
| DEBUG: Attempting to lock node /com/jtv/core/st/ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV for owner Thread[UpHandler (STATE_TRANSFER),5,main]
|
| 2008-04-08 16:59:48,466 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.getOrCreateChild
| DEBUG: created child: fqn=/com
|
| 2008-04-08 16:59:48,466 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.acquireReadLock
| DEBUG: acquiring RL: fqn=/com, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
|
| 2008-04-08 16:59:48,467 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.acquireReadLock
| DEBUG: acquired RL: fqn=/com, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
|
| 2008-04-08 16:59:48,468 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.getOrCreateChild
| DEBUG: created child: fqn=/com/jtv
|
| 2008-04-08 16:59:48,468 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.acquireReadLock
| DEBUG: acquiring RL: fqn=/com/jtv, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
|
| 2008-04-08 16:59:48,469 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.acquireReadLock
| DEBUG: acquired RL: fqn=/com/jtv, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
|
| 2008-04-08 16:59:48,469 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.getOrCreateChild
| DEBUG: created child: fqn=/com/jtv/core
|
| 2008-04-08 16:59:48,469 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.acquireReadLock
| DEBUG: acquiring RL: fqn=/com/jtv/core, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
|
| 2008-04-08 16:59:48,470 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.acquireReadLock
| DEBUG: acquired RL: fqn=/com/jtv/core, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
|
| 2008-04-08 16:59:48,470 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.getOrCreateChild
| DEBUG: created child: fqn=/com/jtv/core/st
|
| 2008-04-08 16:59:48,471 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.acquireReadLock
| DEBUG: acquiring RL: fqn=/com/jtv/core/st, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
|
| 2008-04-08 16:59:48,471 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.acquireReadLock
| DEBUG: acquired RL: fqn=/com/jtv/core/st, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
|
| 2008-04-08 16:59:48,471 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.getOrCreateChild
| DEBUG: created child: fqn=/com/jtv/core/st/ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV
|
| 2008-04-08 16:59:48,472 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.acquireWriteLock
| DEBUG: acquiring WL: fqn=/com/jtv/core/st/ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=<unlocked>
|
| 2008-04-08 16:59:48,472 (UpHandler (STATE_TRANSFER)) org.jboss.cache.Node.acquireWriteLock
| DEBUG: acquired WL: fqn=/com/jtv/core/st/ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV, caller=Thread[UpHandler (STATE_TRANSFER),5,main], lock=write owner=Thread[UpHandler (STATE_TRANSFER),5,main]
|
| 2008-04-08 16:59:48,473 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.CallInterceptor.invoke
| DEBUG: Invoking method _put(null, /com/jtv/core/st/ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV, ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket@a7f3234e, true) on cache.
|
| 2008-04-08 16:59:48,474 (UpHandler (STATE_TRANSFER)) org.jboss.cache.TreeCache._put
| DEBUG: _put(null, "/com/jtv/core/st/ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV", ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV, com.jtv.core.crosscut.impl.spring.security.ticket.ApplicationServiceTicket@a7f3234e)
|
| 2008-04-08 16:59:48,474 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.EvictionInterceptor.invoke
| DEBUG: Invoking EvictionInterceptor
|
| 2008-04-08 16:59:48,474 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.EvictionInterceptor.updateNode
| DEBUG: Updating node/element events with no tx
|
| 2008-04-08 16:59:48,477 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.EvictionInterceptor.doEventUpdatesOnRegionManager
| DEBUG: Adding event EvictedEN[fqn=/com/jtv/core/st/ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV event=3 diff=1] to region at /com/jtv/core/st/
|
| 2008-04-08 16:59:48,478 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.EvictionInterceptor.updateNode
| DEBUG: Finished updating node
|
| 2008-04-08 16:59:48,478 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.EvictionInterceptor.invoke
| DEBUG: Finished invoking EvictionInterceptor
|
| 2008-04-08 16:59:48,479 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.UnlockInterceptor.invoke
| DEBUG: Attempting to release locks on current thread. Lock table is {Thread[UpHandler (STATE_TRANSFER),5,main]=[read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]], write owner=Thread[UpHandler (STATE_TRANSFER),5,main]]}
|
| 2008-04-08 16:59:48,479 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
| DEBUG: releasing lock for /com/jtv/core/st/ST-173404-KOnokuekCK8YP47AWdSzotsQAOkFEO38-JTV: write owner=Thread[UpHandler (STATE_TRANSFER),5,main]
|
| 2008-04-08 16:59:48,480 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
| DEBUG: releasing lock for /com/jtv/core/st: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
|
| 2008-04-08 16:59:48,480 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
| DEBUG: releasing lock for /com/jtv/core: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
|
| 2008-04-08 16:59:48,481 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
| DEBUG: releasing lock for /com/jtv: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
|
| 2008-04-08 16:59:48,481 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.UnlockInterceptor.releaseLocks
| DEBUG: releasing lock for /com: read owners=[Thread[UpHandler (STATE_TRANSFER),5,main]]
|
| 2008-04-08 16:59:48,481 (UpHandler (STATE_TRANSFER)) org.jboss.cache.interceptors.ReplicationInterceptor.invoke
| DEBUG: Non-tx crud meth
|
| 2008-04-08 16:59:48,482 (UpHandler (STATE_TRANSFER)) org.jgroups.blocks.RequestCorrelator.handleRequest
| DEBUG: sending rsp for 1207688388340 to 10.128.0.112:32821
|
| 2008-04-08 16:59:48,483 (DownHandler (UNICAST)) org.jgroups.protocols.UNICAST.down
| DEBUG: 10.128.0.114:32783: created new connection for dst 10.128.0.112:32821
|
| 2008-04-08 16:59:48,484 (DownHandler (UNICAST)) org.jgroups.protocols.UNICAST.down
| DEBUG: 10.128.0.114:32783 --> DATA(10.128.0.112:32821: #1
|
| 2008-04-08 16:59:48,485 (DownHandler (UDP)) org.jgroups.protocols.UDP.down
| DEBUG: sending msg to 10.128.0.112:32821 (src=10.128.0.114:32783), headers are {MessageDispatcher=[Header: name=MessageDispatcher, type=RSP, id=1207688388340, rsp_expected=false], UDP=[channel_name=JTVCORE-Cluster], UNICAST=[UNICAST: DATA, seqno=1]}
|
| 2008-04-08 16:59:48,486 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.receive
| DEBUG: received (ucast) 67 bytes from 10.128.0.112:32821
|
| 2008-04-08 16:59:48,486 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.handleIncomingMessage
| DEBUG: message is [dst: 10.128.0.114:32783, src: 10.128.0.112:32821 (2 headers), size = 0 bytes], headers are {UNICAST=[UNICAST: ACK, seqno=1], UDP=[channel_name=JTVCORE-Cluster]}
|
| 2008-04-08 16:59:48,487 (UpHandler (UNICAST)) org.jgroups.protocols.UNICAST.handleAckReceived
| DEBUG: 10.128.0.114:32783 <-- ACK(10.128.0.112:32821: #1)
|
| 2008-04-08 16:59:49,091 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.receive
| DEBUG: received (ucast) 8290 bytes from 10.128.0.111:32777
|
| 2008-04-08 16:59:49,092 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.handleIncomingMessage
| DEBUG: message is [dst: 10.128.0.114:32783, src: 10.128.0.111:32777 (3 headers), size = 8192 bytes], headers are {FRAG=[id=6, frag_id=0, num_frags=1025], UNICAST=[UNICAST: DATA, seqno=2], UDP=[channel_name=JTVCORE-Cluster]}
|
| 2008-04-08 16:59:49,092 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.receive
| DEBUG: received (ucast) 8290 bytes from 10.128.0.111:32777
|
| 2008-04-08 16:59:49,092 (UpHandler (FD)) org.jgroups.protocols.FD.up
| DEBUG: received msg from 10.128.0.111:32777 (counts as ack)
|
| 2008-04-08 16:59:49,093 (UpHandler (UNICAST)) org.jgroups.protocols.UNICAST.handleDataReceived
| DEBUG: 10.128.0.114:32783 <-- DATA(10.128.0.111:32777: #2
|
| 2008-04-08 16:59:49,093 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.handleIncomingMessage
| DEBUG: message is [dst: 10.128.0.114:32783, src: 10.128.0.111:32777 (3 headers), size = 8192 bytes], headers are {FRAG=[id=6, frag_id=1, num_frags=1025], UNICAST=[UNICAST: DATA, seqno=3], UDP=[channel_name=JTVCORE-Cluster]}
|
| 2008-04-08 16:59:49,093 (UpHandler (UNICAST)) org.jgroups.stack.AckReceiverWindow.remove
| DEBUG: removed seqno=2
|
| 2008-04-08 16:59:49,094 (UpHandler (FD)) org.jgroups.protocols.FD.up
| DEBUG: received msg from 10.128.0.111:32777 (counts as ack)
|
| 2008-04-08 16:59:49,094 (UpHandler (UNICAST)) org.jgroups.protocols.UNICAST.sendAck
| DEBUG: 10.128.0.114:32783 --> ACK(10.128.0.111:32777: #2)
|
| 2008-04-08 16:59:49,094 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.receive
| DEBUG: received (ucast) 8290 bytes from 10.128.0.111:32777
|
| 2008-04-08 16:59:49,097 (UpHandler (UNICAST)) org.jgroups.protocols.UNICAST.handleDataReceived
| DEBUG: 10.128.0.114:32783 <-- DATA(10.128.0.111:32777: #3
|
| 2008-04-08 16:59:49,097 (DownHandler (UDP)) org.jgroups.protocols.UDP.down
| DEBUG: sending msg to 10.128.0.111:32777 (src=10.128.0.114:32783), headers are {UDP=[channel_name=JTVCORE-Cluster], UNICAST=[UNICAST: ACK, seqno=2]}
|
| 2008-04-08 16:59:49,098 (UDP.UcastReceiverThread) org.jgroups.protocols.UDP.handleIncomingMessage
| DEBUG: message is [dst: 10.128.0.114:32783, src: 10.128.0.111:32777 (3 headers), size = 8192 bytes], headers are {FRAG=[id=6, frag_id=2, num_frags=1025], UNICAST=[UNICAST: DATA, seqno=4], UDP=[channel_name=JTVCORE-Cluster]}
|
| 2008-04-08 16:59:49,098 (UpHandler (UNICAST)) org.jgroups.stack.AckReceiverWindow.remove
| DEBUG: removed seqno=3
|
after a while, the replicate request came from 10.128.0.112 again, and the new node 10.128.0.114 started to react to that request again. This procedure repeated until the initial state transfer timed out and failed.
Thanks again.
View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4143522#4143522
Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4143522
18 years, 2 months