4 Replies Latest reply on Mar 15, 2004 9:28 AM by ibruell

    Redhat & JBoss 3.2.3 Shutdown Trouble

    malachii

      Hello,

      I'm new to Linux and JBoss, so this has been a challenge, but I've leared a lot in this time! I'm using Redhat Fedora and JBoss 3.2.3

      Anyhow, I've installed JBoss to the /usr/jboss/jboss3.2.3 folder. I've got it up and running just fine using the run.sh script. However, running the shutdown script simply halts the current console. Logging into another console, I can see that a second java process has been launched. Nothing ever happens.

      I cannot stop JBoss! I have to kill the process with kill -9!

      I've read many posts on the subject, tried several command lines, and even removed all the clustering-related stuff from the "all" server as some person suggested. Still nothing.

      What should I do?

      I'm launching JBoss using sh run.sh.
      I'm trying to stop JBoss using sh shutdown.sh -s localhost -S

      By the way, doing a CTRL-C will EVENTUALLY shutdown JBoss, but since I want it to run as a service, this isn't acceptable, is it (to depend on this method)?

      Even during this CTRL-C shutdown, it complains about a connection problem, waiting a long time at the following lines:

      21:13:05,033 INFO [Http11Protocol] Stoping http11 protocol on 8080 null
      21:13:07,025 INFO [AbstractDeploymentScanner$ScannerThread] Shutdown

      Then it says...

      21:16:14,026 ERROR [PoolTcpEndpoint] Caught exception trying to unlock accept on 8080 java.net.ConnectException: Connection timed out

      and finally shuts down JBoss after about 5-10 minutes (in total).

      About my environment...

      I have the following environment variables set. I believe they are all correct.

      export JAVA_HOME="/usr/java/j2sdk1.4.2_04"
      export JAVA_PATH="$JAVA_HOME"
      export ANT_HOME="/usr/ant/ant1.6.1"
      export JBOSS_DIST="/usr/jboss/jboss3.2.3"
      export JBOSS_HOME="/usr/jboss/jboss3.2.3"
      export PATH="$PATH:$JAVA_HOME/bin:$ANT_HOME/bin:$JBOSS_DIST/bin"

      Do you think switching to Jetty might solve this problem?

      Thanks,

      Mal.

        • 1. Re: Redhat & JBoss 3.2.3 Shutdown Trouble
          ltcmelo

          Hi,
          i'm also a beginner at jboss3.2.3 but i'd sugest you to take a look at your initialization log to see if it's ok. If it is, send your shutsown log to this list ( or anyother one) to see if someelse can help you.

          • 2. Re: Redhat & JBoss 3.2.3 Shutdown Trouble
            malachii

            I'm not sure what you mean by a shutdown log. I only have 3 log files... Keep in mind this is a "fresh", "virgin" JBoss installation.

            localhost_accessXXXX.log
            server.log
            boot.log

            They register nothing when I run the following...

            sh shutdown.sh -s localhost -S (which I have to ctrl-z to exit)

            The only interesting bits in the log files are as follows.

            server.log
            =======
            2004-03-14 12:19:05,625 INFO [org.jboss.web.localhost.Engine] SingleSignOnContextConfig[/jmx-console]: Added certificates -> request attribute Valve
            2004-03-14 12:19:05,653 WARN [org.jboss.web.tomcat.tc4.EmbeddedTomcatService] Unable to invoke setDelegate on class loader:org.jboss.web.tomcat.tc4.WebCtxLoader$ENCLoader@51064e
            2004-03-14 12:19:05,653 INFO [org.jboss.web.localhost.Engine] StandardManager[/jmx-console]: Seeding random number generator class java.security.SecureRandom
            2004-03-14 12:19:05,653 INFO [org.jboss.web.localhost.Engine] StandardManager[/jmx-console]: Seeding of random number generator has been completed
            2004-03-14 12:19:05,654 INFO [org.jboss.web.localhost.Engine] StandardWrapper[/jmx-console:default]: Loading container servlet default
            2004-03-14 12:19:05,655 INFO [org.jboss.web.localhost.Engine] StandardWrapper[/jmx-console:invoker]: Loading container servlet invoker
            2004-03-14 12:19:06,822 INFO [org.jboss.deployment.MainDeployer] Deployed package: file:/usr/jboss/jboss3.2.3/server/default/deploy/jmx-console.war/
            2004-03-14 12:19:06,823 INFO [org.jboss.deployment.MainDeployer] Starting deployment of package: file:/usr/jboss/jboss3.2.3/server/default/deploy/management/web-console.war
            2004-03-14 12:19:07,101 INFO [org.jboss.web.tomcat.tc4.EmbeddedTomcatService] deploy, ctxPath=/web-console, warUrl=file:/usr/jboss/jboss3.2.3/server/default/tmp/deploy/tmp32621web-console.war/
            2004-03-14 12:19:07,410 INFO [org.jboss.web.localhost.Engine] SingleSignOnContextConfig[/web-console]: Added certificates -> request attribute Valve
            2004-03-14 12:19:07,431 WARN [org.jboss.web.tomcat.tc4.EmbeddedTomcatService] Unable to invoke setDelegate on class loader:org.jboss.web.tomcat.tc4.WebCtxLoader$ENCLoader@17af46e
            2004-03-14 12:19:07,431 INFO [org.jboss.web.localhost.Engine] StandardManager[/web-console]: Seeding random number generator class java.security.SecureRandom
            2004-03-14 12:19:07,431 INFO [org.jboss.web.localhost.Engine] StandardManager[/web-console]: Seeding of random number generator has been completed
            2004-03-14 12:19:07,706 INFO [org.jboss.web.localhost.Engine] StandardWrapper[/web-console:default]: Loading container servlet default
            2004-03-14 12:19:07,725 INFO [org.jboss.web.localhost.Engine] StandardWrapper[/web-console:invoker]: Loading container servlet invoker
            2004-03-14 12:19:08,978 INFO [org.jboss.deployment.MainDeployer] Deployed package: file:/usr/jboss/jboss3.2.3/server/default/deploy/management/web-console.war
            2004-03-14 12:19:08,986 INFO [org.jboss.deployment.scanner.URLDeploymentScanner] Started jboss.deployment:type=DeploymentScanner,flavor=URL
            2004-03-14 12:19:09,181 INFO [org.jboss.deployment.MainDeployer] Deployed package: file:/usr/jboss/jboss3.2.3/server/default/conf/jboss-service.xml
            2004-03-14 12:19:09,183 INFO [org.jboss.system.server.Server] JBoss (MX MicroKernel) [3.2.3 (build: CVSTag=JBoss_3_2_3 date=200311301445)] Started in 22s:672ms

            The last parts of boot.log...
            ==================

            12:18:50,593 DEBUG [ServiceConfigurator] RecursiveSearch set to true in jboss.deployment:type=DeploymentScanner,flavor=URL
            12:18:50,594 DEBUG [ServiceController] Creating service jboss.management.local:j2eeType=J2EEDomain,name=Manager
            12:18:50,594 DEBUG [LocalJBossServerDomain] Creating
            12:18:50,624 DEBUG [LocalJBossServerDomain] Registering with all deployers, mainDeployer=jboss.system:service=MainDeployer
            12:18:50,624 DEBUG [LocalJBossServerDomain] Getting current deployers
            12:18:50,624 DEBUG [LocalJBossServerDomain] Registering as listener of deployer: jboss.system:service=ServiceDeployer
            12:18:50,624 DEBUG [LocalJBossServerDomain] Registering as listener of deployer: jboss.system:service=JARDeployer
            12:18:50,624 DEBUG [LocalJBossServerDomain] Created
            12:18:50,624 DEBUG [LocalJBossServerDomain] handleNotification: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=4,timeStamp=1079284730624,message=null,userData=jboss.management.local:j2eeType=J2EEDomain,name=Manager]
            12:18:50,625 DEBUG [DefaultManagedObjectFactoryMap] Failed to find factory for event: javax.management.Notification[source=jboss.system:service=ServiceController,type= org.jboss.system.ServiceMBean.create,sequenceNumber=4,timeStamp=1079284730624,message=null,userData=jboss.management.local:j2eeType=J2EEDomain,name=Manager]
            12:18:50,625 DEBUG [ServiceController] Creating dependent components for: jboss.management.local:j2eeType=J2EEDomain,name=Manager dependents are: []
            12:18:50,625 DEBUG [ServiceController] Creating service jboss.system:type=Log4jService,service=Logging
            12:18:50,625 DEBUG [Log4jService] Creating
            12:18:50,627 INFO [Log4jService$URLWatchTimerTask] Configuring from URL: resource:log4j.xml


            • 3. Re: Redhat & JBoss 3.2.3 Shutdown Trouble
              malachii

              I have a bit more information about my trouble...

              I noticed that I *can*shutdown via the JMX Agent View (web console). However, it behaves the same as CTRL-C and takes a damn long time (5+ minutes) to shut down.

              16:29:46,855 INFO [AbstractDeploymentScanner$ScannerThread] Shutdown
              16:32:55,696 ERROR [PoolTcpEndpoint] Caught exception trying to unlock accept on
              8080 java.net.ConnectException: Connection timed out
              16:36:04,696 INFO [ChannelSocket] Error shutting down the channel 8009 java.net
              .ConnectException: Connection timed out

              Notice the very long gap between the following messages, which all appear sequentially (I have not taken them out of order)

              Here is the complete shutdown process...

              16:29:43,633 INFO [Server] Shutting down
              16:29:43,636 INFO [Server] LifeThread.run exits!
              16:29:43,636 INFO [Server] Shutting down the JVM now!
              16:29:43,637 INFO [Server] JBoss SHUTDOWN: Undeploying all packages
              16:29:43,638 INFO [EmbeddedTomcatService] undeploy, ctxPath=/web-console, warUr
              l=file:/usr/jboss/jboss3.2.3/server/default/tmp/deploy/tmp58761web-console.war/
              16:29:43,639 INFO [Engine] StandardHost[localhost]: Removing web application at
              context path /web-console
              16:29:43,840 INFO [EmbeddedTomcatService] undeploy, ctxPath=/jmx-console, warUr
              l=file:/usr/jboss/jboss3.2.3/server/default/deploy/jmx-console.war/
              16:29:43,841 INFO [Engine] StandardHost[localhost]: Removing web application at
              context path /jmx-console
              16:29:43,856 INFO [Engine] StandardWrapper[/jmx-console:HtmlAdaptor]: Waiting f
              or 1 instance(s) to be deallocated
              16:29:44,456 INFO [Engine] StandardWrapper[/jmx-console:jsp]: Waiting for 1 ins
              tance(s) to be deallocated
              16:29:44,874 INFO [Engine] StandardManager[/jmx-console]: Seeding random number
              generator class java.security.SecureRandom
              16:29:44,875 INFO [Engine] StandardManager[/jmx-console]: Seeding of random num
              ber generator has been completed
              16:29:45,062 INFO [ClientDeployer] Stopping jboss.j2ee:service=ClientDeployer
              16:29:45,062 INFO [MainDeployer] Removing deployer: org.jboss.deployment.Client
              Deployer@bdb375
              16:29:45,069 INFO [MailService] Stopping jboss:service=Mail
              16:29:45,069 INFO [MailService] Mail service 'java:/Mail' removed from JNDI
              16:29:45,073 INFO [InvalidationManager] Stopping jboss.cache:service=Invalidati
              onManager
              16:29:45,073 INFO [InvalidationManager] Stoping Invalidation Manager jboss.cach
              e:service=InvalidationManager
              16:29:45,077 INFO [SystemPropertiesService] Stopping jboss:type=Service,name=Sy
              stemProperties
              16:29:45,077 INFO [PropertyEditorManagerService] Stopping jboss:type=Service,na
              me=PropertyEditorManager
              16:29:45,100 INFO [TxConnectionManager] Stopping jboss.jca:service=TxCM,name=Jm
              sXA
              16:29:45,120 INFO [HTTPServerILService] Stopping jboss.mq:service=InvocationLay
              er,type=HTTP
              16:29:45,120 INFO [JVMServerILService] Stopping jboss.mq:service=InvocationLaye
              r,type=JVM
              16:29:45,121 INFO [OILServerILService] Stopping jboss.mq:service=InvocationLaye
              r,type=OIL
              16:29:45,122 INFO [OIL2ServerILService] Stopping jboss.mq:service=InvocationLay
              er,type=OIL2
              16:29:45,122 INFO [RMIServerILService] Stopping jboss.mq:service=InvocationLaye
              r,type=RMI
              16:29:45,123 INFO [UILServerILService] Stopping jboss.mq:service=InvocationLaye
              r,type=UIL2
              16:29:45,123 INFO [Invoker] Stopping jboss.mq:service=Invoker
              16:29:45,123 INFO [InterceptorLoader] Stopping jboss.mq:service=TracingIntercep
              tor
              16:29:45,124 INFO [DLQ] Stopping jboss.mq.destination:service=Queue,name=DLQ
              16:29:45,127 INFO [testTopic] Stopping jboss.mq.destination:service=Topic,name=
              testTopic
              16:29:45,128 INFO [securedTopic] Stopping jboss.mq.destination:service=Topic,na
              me=securedTopic
              16:29:45,128 INFO [testDurableTopic] Stopping jboss.mq.destination:service=Topi
              c,name=testDurableTopic
              16:29:45,149 INFO [testQueue] Stopping jboss.mq.destination:service=Queue,name=
              testQueue
              16:29:45,150 INFO [SecurityManager] Stopping jboss.mq:service=SecurityManager
              16:29:45,150 INFO [A] Stopping jboss.mq.destination:service=Queue,name=A
              16:29:45,151 INFO [B] Stopping jboss.mq.destination:service=Queue,name=B
              16:29:45,151 INFO [C] Stopping jboss.mq.destination:service=Queue,name=C
              16:29:45,152 INFO [D] Stopping jboss.mq.destination:service=Queue,name=D
              16:29:45,152 INFO [ex] Stopping jboss.mq.destination:service=Queue,name=ex
              16:29:45,153 INFO [DestinationManager] Stopping jboss.mq:service=DestinationMan
              ager
              16:29:45,153 INFO [PersistenceManager] Stopping jboss.mq:service=PersistenceMan
              ager
              16:29:45,154 INFO [TxConnectionManager] Stopping jboss.jca:service=LocalTxCM,na
              me=DefaultDS
              16:29:45,155 INFO [CachedConnectionManager] Stopping jboss.jca:service=CachedCo
              nnectionManager
              16:29:45,168 INFO [NamingAlias] Stopping jboss.mq:service=InvocationLayer,type=
              UILXA
              16:29:45,169 INFO [NamingAlias] Stopping jboss.mq:service=InvocationLayer,type=
              UIL
              16:29:45,255 INFO [DynamicStateManager] Stopping jboss.mq:service=StateManager
              16:29:45,268 INFO [MessageCache] Stopping jboss.mq:service=MessageCache
              16:29:45,298 INFO [JBossManagedConnectionPool] Stopping jboss.jca:service=Manag
              edConnectionPool,name=DefaultDS
              16:29:45,379 INFO [RARDeployment] Stopping jboss.jca:service=ManagedConnectionF
              actory,name=DefaultDS
              16:29:45,382 INFO [JBossManagedConnectionPool] Stopping jboss.jca:service=Manag
              edConnectionPool,name=JmsXA
              16:29:45,382 INFO [RARDeployment] Stopping jboss.jca:service=ManagedConnectionF
              actory,name=JmsXA
              16:29:45,382 INFO [ServerSessionPoolLoader] Stopping jboss.mq:service=ServerSes
              sionPoolMBean,name=StdJMSPool
              16:29:45,383 INFO [ServerSessionPoolLoader] pool factory StdJMSPool unbound fro
              m java:/StdJMSPool
              16:29:45,383 INFO [JMSProviderLoader] Stopping jboss.mq:service=JMSProviderLoad
              er,name=JBossMQProvider
              16:29:45,383 INFO [JMSProviderLoader] unbound adapter DefaultJMSProvider from j
              ava:/DefaultJMSProvider
              16:29:45,386 INFO [RARDeployer] About to undeploy RARDeploymentMBean, objectnam
              e: jboss.jca:service=RARDeployment,name=JBoss JDBC XATransaction ResourceAdapter
              16:29:45,387 INFO [ResourceAdapterModule] destroy(), remove RARModule: jboss.ma
              nagement.local:J2EEApplication=null,J2EEServer=Local,j2eeType=ResourceAdapterMod
              ule,name=jboss-xa-jdbc.rar
              16:29:45,388 INFO [RARDeployer] About to undeploy RARDeploymentMBean, objectnam
              e: jboss.jca:service=RARDeployment,name=JBoss LocalTransaction JDBC Wrapper
              16:29:45,389 INFO [ResourceAdapterModule] destroy(), remove RARModule: jboss.ma
              nagement.local:J2EEApplication=null,J2EEServer=Local,j2eeType=ResourceAdapterMod
              ule,name=jboss-local-jdbc.rar
              16:29:45,391 INFO [RARDeployer] About to undeploy RARDeploymentMBean, objectnam
              e: jboss.jca:service=RARDeployment,name=JMS Adapter
              16:29:45,392 INFO [ResourceAdapterModule] destroy(), remove RARModule: jboss.ma
              nagement.local:J2EEApplication=null,J2EEServer=Local,j2eeType=ResourceAdapterMod
              ule,name=jms-ra.rar
              16:29:45,393 INFO [XSLSubDeployer] Stopping jboss.jca:service=ConnectionFactory
              Deployer
              16:29:45,393 INFO [MainDeployer] Removing deployer: org.jboss.deployment.XSLSub
              Deployer@40afb9
              16:29:45,394 INFO [RARDeployer] Stopping jboss.jca:service=RARDeployer
              16:29:45,394 INFO [MainDeployer] Removing deployer: org.jboss.resource.RARDeplo
              yer@9e8c34
              16:29:45,399 INFO [UUIDKeyGeneratorFactoryService] Stopping jboss:service=UUIDK
              eyGeneratorFactory
              16:29:45,402 INFO [InvokerAdaptorService] Stopping jboss.jmx:type=adaptor,name=
              Invoker
              16:29:45,402 INFO [PluginManager] Stopping jboss.admin:service=PluginManager
              16:29:45,403 INFO [NamingAlias] Stopping jboss.jmx:alias=jmx/rmi/RMIAdaptor
              16:29:45,403 INFO [JRMPProxyFactory] Stopping jboss.jmx:type=adaptor,name=Invok
              er,protocol=jrmp,service=proxyFactory
              16:29:45,413 INFO [SnmpAgentService] Stopping jboss.jmx:name=SnmpAgent,service=
              snmp,type=adaptor
              16:29:45,413 INFO [SnmpAgentService] Stopping heartbeat controller ...
              16:29:45,414 INFO [SnmpAgentService] Stopping subscription manager ...
              16:29:45,414 INFO [SnmpAgentService] Stopping trap emitter ...
              16:29:46,356 INFO [TrapdService] Stopping jboss.jmx:name=SnmpAgent,service=trap
              d,type=logger
              16:29:46,673 INFO [EmbeddedTomcatService] undeploy, ctxPath=/jbossmq-httpil, wa
              rUrl=file:/usr/jboss/jboss3.2.3/server/default/deploy/jms/jbossmq-httpil.sar/jbo
              ssmq-httpil.war/
              16:29:46,673 INFO [Engine] StandardHost[localhost]: Removing web application at
              context path /jbossmq-httpil
              16:29:46,677 INFO [EmbeddedTomcatService] undeploy, ctxPath=/invoker, warUrl=fi
              le:/usr/jboss/jboss3.2.3/server/default/deploy/http-invoker.sar/invoker.war/
              16:29:46,678 INFO [Engine] StandardHost[localhost]: Removing web application at
              context path /invoker
              16:29:46,683 INFO [EmbeddedTomcatService] Stopping jboss.web:service=WebServer
              16:29:46,683 INFO [MainDeployer] Removing deployer: org.jboss.web.tomcat.tc4.Em
              beddedTomcatService@92b1a1
              16:29:46,699 INFO [Http11Protocol] Stoping http11 protocol on 8080 null
              16:29:46,855 INFO [AbstractDeploymentScanner$ScannerThread] Shutdown
              16:32:55,696 ERROR [PoolTcpEndpoint] Caught exception trying to unlock accept on
              8080 java.net.ConnectException: Connection timed out
              16:36:04,696 INFO [ChannelSocket] Error shutting down the channel 8009 java.net
              .ConnectException: Connection timed out
              16:36:04,738 INFO [HttpProxyFactory] Stopping jboss:service=invoker,type=http,t
              arget=Naming,readonly=true
              16:36:04,738 INFO [HttpProxyFactory] Stopping jboss:service=invoker,type=http,t
              arget=Naming
              16:36:04,739 INFO [HttpInvokerHA] Stopping jboss:service=invoker,type=httpHA
              16:36:04,739 INFO [HttpInvoker] Stopping jboss:service=invoker,type=http
              16:36:04,749 INFO [URLDeploymentScanner] Stopping jboss.deployment:type=Deploym
              entScanner,flavor=URL
              16:36:04,749 INFO [BeanShellSubDeployer] Stopping jboss.scripts:service=BSHDepl
              oyer
              16:36:04,749 INFO [MainDeployer] Removing deployer: org.jboss.varia.deployment.
              BeanShellSubDeployer@1690ab
              16:36:04,750 INFO [EARDeployer] Stopping jboss.j2ee:service=EARDeployer
              16:36:04,750 INFO [MainDeployer] Removing deployer: org.jboss.deployment.EARDep
              loyer@1f2cea2
              16:36:04,750 INFO [EJBDeployer] Stopping jboss.ejb:service=EJBDeployer
              16:36:04,750 INFO [MainDeployer] Removing deployer: org.jboss.ejb.EJBDeployer@1
              49eb9f
              16:36:04,751 INFO [PooledInvoker] Stopping jboss:service=invoker,type=pooled
              16:36:04,751 INFO [LocalInvoker] Stopping jboss:service=invoker,type=local
              16:36:04,752 INFO [ClientUserTransactionService] Stopping jboss:service=ClientU
              serTransaction
              16:36:04,752 INFO [JRMPProxyFactory] Stopping jboss:service=proxyFactory,target
              =ClientUserTransactionFactory
              16:36:04,753 INFO [JRMPProxyFactory] Stopping jboss:service=proxyFactory,target
              =ClientUserTransaction
              16:36:04,753 INFO [JRMPInvoker] Stopping jboss:service=invoker,type=jrmp
              16:36:04,754 INFO [TransactionManagerService] Stopping jboss:service=Transactio
              nManager
              16:36:04,755 INFO [JaasSecurityManagerService] Stopping jboss.security:service=
              JaasSecurityManager
              16:36:04,755 INFO [XMLLoginConfig] Stopping jboss.security:service=XMLLoginConf
              ig
              16:36:04,756 INFO [SecurityConfig] Stopping jboss.security:service=SecurityConf
              ig
              16:36:04,756 INFO [JNDIView] Stopping jboss:service=JNDIView
              16:36:04,756 INFO [NamingService] Stopping jboss:service=Naming
              16:36:04,757 INFO [NamingService] Stopping
              16:36:04,757 INFO [NamingService] Stopped
              16:36:04,757 INFO [WebService] Stopping jboss:service=WebService
              16:36:04,758 INFO [Log4jService] Stopping jboss.system:type=Log4jService,servic
              e=Logging
              16:36:04,758 INFO [LocalJBossServerDomain] Stopping jboss.management.local:j2ee
              Type=J2EEDomain,name=Manager
              16:36:04,764 INFO [LocalJBossServerDomain] Found 39 domain mbeans
              16:36:04,970 INFO [Server] Shutting down all services
              Shutting down
              16:36:04,970 INFO [ServiceController] Stopping 3 services
              16:36:04,971 INFO [SARDeployer] Stopping jboss.system:service=ServiceDeployer
              16:36:04,971 INFO [MainDeployer] Removing deployer: org.jboss.deployment.SARDep
              loyer@861f24
              16:36:04,971 INFO [JARDeployer] Stopping jboss.system:service=JARDeployer
              16:36:04,971 INFO [MainDeployer] Stopping jboss.system:service=MainDeployer
              16:36:04,972 INFO [ServiceController] Stopped 3 services
              16:36:04,973 INFO [Server] Shutdown complete
              Shutdown complete
              Halting VM
              =============

              • 4. Re: Redhat & JBoss 3.2.3 Shutdown Trouble

                If i remember right, there was a problem with the domain name in /etc/hosts under RedHat.

                Correct it to something like:

                127.0.0.1 localhost
                [YourIP] [YourHostname]