4 Replies Latest reply on Jun 17, 2009 4:23 AM by Pierangelo Repetti

    JBoss startup delay

    Pierangelo Repetti Newbie

      Hello

      I am using JBoss 4.2.2 GA on a RedHat linux (Linux 2.6.18-53.el5 #1 SMP Wed Oct 10 16:34:19 EDT 2007 x86_64 x86_64 x86_64 GNU/Linux). Java version is 1.6.0_05.

      I am experiencing random delays in JBoss startup time. Normally it would take 1' to start, but quite often it appears to hang for three minutes, one or two times. The result is that it starts in either 4' or 7' and that's quite annoying.

      This instance is part of a two-instance JBoss Cluster. The other instance shows the same problem.

      I can't figure out the reason for this delay. I am attaching the log at DEBUG level, hoping to get some hints. The first stop is at 17:59:24,520 , the second is at 18:02:51,601.

      Thanks
      Pierangelo


      -------------------------- server.log -----------------------------
      2009-06-16 17:59:03,188 DEBUG [org.jboss.logging.Log4jService] Installed System.out adapter
      2009-06-16 17:59:03,189 DEBUG [org.jboss.logging.Log4jService] Installed System.err adapter
      2009-06-16 17:59:03,191 DEBUG [org.jboss.logging.Log4jService] Added ThrowableListener: org.jboss.logging.Log4jService$ThrowableListenerLoggingAdapter@37722456
      2009-06-16 17:59:03,192 DEBUG [org.jboss.logging.Log4jService] Created jboss.system:type=Log4jService,service=Logging
      2009-06-16 17:59:03,192 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.system:type=Log4jService,service=Logging dependents are: []
      2009-06-16 17:59:03,192 DEBUG [org.jboss.system.ServiceController] Creating service jboss.rmi:type=RMIClassLoader
      2009-06-16 17:59:03,193 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.rmi:type=RMIClassLoader dependents are: []
      2009-06-16 17:59:03,193 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=WebService
      2009-06-16 17:59:03,193 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=WebService
      2009-06-16 17:59:03,193 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=NamingBeanImpl
      2009-06-16 17:59:03,193 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=NamingBeanImpl dependents are: [ObjectName: jboss:service=Naming
      State: CONFIGURED
      I Depend On:
      jboss.system:service=ThreadPool
      jboss:service=NamingBeanImpl
      ]
      2009-06-16 17:59:03,194 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=Naming
      2009-06-16 17:59:03,194 DEBUG [org.jboss.naming.NamingService] Creating jboss:service=Naming
      2009-06-16 17:59:03,194 DEBUG [org.jboss.naming.NamingService] Created jboss:service=Naming
      2009-06-16 17:59:03,213 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=Naming dependents are: []
      2009-06-16 17:59:03,213 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=Naming
      2009-06-16 17:59:03,213 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=Naming
      2009-06-16 17:59:03,213 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=JNDIView
      2009-06-16 17:59:03,213 DEBUG [org.jboss.naming.JNDIView] Creating jboss:service=JNDIView
      2009-06-16 17:59:03,214 DEBUG [org.jboss.naming.JNDIView] Created jboss:service=JNDIView
      2009-06-16 17:59:03,214 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=JNDIView dependents are: []
      2009-06-16 17:59:03,214 DEBUG [org.jboss.system.ServiceController] Creating service jboss.security:service=SecurityConfig
      2009-06-16 17:59:03,214 DEBUG [org.jboss.security.plugins.SecurityConfig] Creating jboss.security:service=SecurityConfig
      2009-06-16 17:59:03,214 DEBUG [org.jboss.security.plugins.SecurityConfig] Created jboss.security:service=SecurityConfig
      2009-06-16 17:59:03,214 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.security:service=SecurityConfig dependents are: []
      2009-06-16 17:59:03,214 DEBUG [org.jboss.system.ServiceController] Creating service jboss.security:service=XMLLoginConfig
      2009-06-16 17:59:03,214 DEBUG [org.jboss.security.auth.login.XMLLoginConfig] Creating jboss.security:service=XMLLoginConfig
      2009-06-16 17:59:03,214 DEBUG [org.jboss.security.auth.login.XMLLoginConfig] Created jboss.security:service=XMLLoginConfig
      2009-06-16 17:59:03,215 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.security:service=XMLLoginConfig dependents are: []
      2009-06-16 17:59:03,215 DEBUG [org.jboss.system.ServiceController] Creating service jboss.security:service=JaasSecurityManager
      2009-06-16 17:59:03,215 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Creating jboss.security:service=JaasSecurityManager
      2009-06-16 17:59:03,215 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Created jboss.security:service=JaasSecurityManager
      2009-06-16 17:59:03,215 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.security:service=JaasSecurityManager dependents are: []
      2009-06-16 17:59:03,217 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=XidFactory
      2009-06-16 17:59:03,218 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=XidFactory dependents are: []
      2009-06-16 17:59:03,218 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=TransactionManager
      2009-06-16 17:59:03,218 DEBUG [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Creating jboss:service=TransactionManager
      2009-06-16 17:59:03,218 DEBUG [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Created jboss:service=TransactionManager
      2009-06-16 17:59:03,227 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=TransactionManager dependents are: [ObjectName: jboss:service=invoker,type=unified
      State: CONFIGURED
      I Depend On:
      jboss:service=TransactionManager
      jboss.remoting:service=Connector,transport=socket
      , ObjectName: jboss:service=invoker,type=jrmp
      State: CONFIGURED
      I Depend On:
      jboss:service=TransactionManager
      Depends On Me:
      jboss:service=proxyFactory,target=ClientUserTransactionFactory
      jboss:service=proxyFactory,target=ClientUserTransaction
      , ObjectName: jboss:service=invoker,type=local
      State: CONFIGURED
      I Depend On:
      jboss:service=TransactionManager
      , ObjectName: jboss:service=invoker,type=pooled
      State: CONFIGURED
      I Depend On:
      jboss:service=TransactionManager
      ]
      2009-06-16 17:59:03,227 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=unified
      2009-06-16 17:59:03,227 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss:service=invoker,type=unified waiting on jboss.remoting:service=Connector,transport=socket
      2009-06-16 17:59:03,227 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=jrmp
      2009-06-16 17:59:03,228 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Creating jboss:service=invoker,type=jrmp
      2009-06-16 17:59:03,228 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Created jboss:service=invoker,type=jrmp
      2009-06-16 17:59:03,228 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=jrmp dependents are: [ObjectName: jboss:service=proxyFactory,target=ClientUserTransactionFactory
      State: CONFIGURED
      I Depend On:
      jboss:service=invoker,type=jrmp
      Depends On Me:
      jboss:service=ClientUserTransaction
      , ObjectName: jboss:service=proxyFactory,target=ClientUserTransaction
      State: CONFIGURED
      I Depend On:
      jboss:service=invoker,type=jrmp
      Depends On Me:
      jboss:service=ClientUserTransaction
      ]
      2009-06-16 17:59:03,228 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=proxyFactory,target=ClientUserTransactionFactory
      2009-06-16 17:59:03,229 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Creating jboss:service=proxyFactory,target=ClientUserTransactionFactory
      2009-06-16 17:59:03,229 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Created jboss:service=proxyFactory,target=ClientUserTransactionFactory
      2009-06-16 17:59:03,230 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=proxyFactory,target=ClientUserTransactionFactory dependents are: [ObjectName: jboss:service=ClientUserTransaction
      State: CONFIGURED
      I Depend On:
      jboss:service=proxyFactory,target=ClientUserTransactionFactory
      jboss:service=proxyFactory,target=ClientUserTransaction
      ]
      2009-06-16 17:59:03,230 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=ClientUserTransaction
      2009-06-16 17:59:03,231 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss:service=ClientUserTransaction waiting on jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:03,231 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:03,231 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Creating jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:03,231 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Created jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:03,231 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=proxyFactory,target=ClientUserTransaction dependents are: [ObjectName: jboss:service=ClientUserTransaction
      State: CONFIGURED
      I Depend On:
      jboss:service=proxyFactory,target=ClientUserTransactionFactory
      jboss:service=proxyFactory,target=ClientUserTransaction
      ]
      2009-06-16 17:59:03,231 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=ClientUserTransaction
      2009-06-16 17:59:03,231 DEBUG [org.jboss.tm.usertx.server.ClientUserTransactionService] Creating jboss:service=ClientUserTransaction
      2009-06-16 17:59:03,232 DEBUG [org.jboss.tm.usertx.server.ClientUserTransactionService] Created jboss:service=ClientUserTransaction
      2009-06-16 17:59:03,236 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=ClientUserTransaction dependents are: []
      2009-06-16 17:59:03,236 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=local
      2009-06-16 17:59:03,236 DEBUG [org.jboss.invocation.local.LocalInvoker] Creating jboss:service=invoker,type=local
      2009-06-16 17:59:03,261 DEBUG [org.jboss.invocation.local.LocalInvoker] Created jboss:service=invoker,type=local
      2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=local dependents are: []
      2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=pooled
      2009-06-16 17:59:03,262 DEBUG [org.jboss.invocation.pooled.server.PooledInvoker] Creating jboss:service=invoker,type=pooled
      2009-06-16 17:59:03,262 DEBUG [org.jboss.invocation.pooled.server.PooledInvoker] Created jboss:service=invoker,type=pooled
      2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=pooled dependents are: []
      2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=ClientUserTransaction
      2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=ClientUserTransaction
      2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=proxyFactory,target=ClientUserTransactionFactory
      2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=proxyFactory,target=ClientUserTransactionFactory
      2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=unified
      2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss:service=invoker,type=unified waiting on jboss.remoting:service=Connector,transport=socket
      2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=jrmp
      2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=invoker,type=jrmp
      2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=local
      2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=invoker,type=local
      2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=pooled
      2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=invoker,type=pooled
      2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss.remoting:service=NetworkRegistry
      2009-06-16 17:59:03,264 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.remoting:service=NetworkRegistry dependents are: [ObjectName: jboss.remoting:service=Connector,transport=socket
      State: CONFIGURED
      I Depend On:
      jboss.remoting:service=NetworkRegistry
      Depends On Me:
      jboss:service=invoker,type=unified
      ]
      2009-06-16 17:59:03,264 DEBUG [org.jboss.system.ServiceController] Creating service jboss.remoting:service=Connector,transport=socket
      2009-06-16 17:59:03,333 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] did not find server socket factory configuration as mbean service or classname. Creating default server socket factory.
      2009-06-16 17:59:03,333 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] created server socket factory javax.net.DefaultServerSocketFactory@6b9cd75a
      2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.remoting:service=Connector,transport=socket dependents are: [ObjectName: jboss:service=invoker,type=unified
      State: CONFIGURED
      I Depend On:
      jboss:service=TransactionManager
      jboss.remoting:service=Connector,transport=socket
      ]
      2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=unified
      2009-06-16 17:59:03,354 DEBUG [org.jboss.invocation.unified.server.UnifiedInvoker] Creating jboss:service=invoker,type=unified
      2009-06-16 17:59:03,354 DEBUG [org.jboss.invocation.unified.server.UnifiedInvoker] Created jboss:service=invoker,type=unified
      2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=unified dependents are: []
      2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Creating service jboss.remoting:service=Connector,transport=socket
      2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss.remoting:service=Connector,transport=socket
      2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Creating service jboss.jdbc:service=metadata
      2009-06-16 17:59:03,355 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] Creating jboss.jdbc:service=metadata
      2009-06-16 17:59:03,355 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] Created jboss.jdbc:service=metadata
      2009-06-16 17:59:03,355 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.jdbc:service=metadata dependents are: []
      2009-06-16 17:59:03,355 DEBUG [org.jboss.system.ServiceController] Creating service jboss.deployment:type=DeploymentScanner,flavor=URL
      2009-06-16 17:59:03,355 DEBUG [org.jboss.deployment.scanner.URLDeploymentScanner] Creating jboss.deployment:type=DeploymentScanner,flavor=URL
      2009-06-16 17:59:03,368 DEBUG [org.jboss.deployment.scanner.URLDeploymentScanner] Scanner thread started
      2009-06-16 17:59:03,368 DEBUG [org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread] Running
      2009-06-16 17:59:03,368 DEBUG [org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread] Disabled, waiting for notification
      2009-06-16 17:59:03,369 DEBUG [org.jboss.deployment.scanner.URLDeploymentScanner] Created jboss.deployment:type=DeploymentScanner,flavor=URL
      2009-06-16 17:59:03,369 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.deployment:type=DeploymentScanner,flavor=URL dependents are: []
      2009-06-16 17:59:03,369 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying jboss-service.xml
      2009-06-16 17:59:03,369 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/usr/local/jboss-4.2.2.GA/server/all/conf/jboss-service.xml
      2009-06-16 17:59:03,369 DEBUG [org.jboss.deployment.SARDeployer] Deploying SAR, start step: url file:/usr/local/jboss-4.2.2.GA/server/all/conf/jboss-service.xml
      2009-06-16 17:59:03,369 DEBUG [org.jboss.system.ServiceController] starting service jboss.management.local:j2eeType=J2EEDomain,name=Manager
      2009-06-16 17:59:03,369 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.management.local:j2eeType=J2EEDomain,name=Manager dependent components: []
      2009-06-16 17:59:03,369 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=AttributePersistenceService
      2009-06-16 17:59:03,370 DEBUG [org.jboss.system.pm.AttributePersistenceService] Starting jboss:service=AttributePersistenceService
      2009-06-16 17:59:03,374 DEBUG [org.jboss.system.pm.XMLAttributePersistenceManager] Constructed
      2009-06-16 17:59:03,374 DEBUG [org.jboss.system.pm.XMLAttributePersistenceManager] Using data directory: /usr/local/jboss-4.2.2.GA/server/all/data/xmbean-attrs
      2009-06-16 17:59:03,375 DEBUG [org.jboss.system.pm.AttributePersistenceService] Started jboss:service=AttributePersistenceService
      2009-06-16 17:59:03,375 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=AttributePersistenceService dependent components: []
      2009-06-16 17:59:03,375 DEBUG [org.jboss.system.ServiceController] starting service jboss.system:service=ThreadPool
      2009-06-16 17:59:03,375 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.system:service=ThreadPool dependent components: [ObjectName: jboss:service=WebService
      State: CREATED
      I Depend On:
      jboss.system:service=ThreadPool
      , ObjectName: jboss:service=Naming
      State: CREATED
      I Depend On:
      jboss.system:service=ThreadPool
      jboss:service=NamingBeanImpl
      ]
      2009-06-16 17:59:03,375 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=WebService
      2009-06-16 17:59:03,375 DEBUG [org.jboss.web.WebService] Starting jboss:service=WebService
      2009-06-16 17:59:03,378 DEBUG [org.jboss.web.WebServer] Started server: ServerSocket[addr=rllsirfpas01b.local.sirfarma.it/10.145.9.3,port=0,localport=8083]
      2009-06-16 17:59:03,381 DEBUG [org.jboss.web.WebService] Started WebServer with address: rllsirfpas01b.local.sirfarma.it/10.145.9.3:8083
      2009-06-16 17:59:03,381 DEBUG [org.jboss.web.WebService] Started jboss:service=WebService
      2009-06-16 17:59:03,381 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=WebService dependent components: []
      2009-06-16 17:59:03,381 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=Naming
      2009-06-16 17:59:03,381 DEBUG [org.jboss.system.ServiceController] waiting in start jboss:service=Naming on jboss:service=NamingBeanImpl
      2009-06-16 17:59:03,381 DEBUG [org.jboss.system.ServiceController] starting service jboss.system:type=Log4jService,service=Logging
      2009-06-16 17:59:03,382 DEBUG [org.jboss.logging.Log4jService] Starting jboss.system:type=Log4jService,service=Logging
      2009-06-16 17:59:03,382 DEBUG [org.jboss.logging.Log4jService] Started jboss.system:type=Log4jService,service=Logging
      2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.system:type=Log4jService,service=Logging dependent components: []
      2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] starting service jboss.rmi:type=RMIClassLoader
      2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.rmi:type=RMIClassLoader dependent components: []
      2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=WebService
      2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=WebService
      2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=NamingBeanImpl
      2009-06-16 17:59:03,397 DEBUG [org.jnp.server.NamingBeanImpl] Using NamingServer: org.jnp.server.NamingServer@3ef07355
      2009-06-16 17:59:03,397 DEBUG [org.jnp.server.NamingBeanImpl] Installed global NamingServer: org.jnp.server.NamingServer@3ef07355
      2009-06-16 17:59:03,402 DEBUG [org.jnp.server.NamingBeanImpl] InitialContext Environment:
      2009-06-16 17:59:03,402 DEBUG [org.jnp.server.NamingBeanImpl] key=java.naming.factory.initial, value(java.lang.String)=org.jnp.interfaces.NamingContextFactory
      2009-06-16 17:59:03,402 DEBUG [org.jnp.server.NamingBeanImpl] key=java.naming.factory.url.pkgs, value(java.lang.String)=org.jboss.naming:org.jnp.interfaces
      2009-06-16 17:59:03,407 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=NamingBeanImpl dependent components: [ObjectName: jboss:service=Naming
      State: CREATED
      I Depend On:
      jboss.system:service=ThreadPool
      jboss:service=NamingBeanImpl
      ]
      2009-06-16 17:59:03,407 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=Naming
      2009-06-16 17:59:03,408 DEBUG [org.jboss.naming.NamingService] Starting jboss:service=Naming
      2009-06-16 17:59:03,408 DEBUG [org.jboss.naming.NamingService] System.setProperty, key=java.naming.factory.initial, value=org.jnp.interfaces.NamingContextFactory
      2009-06-16 17:59:03,408 DEBUG [org.jboss.naming.NamingService] System.setProperty, key=java.naming.factory.url.pkgs, value=org.jboss.naming:org.jnp.interfaces
      2009-06-16 17:59:03,409 DEBUG [org.jboss.naming.Naming] Creating NamingServer stub, theServer=MBeanProxyExt[jboss:service=NamingBeanImpl],rmiPort=1098,clientSocketFactory=null,serverSocketFactory=org.jboss.net.sockets.DefaultSocketFactory@41cb2704[bindAddress=rllsirfpas01b.local.sirfarma.it/10.145.9.3]
      2009-06-16 17:59:03,581 DEBUG [org.jboss.naming.Naming] NamingServer stub: NamingServer_Stub[UnicastRef2 [liveRef: [endpoint:[rllsirfpas01b.local.sirfarma.it:1098,org.jboss.net.sockets.DefaultSocketFactory@41cb2704[bindAddress=rllsirfpas01b.local.sirfarma.it/10.145.9.3]](local),objID:[-3079be79:121e9cafef2:-7fff, -6987809006595528208]]]]
      2009-06-16 17:59:03,594 DEBUG [org.jboss.naming.Naming] JNDI bootstrap JNP=rllsirfpas01b.local.sirfarma.it/10.145.9.3:1099, RMI=rllsirfpas01b.local.sirfarma.it/10.145.9.3:1098, backlog=50, no client SocketFactory, Server SocketFactory=org.jboss.net.sockets.DefaultSocketFactory@41cb2704[bindAddress=rllsirfpas01b.local.sirfarma.it/10.145.9.3]
      2009-06-16 17:59:03,603 DEBUG [org.jboss.naming.NamingService] Started jboss:service=Naming
      2009-06-16 17:59:03,603 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=Naming dependent components: []
      2009-06-16 17:59:03,603 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=Naming
      2009-06-16 17:59:03,603 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=Naming
      2009-06-16 17:59:03,603 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=JNDIView
      2009-06-16 17:59:03,603 DEBUG [org.jboss.naming.JNDIView] Starting jboss:service=JNDIView
      2009-06-16 17:59:03,603 DEBUG [org.jboss.naming.JNDIView] Started jboss:service=JNDIView
      2009-06-16 17:59:03,604 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=JNDIView dependent components: []
      2009-06-16 17:59:03,604 DEBUG [org.jboss.system.ServiceController] starting service jboss.security:service=SecurityConfig
      2009-06-16 17:59:03,604 DEBUG [org.jboss.security.plugins.SecurityConfig] Starting jboss.security:service=SecurityConfig
      2009-06-16 17:59:03,605 DEBUG [org.jboss.security.plugins.SecurityConfig] Installed JAAS Configuration service=jboss.security:service=XMLLoginConfig, config=org.jboss.security.auth.login.XMLLoginConfigImpl@6b9fe451
      2009-06-16 17:59:03,605 DEBUG [org.jboss.security.plugins.SecurityConfig] Started jboss.security:service=SecurityConfig
      2009-06-16 17:59:03,605 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.security:service=SecurityConfig dependent components: []
      2009-06-16 17:59:03,605 DEBUG [org.jboss.system.ServiceController] starting service jboss.security:service=XMLLoginConfig
      2009-06-16 17:59:03,605 DEBUG [org.jboss.security.auth.login.XMLLoginConfig] Starting jboss.security:service=XMLLoginConfig
      2009-06-16 17:59:03,606 DEBUG [org.jboss.security.auth.login.XMLLoginConfigImpl] Try loading config as XML, url=file:/usr/local/jboss-4.2.2.GA/server/all/conf/login-config.xml
      2009-06-16 17:59:03,655 DEBUG [org.jboss.security.auth.login.XMLLoginConfig] Started jboss.security:service=XMLLoginConfig
      2009-06-16 17:59:03,655 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.security:service=XMLLoginConfig dependent components: []
      2009-06-16 17:59:03,655 DEBUG [org.jboss.system.ServiceController] starting service jboss.security:service=JaasSecurityManager
      2009-06-16 17:59:03,655 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Starting jboss.security:service=JaasSecurityManager
      2009-06-16 17:59:03,664 DEBUG [org.jboss.security.SecurityAssociation] Using ThreadLocal: false
      2009-06-16 17:59:03,673 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] securityMgrCtxPath=java:/jaas
      2009-06-16 17:59:03,674 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] cachePolicyCtxPath=java:/timedCacheFactory
      2009-06-16 17:59:03,677 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] SecurityProxyFactory=org.jboss.security.SubjectSecurityProxyFactory@6f18278a
      2009-06-16 17:59:03,679 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Registered PrincipalEditor, SecurityDomainEditor
      2009-06-16 17:59:03,679 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] ServerMode=true
      2009-06-16 17:59:03,679 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] SecurityMgrClass=class org.jboss.security.plugins.JaasSecurityManager
      2009-06-16 17:59:03,679 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] CallbackHandlerClass=class org.jboss.security.auth.callback.SecurityAssociationHandler
      2009-06-16 17:59:03,679 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Started jboss.security:service=JaasSecurityManager
      2009-06-16 17:59:03,679 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.security:service=JaasSecurityManager dependent components: []
      2009-06-16 17:59:03,680 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=XidFactory
      2009-06-16 17:59:03,680 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=XidFactory dependent components: []
      2009-06-16 17:59:03,680 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=TransactionManager
      2009-06-16 17:59:03,680 DEBUG [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Starting jboss:service=TransactionManager
      2009-06-16 17:59:03,680 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] JBossTS Transaction Service (JTA version) - JBoss Inc.
      2009-06-16 17:59:03,680 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Setting up property manager MBean and JMX layer
      2009-06-16 17:59:03,820 DEBUG [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.arjuna.recovery.TransactionStatusManager_1] - Starting service com.arjuna.ats.arjuna.recovery.ActionStatusService on port 56056
      2009-06-16 17:59:03,825 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] TransactionStatusManagerItem - host: 10.145.9.3 port: 56056
      2009-06-16 17:59:03,832 DEBUG [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.arjuna.recovery.TransactionStatusManager_3] - TransactionStatusManager started on port 56056 with service com.arjuna.ats.arjuna.recovery.ActionStatusService
      2009-06-16 17:59:03,842 DEBUG [com.arjuna.ats.tsmx.logging.tsmxLogger] Registering mbean for module 'arjuna'
      2009-06-16 17:59:03,846 DEBUG [com.arjuna.ats.tsmx.logging.tsmxLogger] Initialising JMX agent com.arjuna.ats.internal.jbossatx.agent.LocalJBossAgentImpl
      2009-06-16 17:59:03,851 DEBUG [com.arjuna.ats.tsmx.logging.tsmxLogger] Registering mbean for module 'jta'
      2009-06-16 17:59:03,853 DEBUG [com.arjuna.ats.tsmx.logging.tsmxLogger] Registering mbean for module 'txoj'
      2009-06-16 17:59:03,872 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Starting recovery manager
      2009-06-16 17:59:03,878 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger]
      --- Start RecoveryActivators
      2009-06-16 17:59:03,887 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger]
      --- ExpiredEntryMonitor ----Tue, 16 Jun 2009 17:59:03----
      2009-06-16 17:59:03,978 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.noxanodes] No XA recovery nodes specified. Will only recover saved states.
      2009-06-16 17:59:03,982 DEBUG [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.internal.arjuna.recovery.ready] RecoveryManagerImple is ready on port 33,975
      2009-06-16 17:59:03,983 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Recovery manager started
      2009-06-16 17:59:03,983 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Binding TransactionManager JNDI Reference
      2009-06-16 17:59:03,995 DEBUG [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Started jboss:service=TransactionManager
      2009-06-16 17:59:03,996 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=TransactionManager dependent components: [ObjectName: jboss:service=invoker,type=unified
      State: CREATED
      I Depend On:
      jboss:service=TransactionManager
      jboss.remoting:service=Connector,transport=socket
      , ObjectName: jboss:service=invoker,type=jrmp
      State: CREATED
      I Depend On:
      jboss:service=TransactionManager
      Depends On Me:
      jboss:service=proxyFactory,target=ClientUserTransactionFactory
      jboss:service=proxyFactory,target=ClientUserTransaction
      , ObjectName: jboss:service=invoker,type=local
      State: CREATED
      I Depend On:
      jboss:service=TransactionManager
      , ObjectName: jboss:service=invoker,type=pooled
      State: CREATED
      I Depend On:
      jboss:service=TransactionManager
      ]
      2009-06-16 17:59:03,996 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=unified
      2009-06-16 17:59:03,996 DEBUG [org.jboss.system.ServiceController] waiting in start jboss:service=invoker,type=unified on jboss.remoting:service=Connector,transport=socket
      2009-06-16 17:59:03,996 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=jrmp
      2009-06-16 17:59:03,996 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Starting jboss:service=invoker,type=jrmp
      2009-06-16 17:59:04,001 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] RMI Port='4444'
      2009-06-16 17:59:04,001 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Client SocketFactory='Default'
      2009-06-16 17:59:04,001 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Server SocketFactory='org.jboss.net.sockets.DefaultSocketFactory@41cb2704[bindAddress=rllsirfpas01b.local.sirfarma.it/10.145.9.3]'
      2009-06-16 17:59:04,001 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Server SocketAddr='rllsirfpas01b.local.sirfarma.it'
      2009-06-16 17:59:04,001 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] SecurityDomain='Default'
      2009-06-16 17:59:04,016 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Bound JRMP invoker for JMX node
      2009-06-16 17:59:04,016 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Started jboss:service=invoker,type=jrmp
      2009-06-16 17:59:04,016 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=jrmp dependent components: [ObjectName: jboss:service=proxyFactory,target=ClientUserTransactionFactory
      State: CREATED
      I Depend On:
      jboss:service=invoker,type=jrmp
      Depends On Me:
      jboss:service=ClientUserTransaction
      , ObjectName: jboss:service=proxyFactory,target=ClientUserTransaction
      State: CREATED
      I Depend On:
      jboss:service=invoker,type=jrmp
      Depends On Me:
      jboss:service=ClientUserTransaction
      ]
      2009-06-16 17:59:04,016 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=proxyFactory,target=ClientUserTransactionFactory
      2009-06-16 17:59:04,016 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Starting jboss:service=proxyFactory,target=ClientUserTransactionFactory
      2009-06-16 17:59:04,025 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Created JRMPPRoxy for service=jboss:service=ClientUserTransaction, nameHash=-2097396599, invoker=jboss:service=invoker,type=jrmp
      2009-06-16 17:59:04,045 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Bound proxy under jndiName=UserTransactionSessionFactory
      2009-06-16 17:59:04,045 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Started jboss:service=proxyFactory,target=ClientUserTransactionFactory
      2009-06-16 17:59:04,046 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=proxyFactory,target=ClientUserTransactionFactory dependent components: [ObjectName: jboss:service=ClientUserTransaction
      State: CREATED
      I Depend On:
      jboss:service=proxyFactory,target=ClientUserTransactionFactory
      jboss:service=proxyFactory,target=ClientUserTransaction
      ]
      2009-06-16 17:59:04,046 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=ClientUserTransaction
      2009-06-16 17:59:04,046 DEBUG [org.jboss.system.ServiceController] waiting in start jboss:service=ClientUserTransaction on jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:04,046 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:04,046 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Starting jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:04,049 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Created JRMPPRoxy for service=jboss:service=ClientUserTransaction, nameHash=2024786241, invoker=jboss:service=invoker,type=jrmp
      2009-06-16 17:59:04,051 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Started jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:04,051 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=proxyFactory,target=ClientUserTransaction dependent components: [ObjectName: jboss:service=ClientUserTransaction
      State: CREATED
      I Depend On:
      jboss:service=proxyFactory,target=ClientUserTransactionFactory
      jboss:service=proxyFactory,target=ClientUserTransaction
      ]
      2009-06-16 17:59:04,051 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=ClientUserTransaction
      2009-06-16 17:59:04,052 DEBUG [org.jboss.tm.usertx.server.ClientUserTransactionService] Starting jboss:service=ClientUserTransaction
      2009-06-16 17:59:04,055 DEBUG [org.jboss.tm.usertx.server.ClientUserTransactionService] Started jboss:service=ClientUserTransaction
      2009-06-16 17:59:04,055 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=ClientUserTransaction dependent components: []
      2009-06-16 17:59:04,055 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=local
      2009-06-16 17:59:04,055 DEBUG [org.jboss.invocation.local.LocalInvoker] Starting jboss:service=invoker,type=local
      2009-06-16 17:59:04,058 DEBUG [org.jboss.invocation.local.LocalInvoker] Local invoker for JMX node started
      2009-06-16 17:59:04,058 DEBUG [org.jboss.invocation.local.LocalInvoker] Started jboss:service=invoker,type=local
      2009-06-16 17:59:04,058 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=local dependent components: []
      2009-06-16 17:59:04,058 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=pooled
      2009-06-16 17:59:04,058 DEBUG [org.jboss.invocation.pooled.server.PooledInvoker] Starting jboss:service=invoker,type=pooled
      2009-06-16 17:59:04,067 DEBUG [org.jboss.invocation.pooled.server.PooledInvoker] Bound invoker for JMX node
      2009-06-16 17:59:04,067 DEBUG [org.jboss.invocation.pooled.server.PooledInvoker] Started jboss:service=invoker,type=pooled
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=pooled dependent components: []
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=ClientUserTransaction
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=ClientUserTransaction
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=proxyFactory,target=ClientUserTransactionFactory
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=proxyFactory,target=ClientUserTransactionFactory
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=proxyFactory,target=ClientUserTransaction
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=unified
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] waiting in start jboss:service=invoker,type=unified on jboss.remoting:service=Connector,transport=socket
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=jrmp
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=invoker,type=jrmp
      2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=local
      2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=invoker,type=local
      2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=pooled
      2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=invoker,type=pooled
      2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] starting service jboss.remoting:service=NetworkRegistry
      2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.remoting:service=NetworkRegistry dependent components: [ObjectName: jboss.remoting:service=Connector,transport=socket
      State: CREATED
      I Depend On:
      jboss.remoting:service=NetworkRegistry
      Depends On Me:
      jboss:service=invoker,type=unified
      ]
      2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] starting service jboss.remoting:service=Connector,transport=socket
      2009-06-16 17:59:04,073 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] added org.jboss.invocation.unified.server.UnifiedInvoker@44aea710 for subsystem 'invoker'
      2009-06-16 17:59:04,073 DEBUG [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[UNINITIALIZED] starting
      2009-06-16 17:59:04,075 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[10.145.9.3:4446] started for locator InvokerLocator [socket://rllsirfpas01b.local.sirfarma.it:4446/?dataType=invocation&enableTcpNoDelay=true&marshaller=org.jboss.invocation.unified.marshall.InvocationMarshaller&socketTimeout=600000&unmarshaller=org.jboss.invocation.unified.marshall.InvocationUnMarshaller]
      2009-06-16 17:59:04,075 DEBUG [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[10.145.9.3:4446] started
      2009-06-16 17:59:04,075 DEBUG [org.jboss.remoting.transport.Connector] org.jboss.remoting.transport.Connector@524c71d2 started
      2009-06-16 17:59:04,075 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.remoting:service=Connector,transport=socket dependent components: [ObjectName: jboss:service=invoker,type=unified
      State: CREATED
      I Depend On:
      jboss:service=TransactionManager
      jboss.remoting:service=Connector,transport=socket
      ]
      2009-06-16 17:59:04,075 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=unified
      2009-06-16 17:59:04,076 DEBUG [org.jboss.invocation.unified.server.UnifiedInvoker] Starting jboss:service=invoker,type=unified
      2009-06-16 17:59:04,076 DEBUG [org.jboss.invocation.unified.server.UnifiedInvoker] Starting unified invoker service.
      2009-06-16 17:59:04,087 DEBUG [org.jboss.invocation.unified.server.UnifiedInvoker] Started jboss:service=invoker,type=unified
      2009-06-16 17:59:04,087 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=unified dependent components: []
      2009-06-16 17:59:04,087 DEBUG [org.jboss.system.ServiceController] starting service jboss.remoting:service=Connector,transport=socket
      2009-06-16 17:59:04,087 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss.remoting:service=Connector,transport=socket
      2009-06-16 17:59:04,087 DEBUG [org.jboss.system.ServiceController] starting service jboss.jdbc:service=metadata
      2009-06-16 17:59:04,087 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] Starting jboss.jdbc:service=metadata
      2009-06-16 17:59:04,088 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] Loading standardjbosscmp-jdbc.xml : file:/usr/local/jboss-4.2.2.GA/server/all/conf/standardjbosscmp-jdbc.xml
      2009-06-16 17:59:04,210 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: FirstSQL/J
      2009-06-16 17:59:04,217 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Ingres
      2009-06-16 17:59:04,225 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: McKoi
      2009-06-16 17:59:04,234 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Firebird
      2009-06-16 17:59:04,243 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: InterBase
      2009-06-16 17:59:04,252 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: DB2
      2009-06-16 17:59:04,264 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Derby
      2009-06-16 17:59:04,276 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Oracle9i
      2009-06-16 17:59:04,289 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Oracle8
      2009-06-16 17:59:04,302 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Oracle7
      2009-06-16 17:59:04,312 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Sybase
      2009-06-16 17:59:04,326 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: PostgreSQL
      2009-06-16 17:59:04,342 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: PostgreSQL 8.0
      2009-06-16 17:59:04,359 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: PostgreSQL 7.2
      2009-06-16 17:59:04,376 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Hypersonic SQL
      2009-06-16 17:59:04,388 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: PointBase
      2009-06-16 17:59:04,396 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: SOLID
      2009-06-16 17:59:04,406 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: mySQL
      2009-06-16 17:59:04,414 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: MS SQLSERVER
      2009-06-16 17:59:04,419 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: MS SQLSERVER2000
      2009-06-16 17:59:04,423 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: DB2/400
      2009-06-16 17:59:04,427 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: SapDB
      2009-06-16 17:59:04,431 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: MaxDB
      2009-06-16 17:59:04,435 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Cloudscape
      2009-06-16 17:59:04,439 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: InformixDB
      2009-06-16 17:59:04,442 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Mimer SQL
      2009-06-16 17:59:04,442 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] Started jboss.jdbc:service=metadata
      2009-06-16 17:59:04,443 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.jdbc:service=metadata dependent components: []
      2009-06-16 17:59:04,443 DEBUG [org.jboss.system.ServiceController] starting service jboss.deployment:type=DeploymentScanner,flavor=URL
      2009-06-16 17:59:04,443 DEBUG [org.jboss.deployment.scanner.URLDeploymentScanner] Starting jboss.deployment:type=DeploymentScanner,flavor=URL
      2009-06-16 17:59:04,451 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment of package: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
      2009-06-16 17:59:04,451 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
      2009-06-16 17:59:04,451 DEBUG [org.jboss.deployment.MainDeployer] using deployer org.jboss.deployment.SARDeployer@4a53fb57
      2009-06-16 17:59:04,453 DEBUG [org.jboss.deployment.SARDeployer] Updated watch URL to: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/META-INF/jboss-service.xml
      2009-06-16 17:59:04,460 DEBUG [org.jboss.deployment.SARDeployer] about to copy 0 local directories
      2009-06-16 17:59:04,461 DEBUG [org.jboss.deployment.SARDeployer] looking for nested deployments in : file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
      2009-06-16 17:59:04,461 DEBUG [org.jboss.deployment.SARDeployer] nested deployment: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
      2009-06-16 17:59:04,462 DEBUG [org.jboss.deployment.SARDeployer] nested deployment: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
      2009-06-16 17:59:04,462 DEBUG [org.jboss.deployment.DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
      2009-06-16 17:59:04,462 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@1d10c424, cl=org.jboss.mx.loading.UnifiedClassLoader3@6d66f9b9{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ ,addedOrder=0}
      2009-06-16 17:59:04,462 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@1d10c424, cl=org.jboss.mx.loading.UnifiedClassLoader3@6d66f9b9{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ ,addedOrder=0}
      2009-06-16 17:59:04,463 DEBUG [org.jboss.mx.loading.UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@6d66f9b9{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ ,addedOrder=0}
      2009-06-16 17:59:04,463 DEBUG [org.jboss.mx.loading.ClassLoaderUtils] Multiple class loaders found for pkg:
      2009-06-16 17:59:04,464 DEBUG [org.jboss.deployment.MainDeployer] found 2 subpackages of file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
      2009-06-16 17:59:04,464 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
      2009-06-16 17:59:04,464 DEBUG [org.jboss.deployment.MainDeployer] Copying file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar -> /usr/local/jboss-4.2.2.GA/server/all/tmp/deploy/tmp27638jboss-annotations-ejb3.jar
      2009-06-16 17:59:04,467 DEBUG [org.jboss.deployment.JARDeployer] No xml files found
      2009-06-16 17:59:04,467 DEBUG [org.jboss.deployment.MainDeployer] using deployer org.jboss.deployment.JARDeployer@671ff436
      2009-06-16 17:59:04,467 DEBUG [org.jboss.deployment.JARDeployer] looking for nested deployments in : file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
      2009-06-16 17:59:04,482 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] Added url: file:/usr/local/jboss-4.2.2.GA/server/all/tmp/deploy/tmp27638jboss-annotations-ejb3.jar, to ucl: org.jboss.mx.loading.UnifiedClassLoader3@6d66f9b9{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ ,addedOrder=3}
      2009-06-16 17:59:04,483 DEBUG [org.jboss.deployment.MainDeployer] found 0 subpackages of file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
      2009-06-16 17:59:04,483 DEBUG [org.jboss.deployment.MainDeployer] Watching new file: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
      2009-06-16 17:59:04,483 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
      2009-06-16 17:59:04,484 DEBUG [org.jboss.deployment.MainDeployer] Copying file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar -> /usr/local/jboss-4.2.2.GA/server/all/tmp/deploy/tmp27639jboss-ejb3.jar
      2009-06-16 17:59:04,495 DEBUG [org.jboss.deployment.JARDeployer] No xml files found
      2009-06-16 17:59:04,495 DEBUG [org.jboss.deployment.MainDeployer] using deployer org.jboss.deployment.JARDeployer@671ff436
      2009-06-16 17:59:04,495 DEBUG [org.jboss.deployment.JARDeployer] looking for nested deployments in : file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
      2009-06-16 17:59:04,559 DEBUG [org.jboss.mx.loading.ClassLoaderUtils] Multiple class loaders found for pkg: org.jboss.ejb
      2009-06-16 17:59:04,602 DEBUG [org.jboss.mx.loading.ClassLoaderUtils] Multiple class loaders found for pkg: schema
      2009-06-16 17:59:04,607 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] Added url: file:/usr/local/jboss-4.2.2.GA/server/all/tmp/deploy/tmp27639jboss-ejb3.jar, to ucl: org.jboss.mx.loading.UnifiedClassLoader3@6d66f9b9{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ ,addedOrder=3}
      2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] found 0 subpackages of file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
      2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] Watching new file: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
      2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] Watching new file: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
      2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] create step for deployment file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
      2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] create step for deployment file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
      2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying jboss-annotations-ejb3.jar
      2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] create step for deployment file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
      2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying jboss-ejb3.jar
      2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.SARDeployer] Deploying SAR, create step: url file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
      2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.SARDeployer] Registering service UCL=jmx.loading:UCL=6d66f9b9
      2009-06-16 17:59:04,610 DEBUG [org.jboss.system.ServiceCreator] About to create bean: jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3 with code: org.jboss.remoting.transport.Connector
      2009-06-16 17:59:04,613 DEBUG [org.jboss.system.ServiceCreator] Created bean: jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
      2009-06-16 17:59:04,614 DEBUG [org.jboss.system.ServiceController] recording that jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3 depends on jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,614 DEBUG [org.jboss.system.ServiceConfigurator] considering with object name jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,615 DEBUG [org.jboss.system.ServiceConfigurator] InvokerLocator set to socket://rllsirfpas01b.local.sirfarma.it:3873 in jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
      2009-06-16 17:59:04,618 DEBUG [org.jboss.system.ServiceConfigurator] Configuration set to [handlers: null] in jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
      2009-06-16 17:59:04,619 DEBUG [org.jboss.system.ServiceCreator] About to create bean: jboss.ejb3:service=JarsIgnoredForScanning with code: org.jboss.ejb3.JarsIgnoredForScanning
      2009-06-16 17:59:04,624 DEBUG [org.jboss.system.ServiceCreator] Created bean: jboss.ejb3:service=JarsIgnoredForScanning
      2009-06-16 17:59:04,625 DEBUG [org.jboss.system.ServiceConfigurator] IgnoredJars set to [Ljava.lang.String;@6e5dfaf1 in jboss.ejb3:service=JarsIgnoredForScanning
      2009-06-16 17:59:04,626 DEBUG [org.jboss.system.ServiceCreator] About to create xmbean object: jboss.ejb3:service=EJB3Deployer with code: org.jboss.ejb3.EJB3Deployer with embedded descriptor
      2009-06-16 17:59:04,651 DEBUG [org.jboss.system.ServiceCreator] Created bean: jboss.ejb3:service=EJB3Deployer
      2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceConfigurator] DeployEjb3ExtensionOnly set to false in jboss.ejb3:service=EJB3Deployer
      2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceController] recording that jboss.ejb3:service=EJB3Deployer depends on jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceConfigurator] considering with object name jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceController] recording that jboss.ejb3:service=EJB3Deployer depends on jboss.ejb:service=EJBDeployer
      2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceConfigurator] considering with object name jboss.ejb:service=EJBDeployer
      2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceController] recording that jboss.ejb3:service=EJB3Deployer depends on jboss.ejb3:service=JarsIgnoredForScanning
      2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceConfigurator] considering JarsIgnoredForScanning with object name jboss.ejb3:service=JarsIgnoredForScanning
      2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] Creating service jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
      2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3 waiting on jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb3:service=JarsIgnoredForScanning
      2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.ejb3:service=JarsIgnoredForScanning dependents are: [ObjectName: jboss.ejb3:service=EJB3Deployer
      State: CONFIGURED
      I Depend On:
      jboss.aop:service=AspectDeployer
      jboss.ejb:service=EJBDeployer
      jboss.ejb3:service=JarsIgnoredForScanning
      ]
      2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb3:service=EJB3Deployer
      2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.ejb3:service=EJB3Deployer waiting on jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb3:service=EJB3Deployer
      2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.ejb3:service=EJB3Deployer waiting on jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,656 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying ejb3.deployer
      2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
      2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
      2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.MainDeployer] End deployment start on package: jboss-annotations-ejb3.jar
      2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
      2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.MainDeployer] End deployment start on package: jboss-ejb3.jar
      2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.SARDeployer] Deploying SAR, start step: url file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
      2009-06-16 17:59:04,657 DEBUG [org.jboss.system.ServiceController] starting service jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
      2009-06-16 17:59:04,657 DEBUG [org.jboss.system.ServiceController] Start requested before create, calling create now for service: jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
      2009-06-16 17:59:04,657 DEBUG [org.jboss.system.ServiceController] Creating service jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
      2009-06-16 17:59:04,657 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3 waiting on jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] waiting in start jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3 on jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] starting service jboss.ejb3:service=JarsIgnoredForScanning
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.ejb3:service=JarsIgnoredForScanning dependent components: [ObjectName: jboss.ejb3:service=EJB3Deployer
      State: CONFIGURED
      I Depend On:
      jboss.aop:service=AspectDeployer
      jboss.ejb:service=EJBDeployer
      jboss.ejb3:service=JarsIgnoredForScanning
      ]
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] starting service jboss.ejb3:service=EJB3Deployer
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] Start requested before create, calling create now for service: jboss.ejb3:service=EJB3Deployer
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb3:service=EJB3Deployer
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.ejb3:service=EJB3Deployer waiting on jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] waiting in start jboss.ejb3:service=EJB3Deployer on jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] starting service jboss.ejb3:service=EJB3Deployer
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] Start requested before create, calling create now for service: jboss.ejb3:service=EJB3Deployer
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb3:service=EJB3Deployer
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.ejb3:service=EJB3Deployer waiting on jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] waiting in start jboss.ejb3:service=EJB3Deployer on jboss.aop:service=AspectDeployer
      2009-06-16 17:59:04,685 DEBUG [org.jboss.deployment.MainDeployer] End deployment start on package: ejb3.deployer
      2009-06-16 17:59:04,685 DEBUG [org.jboss.deployment.MainDeployer] Deployed package: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
      2009-06-16 17:59:04,687 DEBUG [org.jboss.deployment.scanner.URLDeploymentScanner] Watch URL for: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ -> file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/META-INF/jboss-service.xml
      2009-06-16 17:59:04,688 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment of package: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/
      2009-06-16 17:59:04,688 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/
      2009-06-16 17:59:04,688 DEBUG [org.jboss.deployment.MainDeployer] using deployer org.jboss.deployment.SARDeployer@4a53fb57
      2009-06-16 17:59:04,689 DEBUG [org.jboss.deployment.SARDeployer] Updated watch URL to: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/META-INF/jboss-service.xml
      2009-06-16 17:59:04,691 DEBUG [org.jboss.deployment.SARDeployer] about to copy 0 local directories
      2009-06-16 17:59:04,691 DEBUG [org.jboss.deployment.SARDeployer] looking for nested deployments in : file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/
      2009-06-16 17:59:04,691 DEBUG [org.jboss.deployment.SARDeployer] nested deployment: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/jboss-aop-jdk50.jar
      2009-06-16 17:59:04,692 DEBUG [org.jboss.deployment.SARDeployer] nested deployment: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/trove.jar
      2009-06-16 17:59:04,692 DEBUG [org.jboss.deployment.SARDeployer] nested deployment: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/jboss-aspect-library-jdk50.jar
      2009-06-16 17:59:04,693 DEBUG [org.jboss.deployment.DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
      2009-06-16 17:59:04,693 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@1d10c424, cl=org.jboss.mx.loading.UnifiedClassLoader3@39320a41{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/ ,addedOrder=0}
      2009-06-16 17:59:04,693 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@1d10c424, cl=org.jboss.mx.loading.UnifiedClassLoader3@39320a41{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/ ,addedOrder=0}
      2009-06-16 17:59:04,693 DEBUG [org.jboss.mx.loading.UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@39320a41{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/ ,addedOrder=0}
      2009-06-16 17:59:04,693 DEBUG [org.jboss.mx.loading.ClassLoaderUtils] Multiple class loaders found for pkg:
      2009-06-16 17:59:04,694 DEBUG [org.jboss.deployment.MainDeployer] found 3 subpackages of file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/
      2009-06-16 17:59:04,694 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/jb

        • 1. Re: JBoss startup delay
          Pierangelo Repetti Newbie

          Looks like I did only paste a short part of the log file.
          I try again.
          P


          2009-06-16 17:59:03,188 DEBUG [org.jboss.logging.Log4jService] Installed System.out adapter
          2009-06-16 17:59:03,189 DEBUG [org.jboss.logging.Log4jService] Installed System.err adapter
          2009-06-16 17:59:03,191 DEBUG [org.jboss.logging.Log4jService] Added ThrowableListener: org.jboss.logging.Log4jService$ThrowableListenerLoggingAdapter@37722456
          2009-06-16 17:59:03,192 DEBUG [org.jboss.logging.Log4jService] Created jboss.system:type=Log4jService,service=Logging
          2009-06-16 17:59:03,192 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.system:type=Log4jService,service=Logging dependents are: []
          2009-06-16 17:59:03,192 DEBUG [org.jboss.system.ServiceController] Creating service jboss.rmi:type=RMIClassLoader
          2009-06-16 17:59:03,193 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.rmi:type=RMIClassLoader dependents are: []
          2009-06-16 17:59:03,193 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=WebService
          2009-06-16 17:59:03,193 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=WebService
          2009-06-16 17:59:03,193 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=NamingBeanImpl
          2009-06-16 17:59:03,193 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=NamingBeanImpl dependents are: [ObjectName: jboss:service=Naming
          State: CONFIGURED
          I Depend On:
          jboss.system:service=ThreadPool
          jboss:service=NamingBeanImpl
          ]
          2009-06-16 17:59:03,194 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=Naming
          2009-06-16 17:59:03,194 DEBUG [org.jboss.naming.NamingService] Creating jboss:service=Naming
          2009-06-16 17:59:03,194 DEBUG [org.jboss.naming.NamingService] Created jboss:service=Naming
          2009-06-16 17:59:03,213 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=Naming dependents are: []
          2009-06-16 17:59:03,213 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=Naming
          2009-06-16 17:59:03,213 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=Naming
          2009-06-16 17:59:03,213 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=JNDIView
          2009-06-16 17:59:03,213 DEBUG [org.jboss.naming.JNDIView] Creating jboss:service=JNDIView
          2009-06-16 17:59:03,214 DEBUG [org.jboss.naming.JNDIView] Created jboss:service=JNDIView
          2009-06-16 17:59:03,214 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=JNDIView dependents are: []
          2009-06-16 17:59:03,214 DEBUG [org.jboss.system.ServiceController] Creating service jboss.security:service=SecurityConfig
          2009-06-16 17:59:03,214 DEBUG [org.jboss.security.plugins.SecurityConfig] Creating jboss.security:service=SecurityConfig
          2009-06-16 17:59:03,214 DEBUG [org.jboss.security.plugins.SecurityConfig] Created jboss.security:service=SecurityConfig
          2009-06-16 17:59:03,214 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.security:service=SecurityConfig dependents are: []
          2009-06-16 17:59:03,214 DEBUG [org.jboss.system.ServiceController] Creating service jboss.security:service=XMLLoginConfig
          2009-06-16 17:59:03,214 DEBUG [org.jboss.security.auth.login.XMLLoginConfig] Creating jboss.security:service=XMLLoginConfig
          2009-06-16 17:59:03,214 DEBUG [org.jboss.security.auth.login.XMLLoginConfig] Created jboss.security:service=XMLLoginConfig
          2009-06-16 17:59:03,215 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.security:service=XMLLoginConfig dependents are: []
          2009-06-16 17:59:03,215 DEBUG [org.jboss.system.ServiceController] Creating service jboss.security:service=JaasSecurityManager
          2009-06-16 17:59:03,215 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Creating jboss.security:service=JaasSecurityManager
          2009-06-16 17:59:03,215 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Created jboss.security:service=JaasSecurityManager
          2009-06-16 17:59:03,215 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.security:service=JaasSecurityManager dependents are: []
          2009-06-16 17:59:03,217 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=XidFactory
          2009-06-16 17:59:03,218 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=XidFactory dependents are: []
          2009-06-16 17:59:03,218 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=TransactionManager
          2009-06-16 17:59:03,218 DEBUG [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Creating jboss:service=TransactionManager
          2009-06-16 17:59:03,218 DEBUG [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Created jboss:service=TransactionManager
          2009-06-16 17:59:03,227 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=TransactionManager dependents are: [ObjectName: jboss:service=invoker,type=unified
          State: CONFIGURED
          I Depend On:
          jboss:service=TransactionManager
          jboss.remoting:service=Connector,transport=socket
          , ObjectName: jboss:service=invoker,type=jrmp
          State: CONFIGURED
          I Depend On:
          jboss:service=TransactionManager
          Depends On Me:
          jboss:service=proxyFactory,target=ClientUserTransactionFactory
          jboss:service=proxyFactory,target=ClientUserTransaction
          , ObjectName: jboss:service=invoker,type=local
          State: CONFIGURED
          I Depend On:
          jboss:service=TransactionManager
          , ObjectName: jboss:service=invoker,type=pooled
          State: CONFIGURED
          I Depend On:
          jboss:service=TransactionManager
          ]
          2009-06-16 17:59:03,227 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=unified
          2009-06-16 17:59:03,227 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss:service=invoker,type=unified waiting on jboss.remoting:service=Connector,transport=socket
          2009-06-16 17:59:03,227 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=jrmp
          2009-06-16 17:59:03,228 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Creating jboss:service=invoker,type=jrmp
          2009-06-16 17:59:03,228 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Created jboss:service=invoker,type=jrmp
          2009-06-16 17:59:03,228 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=jrmp dependents are: [ObjectName: jboss:service=proxyFactory,target=ClientUserTransactionFactory
          State: CONFIGURED
          I Depend On:
          jboss:service=invoker,type=jrmp
          Depends On Me:
          jboss:service=ClientUserTransaction
          , ObjectName: jboss:service=proxyFactory,target=ClientUserTransaction
          State: CONFIGURED
          I Depend On:
          jboss:service=invoker,type=jrmp
          Depends On Me:
          jboss:service=ClientUserTransaction
          ]
          2009-06-16 17:59:03,228 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=proxyFactory,target=ClientUserTransactionFactory
          2009-06-16 17:59:03,229 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Creating jboss:service=proxyFactory,target=ClientUserTransactionFactory
          2009-06-16 17:59:03,229 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Created jboss:service=proxyFactory,target=ClientUserTransactionFactory
          2009-06-16 17:59:03,230 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=proxyFactory,target=ClientUserTransactionFactory dependents are: [ObjectName: jboss:service=ClientUserTransaction
          State: CONFIGURED
          I Depend On:
          jboss:service=proxyFactory,target=ClientUserTransactionFactory
          jboss:service=proxyFactory,target=ClientUserTransaction
          ]
          2009-06-16 17:59:03,230 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=ClientUserTransaction
          2009-06-16 17:59:03,231 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss:service=ClientUserTransaction waiting on jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:03,231 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:03,231 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Creating jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:03,231 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Created jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:03,231 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=proxyFactory,target=ClientUserTransaction dependents are: [ObjectName: jboss:service=ClientUserTransaction
          State: CONFIGURED
          I Depend On:
          jboss:service=proxyFactory,target=ClientUserTransactionFactory
          jboss:service=proxyFactory,target=ClientUserTransaction
          ]
          2009-06-16 17:59:03,231 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=ClientUserTransaction
          2009-06-16 17:59:03,231 DEBUG [org.jboss.tm.usertx.server.ClientUserTransactionService] Creating jboss:service=ClientUserTransaction
          2009-06-16 17:59:03,232 DEBUG [org.jboss.tm.usertx.server.ClientUserTransactionService] Created jboss:service=ClientUserTransaction
          2009-06-16 17:59:03,236 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=ClientUserTransaction dependents are: []
          2009-06-16 17:59:03,236 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=local
          2009-06-16 17:59:03,236 DEBUG [org.jboss.invocation.local.LocalInvoker] Creating jboss:service=invoker,type=local
          2009-06-16 17:59:03,261 DEBUG [org.jboss.invocation.local.LocalInvoker] Created jboss:service=invoker,type=local
          2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=local dependents are: []
          2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=pooled
          2009-06-16 17:59:03,262 DEBUG [org.jboss.invocation.pooled.server.PooledInvoker] Creating jboss:service=invoker,type=pooled
          2009-06-16 17:59:03,262 DEBUG [org.jboss.invocation.pooled.server.PooledInvoker] Created jboss:service=invoker,type=pooled
          2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=pooled dependents are: []
          2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=ClientUserTransaction
          2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=ClientUserTransaction
          2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=proxyFactory,target=ClientUserTransactionFactory
          2009-06-16 17:59:03,262 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=proxyFactory,target=ClientUserTransactionFactory
          2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=unified
          2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss:service=invoker,type=unified waiting on jboss.remoting:service=Connector,transport=socket
          2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=jrmp
          2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=invoker,type=jrmp
          2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=local
          2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=invoker,type=local
          2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=pooled
          2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss:service=invoker,type=pooled
          2009-06-16 17:59:03,263 DEBUG [org.jboss.system.ServiceController] Creating service jboss.remoting:service=NetworkRegistry
          2009-06-16 17:59:03,264 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.remoting:service=NetworkRegistry dependents are: [ObjectName: jboss.remoting:service=Connector,transport=socket
          State: CONFIGURED
          I Depend On:
          jboss.remoting:service=NetworkRegistry
          Depends On Me:
          jboss:service=invoker,type=unified
          ]
          2009-06-16 17:59:03,264 DEBUG [org.jboss.system.ServiceController] Creating service jboss.remoting:service=Connector,transport=socket
          2009-06-16 17:59:03,333 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] did not find server socket factory configuration as mbean service or classname. Creating default server socket factory.
          2009-06-16 17:59:03,333 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] created server socket factory javax.net.DefaultServerSocketFactory@6b9cd75a
          2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.remoting:service=Connector,transport=socket dependents are: [ObjectName: jboss:service=invoker,type=unified
          State: CONFIGURED
          I Depend On:
          jboss:service=TransactionManager
          jboss.remoting:service=Connector,transport=socket
          ]
          2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Creating service jboss:service=invoker,type=unified
          2009-06-16 17:59:03,354 DEBUG [org.jboss.invocation.unified.server.UnifiedInvoker] Creating jboss:service=invoker,type=unified
          2009-06-16 17:59:03,354 DEBUG [org.jboss.invocation.unified.server.UnifiedInvoker] Created jboss:service=invoker,type=unified
          2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss:service=invoker,type=unified dependents are: []
          2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Creating service jboss.remoting:service=Connector,transport=socket
          2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Ignoring create request for service: jboss.remoting:service=Connector,transport=socket
          2009-06-16 17:59:03,354 DEBUG [org.jboss.system.ServiceController] Creating service jboss.jdbc:service=metadata
          2009-06-16 17:59:03,355 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] Creating jboss.jdbc:service=metadata
          2009-06-16 17:59:03,355 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] Created jboss.jdbc:service=metadata
          2009-06-16 17:59:03,355 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.jdbc:service=metadata dependents are: []
          2009-06-16 17:59:03,355 DEBUG [org.jboss.system.ServiceController] Creating service jboss.deployment:type=DeploymentScanner,flavor=URL
          2009-06-16 17:59:03,355 DEBUG [org.jboss.deployment.scanner.URLDeploymentScanner] Creating jboss.deployment:type=DeploymentScanner,flavor=URL
          2009-06-16 17:59:03,368 DEBUG [org.jboss.deployment.scanner.URLDeploymentScanner] Scanner thread started
          2009-06-16 17:59:03,368 DEBUG [org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread] Running
          2009-06-16 17:59:03,368 DEBUG [org.jboss.deployment.scanner.AbstractDeploymentScanner$ScannerThread] Disabled, waiting for notification
          2009-06-16 17:59:03,369 DEBUG [org.jboss.deployment.scanner.URLDeploymentScanner] Created jboss.deployment:type=DeploymentScanner,flavor=URL
          2009-06-16 17:59:03,369 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.deployment:type=DeploymentScanner,flavor=URL dependents are: []
          2009-06-16 17:59:03,369 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying jboss-service.xml
          2009-06-16 17:59:03,369 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/usr/local/jboss-4.2.2.GA/server/all/conf/jboss-service.xml
          2009-06-16 17:59:03,369 DEBUG [org.jboss.deployment.SARDeployer] Deploying SAR, start step: url file:/usr/local/jboss-4.2.2.GA/server/all/conf/jboss-service.xml
          2009-06-16 17:59:03,369 DEBUG [org.jboss.system.ServiceController] starting service jboss.management.local:j2eeType=J2EEDomain,name=Manager
          2009-06-16 17:59:03,369 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.management.local:j2eeType=J2EEDomain,name=Manager dependent components: []
          2009-06-16 17:59:03,369 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=AttributePersistenceService
          2009-06-16 17:59:03,370 DEBUG [org.jboss.system.pm.AttributePersistenceService] Starting jboss:service=AttributePersistenceService
          2009-06-16 17:59:03,374 DEBUG [org.jboss.system.pm.XMLAttributePersistenceManager] Constructed
          2009-06-16 17:59:03,374 DEBUG [org.jboss.system.pm.XMLAttributePersistenceManager] Using data directory: /usr/local/jboss-4.2.2.GA/server/all/data/xmbean-attrs
          2009-06-16 17:59:03,375 DEBUG [org.jboss.system.pm.AttributePersistenceService] Started jboss:service=AttributePersistenceService
          2009-06-16 17:59:03,375 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=AttributePersistenceService dependent components: []
          2009-06-16 17:59:03,375 DEBUG [org.jboss.system.ServiceController] starting service jboss.system:service=ThreadPool
          2009-06-16 17:59:03,375 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.system:service=ThreadPool dependent components: [ObjectName: jboss:service=WebService
          State: CREATED
          I Depend On:
          jboss.system:service=ThreadPool
          , ObjectName: jboss:service=Naming
          State: CREATED
          I Depend On:
          jboss.system:service=ThreadPool
          jboss:service=NamingBeanImpl
          ]
          2009-06-16 17:59:03,375 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=WebService
          2009-06-16 17:59:03,375 DEBUG [org.jboss.web.WebService] Starting jboss:service=WebService
          2009-06-16 17:59:03,378 DEBUG [org.jboss.web.WebServer] Started server: ServerSocket[addr=rllsirfpas01b.local.sirfarma.it/10.145.9.3,port=0,localport=8083]
          2009-06-16 17:59:03,381 DEBUG [org.jboss.web.WebService] Started WebServer with address: rllsirfpas01b.local.sirfarma.it/10.145.9.3:8083
          2009-06-16 17:59:03,381 DEBUG [org.jboss.web.WebService] Started jboss:service=WebService
          2009-06-16 17:59:03,381 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=WebService dependent components: []
          2009-06-16 17:59:03,381 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=Naming
          2009-06-16 17:59:03,381 DEBUG [org.jboss.system.ServiceController] waiting in start jboss:service=Naming on jboss:service=NamingBeanImpl
          2009-06-16 17:59:03,381 DEBUG [org.jboss.system.ServiceController] starting service jboss.system:type=Log4jService,service=Logging
          2009-06-16 17:59:03,382 DEBUG [org.jboss.logging.Log4jService] Starting jboss.system:type=Log4jService,service=Logging
          2009-06-16 17:59:03,382 DEBUG [org.jboss.logging.Log4jService] Started jboss.system:type=Log4jService,service=Logging
          2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.system:type=Log4jService,service=Logging dependent components: []
          2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] starting service jboss.rmi:type=RMIClassLoader
          2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.rmi:type=RMIClassLoader dependent components: []
          2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=WebService
          2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=WebService
          2009-06-16 17:59:03,382 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=NamingBeanImpl
          2009-06-16 17:59:03,397 DEBUG [org.jnp.server.NamingBeanImpl] Using NamingServer: org.jnp.server.NamingServer@3ef07355
          2009-06-16 17:59:03,397 DEBUG [org.jnp.server.NamingBeanImpl] Installed global NamingServer: org.jnp.server.NamingServer@3ef07355
          2009-06-16 17:59:03,402 DEBUG [org.jnp.server.NamingBeanImpl] InitialContext Environment:
          2009-06-16 17:59:03,402 DEBUG [org.jnp.server.NamingBeanImpl] key=java.naming.factory.initial, value(java.lang.String)=org.jnp.interfaces.NamingContextFactory
          2009-06-16 17:59:03,402 DEBUG [org.jnp.server.NamingBeanImpl] key=java.naming.factory.url.pkgs, value(java.lang.String)=org.jboss.naming:org.jnp.interfaces
          2009-06-16 17:59:03,407 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=NamingBeanImpl dependent components: [ObjectName: jboss:service=Naming
          State: CREATED
          I Depend On:
          jboss.system:service=ThreadPool
          jboss:service=NamingBeanImpl
          ]
          2009-06-16 17:59:03,407 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=Naming
          2009-06-16 17:59:03,408 DEBUG [org.jboss.naming.NamingService] Starting jboss:service=Naming
          2009-06-16 17:59:03,408 DEBUG [org.jboss.naming.NamingService] System.setProperty, key=java.naming.factory.initial, value=org.jnp.interfaces.NamingContextFactory
          2009-06-16 17:59:03,408 DEBUG [org.jboss.naming.NamingService] System.setProperty, key=java.naming.factory.url.pkgs, value=org.jboss.naming:org.jnp.interfaces
          2009-06-16 17:59:03,409 DEBUG [org.jboss.naming.Naming] Creating NamingServer stub, theServer=MBeanProxyExt[jboss:service=NamingBeanImpl],rmiPort=1098,clientSocketFactory=null,serverSocketFactory=org.jboss.net.sockets.DefaultSocketFactory@41cb2704[bindAddress=rllsirfpas01b.local.sirfarma.it/10.145.9.3]
          2009-06-16 17:59:03,581 DEBUG [org.jboss.naming.Naming] NamingServer stub: NamingServer_Stub[UnicastRef2 [liveRef: [endpoint:[rllsirfpas01b.local.sirfarma.it:1098,org.jboss.net.sockets.DefaultSocketFactory@41cb2704[bindAddress=rllsirfpas01b.local.sirfarma.it/10.145.9.3]](local),objID:[-3079be79:121e9cafef2:-7fff, -6987809006595528208]]]]
          2009-06-16 17:59:03,594 DEBUG [org.jboss.naming.Naming] JNDI bootstrap JNP=rllsirfpas01b.local.sirfarma.it/10.145.9.3:1099, RMI=rllsirfpas01b.local.sirfarma.it/10.145.9.3:1098, backlog=50, no client SocketFactory, Server SocketFactory=org.jboss.net.sockets.DefaultSocketFactory@41cb2704[bindAddress=rllsirfpas01b.local.sirfarma.it/10.145.9.3]
          2009-06-16 17:59:03,603 DEBUG [org.jboss.naming.NamingService] Started jboss:service=Naming
          2009-06-16 17:59:03,603 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=Naming dependent components: []
          2009-06-16 17:59:03,603 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=Naming
          2009-06-16 17:59:03,603 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=Naming
          2009-06-16 17:59:03,603 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=JNDIView
          2009-06-16 17:59:03,603 DEBUG [org.jboss.naming.JNDIView] Starting jboss:service=JNDIView
          2009-06-16 17:59:03,603 DEBUG [org.jboss.naming.JNDIView] Started jboss:service=JNDIView
          2009-06-16 17:59:03,604 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=JNDIView dependent components: []
          2009-06-16 17:59:03,604 DEBUG [org.jboss.system.ServiceController] starting service jboss.security:service=SecurityConfig
          2009-06-16 17:59:03,604 DEBUG [org.jboss.security.plugins.SecurityConfig] Starting jboss.security:service=SecurityConfig
          2009-06-16 17:59:03,605 DEBUG [org.jboss.security.plugins.SecurityConfig] Installed JAAS Configuration service=jboss.security:service=XMLLoginConfig, config=org.jboss.security.auth.login.XMLLoginConfigImpl@6b9fe451
          2009-06-16 17:59:03,605 DEBUG [org.jboss.security.plugins.SecurityConfig] Started jboss.security:service=SecurityConfig
          2009-06-16 17:59:03,605 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.security:service=SecurityConfig dependent components: []
          2009-06-16 17:59:03,605 DEBUG [org.jboss.system.ServiceController] starting service jboss.security:service=XMLLoginConfig
          2009-06-16 17:59:03,605 DEBUG [org.jboss.security.auth.login.XMLLoginConfig] Starting jboss.security:service=XMLLoginConfig
          2009-06-16 17:59:03,606 DEBUG [org.jboss.security.auth.login.XMLLoginConfigImpl] Try loading config as XML, url=file:/usr/local/jboss-4.2.2.GA/server/all/conf/login-config.xml
          2009-06-16 17:59:03,655 DEBUG [org.jboss.security.auth.login.XMLLoginConfig] Started jboss.security:service=XMLLoginConfig
          2009-06-16 17:59:03,655 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.security:service=XMLLoginConfig dependent components: []
          2009-06-16 17:59:03,655 DEBUG [org.jboss.system.ServiceController] starting service jboss.security:service=JaasSecurityManager
          2009-06-16 17:59:03,655 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Starting jboss.security:service=JaasSecurityManager
          2009-06-16 17:59:03,664 DEBUG [org.jboss.security.SecurityAssociation] Using ThreadLocal: false
          2009-06-16 17:59:03,673 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] securityMgrCtxPath=java:/jaas
          2009-06-16 17:59:03,674 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] cachePolicyCtxPath=java:/timedCacheFactory
          2009-06-16 17:59:03,677 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] SecurityProxyFactory=org.jboss.security.SubjectSecurityProxyFactory@6f18278a
          2009-06-16 17:59:03,679 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Registered PrincipalEditor, SecurityDomainEditor
          2009-06-16 17:59:03,679 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] ServerMode=true
          2009-06-16 17:59:03,679 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] SecurityMgrClass=class org.jboss.security.plugins.JaasSecurityManager
          2009-06-16 17:59:03,679 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] CallbackHandlerClass=class org.jboss.security.auth.callback.SecurityAssociationHandler
          2009-06-16 17:59:03,679 DEBUG [org.jboss.security.plugins.JaasSecurityManagerService] Started jboss.security:service=JaasSecurityManager
          2009-06-16 17:59:03,679 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.security:service=JaasSecurityManager dependent components: []
          2009-06-16 17:59:03,680 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=XidFactory
          2009-06-16 17:59:03,680 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=XidFactory dependent components: []
          2009-06-16 17:59:03,680 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=TransactionManager
          2009-06-16 17:59:03,680 DEBUG [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Starting jboss:service=TransactionManager
          2009-06-16 17:59:03,680 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] JBossTS Transaction Service (JTA version) - JBoss Inc.
          2009-06-16 17:59:03,680 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Setting up property manager MBean and JMX layer
          2009-06-16 17:59:03,820 DEBUG [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.arjuna.recovery.TransactionStatusManager_1] - Starting service com.arjuna.ats.arjuna.recovery.ActionStatusService on port 56056
          2009-06-16 17:59:03,825 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] TransactionStatusManagerItem - host: 10.145.9.3 port: 56056
          2009-06-16 17:59:03,832 DEBUG [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.arjuna.recovery.TransactionStatusManager_3] - TransactionStatusManager started on port 56056 with service com.arjuna.ats.arjuna.recovery.ActionStatusService
          2009-06-16 17:59:03,842 DEBUG [com.arjuna.ats.tsmx.logging.tsmxLogger] Registering mbean for module 'arjuna'
          2009-06-16 17:59:03,846 DEBUG [com.arjuna.ats.tsmx.logging.tsmxLogger] Initialising JMX agent com.arjuna.ats.internal.jbossatx.agent.LocalJBossAgentImpl
          2009-06-16 17:59:03,851 DEBUG [com.arjuna.ats.tsmx.logging.tsmxLogger] Registering mbean for module 'jta'
          2009-06-16 17:59:03,853 DEBUG [com.arjuna.ats.tsmx.logging.tsmxLogger] Registering mbean for module 'txoj'
          2009-06-16 17:59:03,872 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Starting recovery manager
          2009-06-16 17:59:03,878 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger]
          --- Start RecoveryActivators
          2009-06-16 17:59:03,887 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger]
          --- ExpiredEntryMonitor ----Tue, 16 Jun 2009 17:59:03----
          2009-06-16 17:59:03,978 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.noxanodes] No XA recovery nodes specified. Will only recover saved states.
          2009-06-16 17:59:03,982 DEBUG [com.arjuna.ats.arjuna.logging.arjLoggerI18N] [com.arjuna.ats.internal.arjuna.recovery.ready] RecoveryManagerImple is ready on port 33,975
          2009-06-16 17:59:03,983 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Recovery manager started
          2009-06-16 17:59:03,983 INFO [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Binding TransactionManager JNDI Reference
          2009-06-16 17:59:03,995 DEBUG [com.arjuna.ats.jbossatx.jta.TransactionManagerService] Started jboss:service=TransactionManager
          2009-06-16 17:59:03,996 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=TransactionManager dependent components: [ObjectName: jboss:service=invoker,type=unified
          State: CREATED
          I Depend On:
          jboss:service=TransactionManager
          jboss.remoting:service=Connector,transport=socket
          , ObjectName: jboss:service=invoker,type=jrmp
          State: CREATED
          I Depend On:
          jboss:service=TransactionManager
          Depends On Me:
          jboss:service=proxyFactory,target=ClientUserTransactionFactory
          jboss:service=proxyFactory,target=ClientUserTransaction
          , ObjectName: jboss:service=invoker,type=local
          State: CREATED
          I Depend On:
          jboss:service=TransactionManager
          , ObjectName: jboss:service=invoker,type=pooled
          State: CREATED
          I Depend On:
          jboss:service=TransactionManager
          ]
          2009-06-16 17:59:03,996 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=unified
          2009-06-16 17:59:03,996 DEBUG [org.jboss.system.ServiceController] waiting in start jboss:service=invoker,type=unified on jboss.remoting:service=Connector,transport=socket
          2009-06-16 17:59:03,996 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=jrmp
          2009-06-16 17:59:03,996 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Starting jboss:service=invoker,type=jrmp
          2009-06-16 17:59:04,001 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] RMI Port='4444'
          2009-06-16 17:59:04,001 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Client SocketFactory='Default'
          2009-06-16 17:59:04,001 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Server SocketFactory='org.jboss.net.sockets.DefaultSocketFactory@41cb2704[bindAddress=rllsirfpas01b.local.sirfarma.it/10.145.9.3]'
          2009-06-16 17:59:04,001 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Server SocketAddr='rllsirfpas01b.local.sirfarma.it'
          2009-06-16 17:59:04,001 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] SecurityDomain='Default'
          2009-06-16 17:59:04,016 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Bound JRMP invoker for JMX node
          2009-06-16 17:59:04,016 DEBUG [org.jboss.invocation.jrmp.server.JRMPInvoker] Started jboss:service=invoker,type=jrmp
          2009-06-16 17:59:04,016 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=jrmp dependent components: [ObjectName: jboss:service=proxyFactory,target=ClientUserTransactionFactory
          State: CREATED
          I Depend On:
          jboss:service=invoker,type=jrmp
          Depends On Me:
          jboss:service=ClientUserTransaction
          , ObjectName: jboss:service=proxyFactory,target=ClientUserTransaction
          State: CREATED
          I Depend On:
          jboss:service=invoker,type=jrmp
          Depends On Me:
          jboss:service=ClientUserTransaction
          ]
          2009-06-16 17:59:04,016 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=proxyFactory,target=ClientUserTransactionFactory
          2009-06-16 17:59:04,016 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Starting jboss:service=proxyFactory,target=ClientUserTransactionFactory
          2009-06-16 17:59:04,025 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Created JRMPPRoxy for service=jboss:service=ClientUserTransaction, nameHash=-2097396599, invoker=jboss:service=invoker,type=jrmp
          2009-06-16 17:59:04,045 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Bound proxy under jndiName=UserTransactionSessionFactory
          2009-06-16 17:59:04,045 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Started jboss:service=proxyFactory,target=ClientUserTransactionFactory
          2009-06-16 17:59:04,046 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=proxyFactory,target=ClientUserTransactionFactory dependent components: [ObjectName: jboss:service=ClientUserTransaction
          State: CREATED
          I Depend On:
          jboss:service=proxyFactory,target=ClientUserTransactionFactory
          jboss:service=proxyFactory,target=ClientUserTransaction
          ]
          2009-06-16 17:59:04,046 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=ClientUserTransaction
          2009-06-16 17:59:04,046 DEBUG [org.jboss.system.ServiceController] waiting in start jboss:service=ClientUserTransaction on jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:04,046 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:04,046 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Starting jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:04,049 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Created JRMPPRoxy for service=jboss:service=ClientUserTransaction, nameHash=2024786241, invoker=jboss:service=invoker,type=jrmp
          2009-06-16 17:59:04,051 DEBUG [org.jboss.invocation.jrmp.server.JRMPProxyFactory] Started jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:04,051 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=proxyFactory,target=ClientUserTransaction dependent components: [ObjectName: jboss:service=ClientUserTransaction
          State: CREATED
          I Depend On:
          jboss:service=proxyFactory,target=ClientUserTransactionFactory
          jboss:service=proxyFactory,target=ClientUserTransaction
          ]
          2009-06-16 17:59:04,051 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=ClientUserTransaction
          2009-06-16 17:59:04,052 DEBUG [org.jboss.tm.usertx.server.ClientUserTransactionService] Starting jboss:service=ClientUserTransaction
          2009-06-16 17:59:04,055 DEBUG [org.jboss.tm.usertx.server.ClientUserTransactionService] Started jboss:service=ClientUserTransaction
          2009-06-16 17:59:04,055 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=ClientUserTransaction dependent components: []
          2009-06-16 17:59:04,055 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=local
          2009-06-16 17:59:04,055 DEBUG [org.jboss.invocation.local.LocalInvoker] Starting jboss:service=invoker,type=local
          2009-06-16 17:59:04,058 DEBUG [org.jboss.invocation.local.LocalInvoker] Local invoker for JMX node started
          2009-06-16 17:59:04,058 DEBUG [org.jboss.invocation.local.LocalInvoker] Started jboss:service=invoker,type=local
          2009-06-16 17:59:04,058 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=local dependent components: []
          2009-06-16 17:59:04,058 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=pooled
          2009-06-16 17:59:04,058 DEBUG [org.jboss.invocation.pooled.server.PooledInvoker] Starting jboss:service=invoker,type=pooled
          2009-06-16 17:59:04,067 DEBUG [org.jboss.invocation.pooled.server.PooledInvoker] Bound invoker for JMX node
          2009-06-16 17:59:04,067 DEBUG [org.jboss.invocation.pooled.server.PooledInvoker] Started jboss:service=invoker,type=pooled
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=pooled dependent components: []
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=ClientUserTransaction
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=ClientUserTransaction
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=proxyFactory,target=ClientUserTransactionFactory
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=proxyFactory,target=ClientUserTransactionFactory
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=proxyFactory,target=ClientUserTransaction
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=unified
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] waiting in start jboss:service=invoker,type=unified on jboss.remoting:service=Connector,transport=socket
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=jrmp
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=invoker,type=jrmp
          2009-06-16 17:59:04,068 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=local
          2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=invoker,type=local
          2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=pooled
          2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss:service=invoker,type=pooled
          2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] starting service jboss.remoting:service=NetworkRegistry
          2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.remoting:service=NetworkRegistry dependent components: [ObjectName: jboss.remoting:service=Connector,transport=socket
          State: CREATED
          I Depend On:
          jboss.remoting:service=NetworkRegistry
          Depends On Me:
          jboss:service=invoker,type=unified
          ]
          2009-06-16 17:59:04,069 DEBUG [org.jboss.system.ServiceController] starting service jboss.remoting:service=Connector,transport=socket
          2009-06-16 17:59:04,073 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[UNINITIALIZED] added org.jboss.invocation.unified.server.UnifiedInvoker@44aea710 for subsystem 'invoker'
          2009-06-16 17:59:04,073 DEBUG [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[UNINITIALIZED] starting
          2009-06-16 17:59:04,075 DEBUG [org.jboss.remoting.ServerInvoker] SocketServerInvoker[10.145.9.3:4446] started for locator InvokerLocator [socket://rllsirfpas01b.local.sirfarma.it:4446/?dataType=invocation&enableTcpNoDelay=true&marshaller=org.jboss.invocation.unified.marshall.InvocationMarshaller&socketTimeout=600000&unmarshaller=org.jboss.invocation.unified.marshall.InvocationUnMarshaller]
          2009-06-16 17:59:04,075 DEBUG [org.jboss.remoting.transport.socket.SocketServerInvoker] SocketServerInvoker[10.145.9.3:4446] started
          2009-06-16 17:59:04,075 DEBUG [org.jboss.remoting.transport.Connector] org.jboss.remoting.transport.Connector@524c71d2 started
          2009-06-16 17:59:04,075 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.remoting:service=Connector,transport=socket dependent components: [ObjectName: jboss:service=invoker,type=unified
          State: CREATED
          I Depend On:
          jboss:service=TransactionManager
          jboss.remoting:service=Connector,transport=socket
          ]
          2009-06-16 17:59:04,075 DEBUG [org.jboss.system.ServiceController] starting service jboss:service=invoker,type=unified
          2009-06-16 17:59:04,076 DEBUG [org.jboss.invocation.unified.server.UnifiedInvoker] Starting jboss:service=invoker,type=unified
          2009-06-16 17:59:04,076 DEBUG [org.jboss.invocation.unified.server.UnifiedInvoker] Starting unified invoker service.
          2009-06-16 17:59:04,087 DEBUG [org.jboss.invocation.unified.server.UnifiedInvoker] Started jboss:service=invoker,type=unified
          2009-06-16 17:59:04,087 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss:service=invoker,type=unified dependent components: []
          2009-06-16 17:59:04,087 DEBUG [org.jboss.system.ServiceController] starting service jboss.remoting:service=Connector,transport=socket
          2009-06-16 17:59:04,087 DEBUG [org.jboss.system.ServiceController] Ignoring start request for service: jboss.remoting:service=Connector,transport=socket
          2009-06-16 17:59:04,087 DEBUG [org.jboss.system.ServiceController] starting service jboss.jdbc:service=metadata
          2009-06-16 17:59:04,087 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] Starting jboss.jdbc:service=metadata
          2009-06-16 17:59:04,088 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] Loading standardjbosscmp-jdbc.xml : file:/usr/local/jboss-4.2.2.GA/server/all/conf/standardjbosscmp-jdbc.xml
          2009-06-16 17:59:04,210 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: FirstSQL/J
          2009-06-16 17:59:04,217 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Ingres
          2009-06-16 17:59:04,225 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: McKoi
          2009-06-16 17:59:04,234 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Firebird
          2009-06-16 17:59:04,243 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: InterBase
          2009-06-16 17:59:04,252 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: DB2
          2009-06-16 17:59:04,264 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Derby
          2009-06-16 17:59:04,276 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Oracle9i
          2009-06-16 17:59:04,289 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Oracle8
          2009-06-16 17:59:04,302 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Oracle7
          2009-06-16 17:59:04,312 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Sybase
          2009-06-16 17:59:04,326 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: PostgreSQL
          2009-06-16 17:59:04,342 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: PostgreSQL 8.0
          2009-06-16 17:59:04,359 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: PostgreSQL 7.2
          2009-06-16 17:59:04,376 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Hypersonic SQL
          2009-06-16 17:59:04,388 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: PointBase
          2009-06-16 17:59:04,396 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: SOLID
          2009-06-16 17:59:04,406 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: mySQL
          2009-06-16 17:59:04,414 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: MS SQLSERVER
          2009-06-16 17:59:04,419 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: MS SQLSERVER2000
          2009-06-16 17:59:04,423 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: DB2/400
          2009-06-16 17:59:04,427 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: SapDB
          2009-06-16 17:59:04,431 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: MaxDB
          2009-06-16 17:59:04,435 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Cloudscape
          2009-06-16 17:59:04,439 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: InformixDB
          2009-06-16 17:59:04,442 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] added type-mapping: Mimer SQL
          2009-06-16 17:59:04,442 DEBUG [org.jboss.ejb.plugins.cmp.jdbc.metadata.MetaDataLibrary] Started jboss.jdbc:service=metadata
          2009-06-16 17:59:04,443 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.jdbc:service=metadata dependent components: []
          2009-06-16 17:59:04,443 DEBUG [org.jboss.system.ServiceController] starting service jboss.deployment:type=DeploymentScanner,flavor=URL
          2009-06-16 17:59:04,443 DEBUG [org.jboss.deployment.scanner.URLDeploymentScanner] Starting jboss.deployment:type=DeploymentScanner,flavor=URL
          2009-06-16 17:59:04,451 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment of package: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
          2009-06-16 17:59:04,451 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
          2009-06-16 17:59:04,451 DEBUG [org.jboss.deployment.MainDeployer] using deployer org.jboss.deployment.SARDeployer@4a53fb57
          2009-06-16 17:59:04,453 DEBUG [org.jboss.deployment.SARDeployer] Updated watch URL to: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/META-INF/jboss-service.xml
          2009-06-16 17:59:04,460 DEBUG [org.jboss.deployment.SARDeployer] about to copy 0 local directories
          2009-06-16 17:59:04,461 DEBUG [org.jboss.deployment.SARDeployer] looking for nested deployments in : file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
          2009-06-16 17:59:04,461 DEBUG [org.jboss.deployment.SARDeployer] nested deployment: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
          2009-06-16 17:59:04,462 DEBUG [org.jboss.deployment.SARDeployer] nested deployment: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
          2009-06-16 17:59:04,462 DEBUG [org.jboss.deployment.DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
          2009-06-16 17:59:04,462 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@1d10c424, cl=org.jboss.mx.loading.UnifiedClassLoader3@6d66f9b9{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ ,addedOrder=0}
          2009-06-16 17:59:04,462 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@1d10c424, cl=org.jboss.mx.loading.UnifiedClassLoader3@6d66f9b9{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ ,addedOrder=0}
          2009-06-16 17:59:04,463 DEBUG [org.jboss.mx.loading.UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@6d66f9b9{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ ,addedOrder=0}
          2009-06-16 17:59:04,463 DEBUG [org.jboss.mx.loading.ClassLoaderUtils] Multiple class loaders found for pkg:
          2009-06-16 17:59:04,464 DEBUG [org.jboss.deployment.MainDeployer] found 2 subpackages of file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
          2009-06-16 17:59:04,464 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
          2009-06-16 17:59:04,464 DEBUG [org.jboss.deployment.MainDeployer] Copying file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar -> /usr/local/jboss-4.2.2.GA/server/all/tmp/deploy/tmp27638jboss-annotations-ejb3.jar
          2009-06-16 17:59:04,467 DEBUG [org.jboss.deployment.JARDeployer] No xml files found
          2009-06-16 17:59:04,467 DEBUG [org.jboss.deployment.MainDeployer] using deployer org.jboss.deployment.JARDeployer@671ff436
          2009-06-16 17:59:04,467 DEBUG [org.jboss.deployment.JARDeployer] looking for nested deployments in : file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
          2009-06-16 17:59:04,482 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] Added url: file:/usr/local/jboss-4.2.2.GA/server/all/tmp/deploy/tmp27638jboss-annotations-ejb3.jar, to ucl: org.jboss.mx.loading.UnifiedClassLoader3@6d66f9b9{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ ,addedOrder=3}
          2009-06-16 17:59:04,483 DEBUG [org.jboss.deployment.MainDeployer] found 0 subpackages of file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
          2009-06-16 17:59:04,483 DEBUG [org.jboss.deployment.MainDeployer] Watching new file: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
          2009-06-16 17:59:04,483 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
          2009-06-16 17:59:04,484 DEBUG [org.jboss.deployment.MainDeployer] Copying file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar -> /usr/local/jboss-4.2.2.GA/server/all/tmp/deploy/tmp27639jboss-ejb3.jar
          2009-06-16 17:59:04,495 DEBUG [org.jboss.deployment.JARDeployer] No xml files found
          2009-06-16 17:59:04,495 DEBUG [org.jboss.deployment.MainDeployer] using deployer org.jboss.deployment.JARDeployer@671ff436
          2009-06-16 17:59:04,495 DEBUG [org.jboss.deployment.JARDeployer] looking for nested deployments in : file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
          2009-06-16 17:59:04,559 DEBUG [org.jboss.mx.loading.ClassLoaderUtils] Multiple class loaders found for pkg: org.jboss.ejb
          2009-06-16 17:59:04,602 DEBUG [org.jboss.mx.loading.ClassLoaderUtils] Multiple class loaders found for pkg: schema
          2009-06-16 17:59:04,607 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] Added url: file:/usr/local/jboss-4.2.2.GA/server/all/tmp/deploy/tmp27639jboss-ejb3.jar, to ucl: org.jboss.mx.loading.UnifiedClassLoader3@6d66f9b9{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ ,addedOrder=3}
          2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] found 0 subpackages of file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
          2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] Watching new file: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
          2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] Watching new file: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
          2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] create step for deployment file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
          2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] create step for deployment file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
          2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying jboss-annotations-ejb3.jar
          2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] create step for deployment file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
          2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying jboss-ejb3.jar
          2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.SARDeployer] Deploying SAR, create step: url file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
          2009-06-16 17:59:04,608 DEBUG [org.jboss.deployment.SARDeployer] Registering service UCL=jmx.loading:UCL=6d66f9b9
          2009-06-16 17:59:04,610 DEBUG [org.jboss.system.ServiceCreator] About to create bean: jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3 with code: org.jboss.remoting.transport.Connector
          2009-06-16 17:59:04,613 DEBUG [org.jboss.system.ServiceCreator] Created bean: jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
          2009-06-16 17:59:04,614 DEBUG [org.jboss.system.ServiceController] recording that jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3 depends on jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,614 DEBUG [org.jboss.system.ServiceConfigurator] considering with object name jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,615 DEBUG [org.jboss.system.ServiceConfigurator] InvokerLocator set to socket://rllsirfpas01b.local.sirfarma.it:3873 in jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
          2009-06-16 17:59:04,618 DEBUG [org.jboss.system.ServiceConfigurator] Configuration set to [handlers: null] in jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
          2009-06-16 17:59:04,619 DEBUG [org.jboss.system.ServiceCreator] About to create bean: jboss.ejb3:service=JarsIgnoredForScanning with code: org.jboss.ejb3.JarsIgnoredForScanning
          2009-06-16 17:59:04,624 DEBUG [org.jboss.system.ServiceCreator] Created bean: jboss.ejb3:service=JarsIgnoredForScanning
          2009-06-16 17:59:04,625 DEBUG [org.jboss.system.ServiceConfigurator] IgnoredJars set to [Ljava.lang.String;@6e5dfaf1 in jboss.ejb3:service=JarsIgnoredForScanning
          2009-06-16 17:59:04,626 DEBUG [org.jboss.system.ServiceCreator] About to create xmbean object: jboss.ejb3:service=EJB3Deployer with code: org.jboss.ejb3.EJB3Deployer with embedded descriptor
          2009-06-16 17:59:04,651 DEBUG [org.jboss.system.ServiceCreator] Created bean: jboss.ejb3:service=EJB3Deployer
          2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceConfigurator] DeployEjb3ExtensionOnly set to false in jboss.ejb3:service=EJB3Deployer
          2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceController] recording that jboss.ejb3:service=EJB3Deployer depends on jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceConfigurator] considering with object name jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceController] recording that jboss.ejb3:service=EJB3Deployer depends on jboss.ejb:service=EJBDeployer
          2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceConfigurator] considering with object name jboss.ejb:service=EJBDeployer
          2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceController] recording that jboss.ejb3:service=EJB3Deployer depends on jboss.ejb3:service=JarsIgnoredForScanning
          2009-06-16 17:59:04,652 DEBUG [org.jboss.system.ServiceConfigurator] considering JarsIgnoredForScanning with object name jboss.ejb3:service=JarsIgnoredForScanning
          2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] Creating service jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
          2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3 waiting on jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb3:service=JarsIgnoredForScanning
          2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] Creating dependent components for: jboss.ejb3:service=JarsIgnoredForScanning dependents are: [ObjectName: jboss.ejb3:service=EJB3Deployer
          State: CONFIGURED
          I Depend On:
          jboss.aop:service=AspectDeployer
          jboss.ejb:service=EJBDeployer
          jboss.ejb3:service=JarsIgnoredForScanning
          ]
          2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb3:service=EJB3Deployer
          2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.ejb3:service=EJB3Deployer waiting on jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb3:service=EJB3Deployer
          2009-06-16 17:59:04,656 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.ejb3:service=EJB3Deployer waiting on jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,656 DEBUG [org.jboss.deployment.MainDeployer] Done with create step of deploying ejb3.deployer
          2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
          2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-annotations-ejb3.jar
          2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.MainDeployer] End deployment start on package: jboss-annotations-ejb3.jar
          2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.MainDeployer] Begin deployment start file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/jboss-ejb3.jar
          2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.MainDeployer] End deployment start on package: jboss-ejb3.jar
          2009-06-16 17:59:04,657 DEBUG [org.jboss.deployment.SARDeployer] Deploying SAR, start step: url file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
          2009-06-16 17:59:04,657 DEBUG [org.jboss.system.ServiceController] starting service jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
          2009-06-16 17:59:04,657 DEBUG [org.jboss.system.ServiceController] Start requested before create, calling create now for service: jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
          2009-06-16 17:59:04,657 DEBUG [org.jboss.system.ServiceController] Creating service jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3
          2009-06-16 17:59:04,657 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3 waiting on jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] waiting in start jboss.remoting:type=Connector,name=DefaultEjb3Connector,handler=ejb3 on jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] starting service jboss.ejb3:service=JarsIgnoredForScanning
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.ejb3:service=JarsIgnoredForScanning dependent components: [ObjectName: jboss.ejb3:service=EJB3Deployer
          State: CONFIGURED
          I Depend On:
          jboss.aop:service=AspectDeployer
          jboss.ejb:service=EJBDeployer
          jboss.ejb3:service=JarsIgnoredForScanning
          ]
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] starting service jboss.ejb3:service=EJB3Deployer
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] Start requested before create, calling create now for service: jboss.ejb3:service=EJB3Deployer
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb3:service=EJB3Deployer
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.ejb3:service=EJB3Deployer waiting on jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] waiting in start jboss.ejb3:service=EJB3Deployer on jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] starting service jboss.ejb3:service=EJB3Deployer
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] Start requested before create, calling create now for service: jboss.ejb3:service=EJB3Deployer
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] Creating service jboss.ejb3:service=EJB3Deployer
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] waiting in create of jboss.ejb3:service=EJB3Deployer waiting on jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,658 DEBUG [org.jboss.system.ServiceController] waiting in start jboss.ejb3:service=EJB3Deployer on jboss.aop:service=AspectDeployer
          2009-06-16 17:59:04,685 DEBUG [org.jboss.deployment.MainDeployer] End deployment start on package: ejb3.deployer
          2009-06-16 17:59:04,685 DEBUG [org.jboss.deployment.MainDeployer] Deployed package: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/
          2009-06-16 17:59:04,687 DEBUG [org.jboss.deployment.scanner.URLDeploymentScanner] Watch URL for: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/ -> file:/usr/local/jboss-4.2.2.GA/server/all/deploy/ejb3.deployer/META-INF/jboss-service.xml
          2009-06-16 17:59:04,688 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment of package: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/
          2009-06-16 17:59:04,688 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/
          2009-06-16 17:59:04,688 DEBUG [org.jboss.deployment.MainDeployer] using deployer org.jboss.deployment.SARDeployer@4a53fb57
          2009-06-16 17:59:04,689 DEBUG [org.jboss.deployment.SARDeployer] Updated watch URL to: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/META-INF/jboss-service.xml
          2009-06-16 17:59:04,691 DEBUG [org.jboss.deployment.SARDeployer] about to copy 0 local directories
          2009-06-16 17:59:04,691 DEBUG [org.jboss.deployment.SARDeployer] looking for nested deployments in : file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/
          2009-06-16 17:59:04,691 DEBUG [org.jboss.deployment.SARDeployer] nested deployment: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/jboss-aop-jdk50.jar
          2009-06-16 17:59:04,692 DEBUG [org.jboss.deployment.SARDeployer] nested deployment: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/trove.jar
          2009-06-16 17:59:04,692 DEBUG [org.jboss.deployment.SARDeployer] nested deployment: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/jboss-aspect-library-jdk50.jar
          2009-06-16 17:59:04,693 DEBUG [org.jboss.deployment.DeploymentInfo] createLoaderRepository from config: LoaderRepositoryConfig(repositoryName: JMImplementation:service=LoaderRepository,name=Default, repositoryClassName: null, configParserClassName: null, repositoryConfig: null)
          2009-06-16 17:59:04,693 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@1d10c424, cl=org.jboss.mx.loading.UnifiedClassLoader3@39320a41{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/ ,addedOrder=0}
          2009-06-16 17:59:04,693 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.UnifiedLoaderRepository3@1d10c424, cl=org.jboss.mx.loading.UnifiedClassLoader3@39320a41{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/ ,addedOrder=0}
          2009-06-16 17:59:04,693 DEBUG [org.jboss.mx.loading.UnifiedLoaderRepository3] Adding org.jboss.mx.loading.UnifiedClassLoader3@39320a41{ url=file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/ ,addedOrder=0}
          2009-06-16 17:59:04,693 DEBUG [org.jboss.mx.loading.ClassLoaderUtils] Multiple class loaders found for pkg:
          2009-06-16 17:59:04,694 DEBUG [org.jboss.deployment.MainDeployer] found 3 subpackages of file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/
          2009-06-16 17:59:04,694 DEBUG [org.jboss.deployment.MainDeployer] Starting deployment (init step) of package at: file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/jboss-aop-jdk50.jar
          2009-06-16 17:59:04,694 DEBUG [org.jboss.deployment.MainDeployer] Copying file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/jboss-aop-jdk50.jar -> /usr/local/jboss-4.2.2.GA/server/all/tmp/deploy/tmp27640jboss-aop-jdk50.jar
          2009-06-16 17:59:04,706 DEBUG [org.jboss.deployment.JARDeployer] No xml files found
          2009-06-16 17:59:04,706 DEBUG [org.jboss.deployment.MainDeployer] using deployer org.jboss.deployment.JARDeployer@671ff436
          2009-06-16 17:59:04,706 DEBUG [org.jboss.deployment.JARDeployer] looking for nested deployments in : file:/usr/local/jboss-4.2.2.GA/server/all/deploy/jboss-aop-jdk50.deployer/jboss-aop-jdk50.jar
          2009-06-16 17:59:04,814 DEBUG [org.j

          • 2. Re: JBoss startup delay
            Pierangelo Repetti Newbie

            How can I attach a file to this post ?

            • 3. Re: JBoss startup delay
              Frank Henry Novice

              Can you detect any deployments that seem to cause this delay?
              How are you starting your JBoss? Automagically?

              Are you depending on some kind of extra-jboss service or thing that might be delaying it?

              You're not running an anti-virus, are you?

              • 4. Re: JBoss startup delay
                Pierangelo Repetti Newbie

                Hello, thanks for replying


                Can you detect any deployments that seem to cause this delay?


                It happens with the main EAR in my project. Anyway, about 1 in 5 times there's no delay at all.

                How are you starting your JBoss? Automagically?

                Starting it by hand with a shell script.

                Are you depending on some kind of extra-jboss service or thing that might be delaying it?


                Not really. I am creating connection pools with DBs that reside on other machines. Then, as I said, there's the cluster.

                You're not running an anti-virus, are you?


                No.

                I really wish I could attach the whole log file, as I guess that would give the right info to investigate. FOr some reason it is cut when I submit the post.
                I'll try and post only the log records around the 3' steps.

                First:

                2009-06-16 17:59:24,489 DEBUG [org.jboss.system.ServiceController] starting service jboss.jca:service=DataSourceBinding,name=DefaultDS
                2009-06-16 17:59:24,489 DEBUG [org.jboss.resource.adapter.jdbc.remote.WrapperDataSourceService] Starting jboss.jca:service=DataSourceBinding,name=DefaultDS
                2009-06-16 17:59:24,489 DEBUG [org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] Binding object 'org.jboss.resource.adapter.jdbc.WrapperDataSource@29d22104' into JNDI at 'java:DefaultDS'
                2009-06-16 17:59:24,489 INFO [org.jboss.resource.connectionmanager.ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'
                2009-06-16 17:59:24,489 DEBUG [org.jboss.resource.adapter.jdbc.remote.WrapperDataSourceService] Started jboss.jca:service=DataSourceBinding,name=DefaultDS
                2009-06-16 17:59:24,490 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.jca:service=DataSourceBinding,name=DefaultDS dependent components: [ObjectName: jboss.ejb:service=EJBTimerService,persistencePolicy=database
                 State: CREATED
                 I Depend On:
                 jboss.jca:service=DataSourceBinding,name=DefaultDS
                , ObjectName: jboss:service=juddi
                 State: CREATED
                 I Depend On:
                 jboss.jca:service=DataSourceBinding,name=DefaultDS
                , ObjectName: jboss:service=KeyGeneratorFactory,type=HiLo
                 State: CREATED
                 I Depend On:
                 jboss:service=TransactionManager
                 jboss.jca:service=DataSourceBinding,name=DefaultDS
                ]
                2009-06-16 17:59:24,490 DEBUG [org.jboss.system.ServiceController] starting service jboss.ejb:service=EJBTimerService,persistencePolicy=database
                2009-06-16 17:59:24,490 DEBUG [org.jboss.ejb.txtimer.DatabasePersistencePolicy] Starting jboss.ejb:service=EJBTimerService,persistencePolicy=database
                2009-06-16 17:59:24,516 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] internalRegisterPool: registering pool with interval 900000 old interval: 9223372036854775807
                2009-06-16 17:59:24,520 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] internalRegisterPool: about to notify thread: old next: 1245168414520, new next: 1245168414520
                2009-06-16 18:02:33,901 DEBUG [org.jboss.ejb.txtimer.DatabasePersistencePolicy] Started jboss.ejb:service=EJBTimerService,persistencePolicy=database
                2009-06-16 18:02:33,901 DEBUG [org.jboss.system.ServiceController] Starting dependent components for: jboss.ejb:service=EJBTimerService,persistencePolicy=database dependent components: []


                Second:
                2009-06-16 18:02:49,483 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] internalRegisterPool: registering pool with interval 900000 old interval: 450000
                2009-06-16 18:02:51,601 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:02:51,602 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:02:51,602 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:02:51,603 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:02:51,603 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:01,604 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:01,605 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:01,605 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:01,605 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:01,605 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:11,607 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:11,607 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:11,608 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:11,608 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:11,608 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:21,610 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:21,610 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:21,611 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:21,611 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:21,611 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:31,613 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:31,613 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:31,613 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:31,614 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:31,614 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:41,615 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:41,615 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:41,615 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:41,616 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:41,616 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:51,618 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:51,618 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:51,618 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:51,618 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:03:51,618 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:01,621 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:01,621 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:01,621 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:01,621 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:01,621 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:11,624 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:11,624 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:11,624 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:11,624 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:11,625 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:21,627 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:21,627 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:21,627 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:21,627 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:21,627 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:31,630 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:31,630 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:31,630 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:31,630 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:31,630 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:41,633 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:41,633 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:41,633 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:41,633 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:41,633 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:51,634 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:51,635 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:51,635 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:51,635 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:04:51,635 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:01,637 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:01,638 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:01,638 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:01,638 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:01,638 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:11,640 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:11,640 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:11,641 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:11,641 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:11,641 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:21,643 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:21,643 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:21,643 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:21,644 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:21,644 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:31,645 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:31,645 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:31,645 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:31,646 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:31,646 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:41,648 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:41,648 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:41,648 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:41,648 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:41,649 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:51,651 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:51,651 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:51,651 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:51,651 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:51,651 DEBUG [org.jboss.web.tomcat.service.session.JBossCacheManager] Looking for sessions that have expired ...
                2009-06-16 18:05:58,828 DEBUG [org.jboss.mx.loading.RepositoryClassLoader] setRepository, repository=org.jboss.mx.loading.HeirarchicalLoaderRepository3@8f0756d, cl=org.jboss.mx.loading.HeirarchicalLoaderRepository3$CacheClassLoader@684c72e3{ url=null ,addedOrder=0}