EAR migration problem with session bean extendi...| JBoss.org Content Archive (Read Only)

1. Re: EAR migration problem with session bean extending a class in another jar

smarlow Feb 8, 2012 2:49 PM (in response to jtkim)

Could you attach the server.log or a http://pastebin.com/ link with the contents (include the lines before/after the error).

2. Re: EAR migration problem with session bean extending a class in another jar

jtkim Feb 8, 2012 3:13 PM (in response to smarlow)

Ok, here's a server log resulting from starting the server, deploying the demo app, and shutting down the server.

20:09:32,233 INFO  [org.jboss.as.connector.subsystems.datasources] (Controller Boot Thread) Deploying JDBC-compliant driver class org.h2.Driver (version 1.2)
20:09:32,251 INFO  [org.jboss.as.clustering.infinispan.subsystem] (Controller Boot Thread) Activating Infinispan subsystem.
20:09:32,385 INFO  [org.jboss.as.naming] (Controller Boot Thread) JBAS011800: Activating Naming Subsystem
20:09:32,401 INFO  [org.jboss.as.naming] (MSC service thread 1-4) JBAS011802: Starting Naming Service
20:09:32,408 INFO  [org.jboss.as.osgi] (Controller Boot Thread) JBAS011910: Activating OSGi Subsystem
20:09:32,435 INFO  [org.jboss.as.security] (Controller Boot Thread) Activating Security Subsystem
20:09:32,438 INFO  [org.jboss.remoting] (MSC service thread 1-2) JBoss Remoting version 3.2.0.Beta2
20:09:32,452 INFO  [org.xnio] (MSC service thread 1-2) XNIO Version 3.0.0.Beta3
20:09:32,467 INFO  [org.xnio.nio] (MSC service thread 1-2) XNIO NIO Implementation Version 3.0.0.Beta3
20:09:32,734 INFO  [org.apache.catalina.core.AprLifecycleListener] (MSC service thread 1-3) The Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: /usr/local/jdk1.6.0_27/jre/lib/i386/server:/usr/local/jdk1.6.0_27/jre/lib/i386:/usr/local/jdk1.6.0_27/jre/../lib/i386:/usr/java/packages/lib/i386:/lib:/usr/lib
20:09:32,772 INFO  [org.jboss.as.jmx.JMXConnectorService] (MSC service thread 1-4) Starting remote JMX connector
20:09:32,788 INFO  [org.jboss.as.ee] (Controller Boot Thread) Activating EE subsystem
20:09:32,795 INFO  [org.jboss.as.remoting] (MSC service thread 1-2) Listening on /127.0.0.1:9999
20:09:33,018 INFO  [org.apache.coyote.http11.Http11Protocol] (MSC service thread 1-2) Starting Coyote HTTP/1.1 on http--127.0.0.1-8080
20:09:33,140 INFO  [org.jboss.as.connector] (MSC service thread 1-3) Starting JCA Subsystem (JBoss IronJacamar 1.0.3.Final)
20:09:33,216 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-1) Bound data source [java:jboss/datasources/ExampleDS]
20:09:33,682 INFO  [org.jboss.as.deployment] (MSC service thread 1-3) Started FileSystemDeploymentService for directory /home/jtk/hacking/java/jboss/jboss-as-7.0.2.Final/standalone/deployments
20:09:34,092 INFO  [org.jboss.as.controller] (Controller Boot Thread) Service status report
   New missing/unsatisfied dependencies:
      service jboss.jdbc-driver.postgresql-8_4-703_jdbc4_jar (missing)

20:09:34,103 ERROR [org.jboss.as] (Controller Boot Thread) JBoss AS 7.0.2.Final "Arc" started (with errors) in 3690ms - Started 95 of 156 services (6 services failed or missing dependencies, 55 services are passive or on-demand)
20:09:34,117 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-4) Starting deployment of "postgresql-8.4-703.jdbc4.jar"
20:09:34,260 INFO  [org.jboss.as.jpa] (MSC service thread 1-3) added javax.persistence.api dependency to postgresql-8.4-703.jdbc4.jar
20:09:34,294 INFO  [org.jboss.as.connector.deployers.jdbc] (MSC service thread 1-2) Deploying non-JDBC-compliant driver class org.postgresql.Driver (version 8.4)
20:09:34,299 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-1) Bound data source [java:/IsacrodiPostgresDS]
20:09:34,300 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-4) Bound data source [java:/PostgresDS]
20:09:34,347 INFO  [org.jboss.as.server.controller] (DeploymentScanner-threads - 2) Deployed "postgresql-8.4-703.jdbc4.jar"
20:09:34,347 INFO  [org.jboss.as.controller] (DeploymentScanner-threads - 2) Service status report
   Newly corrected services:
      service jboss.jdbc-driver.postgresql-8_4-703_jdbc4_jar (now available)

20:09:39,378 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-4) Starting deployment of "clp.ear"
20:09:39,464 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-2) Starting deployment of "clp.jar"
20:09:39,464 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-1) Starting deployment of "clp.war"
20:09:39,501 INFO  [org.jboss.jpa] (MSC service thread 1-2) read persistence.xml for tempdb
20:09:39,515 INFO  [org.jboss.as.jpa] (MSC service thread 1-4) added javax.persistence.api, javaee.api, org.jboss.as.jpa, org.javassist dependencies to clp.ear
20:09:39,516 INFO  [org.jboss.as.jpa] (MSC service thread 1-4) added (default provider) org.hibernate dependency to application deployment (since 1 PU(s) didn't specify jboss.as.jpa.providerModule)
20:09:39,516 INFO  [org.jboss.as.jpa] (MSC service thread 1-4) added org.hibernate dependency to application deployment
20:09:39,518 INFO  [org.jboss.as.jpa] (MSC service thread 1-3) added javax.persistence.api, javaee.api, org.jboss.as.jpa, org.javassist dependencies to clp.war
20:09:39,518 INFO  [org.jboss.as.jpa] (MSC service thread 1-2) added javax.persistence.api, javaee.api, org.jboss.as.jpa, org.javassist dependencies to clp.jar
20:09:39,519 INFO  [org.jboss.as.jpa] (MSC service thread 1-2) added (default provider) org.hibernate dependency to application deployment (since 1 PU(s) didn't specify jboss.as.jpa.providerModule)
20:09:39,520 INFO  [org.jboss.as.jpa] (MSC service thread 1-2) added org.hibernate dependency to application deployment
20:09:39,584 ERROR [org.jboss.msc.service.fail] (MSC service thread 1-2) MSC00001: Failed to start service jboss.deployment.subunit."clp.ear"."clp.jar".POST_MODULE: org.jboss.msc.service.StartException in service jboss.deployment.subunit."clp.ear"."clp.jar".POST_MODULE: Failed to process phase POST_MODULE of subdeployment "clp.jar" of deployment "clp.ear"
        at org.jboss.as.server.deployment.DeploymentUnitPhaseService.start(DeploymentUnitPhaseService.java:121) [jboss-as-server-7.0.2.Final.jar:7.0.2.Final]
        at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1824) [jboss-msc-1.0.1.GA.jar:1.0.1.GA]
        at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1759) [jboss-msc-1.0.1.GA.jar:1.0.1.GA]
        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [:1.6.0_27]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [:1.6.0_27]
        at java.lang.Thread.run(Thread.java:662) [:1.6.0_27]
Caused by: java.lang.RuntimeException: Error getting reflective information for class jarmodule.BaseBean
        at org.jboss.as.server.deployment.reflect.DeploymentReflectionIndex.getClassIndex(DeploymentReflectionIndex.java:70) [jboss-as-server-7.0.2.Final.jar:7.0.2.Final]
        at org.jboss.as.ee.metadata.MethodAnnotationAggregator.runtimeAnnotationInformation(MethodAnnotationAggregator.java:59)
        at org.jboss.as.ejb3.deployment.processors.merging.TransactionAttributeMergingProcessor.processTransactionAttributeAnnotation(TransactionAttributeMergingProcessor.java:76)
        at org.jboss.as.ejb3.deployment.processors.merging.TransactionAttributeMergingProcessor.handleAnnotations(TransactionAttributeMergingProcessor.java:61)
        at org.jboss.as.ejb3.deployment.processors.merging.AbstractMergingProcessor.processComponentConfig(AbstractMergingProcessor.java:87)
        at org.jboss.as.ejb3.deployment.processors.merging.AbstractMergingProcessor.deploy(AbstractMergingProcessor.java:72)
        at org.jboss.as.server.deployment.DeploymentUnitPhaseService.start(DeploymentUnitPhaseService.java:115) [jboss-as-server-7.0.2.Final.jar:7.0.2.Final]
        ... 5 more
Caused by: java.lang.NoClassDefFoundError: Ljavax/persistence/EntityManager;
        at java.lang.Class.getDeclaredFields0(Native Method) [:1.6.0_27]
        at java.lang.Class.privateGetDeclaredFields(Class.java:2291) [:1.6.0_27]
        at java.lang.Class.getDeclaredFields(Class.java:1743) [:1.6.0_27]
        at org.jboss.as.server.deployment.reflect.ClassReflectionIndex.<init>(ClassReflectionIndex.java:57) [jboss-as-server-7.0.2.Final.jar:7.0.2.Final]
        at org.jboss.as.server.deployment.reflect.DeploymentReflectionIndex.getClassIndex(DeploymentReflectionIndex.java:66) [jboss-as-server-7.0.2.Final.jar:7.0.2.Final]
        ... 11 more
Caused by: java.lang.ClassNotFoundException: javax.persistence.EntityManager from [Module "deployment.clp.ear.jarmodule.jar:main" from Service Module Loader]
        at org.jboss.modules.ModuleClassLoader.findClass(ModuleClassLoader.java:191)
        at org.jboss.modules.ConcurrentClassLoader.performLoadClassChecked(ConcurrentClassLoader.java:361)
        at org.jboss.modules.ConcurrentClassLoader.performLoadClassChecked(ConcurrentClassLoader.java:333)
        at org.jboss.modules.ConcurrentClassLoader.performLoadClass(ConcurrentClassLoader.java:310)
        at org.jboss.modules.ConcurrentClassLoader.loadClass(ConcurrentClassLoader.java:103)
        ... 16 more

20:09:39,792 INFO  [org.jboss.as.server.controller] (DeploymentScanner-threads - 2) Deployment of "clp.ear" was rolled back with failure message {"Failed services" => {"jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".POST_MODULE" => "org.jboss.msc.service.StartException in service jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".POST_MODULE: Failed to process phase POST_MODULE of subdeployment \"clp.jar\" of deployment \"clp.ear\""},"Services with missing/unavailable dependencies" => ["jboss.naming.context.java.module.clp.\"clp.war\".ValidatorFactory missing [ jboss.naming.context.java.module.clp.\"clp.war\" ]","jboss.naming.context.java.module.clp.\"clp.war\".Validator missing [ jboss.naming.context.java.module.clp.\"clp.war\" ]"]}
20:09:39,814 INFO  [org.jboss.as.controller] (DeploymentScanner-threads - 2) Service status report
   New missing/unsatisfied dependencies:
      service jboss.naming.context.java.module.clp."clp.war" (missing)
  Services which failed to start:
      service jboss.deployment.subunit."clp.ear"."clp.jar".POST_MODULE: org.jboss.msc.service.StartException in service jboss.deployment.subunit."clp.ear"."clp.jar".POST_MODULE: Failed to process phase POST_MODULE of subdeployment "clp.jar" of deployment "clp.ear"

20:09:39,818 ERROR [org.jboss.as.deployment] (DeploymentScanner-threads - 1) {"Composite operation failed and was rolled back. Steps that failed:" => {"Operation step-2" => {"Failed services" => {"jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".POST_MODULE" => "org.jboss.msc.service.StartException in service jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".POST_MODULE: Failed to process phase POST_MODULE of subdeployment \"clp.jar\" of deployment \"clp.ear\""},"Services with missing/unavailable dependencies" => ["jboss.naming.context.java.module.clp.\"clp.war\".ValidatorFactory missing [ jboss.naming.context.java.module.clp.\"clp.war\" ]","jboss.naming.context.java.module.clp.\"clp.war\".Validator missing [ jboss.naming.context.java.module.clp.\"clp.war\" ]"]}}}
20:09:39,834 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-1) Stopped deployment clp.jar in 16ms
20:09:39,836 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-2) Stopped deployment clp.war in 18ms
20:09:39,839 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-1) Stopped deployment clp.ear in 46ms
20:09:39,840 INFO  [org.jboss.as.controller] (MSC service thread 1-3) Service status report
   Newly corrected services:
      service jboss.naming.context.java.module.clp."clp.war" (no longer required)

20:09:56,202 INFO  [org.jboss.as.controller] (MSC service thread 1-4) Service status report
   New missing/unsatisfied dependencies:
      service jboss.data-source-config.java:jboss/datasources/ExampleDS (missing)
      service jboss.jdbc-driver.postgresql-8_4-703_jdbc4_jar (missing)
      service jboss.jndi-view.extension.registry (missing)
      service jboss.module.service."deployment.postgresql-8.4-703.jdbc4.jar".main (missing)

20:09:56,218 INFO  [org.jboss.as.osgi] (MSC service thread 1-1) JBAS011921: Stopping OSGi Framework
20:09:56,219 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-1) Unbound data source [java:/PostgresDS]

3. Re: EAR migration problem with session bean extending a class in another jar

smarlow Feb 8, 2012 9:26 PM (in response to jtkim)

I think that clp.jar needs to move to the ear/lib folder (this is changed for JPA 2.0). I'll try that locally myself.

Thanks for reporting this, I just found an error message bug with the attached test case:

Can't find a deployment unit named null in deployment "clp.ear"

Should be:

Can't find a persistence unit named null in deployment "clp.ear"

Scott

4. Re: EAR migration problem with session bean extending a class in another jar

smarlow Feb 8, 2012 9:31 PM (in response to smarlow)

Never mind, that's not it. but, the error message fix is good.

5. Re: EAR migration problem with session bean extending a class in another jar

smarlow Feb 8, 2012 9:49 PM (in response to smarlow)

Can you try deploying your original application with the nightly AS7 build. Link is here.

There is definitely something wrong still, as deployment of the attached test sometimes looks like:

21:44:52,687 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "clp.ear"
21:44:52,805 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "clp.war"
21:44:52,805 INFO [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015876: Starting deployment of "clp.jar"
21:44:52,931 INFO [org.jboss.as.jpa] (MSC service thread 1-2) JBAS011401: Read persistence.xml for tempdb
21:44:53,078 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named ExtendedBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:app/clp.jar/ExtendedBean!jarmodule.Base
        java:module/ExtendedBean!jarmodule.Base
        java:jboss/exported/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:global/clp/clp.jar/ExtendedBean
        java:app/clp.jar/ExtendedBean
        java:module/ExtendedBean

21:44:53,099 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-3) JNDI bindings for session bean named DemoSessionBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:app/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:module/DemoSessionBean!clp.ejb.session.DemoSession
        java:jboss/exported/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:global/clp/clp.jar/DemoSessionBean
        java:app/clp.jar/DemoSessionBean
        java:module/DemoSessionBean

21:44:53,352 INFO [org.jboss.as.jpa] (MSC service thread 1-1) JBAS011402: Starting Persistence Unit Service 'clp.ear/clp.jar#tempdb'
21:44:53,452 INFO [org.jboss.web] (MSC service thread 1-2) JBAS018210: Registering web context: /clpdemo
21:44:53,594 INFO [org.hibernate.annotations.common.Version] (MSC service thread 1-1) HCANN000001: Hibernate Commons Annotations {4.0.1.Final}
21:44:53,602 INFO [org.hibernate.Version] (MSC service thread 1-1) HHH000412: Hibernate Core {4.0.1.Final}
21:44:53,610 INFO [org.hibernate.cfg.Environment] (MSC service thread 1-1) HHH000206: hibernate.properties not found
21:44:53,614 INFO [org.hibernate.cfg.Environment] (MSC service thread 1-1) HHH000021: Bytecode provider name : javassist
21:44:53,650 INFO [org.hibernate.ejb.Ejb3Configuration] (MSC service thread 1-1) HHH000204: Processing PersistenceUnitInfo [
        name: tempdb
        ...]
21:44:53,739 INFO [org.hibernate.service.jdbc.connections.internal.ConnectionProviderInitiator] (MSC service thread 1-1) HHH000130: Instantiating explicit connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
21:44:54,038 INFO [org.hibernate.dialect.Dialect] (MSC service thread 1-1) HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
21:44:54,047 WARN [org.hibernate.dialect.H2Dialect] (MSC service thread 1-1) HHH000431: Unable to determine H2 database version, certain features may not work
21:44:54,061 INFO [org.hibernate.engine.jdbc.internal.LobCreatorBuilder] (MSC service thread 1-1) HHH000423: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4
21:44:54,072 INFO [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] (MSC service thread 1-1) HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory
21:44:54,082 INFO [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] (MSC service thread 1-1) HHH000397: Using ASTQueryTranslatorFactory
21:44:54,284 INFO [org.hibernate.tool.hbm2ddl.SchemaExport] (MSC service thread 1-1) HHH000227: Running hbm2ddl schema export
21:44:54,294 INFO [org.hibernate.tool.hbm2ddl.SchemaExport] (MSC service thread 1-1) HHH000230: Schema export complete
21:44:54,348 INFO [org.jboss.as] (MSC service thread 1-4) JBAS015874: JBoss AS 7.1.0.Final-SNAPSHOT "Flux Capacitor" started in 5020ms - Started 243 of 320 services (76 services are passive or on-demand)
21:44:54,426 INFO [org.jboss.as.server] (DeploymentScanner-threads - 2) JBAS018559: Deployed "clp.ear"

A few times that I have started the AS with the attached test, I get:

21:29:51,960 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "fixed.ear"
21:29:52,004 ERROR [org.jboss.msc.service.fail] (MSC service thread 1-2) MSC00001: Failed to start service jboss.deployment.unit."fixed.ear".STRUCTURE: org.jboss.msc.service.StartException in service jboss.deployment.unit."fixed.ear".STRUCTURE: Failed to process phase STRUCTURE of deployment "fixed.ear"
        at org.jboss.as.server.deployment.DeploymentUnitPhaseService.start(DeploymentUnitPhaseService.java:119) [jboss-as-server-7.1.0.Final-SNAPSHOT.jar:7.1.0.Final-SNAPSHOT]
        at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811) [jboss-msc-1.0.2.GA.jar:1.0.2.GA]
        at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746) [jboss-msc-1.0.2.GA.jar:1.0.2.GA]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) [rt.jar:1.6.0_22]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) [rt.jar:1.6.0_22]
        at java.lang.Thread.run(Thread.java:679) [rt.jar:1.6.0_22]
Caused by: org.jboss.as.server.deployment.DeploymentUnitProcessingException: JBAS011037: Unable to process modules in application.xml for EAR ["/content/fixed.ear"], module file clp.jar not found
        at org.jboss.as.ee.structure.EarStructureProcessor.deploy(EarStructureProcessor.java:168)
        at org.jboss.as.server.deployment.DeploymentUnitPhaseService.start(DeploymentUnitPhaseService.java:113) [jboss-as-server-7.1.0.Final-SNAPSHOT.jar:7.1.0.Final-SNAPSHOT]
        ... 5 more

21:29:52,020 INFO [org.jboss.as.server] (DeploymentScanner-threads - 2) JBAS015870: Deploy of deployment "fixed.ear" was rolled back with failure message {"JBAS014671: Failed services" => {"jboss.deployment.unit.\"fixed.ear\".STRUCTURE" => "org.jboss.msc.service.StartException in service jboss.deployment.unit.\"fixed.ear\".STRUCTURE: Failed to process phase STRUCTURE of deployment \"fixed.ear\""}}
21:29:52,027 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015877: Stopped deployment fixed.ear in 5ms
21:29:52,030 INFO [org.jboss.as.controller] (DeploymentScanner-threads - 2) JBAS014774: Service status report
JBAS014777:   Services which failed to start:      service jboss.deployment.unit."fixed.ear".STRUCTURE: org.jboss.msc.service.StartException in service jboss.deployment.unit."fixed.ear".STRUCTURE: Failed to process phase STRUCTURE of deployment "fixed.ear"

21:29:52,032 ERROR [org.jboss.as.server.deployment.scanner] (DeploymentScanner-threads - 1) {"JBAS014653: Composite operation failed and was rolled back. Steps that failed:" => {"Operation step-2" => {"JBAS014671: Failed services" => {"jboss.deployment.unit.\"fixed.ear\".STRUCTURE" => "org.jboss.msc.service.StartException in service jboss.deployment.unit.\"fixed.ear\".STRUCTURE: Failed to process phase STRUCTURE of deployment \"fixed.ear\""}}}}
^C21:30:04,842 INFO [org.jboss.as.controller] (MSC service thread 1-3) JBAS014774: Service status report
JBAS014775:    New missing/unsatisfied dependencies:
      service jboss.thread.executor.default-short-running-threads.thread-factory (missing) dependents: [service jboss.thread.executor.default-short-running-threads]

21:30:04,846 INFO [org.jboss.as.controller] (MSC service thread 1-4) JBAS014774: Service status report
JBAS014775:    New missing/unsatisfied dependencies:
      service jboss.connector.config (missing) dependents: [service jboss.connector.archive_validation.config, service jboss.connector.bean_validation.config, service jboss.connector.bootstrapcontext.default]

Looking into this...

6. Re: EAR migration problem with session bean extending a class in another jar

smarlow Feb 8, 2012 10:10 PM (in response to smarlow)

Just to be complete, I also see this error sometimes as well:

22:09:02,130 INFO [org.jboss.as.server.deployment] (MSC service thread 1-4) JBAS015876: Starting deployment of "clp.ear"
22:09:02,235 INFO [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015876: Starting deployment of "clp.war"
22:09:02,235 INFO [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015876: Starting deployment of "clp.jar"
22:09:02,337 INFO [org.jboss.as.jpa] (MSC service thread 1-4) JBAS011401: Read persistence.xml for tempdb
22:09:02,458 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-4) JNDI bindings for session bean named ExtendedBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:app/clp.jar/ExtendedBean!jarmodule.Base
        java:module/ExtendedBean!jarmodule.Base
        java:jboss/exported/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:global/clp/clp.jar/ExtendedBean
        java:app/clp.jar/ExtendedBean
        java:module/ExtendedBean

22:09:02,475 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-4) JNDI bindings for session bean named DemoSessionBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:app/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:module/DemoSessionBean!clp.ejb.session.DemoSession
        java:jboss/exported/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:global/clp/clp.jar/DemoSessionBean
        java:app/clp.jar/DemoSessionBean
        java:module/DemoSessionBean

22:09:02,690 ERROR [org.jboss.msc.service.fail] (MSC service thread 1-4) MSC00001: Failed to start service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: org.jboss.msc.service.StartException in service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: Failed to process phase INSTALL of subdeployment "clp.jar" of deployment "clp.ear"
        at org.jboss.as.server.deployment.DeploymentUnitPhaseService.start(DeploymentUnitPhaseService.java:119) [jboss-as-server-7.1.0.Final-SNAPSHOT.jar:7.1.0.Final-SNAPSHOT]
        at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811) [jboss-msc-1.0.2.GA.jar:1.0.2.GA]
        at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746) [jboss-msc-1.0.2.GA.jar:1.0.2.GA]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) [rt.jar:1.6.0_22]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) [rt.jar:1.6.0_22]
        at java.lang.Thread.run(Thread.java:679) [rt.jar:1.6.0_22]
Caused by: org.jboss.as.server.deployment.DeploymentUnitProcessingException: JBAS011047: Component class jarmodule.BaseBean for component ExtendedBean has errors:
JBAS011440: Can't find a persistence unit named null in deployment "clp.ear"
        at org.jboss.as.ee.component.deployers.ModuleJndiBindingProcessor$1.handle(ModuleJndiBindingProcessor.java:169)
        at org.jboss.as.ee.component.ClassDescriptionTraversal.run(ClassDescriptionTraversal.java:54)
        at org.jboss.as.ee.component.deployers.ModuleJndiBindingProcessor.processClassConfigurations(ModuleJndiBindingProcessor.java:162)
        at org.jboss.as.ee.component.deployers.ModuleJndiBindingProcessor.deploy(ModuleJndiBindingProcessor.java:155)
        at org.jboss.as.server.deployment.DeploymentUnitPhaseService.start(DeploymentUnitPhaseService.java:113) [jboss-as-server-7.1.0.Final-SNAPSHOT.jar:7.1.0.Final-SNAPSHOT]
        ... 5 more

22:09:02,797 INFO [org.jboss.web] (MSC service thread 1-2) JBAS018210: Registering web context: /clpdemo
22:09:03,004 INFO [org.jboss.as.server] (DeploymentScanner-threads - 2) JBAS015870: Deploy of deployment "clp.ear" was rolled back with failure message {"JBAS014671: Failed services" => {"jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL" => "org.jboss.msc.service.StartException in service jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL: Failed to process phase INSTALL of subdeployment \"clp.jar\" of deployment \"clp.ear\""},"JBAS014771: Services with missing/unavailable dependencies" => ["jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]"]}
22:09:03,057 INFO [org.jboss.as.server.deployment] (MSC service thread 1-4) JBAS015877: Stopped deployment clp.war in 51ms
22:09:03,058 INFO [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015877: Stopped deployment clp.jar in 52ms
22:09:03,063 INFO [org.jboss.as.server.deployment] (MSC service thread 1-4) JBAS015877: Stopped deployment clp.ear in 57ms
22:09:03,065 INFO [org.jboss.as.controller] (DeploymentScanner-threads - 2) JBAS014774: Service status report
JBAS014775:    New missing/unsatisfied dependencies:
      service jboss.naming.context.java.comp.clp."clp.jar".DemoSessionBean (missing) dependents: [service jboss.naming.context.java.comp.clp."clp.jar".DemoSessionBean.ValidatorFactory, service jboss.naming.context.java.comp.clp."clp.jar".DemoSessionBean.Validator]
      service jboss.naming.context.java.comp.clp."clp.jar".ExtendedBean (missing) dependents: [service jboss.naming.context.java.comp.clp."clp.jar".ExtendedBean.Validator, service jboss.naming.context.java.comp.clp."clp.jar".ExtendedBean.ValidatorFactory]
JBAS014777:   Services which failed to start:      service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: org.jboss.msc.service.StartException in service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: Failed to process phase INSTALL of subdeployment "clp.jar" of deployment "clp.ear"

22:09:03,090 ERROR [org.jboss.as.server.deployment.scanner] (DeploymentScanner-threads - 1) {"JBAS014653: Composite operation failed and was rolled back. Steps that failed:" => {"Operation step-2" => {"JBAS014671: Failed services" => {"jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL" => "org.jboss.msc.service.StartException in service jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL: Failed to process phase INSTALL of subdeployment \"clp.jar\" of deployment \"clp.ear\""},"JBAS014771: Services with missing/unavailable dependencies" => ["jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]"]}}}

7. Re: EAR migration problem with session bean extending a class in another jar

smarlow Feb 8, 2012 10:19 PM (in response to smarlow)

Have you tried moving your BaseBean jar (jarmodule.jar in the test case), to the ear lib folder?

8. Re: EAR migration problem with session bean extending a class in another jar

smarlow Feb 8, 2012 11:05 PM (in response to smarlow)

I enabled JPA TRACE and am getting more information. Seems like a race condition but I'm not yet sure why. The below highlighted "22:28:03,858 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) pu search for name 'null' inside of clp.ear", is coming from the PARSE_PERSISTENCE_ANNOTATION (0x3000) which appears to be running before the PARSE_PERSISTENCE_UNIT (0x2F00) has finished.

I don't know if the ear is influencing this to happen somehow but this seems wrong to me.

server output:

22:28:00,466 INFO [org.jboss.modules] JBoss Modules version 1.1.0.GA
22:28:00,757 INFO [org.jboss.msc] JBoss MSC version 1.0.2.GA
22:28:00,822 INFO [org.jboss.as] JBAS015899: JBoss AS 7.1.0.Final-SNAPSHOT "Flux Capacitor" starting
22:28:02,013 INFO [org.jboss.as.server] JBAS015888: Creating http management service using socket-binding (management-http)
22:28:02,014 INFO [org.xnio] XNIO Version 3.0.0.GA
22:28:02,065 INFO [org.xnio.nio] XNIO NIO Implementation Version 3.0.0.GA
22:28:02,147 INFO [org.jboss.remoting] JBoss Remoting version 3.2.0.GA
22:28:02,168 INFO [org.jboss.as.logging] JBAS011502: Removing bootstrap log handlers
22:28:02,191 INFO [org.jboss.as.configadmin] (ServerService Thread Pool -- 26) JBAS016200: Activating ConfigAdmin Subsystem
22:28:02,252 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 31) JBAS010280: Activating Infinispan subsystem.
22:28:02,339 INFO [org.jboss.as.connector] (MSC service thread 1-4) JBAS010408: Starting JCA Subsystem (JBoss IronJacamar 1.0.7.Final)
22:28:02,341 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 27) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)
22:28:02,379 INFO [org.jboss.as.osgi] (ServerService Thread Pool -- 39) JBAS011940: Activating OSGi Subsystem
22:28:02,396 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 38) JBAS011800: Activating Naming Subsystem
22:28:02,430 INFO [org.jboss.as.security] (ServerService Thread Pool -- 44) JBAS013101: Activating Security Subsystem
22:28:02,482 INFO [org.jboss.as.security] (MSC service thread 1-2) JBAS013100: Current PicketBox version=4.0.6.final
22:28:02,510 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 48) JBAS015537: Activating WebServices Extension
22:28:02,495 INFO [org.jboss.as.naming] (MSC service thread 1-3) JBAS011802: Starting Naming Service
22:28:02,629 INFO [org.jboss.as.remoting] (MSC service thread 1-3) JBAS017100: Listening on /127.0.0.1:4447
22:28:02,642 INFO [org.jboss.as.mail.extension] (MSC service thread 1-2) JBAS015400: Bound mail session [java:jboss/mail/Default]
22:28:02,741 INFO [org.jboss.ws.common.management.AbstractServerConfig] (MSC service thread 1-3) JBoss Web Services - Stack CXF Server 4.0.1.GA
22:28:02,989 INFO [org.apache.coyote.http11.Http11AprProtocol] (MSC service thread 1-4) Starting Coyote HTTP/1.1 on http--127.0.0.1-8080
22:28:03,209 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-3) ARJUNA032010: JBossTS Recovery Service (tag: JBOSSTS_4_16_2_Final) - JBoss Inc.
22:28:03,250 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-3) ARJUNA012324: Start RecoveryActivators
22:28:03,281 INFO [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) ARJUNA012296: ExpiredEntryMonitor running at Wed, 8 Feb 2012 22:28:03
22:28:03,337 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-3) ARJUNA012343: RecoveryManagerImple is ready. Socket listener is turned off.
22:28:03,339 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-3) ARJUNA032013: Starting transaction recovery manager
22:28:03,395 INFO [org.jboss.as.remoting] (MSC service thread 1-1) JBAS017100: Listening on /127.0.0.1:9999
22:28:03,436 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-2) ARJUNA032017: JBossTS Transaction Service (JTA version - tag: JBOSSTS_4_16_2_Final) - JBoss Inc.
22:28:03,442 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-4) JBAS015012: Started FileSystemDeploymentService for directory /home/smarlow/work/as7/build/target/jboss-as-7.1.0.Final-SNAPSHOT/standalone/deployments
22:28:03,593 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-2) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]
22:28:03,654 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "clp.ear"
22:28:03,783 INFO [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015876: Starting deployment of "clp.jar"
22:28:03,783 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "clp.war"
22:28:03,853 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) parsed persistence unit definitions for ear clp.ear
22:28:03,855 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) incrementing PU count for clp.ear by 0
22:28:03,858 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) pu search for name 'null' inside of clp.ear
22:28:03,860 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) pu searching with empty unit name, application clp.ear has 0 persistence unit definitions
22:28:03,900 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) parse persistence.xml: attribute value(0) = tempdb
22:28:03,915 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) parse persistence.xml: element=jta-data-source
22:28:03,918 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) parse persistence.xml: element=properties
22:28:03,921 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) parsed persistence unit definitions for war clp.war
22:28:03,926 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) parse persistence.xml: reached ending persistence-unit tag
22:28:03,929 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) incrementing PU count for clp.ear by 0
22:28:03,932 INFO [org.jboss.as.jpa] (MSC service thread 1-3) JBAS011401: Read persistence.xml for tempdb
22:28:03,937 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) PersistenceUnitMetadataImpl(version=1.0) [
        name: tempdb
        jtaDataSource: java:jboss/datasources/ExampleDS
        nonJtaDataSource: null
        transactionType: JTA
        provider: org.hibernate.ejb.HibernatePersistence
        classes[
        ]
        packages[
        ]
        mappingFiles[
        ]
        jarFiles[
        ]
        validation-mode: AUTO
        shared-cache-mode: UNSPECIFIED
        properties[
                hibernate.hbm2ddl.auto: create-drop
        ]]
22:28:03,938 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) pu search for name 'null' inside of clp.war
22:28:03,951 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) pu searching with empty unit name, application clp.ear has 0 persistence unit definitions
22:28:03,958 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) parsed persistence unit definitions for jar clp.jar
22:28:03,967 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) incrementing PU count for clp.ear by 1
22:28:03,969 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) pu search for name 'null' inside of clp.jar
22:28:03,972 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) pu searching with empty unit name, application clp.ear has 1 persistence unit definitions
22:28:03,975 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) findWithinDeployment check 'null' against pu 'tempdb'
22:28:03,978 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) findWithinDeployment matched 'null' against pu 'tempdb'
22:28:03,980 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) pu search found clp.ear/clp.jar#tempdb
22:28:04,003 DEBUG [org.jboss.as.jpa] (MSC service thread 1-4) added javax.persistence.api, javaee.api, org.jboss.as.jpa, org.javassist dependencies to clp.ear
22:28:04,008 DEBUG [org.jboss.as.jpa] (MSC service thread 1-4) added (default provider) org.hibernate dependency to clp.ear (since 1 PU(s) didn't specify jboss.as.jpa.providerModule)
22:28:04,012 DEBUG [org.jboss.as.jpa] (MSC service thread 1-4) added org.hibernate dependency to application deployment
22:28:04,019 DEBUG [org.jboss.as.jpa] (MSC service thread 1-2) added javax.persistence.api, javaee.api, org.jboss.as.jpa, org.javassist dependencies to clp.war
22:28:04,020 DEBUG [org.jboss.as.jpa] (MSC service thread 1-1) added javax.persistence.api, javaee.api, org.jboss.as.jpa, org.javassist dependencies to clp.jar
22:28:04,024 DEBUG [org.jboss.as.jpa] (MSC service thread 1-2) added (default provider) org.hibernate dependency to clp.war (since 1 PU(s) didn't specify jboss.as.jpa.providerModule)
22:28:04,029 DEBUG [org.jboss.as.jpa] (MSC service thread 1-1) added (default provider) org.hibernate dependency to clp.jar (since 1 PU(s) didn't specify jboss.as.jpa.providerModule)
22:28:04,036 DEBUG [org.jboss.as.jpa] (MSC service thread 1-2) added org.hibernate dependency to application deployment
22:28:04,041 DEBUG [org.jboss.as.jpa] (MSC service thread 1-1) added org.hibernate dependency to application deployment

22:28:04,281 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-4) JNDI bindings for session bean named ExtendedBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:app/clp.jar/ExtendedBean!jarmodule.Base
        java:module/ExtendedBean!jarmodule.Base
        java:jboss/exported/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:global/clp/clp.jar/ExtendedBean
        java:app/clp.jar/ExtendedBean
        java:module/ExtendedBean

22:28:04,296 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-4) JNDI bindings for session bean named DemoSessionBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:app/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:module/DemoSessionBean!clp.ejb.session.DemoSession
        java:jboss/exported/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:global/clp/clp.jar/DemoSessionBean
        java:app/clp.jar/DemoSessionBean
        java:module/DemoSessionBean

22:28:04,334 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) install persistence unit definitions for ear clp.ear
22:28:04,342 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) install persistence unit definitions for ear jarmodule.jar
22:28:04,394 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) registering session bean interceptors for component 'ExtendedBean' in 'clp.jar'
22:28:04,397 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) registering session bean interceptors for component 'DemoSessionBean' in 'clp.jar'
22:28:04,423 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) install persistence unit definitions for war clp.war
22:28:04,603 ERROR [org.jboss.msc.service.fail] (MSC service thread 1-2) MSC00001: Failed to start service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: org.jboss.msc.service.StartException in service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: Failed to process phase INSTALL of subdeployment "clp.jar" of deployment "clp.ear"
        at org.jboss.as.server.deployment.DeploymentUnitPhaseService.start(DeploymentUnitPhaseService.java:119) [jboss-as-server-7.1.0.Final-SNAPSHOT.jar:7.1.0.Final-SNAPSHOT]
        at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811) [jboss-msc-1.0.2.GA.jar:1.0.2.GA]
        at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746) [jboss-msc-1.0.2.GA.jar:1.0.2.GA]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) [rt.jar:1.6.0_22]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) [rt.jar:1.6.0_22]
        at java.lang.Thread.run(Thread.java:679) [rt.jar:1.6.0_22]
Caused by: org.jboss.as.server.deployment.DeploymentUnitProcessingException: JBAS011047: Component class jarmodule.BaseBean for component ExtendedBean has errors:
JBAS011440: Can't find a persistence unit named null in deployment "clp.ear"
        at org.jboss.as.ee.component.deployers.ModuleJndiBindingProcessor$1.handle(ModuleJndiBindingProcessor.java:169)
        at org.jboss.as.ee.component.ClassDescriptionTraversal.run(ClassDescriptionTraversal.java:54)
        at org.jboss.as.ee.component.deployers.ModuleJndiBindingProcessor.processClassConfigurations(ModuleJndiBindingProcessor.java:162)
        at org.jboss.as.ee.component.deployers.ModuleJndiBindingProcessor.deploy(ModuleJndiBindingProcessor.java:155)
        at org.jboss.as.server.deployment.DeploymentUnitPhaseService.start(DeploymentUnitPhaseService.java:113) [jboss-as-server-7.1.0.Final-SNAPSHOT.jar:7.1.0.Final-SNAPSHOT]
        ... 5 more

22:28:04,737 INFO [org.jboss.web] (MSC service thread 1-4) JBAS018210: Registering web context: /clpdemo
22:28:04,953 INFO [org.jboss.as.server] (DeploymentScanner-threads - 2) JBAS015870: Deploy of deployment "clp.ear" was rolled back with failure message {"JBAS014671: Failed services" => {"jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL" => "org.jboss.msc.service.StartException in service jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL: Failed to process phase INSTALL of subdeployment \"clp.jar\" of deployment \"clp.ear\""},"JBAS014771: Services with missing/unavailable dependencies" => ["jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]"]}
22:28:05,038 INFO [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015877: Stopped deployment clp.war in 87ms
22:28:05,039 INFO [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015877: Stopped deployment clp.jar in 88ms
22:28:05,040 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015877: Stopped deployment clp.ear in 90ms
22:28:05,041 INFO [org.jboss.as.controller] (DeploymentScanner-threads - 2) JBAS014774: Service status report
JBAS014775:    New missing/unsatisfied dependencies:
      service jboss.naming.context.java.comp.clp."clp.jar".DemoSessionBean (missing) dependents: [service jboss.naming.context.java.comp.clp."clp.jar".DemoSessionBean.ValidatorFactory, service jboss.naming.context.java.comp.clp."clp.jar".DemoSessionBean.Validator]
      service jboss.naming.context.java.comp.clp."clp.jar".ExtendedBean (missing) dependents: [service jboss.naming.context.java.comp.clp."clp.jar".ExtendedBean.Validator, service jboss.naming.context.java.comp.clp."clp.jar".ExtendedBean.ValidatorFactory]
JBAS014777:   Services which failed to start:      service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: org.jboss.msc.service.StartException in service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: Failed to process phase INSTALL of subdeployment "clp.jar" of deployment "clp.ear"

22:28:05,045 ERROR [org.jboss.as.server.deployment.scanner] (DeploymentScanner-threads - 1) {"JBAS014653: Composite operation failed and was rolled back. Steps that failed:" => {"Operation step-2" => {"JBAS014671: Failed services" => {"jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL" => "org.jboss.msc.service.StartException in service jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL: Failed to process phase INSTALL of subdeployment \"clp.jar\" of deployment \"clp.ear\""},"JBAS014771: Services with missing/unavailable dependencies" => ["jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]"]}}}

9. Re: EAR migration problem with session bean extending a class in another jar

smarlow Feb 8, 2012 11:20 PM (in response to smarlow)

The deployment output when we succeed is below.

22:27:02,438 INFO [org.jboss.modules] JBoss Modules version 1.1.0.GA
22:27:02,635 INFO [org.jboss.msc] JBoss MSC version 1.0.2.GA
22:27:02,678 INFO [org.jboss.as] JBAS015899: JBoss AS 7.1.0.Final-SNAPSHOT "Flux Capacitor" starting
22:27:03,871 INFO [org.jboss.as.server] JBAS015888: Creating http management service using socket-binding (management-http)
22:27:03,871 INFO [org.xnio] XNIO Version 3.0.0.GA
22:27:03,906 INFO [org.xnio.nio] XNIO NIO Implementation Version 3.0.0.GA
22:27:03,940 INFO [org.jboss.remoting] JBoss Remoting version 3.2.0.GA
22:27:03,959 INFO [org.jboss.as.logging] JBAS011502: Removing bootstrap log handlers
22:27:03,970 INFO [org.jboss.as.configadmin] (ServerService Thread Pool -- 26) JBAS016200: Activating ConfigAdmin Subsystem
22:27:04,018 INFO [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 31) JBAS010280: Activating Infinispan subsystem.
22:27:04,111 INFO [org.jboss.as.connector] (MSC service thread 1-1) JBAS010408: Starting JCA Subsystem (JBoss IronJacamar 1.0.7.Final)
22:27:04,174 INFO [org.jboss.as.osgi] (ServerService Thread Pool -- 39) JBAS011940: Activating OSGi Subsystem
22:27:04,176 INFO [org.jboss.as.naming] (ServerService Thread Pool -- 38) JBAS011800: Activating Naming Subsystem
22:27:04,175 INFO [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 27) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)
22:27:04,249 INFO [org.jboss.as.naming] (MSC service thread 1-3) JBAS011802: Starting Naming Service
22:27:04,253 INFO [org.jboss.as.mail.extension] (MSC service thread 1-4) JBAS015400: Bound mail session [java:jboss/mail/Default]
22:27:04,257 INFO [org.jboss.as.security] (ServerService Thread Pool -- 44) JBAS013101: Activating Security Subsystem
22:27:04,303 INFO [org.jboss.as.security] (MSC service thread 1-1) JBAS013100: Current PicketBox version=4.0.6.final
22:27:04,312 INFO [org.jboss.as.webservices] (ServerService Thread Pool -- 48) JBAS015537: Activating WebServices Extension
22:27:04,334 INFO [org.jboss.as.remoting] (MSC service thread 1-3) JBAS017100: Listening on /127.0.0.1:4447
22:27:04,539 INFO [org.jboss.ws.common.management.AbstractServerConfig] (MSC service thread 1-3) JBoss Web Services - Stack CXF Server 4.0.1.GA
22:27:04,901 INFO [org.apache.coyote.http11.Http11AprProtocol] (MSC service thread 1-1) Starting Coyote HTTP/1.1 on http--127.0.0.1-8080
22:27:05,000 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-3) ARJUNA032010: JBossTS Recovery Service (tag: JBOSSTS_4_16_2_Final) - JBoss Inc.
22:27:05,040 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-3) ARJUNA012324: Start RecoveryActivators
22:27:05,072 INFO [com.arjuna.ats.arjuna] (Transaction Expired Entry Monitor) ARJUNA012296: ExpiredEntryMonitor running at Wed, 8 Feb 2012 22:27:05
22:27:05,127 INFO [com.arjuna.ats.arjuna] (MSC service thread 1-3) ARJUNA012343: RecoveryManagerImple is ready. Socket listener is turned off.
22:27:05,141 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-3) ARJUNA032013: Starting transaction recovery manager
22:27:05,210 INFO [org.jboss.as.remoting] (MSC service thread 1-2) JBAS017100: Listening on /127.0.0.1:9999
22:27:05,237 INFO [org.jboss.as.server.deployment.scanner] (MSC service thread 1-1) JBAS015012: Started FileSystemDeploymentService for directory /home/smarlow/work/as7/build/target/jboss-as-7.1.0.Final-SNAPSHOT/standalone/deployments
22:27:05,240 INFO [org.jboss.as.server.deployment.scanner] (DeploymentScanner-threads - 1) JBAS015014: Re-attempting failed deployment clp.ear
22:27:05,244 INFO [com.arjuna.ats.jbossatx] (MSC service thread 1-3) ARJUNA032017: JBossTS Transaction Service (JTA version - tag: JBOSSTS_4_16_2_Final) - JBoss Inc.
22:27:05,377 INFO [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-1) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]
22:27:05,406 INFO [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015876: Starting deployment of "clp.ear"
22:27:05,518 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "clp.war"
22:27:05,518 INFO [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015876: Starting deployment of "clp.jar"
22:27:05,560 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) parsed persistence unit definitions for ear clp.ear
22:27:05,562 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) incrementing PU count for clp.ear by 0
22:27:05,567 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) pu search for name 'null' inside of clp.ear
22:27:05,569 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) pu searching with empty unit name, application clp.ear has 0 persistence unit definitions
22:27:05,616 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) parse persistence.xml: attribute value(0) = tempdb
22:27:05,628 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) parse persistence.xml: element=jta-data-source
22:27:05,631 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) parse persistence.xml: element=properties
22:27:05,636 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) parse persistence.xml: reached ending persistence-unit tag
22:27:05,639 INFO [org.jboss.as.jpa] (MSC service thread 1-4) JBAS011401: Read persistence.xml for tempdb
22:27:05,642 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) PersistenceUnitMetadataImpl(version=1.0) [
        name: tempdb
        jtaDataSource: java:jboss/datasources/ExampleDS
        nonJtaDataSource: null
        transactionType: JTA
        provider: org.hibernate.ejb.HibernatePersistence
        classes[
        ]
        packages[
        ]
        mappingFiles[
        ]
        jarFiles[
        ]
        validation-mode: AUTO
        shared-cache-mode: UNSPECIFIED
        properties[
                hibernate.hbm2ddl.auto: create-drop
        ]]
22:27:05,656 TRACE [org.jboss.as.jpa] (MSC service thread 1-1) parsed persistence unit definitions for war clp.war
22:27:05,669 TRACE [org.jboss.as.jpa] (MSC service thread 1-1) incrementing PU count for clp.ear by 0
22:27:05,674 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) parsed persistence unit definitions for jar clp.jar
22:27:05,675 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) incrementing PU count for clp.ear by 1
22:27:05,676 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) pu search for name 'null' inside of clp.jar
22:27:05,676 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) pu searching with empty unit name, application clp.ear has 1 persistence unit definitions
22:27:05,677 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) findWithinDeployment check 'null' against pu 'tempdb'
22:27:05,677 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) findWithinDeployment matched 'null' against pu 'tempdb'
22:27:05,678 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) pu search found clp.ear/clp.jar#tempdb
22:27:05,681 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) pu search for name 'null' inside of clp.jar
22:27:05,681 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) pu searching with empty unit name, application clp.ear has 1 persistence unit definitions
22:27:05,682 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) findWithinDeployment check 'null' against pu 'tempdb'
22:27:05,682 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) findWithinDeployment matched 'null' against pu 'tempdb'
22:27:05,683 TRACE [org.jboss.as.jpa] (MSC service thread 1-4) pu search found clp.ear/clp.jar#tempdb
22:27:05,688 DEBUG [org.jboss.as.jpa] (MSC service thread 1-1) added javax.persistence.api, javaee.api, org.jboss.as.jpa, org.javassist dependencies to clp.ear
22:27:05,689 DEBUG [org.jboss.as.jpa] (MSC service thread 1-1) added (default provider) org.hibernate dependency to clp.ear (since 1 PU(s) didn't specify jboss.as.jpa.providerModule)
22:27:05,691 DEBUG [org.jboss.as.jpa] (MSC service thread 1-1) added org.hibernate dependency to application deployment
22:27:05,693 DEBUG [org.jboss.as.jpa] (MSC service thread 1-3) added javax.persistence.api, javaee.api, org.jboss.as.jpa, org.javassist dependencies to clp.war
22:27:05,693 DEBUG [org.jboss.as.jpa] (MSC service thread 1-2) added javax.persistence.api, javaee.api, org.jboss.as.jpa, org.javassist dependencies to clp.jar
22:27:05,696 DEBUG [org.jboss.as.jpa] (MSC service thread 1-3) added (default provider) org.hibernate dependency to clp.war (since 1 PU(s) didn't specify jboss.as.jpa.providerModule)
22:27:05,697 DEBUG [org.jboss.as.jpa] (MSC service thread 1-3) added org.hibernate dependency to application deployment
22:27:05,696 DEBUG [org.jboss.as.jpa] (MSC service thread 1-2) added (default provider) org.hibernate dependency to clp.jar (since 1 PU(s) didn't specify jboss.as.jpa.providerModule)
22:27:05,699 DEBUG [org.jboss.as.jpa] (MSC service thread 1-2) added org.hibernate dependency to application deployment
22:27:05,820 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-1) JNDI bindings for session bean named ExtendedBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:app/clp.jar/ExtendedBean!jarmodule.Base
        java:module/ExtendedBean!jarmodule.Base
        java:jboss/exported/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:global/clp/clp.jar/ExtendedBean
        java:app/clp.jar/ExtendedBean
        java:module/ExtendedBean

22:27:05,827 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-1) JNDI bindings for session bean named DemoSessionBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:app/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:module/DemoSessionBean!clp.ejb.session.DemoSession
        java:jboss/exported/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:global/clp/clp.jar/DemoSessionBean
        java:app/clp.jar/DemoSessionBean
        java:module/DemoSessionBean

22:27:05,836 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) install persistence unit definitions for ear clp.ear
22:27:05,840 TRACE [org.jboss.as.jpa] (MSC service thread 1-3) install persistence unit definitions for ear jarmodule.jar
22:27:05,878 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) registering session bean interceptors for component 'ExtendedBean' in 'clp.jar'
22:27:05,884 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) registering session bean interceptors for component 'DemoSessionBean' in 'clp.jar'
22:27:05,915 TRACE [org.jboss.as.jpa] (MSC service thread 1-1) install persistence unit definitions for war clp.war
22:27:06,102 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) install persistence unit definition for jar clp.jar
22:27:06,105 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) adding 'vfs:/content/clp.ear/clp.jar/' to annotation index map
22:27:06,108 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) adding 'vfs:/content/clp.ear/jarmodule.jar/' to annotation index map
22:27:06,111 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) adding 'vfs:/content/clp.ear/clp.jar/' to annotation index map
22:27:06,115 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) adding 'vfs:/content/clp.ear/jarmodule.jar/' to annotation index map
22:27:06,119 DEBUG [org.jboss.as.jpa] (MSC service thread 1-2) loaded persistence provider adapter org.jboss.as.jpa.hibernate:4
22:27:06,127 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) lookup provider checking provider version (4.0.1.Final)
22:27:06,135 DEBUG [org.jboss.as.jpa] (MSC service thread 1-2) Adding dependency on PU service service jboss.persistenceunit."clp.ear/clp.jar#tempdb" for component clp.ejb.session.ExtendedBean
22:27:06,139 DEBUG [org.jboss.as.jpa] (MSC service thread 1-2) Adding dependency on PU service service jboss.persistenceunit."clp.ear/clp.jar#tempdb" for component clp.ejb.session.DemoSessionBean
22:27:06,145 TRACE [org.jboss.as.jpa] (MSC service thread 1-2) added PersistenceUnitService for 'service jboss.persistenceunit."clp.ear/clp.jar#tempdb"'. PU is ready for injector action.
22:27:06,196 INFO [org.jboss.as.jpa] (MSC service thread 1-1) JBAS011402: Starting Persistence Unit Service 'clp.ear/clp.jar#tempdb'
22:27:06,286 INFO [org.jboss.web] (MSC service thread 1-4) JBAS018210: Registering web context: /clpdemo
22:27:06,397 INFO [org.hibernate.annotations.common.Version] (MSC service thread 1-1) HCANN000001: Hibernate Commons Annotations {4.0.1.Final}
22:27:06,404 INFO [org.hibernate.Version] (MSC service thread 1-1) HHH000412: Hibernate Core {4.0.1.Final}
22:27:06,410 INFO [org.hibernate.cfg.Environment] (MSC service thread 1-1) HHH000206: hibernate.properties not found
22:27:06,414 INFO [org.hibernate.cfg.Environment] (MSC service thread 1-1) HHH000021: Bytecode provider name : javassist
22:27:06,437 INFO [org.hibernate.ejb.Ejb3Configuration] (MSC service thread 1-1) HHH000204: Processing PersistenceUnitInfo [
        name: tempdb
        ...]
22:27:06,452 TRACE [org.jboss.as.jpa] (MSC service thread 1-1) getClassesInJar url=/content/clp.ear/clp.jar/ annotations=[interface javax.persistence.Embeddable, interface javax.persistence.MappedSuperclass, interface javax.persistence.Entity]
22:27:06,459 TRACE [org.jboss.as.jpa] (MSC service thread 1-1) getPackagesInJar url=/content/clp.ear/clp.jar/ annotations=[]
22:27:06,463 TRACE [org.jboss.as.jpa] (MSC service thread 1-1) getPackagesInJar found package package clp.ejb.session
22:27:06,466 TRACE [org.jboss.as.jpa] (MSC service thread 1-1) getPackagesInJar found package package clp.ejb.session
22:27:06,474 TRACE [org.jboss.as.jpa] (MSC service thread 1-1) getPackagesInJar found package package clp.ejb.session
22:27:06,576 INFO [org.hibernate.service.jdbc.connections.internal.ConnectionProviderInitiator] (MSC service thread 1-1) HHH000130: Instantiating explicit connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
22:27:06,928 INFO [org.hibernate.dialect.Dialect] (MSC service thread 1-1) HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
22:27:06,940 WARN [org.hibernate.dialect.H2Dialect] (MSC service thread 1-1) HHH000431: Unable to determine H2 database version, certain features may not work
22:27:06,951 INFO [org.hibernate.engine.jdbc.internal.LobCreatorBuilder] (MSC service thread 1-1) HHH000423: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4
22:27:06,971 INFO [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] (MSC service thread 1-1) HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory
22:27:06,983 INFO [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] (MSC service thread 1-1) HHH000397: Using ASTQueryTranslatorFactory
22:27:07,216 INFO [org.hibernate.tool.hbm2ddl.SchemaExport] (MSC service thread 1-1) HHH000227: Running hbm2ddl schema export
22:27:07,220 INFO [org.hibernate.tool.hbm2ddl.SchemaExport] (MSC service thread 1-1) HHH000230: Schema export complete
22:27:07,263 INFO [org.jboss.as] (MSC service thread 1-2) JBAS015874: JBoss AS 7.1.0.Final-SNAPSHOT "Flux Capacitor" started in 5045ms - Started 243 of 320 services (76 services are passive or on-demand)
22:27:07,342 INFO [org.jboss.as.server] (DeploymentScanner-threads - 2) JBAS018559: Deployed "clp.ear"

10. Re: EAR migration problem with session bean extending a class in another jar

smarlow Feb 9, 2012 12:10 AM (in response to smarlow)

I created jira AS7-3683 for this issue.

11. Re: EAR migration problem with session bean extending a class in another jar

smarlow Feb 9, 2012 6:58 AM (in response to smarlow)

I can see that the AS7 nightly build hasn't been created yet. The change that lets me consistently deploy the attached test application, should be in the next nightly build when it is built. For verification of the AS7-3683 fix, try the next aS7 build (in the Feb 09 or later build). Link is https://ci.jboss.org/jenkins/job/JBoss-AS-7.x-latest/

Scott

12. Re: EAR migration problem with session bean extending a class in another jar

jtkim Feb 9, 2012 5:20 PM (in response to smarlow)

I've tried my test app with build 584 from jenkins and I confirm that it successfully deploys with that build.

This was an unexpected solution to me -- I had anticipated to find that the primitive pattern of just dumping jars into the top level of the ear turns out to be somewhat sloppy or something. I'm impressed at how quickly you tracked this down, congratulations.

As I said, I'm not really sophisticated in application assembly but following your suggestion, I created a directory called lib and put jarmodule.jar into that, rather than into the top level directory of the ear. As far as I understand from a cursory read of the Java EE spec, that should be all -- but (interestingly?), I get a "Can't find a persistence unit named null in deployment" exception. Please tell me if I've got the setup of the lib directory wrong.

I wonder what triggers the search for "persistence unit named null", it looks to me as though the string that's supposed to hold the name of the persistence unit is a null reference and therefore, the search is perhaps doomed from the start. As a wild guess, perhaps JBoss somehow starts looking for a persistence unit for jarmodule.jar because it sees the @PersistenceContext and @EntityManager annotations in ExtendedBean?

Anyway, I post the server log below and attach the modified version of the test app.

Best regards, Jan

21:34:59,474 INFO  [org.jboss.as.configadmin] (ServerService Thread Pool -- 26) JBAS016200: Activating ConfigAdmin Subsystem
21:34:59,503 INFO  [org.jboss.as.clustering.infinispan] (ServerService Thread Pool -- 31) JBAS010280: Activating Infinispan subsystem.
21:34:59,581 INFO  [org.jboss.as.naming] (ServerService Thread Pool -- 38) JBAS011800: Activating Naming Subsystem
21:34:59,601 INFO  [org.jboss.as.security] (ServerService Thread Pool -- 44) JBAS013101: Activating Security Subsystem
21:34:59,616 INFO  [org.jboss.as.connector] (MSC service thread 1-1) JBAS010408: Starting JCA Subsystem (JBoss IronJacamar 1.0.7.Final)
21:34:59,634 INFO  [org.jboss.as.osgi] (ServerService Thread Pool -- 39) JBAS011940: Activating OSGi Subsystem
21:34:59,645 INFO  [org.jboss.as.webservices] (ServerService Thread Pool -- 48) JBAS015537: Activating WebServices Extension
21:34:59,706 INFO  [org.jboss.as.security] (MSC service thread 1-2) JBAS013100: Current PicketBox version=4.0.6.final
21:34:59,800 INFO  [org.jboss.as.naming] (MSC service thread 1-1) JBAS011802: Starting Naming Service
21:34:59,830 INFO  [org.jboss.as.connector.subsystems.datasources] (ServerService Thread Pool -- 27) JBAS010403: Deploying JDBC-compliant driver class org.h2.Driver (version 1.3)
21:34:59,844 INFO  [org.jboss.as.mail.extension] (MSC service thread 1-4) JBAS015400: Bound mail session [java:jboss/mail/Default]
21:34:59,944 INFO  [org.jboss.ws.common.management.AbstractServerConfig] (MSC service thread 1-1) JBoss Web Services - Stack CXF Server 4.0.1.GA
21:35:00,467 INFO  [org.apache.coyote.http11.Http11AprProtocol] (MSC service thread 1-2) Starting Coyote HTTP/1.1 on http--127.0.0.1-8080
21:35:00,594 INFO  [org.jboss.as.remoting] (MSC service thread 1-2) JBAS017100: Listening on /127.0.0.1:4447
21:35:00,597 INFO  [org.jboss.as.remoting] (MSC service thread 1-3) JBAS017100: Listening on /127.0.0.1:9999
21:35:00,634 INFO  [org.jboss.as.server.deployment.scanner] (MSC service thread 1-3) JBAS015012: Started FileSystemDeploymentService for directory /home/jtk/hacking/java/jboss/jboss-as-7.1.0.Final-SNAPSHOT/standalone/deployments
21:35:00,768 INFO  [org.jboss.as.connector.subsystems.datasources] (MSC service thread 1-1) JBAS010400: Bound data source [java:jboss/datasources/ExampleDS]
21:35:00,789 INFO  [org.jboss.as] (Controller Boot Thread) JBAS015874: JBoss AS 7.1.0.Final-SNAPSHOT "Flux Capacitor" started in 3283ms - Started 134 of 205 services (70 services are passive or on-demand)
21:35:10,692 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-4) JBAS015876: Starting deployment of "clp.ear"
21:35:10,782 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "clp.jar"
21:35:10,783 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015876: Starting deployment of "clp.war"
21:35:10,793 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-4) Class Path entry jarmodule.jar in "/content/clp.ear/clp.war"  does not point to a valid jar for a Class-Path reference.
21:35:10,795 WARN  [org.jboss.as.server.deployment] (MSC service thread 1-1) Class Path entry jarmodule.jar in "/content/clp.ear/clp.jar"  does not point to a valid jar for a Class-Path reference.
21:35:10,886 INFO  [org.jboss.as.jpa] (MSC service thread 1-1) JBAS011401: Read persistence.xml for tempdb
21:35:11,051 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-4) JNDI bindings for session bean named ExtendedBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:app/clp.jar/ExtendedBean!jarmodule.Base
        java:module/ExtendedBean!jarmodule.Base
        java:jboss/exported/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:global/clp/clp.jar/ExtendedBean
        java:app/clp.jar/ExtendedBean
        java:module/ExtendedBean

21:35:11,053 INFO  [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-4) JNDI bindings for session bean named DemoSessionBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:app/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:module/DemoSessionBean!clp.ejb.session.DemoSession
        java:jboss/exported/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:global/clp/clp.jar/DemoSessionBean
        java:app/clp.jar/DemoSessionBean
        java:module/DemoSessionBean

21:35:11,379 ERROR [org.jboss.msc.service.fail] (MSC service thread 1-1) MSC00001: Failed to start service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: org.jboss.msc.service.StartException in service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: Failed to process phase INSTALL of subdeployment "clp.jar" of deployment "clp.ear"
        at org.jboss.as.server.deployment.DeploymentUnitPhaseService.start(DeploymentUnitPhaseService.java:119) [jboss-as-server-7.1.0.Final-SNAPSHOT.jar:7.1.0.Final-SNAPSHOT]
        at org.jboss.msc.service.ServiceControllerImpl$StartTask.startService(ServiceControllerImpl.java:1811) [jboss-msc-1.0.2.GA.jar:1.0.2.GA]
        at org.jboss.msc.service.ServiceControllerImpl$StartTask.run(ServiceControllerImpl.java:1746) [jboss-msc-1.0.2.GA.jar:1.0.2.GA]
        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) [rt.jar:1.6.0_27]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) [rt.jar:1.6.0_27]
        at java.lang.Thread.run(Thread.java:662) [rt.jar:1.6.0_27]
Caused by: org.jboss.as.server.deployment.DeploymentUnitProcessingException: JBAS011047: Component class jarmodule.BaseBean for component ExtendedBean has errors: 
JBAS011440: Can't find a persistence unit named null in deployment "clp.ear"
        at org.jboss.as.ee.component.deployers.ModuleJndiBindingProcessor$1.handle(ModuleJndiBindingProcessor.java:169)
        at org.jboss.as.ee.component.ClassDescriptionTraversal.run(ClassDescriptionTraversal.java:54)
        at org.jboss.as.ee.component.deployers.ModuleJndiBindingProcessor.processClassConfigurations(ModuleJndiBindingProcessor.java:162)
        at org.jboss.as.ee.component.deployers.ModuleJndiBindingProcessor.deploy(ModuleJndiBindingProcessor.java:155)
        at org.jboss.as.server.deployment.DeploymentUnitPhaseService.start(DeploymentUnitPhaseService.java:113) [jboss-as-server-7.1.0.Final-SNAPSHOT.jar:7.1.0.Final-SNAPSHOT]
        ... 5 more

21:35:11,484 INFO  [org.jboss.web] (MSC service thread 1-4) JBAS018210: Registering web context: /clpdemo
21:35:11,703 INFO  [org.jboss.as.server] (DeploymentScanner-threads - 2) JBAS015870: Deploy of deployment "clp.ear" was rolled back with failure message {"JBAS014671: Failed services" => {"jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL" => "org.jboss.msc.service.StartException in service jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL: Failed to process phase INSTALL of subdeployment \"clp.jar\" of deployment \"clp.ear\""},"JBAS014771: Services with missing/unavailable dependencies" => ["jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]"]}
21:35:11,744 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015877: Stopped deployment clp.jar in 53ms
21:35:11,744 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-1) JBAS015877: Stopped deployment clp.war in 54ms
21:35:11,749 INFO  [org.jboss.as.server.deployment] (MSC service thread 1-4) JBAS015877: Stopped deployment clp.ear in 59ms
21:35:11,751 INFO  [org.jboss.as.controller] (DeploymentScanner-threads - 2) JBAS014774: Service status report
JBAS014775:    New missing/unsatisfied dependencies:
      service jboss.naming.context.java.comp.clp."clp.jar".DemoSessionBean (missing) dependents: [service jboss.naming.context.java.comp.clp."clp.jar".DemoSessionBean.ValidatorFactory, service jboss.naming.context.java.comp.clp."clp.jar".DemoSessionBean.Validator] 
      service jboss.naming.context.java.comp.clp."clp.jar".ExtendedBean (missing) dependents: [service jboss.naming.context.java.comp.clp."clp.jar".ExtendedBean.Validator, service jboss.naming.context.java.comp.clp."clp.jar".ExtendedBean.ValidatorFactory] 
JBAS014777:   Services which failed to start:      service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: org.jboss.msc.service.StartException in service jboss.deployment.subunit."clp.ear"."clp.jar".INSTALL: Failed to process phase INSTALL of subdeployment "clp.jar" of deployment "clp.ear"

21:35:11,755 ERROR [org.jboss.as.server.deployment.scanner] (DeploymentScanner-threads - 1) {"JBAS014653: Composite operation failed and was rolled back. Steps that failed:" => {"Operation step-2" => {"JBAS014671: Failed services" => {"jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL" => "org.jboss.msc.service.StartException in service jboss.deployment.subunit.\"clp.ear\".\"clp.jar\".INSTALL: Failed to process phase INSTALL of subdeployment \"clp.jar\" of deployment \"clp.ear\""},"JBAS014771: Services with missing/unavailable dependencies" => ["jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.ValidatorFactoryjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".DemoSessionBean]","jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBeanMissing[jboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean.Validatorjboss.naming.context.java.comp.clp.\"clp.jar\".ExtendedBean]"]}}}
21:35:15,254 INFO  [org.jboss.as.osgi] (MSC service thread 1-4) JBAS011942: Stopping OSGi Framework
21:35:15,267 INFO  [org.apache.coyote.http11.Http11AprProtocol] (MSC service thread 1-3) Pausing Coyote HTTP/1.1 on http--127.0.0.1-8080
21:35:15,267 INFO  [org.apache.coyote.http11.Http11AprProtocol] (MSC service thread 1-3) Stopping Coyote HTTP/1.1 on http--127.0.0.1-8080
21:35:15,269 INFO  [org.apache.catalina.core.StandardContext] (MSC service thread 1-1) Container org.apache.catalina.core.ContainerBase.[jboss.web].[default-host].[/] has not been started

classloadproblem.tar.gz 16.9 KB

13. Re: EAR migration problem with session bean extending a class in another jar

smarlow Feb 9, 2012 6:52 PM (in response to jtkim)

Jan Kim wrote:

As I said, I'm not really sophisticated in application assembly but following your suggestion, I created a directory called lib and put jarmodule.jar into that, rather than into the top level directory of the ear. As far as I understand from a cursory read of the Java EE spec, that should be all -- but (interestingly?), I get a "Can't find a persistence unit named null in deployment" exception. Please tell me if I've got the setup of the lib directory wrong.

I wonder what triggers the search for "persistence unit named null", it looks to me as though the string that's supposed to hold the name of the persistence unit is a null reference and therefore, the search is perhaps doomed from the start. As a wild guess, perhaps JBoss somehow starts looking for a persistence unit for jarmodule.jar because it sees the @PersistenceContext and @EntityManager annotations in ExtendedBean?

I think the error message is too cryptic, if you have supplied a unitName:

@PersistenceContext(unitName = "tempdb")
  protected EntityManager entityManager;

Then the error message would of been

Can't find a persistence unit named tempdb in deployment"

If the persistence unit name is not specified, it would be more meaningful to say something like "Can't find the persistence unit in deployment". But, that gives you an idea of what is really meant. The fixed bug, is that we didn't actually parse the persistence.xml yet and that is why the failure occurs. The fix ensures that we parse the persistence.xml before searching for it.

I think that the fix was actually merged about 15 hours ago (around 3:00am EST), so you should grab a later build (build 586 should have it https://ci.jboss.org/hudson/job/JBoss-AS-7.x-latest/586/)

The attached test is deploying correctly for me without error.

8:52:39,780 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "clp.ear"
18:52:39,902 INFO [org.jboss.as.server.deployment] (MSC service thread 1-2) JBAS015876: Starting deployment of "clp.jar"
18:52:39,902 INFO [org.jboss.as.server.deployment] (MSC service thread 1-3) JBAS015876: Starting deployment of "clp.war"
18:52:39,909 WARN [org.jboss.as.server.deployment] (MSC service thread 1-2) Class Path entry jarmodule.jar in "/content/clp.ear/clp.jar" does not point to a valid jar for a Class-Path reference.
18:52:39,921 WARN [org.jboss.as.server.deployment] (MSC service thread 1-4) Class Path entry jarmodule.jar in "/content/clp.ear/clp.war" does not point to a valid jar for a Class-Path reference.
18:52:40,016 INFO [org.jboss.as.jpa] (MSC service thread 1-1) JBAS011401: Read persistence.xml for tempdb
18:52:40,157 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-2) JNDI bindings for session bean named ExtendedBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:app/clp.jar/ExtendedBean!jarmodule.Base
        java:module/ExtendedBean!jarmodule.Base
        java:jboss/exported/clp/clp.jar/ExtendedBean!jarmodule.Base
        java:global/clp/clp.jar/ExtendedBean
        java:app/clp.jar/ExtendedBean
        java:module/ExtendedBean

18:52:40,174 INFO [org.jboss.as.ejb3.deployment.processors.EjbJndiBindingsDeploymentUnitProcessor] (MSC service thread 1-2) JNDI bindings for session bean named DemoSessionBean in deployment unit subdeployment "clp.jar" of deployment "clp.ear" are as follows:

        java:global/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:app/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:module/DemoSessionBean!clp.ejb.session.DemoSession
        java:jboss/exported/clp/clp.jar/DemoSessionBean!clp.ejb.session.DemoSession
        java:global/clp/clp.jar/DemoSessionBean
        java:app/clp.jar/DemoSessionBean
        java:module/DemoSessionBean

18:52:40,447 INFO [org.jboss.as.jpa] (MSC service thread 1-1) JBAS011402: Starting Persistence Unit Service 'clp.ear/clp.jar#tempdb'
18:52:40,603 INFO [org.jboss.web] (MSC service thread 1-4) JBAS018210: Registering web context: /clpdemo
18:52:40,681 INFO [org.hibernate.annotations.common.Version] (MSC service thread 1-1) HCANN000001: Hibernate Commons Annotations {4.0.1.Final}
18:52:40,691 INFO [org.hibernate.Version] (MSC service thread 1-1) HHH000412: Hibernate Core {4.0.1.Final}
18:52:40,703 INFO [org.hibernate.cfg.Environment] (MSC service thread 1-1) HHH000206: hibernate.properties not found
18:52:40,707 INFO [org.hibernate.cfg.Environment] (MSC service thread 1-1) HHH000021: Bytecode provider name : javassist
18:52:40,748 INFO [org.hibernate.ejb.Ejb3Configuration] (MSC service thread 1-1) HHH000204: Processing PersistenceUnitInfo [
        name: tempdb
        ...]
18:52:40,866 INFO [org.hibernate.service.jdbc.connections.internal.ConnectionProviderInitiator] (MSC service thread 1-1) HHH000130: Instantiating explicit connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
18:52:41,208 INFO [org.hibernate.dialect.Dialect] (MSC service thread 1-1) HHH000400: Using dialect: org.hibernate.dialect.H2Dialect
18:52:41,229 WARN [org.hibernate.dialect.H2Dialect] (MSC service thread 1-1) HHH000431: Unable to determine H2 database version, certain features may not work
18:52:41,235 INFO [org.hibernate.engine.jdbc.internal.LobCreatorBuilder] (MSC service thread 1-1) HHH000423: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4
18:52:41,248 INFO [org.hibernate.engine.transaction.internal.TransactionFactoryInitiator] (MSC service thread 1-1) HHH000268: Transaction strategy: org.hibernate.engine.transaction.internal.jta.CMTTransactionFactory
18:52:41,269 INFO [org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory] (MSC service thread 1-1) HHH000397: Using ASTQueryTranslatorFactory
18:52:41,522 INFO [org.hibernate.tool.hbm2ddl.SchemaExport] (MSC service thread 1-1) HHH000227: Running hbm2ddl schema export
18:52:41,547 INFO [org.hibernate.tool.hbm2ddl.SchemaExport] (MSC service thread 1-1) HHH000230: Schema export complete
18:52:41,593 INFO [org.jboss.as] (MSC service thread 1-4) JBAS015874: JBoss AS 7.1.0.Final-SNAPSHOT "Flux Capacitor" started in 4942ms - Started 243 of 320 services (76 services are passive or on-demand)
18:52:41,652 INFO [org.jboss.as.server] (DeploymentScanner-threads - 2) JBAS018559: Deployed "clp.ear"