EntityManager not injected
gulygab Jan 5, 2011 10:44 AMhi,
sorry for this dummy question, i started to use Jboss 6 Final and probably mixed up something and could not find the reason why my EntityManager is not injected.
I could not see any big problems during deployment, all my stateless EJBs and the persistence unit seem to be deployed correctly:
15:37:00,457 INFO [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@2071068979{vfs:///home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/auth-ejbs-1.0.0-SNAPSHOT.jar/} 15:37:00,457 INFO [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@2071068979{vfs:///home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/auth-ejbs-1.0.0-SNAPSHOT.jar/} 15:37:00,457 INFO [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@2041379477{vfs:///home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/base-ejbs-1.0.0-SNAPSHOT.jar/} 15:37:00,458 INFO [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@2041379477{vfs:///home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/base-ejbs-1.0.0-SNAPSHOT.jar/} 15:37:00,489 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@214789ac into MC at org.jboss.ejb.bean.instantiator/pilot-ear/auth-ejbs-1.0.0-SNAPSHOT/AuthorizationServiceImpl 15:37:00,491 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@214789ac into MC at org.jboss.ejb.bean.instantiator/pilot-ear/auth-ejbs-1.0.0-SNAPSHOT/AuthenticationServiceImpl 15:37:00,493 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@214789ac into MC at org.jboss.ejb.bean.instantiator/pilot-ear/base-ejbs-1.0.0-SNAPSHOT/LocaleMgrBean 15:37:00,494 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@214789ac into MC at org.jboss.ejb.bean.instantiator/pilot-ear/base-ejbs-1.0.0-SNAPSHOT/BaseEntityMgrBean 15:37:00,495 WARN [org.jboss.ejb3.interceptor.InterceptorInfoRepository] EJBTHREE-1852: InterceptorInfoRepository is deprecated 15:37:00,654 WARN [org.jboss.ejb3.interceptor.InterceptorInfoRepository] EJBTHREE-1852: InterceptorInfoRepository is deprecated 15:37:03,254 INFO [org.jboss.ejb3.deployers.JBossASKernel] Created KernelDeployment for: auth-ejbs-1.0.0-SNAPSHOT.jar 15:37:03,254 INFO [org.jboss.ejb3.deployers.JBossASKernel] installing bean: jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthenticationServiceImpl,service=EJB3 15:37:03,254 INFO [org.jboss.ejb3.deployers.JBossASKernel] with dependencies: 15:37:03,255 INFO [org.jboss.ejb3.deployers.JBossASKernel] and demands: 15:37:03,255 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss.ejb:service=EJBTimerService; Required: Described 15:37:03,255 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss-switchboard:appName=pilot-ear,module=auth-ejbs-1.0.0-SNAPSHOT,name=AuthenticationServiceImpl; Required: Create 15:37:03,255 INFO [org.jboss.ejb3.deployers.JBossASKernel] and supplies: 15:37:03,256 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:AuthenticationServiceImpl 15:37:03,256 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.remote.BaseEntityMgrBeanRemote 15:37:03,256 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.BaseEntityMgrBeanLocal 15:37:03,256 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/AuthenticationServiceImpl/local 15:37:03,256 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/AuthenticationServiceImpl/local-hu.ideal.common.tools.interfaces.IBasicLoginAuthentication 15:37:03,257 INFO [org.jboss.ejb3.deployers.JBossASKernel] Added bean(jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthenticationServiceImpl,service=EJB3) to KernelDeployment of: auth-ejbs-1.0.0-SNAPSHOT.jar 15:37:03,260 INFO [org.jboss.ejb3.deployers.JBossASKernel] installing bean: jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthorizationServiceImpl,service=EJB3 15:37:03,260 INFO [org.jboss.ejb3.deployers.JBossASKernel] with dependencies: 15:37:03,260 INFO [org.jboss.ejb3.deployers.JBossASKernel] and demands: 15:37:03,260 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss.ejb:service=EJBTimerService; Required: Described 15:37:03,261 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss-switchboard:appName=pilot-ear,module=auth-ejbs-1.0.0-SNAPSHOT,name=AuthorizationServiceImpl; Required: Create 15:37:03,261 INFO [org.jboss.ejb3.deployers.JBossASKernel] and supplies: 15:37:03,261 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:AuthorizationServiceImpl 15:37:03,261 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/AuthorizationServiceImpl/local-hu.ideal.auth.interfaces.IAuthorizationService 15:37:03,261 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/AuthorizationServiceImpl/local 15:37:03,262 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.auth.interfaces.IAuthorizationService 15:37:03,262 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.remote.BaseEntityMgrBeanRemote 15:37:03,262 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.BaseEntityMgrBeanLocal 15:37:03,263 INFO [org.jboss.ejb3.deployers.JBossASKernel] Added bean(jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthorizationServiceImpl,service=EJB3) to KernelDeployment of: auth-ejbs-1.0.0-SNAPSHOT.jar 15:37:03,265 INFO [org.jboss.ejb3.deployers.JBossASKernel] Created KernelDeployment for: base-ejbs-1.0.0-SNAPSHOT.jar 15:37:03,265 INFO [org.jboss.ejb3.deployers.JBossASKernel] installing bean: jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=LocaleMgrBean,service=EJB3 15:37:03,265 INFO [org.jboss.ejb3.deployers.JBossASKernel] with dependencies: 15:37:03,266 INFO [org.jboss.ejb3.deployers.JBossASKernel] and demands: 15:37:03,266 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss.ejb:service=EJBTimerService; Required: Described 15:37:03,266 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss-switchboard:appName=pilot-ear,module=base-ejbs-1.0.0-SNAPSHOT,name=LocaleMgrBean; Required: Create 15:37:03,266 INFO [org.jboss.ejb3.deployers.JBossASKernel] and supplies: 15:37:03,267 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/LocaleMgrBean/remote-hu.ideal.common.base.ejb.remote.LocaleMgrBeanRemote 15:37:03,267 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/LocaleMgrBean/local-hu.ideal.common.base.ejb.LocaleMgrBeanLocal 15:37:03,267 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/LocaleMgrBean/local 15:37:03,267 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.remote.LocaleMgrBeanRemote 15:37:03,267 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:LocaleMgrBean 15:37:03,268 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/LocaleMgrBean/remote 15:37:03,268 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.LocaleMgrBeanLocal 15:37:03,268 INFO [org.jboss.ejb3.deployers.JBossASKernel] Added bean(jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=LocaleMgrBean,service=EJB3) to KernelDeployment of: base-ejbs-1.0.0-SNAPSHOT.jar 15:37:03,270 INFO [org.jboss.ejb3.deployers.JBossASKernel] installing bean: jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=BaseEntityMgrBean,service=EJB3 15:37:03,271 INFO [org.jboss.ejb3.deployers.JBossASKernel] with dependencies: 15:37:03,271 INFO [org.jboss.ejb3.deployers.JBossASKernel] and demands: 15:37:03,271 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss.ejb:service=EJBTimerService; Required: Described 15:37:03,271 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss-switchboard:appName=pilot-ear,module=base-ejbs-1.0.0-SNAPSHOT,name=BaseEntityMgrBean; Required: Create 15:37:03,271 INFO [org.jboss.ejb3.deployers.JBossASKernel] and supplies: 15:37:03,272 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/BaseEntityMgrBean/remote-hu.ideal.common.base.ejb.remote.BaseEntityMgrBeanRemote 15:37:03,272 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/BaseEntityMgrBean/local-hu.ideal.common.base.ejb.BaseEntityMgrBeanLocal 15:37:03,272 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.remote.BaseEntityMgrBeanRemote 15:37:03,272 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:BaseEntityMgrBean 15:37:03,273 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.BaseEntityMgrBeanLocal 15:37:03,273 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/BaseEntityMgrBean/local 15:37:03,273 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/BaseEntityMgrBean/remote 15:37:03,274 INFO [org.jboss.ejb3.deployers.JBossASKernel] Added bean(jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=BaseEntityMgrBean,service=EJB3) to KernelDeployment of: base-ejbs-1.0.0-SNAPSHOT.jar 15:37:04,401 INFO [org.jboss.ejb3.session.SessionSpecContainer] Starting jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthenticationServiceImpl,service=EJB3 15:37:04,401 INFO [org.jboss.ejb3.EJBContainer] STARTED EJB: hu.ideal.auth.ejb.AuthenticationServiceImpl ejbName: AuthenticationServiceImpl 15:37:04,408 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] Binding the following Entries in Global JNDI: pilot-ear/AuthenticationServiceImpl/local - EJB3.x Default Local Business Interface pilot-ear/AuthenticationServiceImpl/local-hu.ideal.common.tools.interfaces.IBasicLoginAuthentication - EJB3.x Local Business Interface 15:37:04,409 WARN [org.jboss.ejb3.TimerServiceContainer] EJBTHREE-2193: using deprecated TimerServiceFactory for restoring timers 15:37:04,412 INFO [org.jboss.ejb3.session.SessionSpecContainer] Starting jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthorizationServiceImpl,service=EJB3 15:37:04,412 INFO [org.jboss.ejb3.EJBContainer] STARTED EJB: hu.ideal.auth.ejb.AuthorizationServiceImpl ejbName: AuthorizationServiceImpl 15:37:04,418 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] Binding the following Entries in Global JNDI: pilot-ear/AuthorizationServiceImpl/local - EJB3.x Default Local Business Interface pilot-ear/AuthorizationServiceImpl/local-hu.ideal.auth.interfaces.IAuthorizationService - EJB3.x Local Business Interface 15:37:04,419 WARN [org.jboss.ejb3.TimerServiceContainer] EJBTHREE-2193: using deprecated TimerServiceFactory for restoring timers 15:37:04,720 INFO [STDOUT] 15:37:04,485 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 15:37:04,720 INFO [STDOUT] 15:37:04,486 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [vfs:/home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/lib/ejbtools-1.0.0-SNAPSHOT.jar/logback.xml] 15:37:04,720 INFO [STDOUT] 15:37:04,589 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set 15:37:04,721 INFO [STDOUT] 15:37:04,593 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeFilter scanning period to 30 seconds 15:37:04,721 INFO [STDOUT] 15:37:04,595 |-ERROR in ch.qos.logback.classic.turbo.ReconfigureOnChangeFilter@7675f36f - URL [vfs:/home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/lib/ejbtools-1.0.0-SNAPSHOT.jar/logback.xml] is not of type file 15:37:04,721 INFO [STDOUT] 15:37:04,595 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Adding ReconfigureOnChangeFilter as a turbo filter 15:37:04,721 INFO [STDOUT] 15:37:04,595 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender] 15:37:04,721 INFO [STDOUT] 15:37:04,604 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [BUSINESS-FILE] 15:37:04,722 INFO [STDOUT] 15:37:04,662 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Pushing component [layout] on top of the object stack. 15:37:04,722 INFO [STDOUT] 15:37:04,704 |-INFO in ch.qos.logback.core.FileAppender[BUSINESS-FILE] - File property is set to [/tmp/nde-x-test-business.log] 15:37:04,722 INFO [STDOUT] 15:37:04,706 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Popping appender named [BUSINESS-FILE] from the object stack 15:37:04,722 INFO [STDOUT] 15:37:04,706 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 15:37:04,723 INFO [STDOUT] 15:37:04,708 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT] 15:37:04,723 INFO [STDOUT] 15:37:04,711 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Pushing component [layout] on top of the object stack. 15:37:04,723 INFO [STDOUT] 15:37:04,712 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Popping appender named [STDOUT] from the object stack 15:37:04,723 INFO [STDOUT] 15:37:04,713 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - root level set to DEBUG 15:37:04,723 INFO [STDOUT] 15:37:04,713 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [BUSINESS-FILE] to Logger[root] 15:37:04,724 INFO [STDOUT] 15:37:04,715 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[root] 15:37:04,724 INFO [STDOUT] 15:37:04,729 ERROR [STDERR] SLF4J: Class path contains multiple SLF4J bindings. 15:37:04,730 ERROR [STDERR] SLF4J: Found binding in [vfs:/home/gg/jboss-6.0.0.Final/common/lib/slf4j-jboss-logmanager.jar/org/slf4j/impl/StaticLoggerBinder.class] 15:37:04,730 ERROR [STDERR] SLF4J: Found binding in [vfs:/home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/lib/logback-classic-0.9.16.jar/org/slf4j/impl/StaticLoggerBinder.class] 15:37:04,730 ERROR [STDERR] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. 15:37:04,789 INFO [org.jboss.ejb3.session.SessionSpecContainer] Starting jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=LocaleMgrBean,service=EJB3 15:37:04,789 INFO [org.jboss.ejb3.EJBContainer] STARTED EJB: hu.ideal.common.base.ejb.impl.LocaleMgrBean ejbName: LocaleMgrBean 15:37:04,804 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] Binding the following Entries in Global JNDI: pilot-ear/LocaleMgrBean/remote - EJB3.x Default Remote Business Interface pilot-ear/LocaleMgrBean/remote-hu.ideal.common.base.ejb.remote.LocaleMgrBeanRemote - EJB3.x Remote Business Interface pilot-ear/LocaleMgrBean/local - EJB3.x Default Local Business Interface pilot-ear/LocaleMgrBean/local-hu.ideal.common.base.ejb.LocaleMgrBeanLocal - EJB3.x Local Business Interface 15:37:04,806 WARN [org.jboss.ejb3.TimerServiceContainer] EJBTHREE-2193: using deprecated TimerServiceFactory for restoring timers 15:37:04,808 INFO [org.jboss.ejb3.session.SessionSpecContainer] Starting jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=BaseEntityMgrBean,service=EJB3 15:37:04,808 INFO [org.jboss.ejb3.EJBContainer] STARTED EJB: hu.ideal.common.base.ejb.impl.BaseEntityMgrBean ejbName: BaseEntityMgrBean 15:37:04,820 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] Binding the following Entries in Global JNDI: pilot-ear/BaseEntityMgrBean/remote - EJB3.x Default Remote Business Interface pilot-ear/BaseEntityMgrBean/remote-hu.ideal.common.base.ejb.remote.BaseEntityMgrBeanRemote - EJB3.x Remote Business Interface pilot-ear/BaseEntityMgrBean/local - EJB3.x Default Local Business Interface pilot-ear/BaseEntityMgrBean/local-hu.ideal.common.base.ejb.BaseEntityMgrBeanLocal - EJB3.x Local Business Interface 15:37:04,821 WARN [org.jboss.ejb3.TimerServiceContainer] EJBTHREE-2193: using deprecated TimerServiceFactory for restoring timers 15:37:04,831 INFO [org.jboss.jpa.deployment.PersistenceUnitDeployment] Starting persistence unit persistence.unit:unitName=pilot-ear.ear/lib/base-entities-1.0.0-SNAPSHOT.jar#default 15:37:04,834 INFO [org.hibernate.ejb.Ejb3Configuration] Processing PersistenceUnitInfo [ name: default ...] 15:37:04,836 WARN [org.hibernate.ejb.Ejb3Configuration] Persistence provider caller does not implement the EJB3 spec correctly.PersistenceUnitInfo.getNewTempClassLoader() is null. 15:37:04,843 INFO [org.hibernate.validator.engine.resolver.DefaultTraversableResolver] Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. 15:37:04,851 INFO [org.hibernate.validator.engine.resolver.DefaultTraversableResolver] Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. 15:37:04,853 INFO [org.hibernate.validator.engine.resolver.DefaultTraversableResolver] Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. 15:37:04,858 INFO [org.hibernate.cfg.search.HibernateSearchEventListenerRegister] Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled. 15:37:04,859 INFO [org.hibernate.connection.ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider 15:37:04,860 INFO [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider] Using provided datasource 15:37:06,029 INFO [org.hibernate.cfg.SettingsFactory] Database -> name : Oracle version : Oracle Database 10g Enterprise Edition Release 10.2.0.1.0 - 64bit Production With the Partitioning, OLAP and Data Mining options major : 10 minor : 2 15:37:06,029 INFO [org.hibernate.cfg.SettingsFactory] Driver -> name : Oracle JDBC driver version : 10.1.0.5.0 major : 10 minor : 1 15:37:06,047 INFO [org.hibernate.dialect.Dialect] Using dialect: org.hibernate.dialect.OracleDialect 15:37:06,048 WARN [org.hibernate.dialect.Oracle9Dialect] The Oracle9Dialect dialect has been deprecated; use either Oracle9iDialect or Oracle10gDialect instead 15:37:06,052 WARN [org.hibernate.dialect.OracleDialect] The OracleDialect dialect has been deprecated; use Oracle8iDialect instead 15:37:06,058 INFO [org.hibernate.engine.jdbc.JdbcSupportLoader] Disabling contextual LOB creation as createClob() method threw error : java.lang.reflect.InvocationTargetException 15:37:06,059 INFO [org.hibernate.transaction.TransactionFactoryFactory] Transaction strategy: org.hibernate.ejb.transaction.JoinableCMTTransactionFactory 15:37:06,059 INFO [org.hibernate.transaction.TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup 15:37:06,060 INFO [org.hibernate.transaction.TransactionManagerLookupFactory] instantiated TransactionManagerLookup 15:37:06,060 INFO [org.hibernate.cfg.SettingsFactory] Automatic flush during beforeCompletion(): disabled 15:37:06,061 INFO [org.hibernate.cfg.SettingsFactory] Automatic session close at end of transaction: disabled 15:37:06,061 INFO [org.hibernate.cfg.SettingsFactory] JDBC batch size: 15 15:37:06,061 INFO [org.hibernate.cfg.SettingsFactory] JDBC batch updates for versioned data: disabled 15:37:06,061 INFO [org.hibernate.cfg.SettingsFactory] Scrollable result sets: enabled 15:37:06,062 INFO [org.hibernate.cfg.SettingsFactory] JDBC3 getGeneratedKeys(): disabled 15:37:06,062 INFO [org.hibernate.cfg.SettingsFactory] Connection release mode: auto 15:37:06,062 INFO [org.hibernate.cfg.SettingsFactory] Default batch fetch size: 1 15:37:06,062 INFO [org.hibernate.cfg.SettingsFactory] Generate SQL with comments: disabled 15:37:06,062 INFO [org.hibernate.cfg.SettingsFactory] Order SQL updates by primary key: disabled 15:37:06,063 INFO [org.hibernate.cfg.SettingsFactory] Order SQL inserts for batching: disabled 15:37:06,063 INFO [org.hibernate.cfg.SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory 15:37:06,064 INFO [org.hibernate.hql.ast.ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory 15:37:06,064 INFO [org.hibernate.cfg.SettingsFactory] Query language substitutions: {} 15:37:06,064 INFO [org.hibernate.cfg.SettingsFactory] JPA-QL strict compliance: enabled 15:37:06,064 INFO [org.hibernate.cfg.SettingsFactory] Second-level cache: enabled 15:37:06,066 INFO [org.hibernate.cfg.SettingsFactory] Query cache: disabled 15:37:06,066 INFO [org.hibernate.cfg.SettingsFactory] Cache region factory : org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge 15:37:06,078 INFO [org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge] Cache provider: org.hibernate.cache.HashtableCacheProvider 15:37:06,079 INFO [org.hibernate.cfg.SettingsFactory] Optimize cache for minimal puts: disabled 15:37:06,080 INFO [org.hibernate.cfg.SettingsFactory] Cache region prefix: persistence.unit:unitName=pilot-ear.ear/lib/base-entities-1.0.0-SNAPSHOT.jar#default 15:37:06,080 INFO [org.hibernate.cfg.SettingsFactory] Structured second-level cache entries: disabled 15:37:06,080 INFO [org.hibernate.cfg.SettingsFactory] Echoing all SQL to stdout 15:37:06,080 INFO [org.hibernate.cfg.SettingsFactory] Statistics: disabled 15:37:06,085 INFO [org.hibernate.cfg.SettingsFactory] Deleted entity synthetic identifier rollback: disabled 15:37:06,085 INFO [org.hibernate.cfg.SettingsFactory] Default entity-mode: pojo 15:37:06,085 INFO [org.hibernate.cfg.SettingsFactory] Named query checking : enabled 15:37:06,085 INFO [org.hibernate.cfg.SettingsFactory] Check Nullability in Core (should be disabled when Bean Validation is on): disabled 15:37:06,094 INFO [org.hibernate.impl.SessionFactoryImpl] building session factory 15:37:06,095 INFO [org.hibernate.impl.SessionFactoryObjectFactory] Factory name: persistence.unit:unitName=pilot-ear.ear/lib/base-entities-1.0.0-SNAPSHOT.jar#default 15:37:06,113 INFO [org.hibernate.util.NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 15:37:06,119 INFO [org.hibernate.util.NamingHelper] Creating subcontext: persistence.unit:unitName=pilot-ear.ear 15:37:06,121 INFO [org.hibernate.util.NamingHelper] Creating subcontext: lib 15:37:06,123 INFO [org.hibernate.impl.SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.unit:unitName=pilot-ear.ear/lib/base-entities-1.0.0-SNAPSHOT.jar#default 15:37:06,124 WARN [org.hibernate.impl.SessionFactoryObjectFactory] InitialContext did not implement EventContext 15:37:06,124 INFO [org.hibernate.util.NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 15:37:06,155 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] deploy, ctxPath=/auth-webapp 15:37:06,231 INFO [javax.enterprise.resource.webcontainer.jsf.config] Initializing Mojarra 2.0.3 ( b05) for context '/auth-webapp' 15:37:09,588 INFO [org.richfaces.log.Cache] Selected fallback cache factory 15:37:09,591 INFO [org.richfaces.log.Cache] Creating LRUMap cache instance using parameters: {com.sun.faces.duplicateJARPattern=^tmp\d+(\S*\.jar), org.richfaces.skin=#{skinBean.skin}, com.sun.faces.injectionProvider=org.jboss.web.jsf.integration.injection.JBossDelegatingInjectionProvider, javax.faces.PROJECT_STAGE=Development, org.richfaces.enableControlSkinningClasses=#{skinBean.enableClassesSkinning}, org.richfaces.enableControlSkinning=#{skinBean.enableElementsSkinning}, resteasy.unwrapped.exceptions=javax.ejb.EJBException, javax.faces.STATE_SAVING_METHOD=client} 15:37:09,597 INFO [org.richfaces.log.Cache] Creating LRUMap cache instance of 512 items capacity 15:37:09,602 INFO [org.richfaces.log.Application] RichFaces Core Implementation by JBoss, a division of Red Hat, Inc., version v.4.0.0.20101110-M4 SVN r.20021 15:37:09,613 INFO [javax.enterprise.resource.webcontainer.jsf.config] Monitoring jndi:/localhost/auth-webapp/WEB-INF/faces-config.xml for modifications 15:37:00,491 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@214789ac into MC at org.jboss.ejb.bean.instantiator/pilot-ear/auth-ejbs-1.0.0-SNAPSHOT/AuthenticationServiceImpl 15:37:00,493 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@214789ac into MC at org.jboss.ejb.bean.instantiator/pilot-ear/base-ejbs-1.0.0-SNAPSHOT/LocaleMgrBean 15:37:00,494 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@214789ac into MC at org.jboss.ejb.bean.instantiator/pilot-ear/base-ejbs-1.0.0-SNAPSHOT/BaseEntityMgrBean 15:37:00,495 WARN [org.jboss.ejb3.interceptor.InterceptorInfoRepository] EJBTHREE-1852: InterceptorInfoRepository is deprecated 15:37:00,654 WARN [org.jboss.ejb3.interceptor.InterceptorInfoRepository] EJBTHREE-1852: InterceptorInfoRepository is deprecated 15:37:03,254 INFO [org.jboss.ejb3.deployers.JBossASKernel] Created KernelDeployment for: auth-ejbs-1.0.0-SNAPSHOT.jar 15:37:03,254 INFO [org.jboss.ejb3.deployers.JBossASKernel] installing bean: jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthenticationServiceImpl,service=EJB3 15:37:00,457 INFO [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@2071068979{vfs:///home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/auth-ejbs-1.0.0-SNAPSHOT.jar/} 15:37:00,457 INFO [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@2071068979{vfs:///home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/auth-ejbs-1.0.0-SNAPSHOT.jar/} 15:37:00,457 INFO [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@2041379477{vfs:///home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/base-ejbs-1.0.0-SNAPSHOT.jar/} 15:37:00,458 INFO [org.jboss.ejb3.deployers.Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@2041379477{vfs:///home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/base-ejbs-1.0.0-SNAPSHOT.jar/} 15:37:00,489 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@214789ac into MC at org.jboss.ejb.bean.instantiator/pilot-ear/auth-ejbs-1.0.0-SNAPSHOT/AuthorizationServiceImpl 15:37:00,491 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@214789ac into MC at org.jboss.ejb.bean.instantiator/pilot-ear/auth-ejbs-1.0.0-SNAPSHOT/AuthenticationServiceImpl 15:37:00,493 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@214789ac into MC at org.jboss.ejb.bean.instantiator/pilot-ear/base-ejbs-1.0.0-SNAPSHOT/LocaleMgrBean 15:37:00,494 INFO [org.jboss.ejb3.instantiator.deployer.BeanInstantiatorDeployerBase] Installed org.jboss.ejb3.instantiator.impl.Ejb31SpecBeanInstantiator@214789ac into MC at org.jboss.ejb.bean.instantiator/pilot-ear/base-ejbs-1.0.0-SNAPSHOT/BaseEntityMgrBean 15:37:00,495 WARN [org.jboss.ejb3.interceptor.InterceptorInfoRepository] EJBTHREE-1852: InterceptorInfoRepository is deprecated 15:37:00,654 WARN [org.jboss.ejb3.interceptor.InterceptorInfoRepository] EJBTHREE-1852: InterceptorInfoRepository is deprecated 15:37:03,254 INFO [org.jboss.ejb3.deployers.JBossASKernel] Created KernelDeployment for: auth-ejbs-1.0.0-SNAPSHOT.jar 15:37:03,254 INFO [org.jboss.ejb3.deployers.JBossASKernel] installing bean: jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthenticationServiceImpl,service=EJB3 15:37:03,254 INFO [org.jboss.ejb3.deployers.JBossASKernel] with dependencies: 15:37:03,255 INFO [org.jboss.ejb3.deployers.JBossASKernel] and demands: 15:37:03,255 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss.ejb:service=EJBTimerService; Required: Described 15:37:03,255 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss-switchboard:appName=pilot-ear,module=auth-ejbs-1.0.0-SNAPSHOT,name=AuthenticationServiceImpl; Required: Create 15:37:03,255 INFO [org.jboss.ejb3.deployers.JBossASKernel] and supplies: 15:37:03,256 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:AuthenticationServiceImpl 15:37:03,256 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.remote.BaseEntityMgrBeanRemote 15:37:03,256 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.BaseEntityMgrBeanLocal 15:37:03,256 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/AuthenticationServiceImpl/local 15:37:03,256 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/AuthenticationServiceImpl/local-hu.ideal.common.tools.interfaces.IBasicLoginAuthentication 15:37:03,257 INFO [org.jboss.ejb3.deployers.JBossASKernel] Added bean(jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthenticationServiceImpl,service=EJB3) to KernelDeployment of: auth-ejbs-1.0.0-SNAPSHOT.jar 15:37:03,260 INFO [org.jboss.ejb3.deployers.JBossASKernel] installing bean: jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthorizationServiceImpl,service=EJB3 15:37:03,260 INFO [org.jboss.ejb3.deployers.JBossASKernel] with dependencies: 15:37:03,260 INFO [org.jboss.ejb3.deployers.JBossASKernel] and demands: 15:37:03,260 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss.ejb:service=EJBTimerService; Required: Described 15:37:03,261 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss-switchboard:appName=pilot-ear,module=auth-ejbs-1.0.0-SNAPSHOT,name=AuthorizationServiceImpl; Required: Create 15:37:03,261 INFO [org.jboss.ejb3.deployers.JBossASKernel] and supplies: 15:37:03,261 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:AuthorizationServiceImpl 15:37:03,261 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/AuthorizationServiceImpl/local-hu.ideal.auth.interfaces.IAuthorizationService 15:37:03,261 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/AuthorizationServiceImpl/local 15:37:03,262 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.auth.interfaces.IAuthorizationService 15:37:03,262 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.remote.BaseEntityMgrBeanRemote 15:37:03,262 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.BaseEntityMgrBeanLocal 15:37:03,263 INFO [org.jboss.ejb3.deployers.JBossASKernel] Added bean(jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthorizationServiceImpl,service=EJB3) to KernelDeployment of: auth-ejbs-1.0.0-SNAPSHOT.jar 15:37:03,265 INFO [org.jboss.ejb3.deployers.JBossASKernel] Created KernelDeployment for: base-ejbs-1.0.0-SNAPSHOT.jar 15:37:03,265 INFO [org.jboss.ejb3.deployers.JBossASKernel] installing bean: jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=LocaleMgrBean,service=EJB3 15:37:03,265 INFO [org.jboss.ejb3.deployers.JBossASKernel] with dependencies: 15:37:03,266 INFO [org.jboss.ejb3.deployers.JBossASKernel] and demands: 15:37:03,266 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss.ejb:service=EJBTimerService; Required: Described 15:37:03,266 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss-switchboard:appName=pilot-ear,module=base-ejbs-1.0.0-SNAPSHOT,name=LocaleMgrBean; Required: Create 15:37:03,266 INFO [org.jboss.ejb3.deployers.JBossASKernel] and supplies: 15:37:03,267 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/LocaleMgrBean/remote-hu.ideal.common.base.ejb.remote.LocaleMgrBeanRemote 15:37:03,267 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/LocaleMgrBean/local-hu.ideal.common.base.ejb.LocaleMgrBeanLocal 15:37:03,267 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/LocaleMgrBean/local 15:37:03,267 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.remote.LocaleMgrBeanRemote 15:37:03,267 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:LocaleMgrBean 15:37:03,268 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/LocaleMgrBean/remote 15:37:03,268 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.LocaleMgrBeanLocal 15:37:03,268 INFO [org.jboss.ejb3.deployers.JBossASKernel] Added bean(jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=LocaleMgrBean,service=EJB3) to KernelDeployment of: base-ejbs-1.0.0-SNAPSHOT.jar 15:37:03,270 INFO [org.jboss.ejb3.deployers.JBossASKernel] installing bean: jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=BaseEntityMgrBean,service=EJB3 15:37:03,271 INFO [org.jboss.ejb3.deployers.JBossASKernel] with dependencies: 15:37:03,271 INFO [org.jboss.ejb3.deployers.JBossASKernel] and demands: 15:37:03,271 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss.ejb:service=EJBTimerService; Required: Described 15:37:03,271 INFO [org.jboss.ejb3.deployers.JBossASKernel] jboss-switchboard:appName=pilot-ear,module=base-ejbs-1.0.0-SNAPSHOT,name=BaseEntityMgrBean; Required: Create 15:37:03,271 INFO [org.jboss.ejb3.deployers.JBossASKernel] and supplies: 15:37:03,272 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/BaseEntityMgrBean/remote-hu.ideal.common.base.ejb.remote.BaseEntityMgrBeanRemote 15:37:03,272 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/BaseEntityMgrBean/local-hu.ideal.common.base.ejb.BaseEntityMgrBeanLocal 15:37:03,272 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.remote.BaseEntityMgrBeanRemote 15:37:03,272 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:BaseEntityMgrBean 15:37:03,273 INFO [org.jboss.ejb3.deployers.JBossASKernel] Class:hu.ideal.common.base.ejb.BaseEntityMgrBeanLocal 15:37:03,273 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/BaseEntityMgrBean/local 15:37:03,273 INFO [org.jboss.ejb3.deployers.JBossASKernel] jndi:pilot-ear/BaseEntityMgrBean/remote 15:37:03,274 INFO [org.jboss.ejb3.deployers.JBossASKernel] Added bean(jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=BaseEntityMgrBean,service=EJB3) to KernelDeployment of: base-ejbs-1.0.0-SNAPSHOT.jar 15:37:04,401 INFO [org.jboss.ejb3.session.SessionSpecContainer] Starting jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthenticationServiceImpl,service=EJB3 15:37:04,401 INFO [org.jboss.ejb3.EJBContainer] STARTED EJB: hu.ideal.auth.ejb.AuthenticationServiceImpl ejbName: AuthenticationServiceImpl 15:37:04,408 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] Binding the following Entries in Global JNDI: pilot-ear/AuthenticationServiceImpl/local - EJB3.x Default Local Business Interface pilot-ear/AuthenticationServiceImpl/local-hu.ideal.common.tools.interfaces.IBasicLoginAuthentication - EJB3.x Local Business Interface 15:37:04,409 WARN [org.jboss.ejb3.TimerServiceContainer] EJBTHREE-2193: using deprecated TimerServiceFactory for restoring timers 15:37:04,412 INFO [org.jboss.ejb3.session.SessionSpecContainer] Starting jboss.j2ee:ear=pilot-ear.ear,jar=auth-ejbs-1.0.0-SNAPSHOT.jar,name=AuthorizationServiceImpl,service=EJB3 15:37:04,412 INFO [org.jboss.ejb3.EJBContainer] STARTED EJB: hu.ideal.auth.ejb.AuthorizationServiceImpl ejbName: AuthorizationServiceImpl 15:37:04,418 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] Binding the following Entries in Global JNDI: pilot-ear/AuthorizationServiceImpl/local - EJB3.x Default Local Business Interface pilot-ear/AuthorizationServiceImpl/local-hu.ideal.auth.interfaces.IAuthorizationService - EJB3.x Local Business Interface 15:37:04,419 WARN [org.jboss.ejb3.TimerServiceContainer] EJBTHREE-2193: using deprecated TimerServiceFactory for restoring timers 15:37:04,720 INFO [STDOUT] 15:37:04,485 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml] 15:37:04,720 INFO [STDOUT] 15:37:04,486 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [vfs:/home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/lib/ejbtools-1.0.0-SNAPSHOT.jar/logback.xml] 15:37:04,720 INFO [STDOUT] 15:37:04,589 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set 15:37:04,721 INFO [STDOUT] 15:37:04,593 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Setting ReconfigureOnChangeFilter scanning period to 30 seconds 15:37:04,721 INFO [STDOUT] 15:37:04,595 |-ERROR in ch.qos.logback.classic.turbo.ReconfigureOnChangeFilter@7675f36f - URL [vfs:/home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/lib/ejbtools-1.0.0-SNAPSHOT.jar/logback.xml] is not of type file 15:37:04,721 INFO [STDOUT] 15:37:04,595 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Adding ReconfigureOnChangeFilter as a turbo filter 15:37:04,721 INFO [STDOUT] 15:37:04,595 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.FileAppender] 15:37:04,721 INFO [STDOUT] 15:37:04,604 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [BUSINESS-FILE] 15:37:04,722 INFO [STDOUT] 15:37:04,662 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Pushing component [layout] on top of the object stack. 15:37:04,722 INFO [STDOUT] 15:37:04,704 |-INFO in ch.qos.logback.core.FileAppender[BUSINESS-FILE] - File property is set to [/tmp/nde-x-test-business.log] 15:37:04,722 INFO [STDOUT] 15:37:04,706 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Popping appender named [BUSINESS-FILE] from the object stack 15:37:04,722 INFO [STDOUT] 15:37:04,706 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender] 15:37:04,723 INFO [STDOUT] 15:37:04,708 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT] 15:37:04,723 INFO [STDOUT] 15:37:04,711 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Pushing component [layout] on top of the object stack. 15:37:04,723 INFO [STDOUT] 15:37:04,712 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Popping appender named [STDOUT] from the object stack 15:37:04,723 INFO [STDOUT] 15:37:04,713 |-INFO in ch.qos.logback.classic.joran.action.LevelAction - root level set to DEBUG 15:37:04,723 INFO [STDOUT] 15:37:04,713 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [BUSINESS-FILE] to Logger[root] 15:37:04,724 INFO [STDOUT] 15:37:04,715 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[root] 15:37:04,724 INFO [STDOUT] 15:37:04,729 ERROR [STDERR] SLF4J: Class path contains multiple SLF4J bindings. 15:37:04,730 ERROR [STDERR] SLF4J: Found binding in [vfs:/home/gg/jboss-6.0.0.Final/common/lib/slf4j-jboss-logmanager.jar/org/slf4j/impl/StaticLoggerBinder.class] 15:37:04,730 ERROR [STDERR] SLF4J: Found binding in [vfs:/home/gg/jboss-6.0.0.Final/server/default/deploy/pilot-ear.ear/lib/logback-classic-0.9.16.jar/org/slf4j/impl/StaticLoggerBinder.class] 15:37:04,730 ERROR [STDERR] SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. 15:37:04,789 INFO [org.jboss.ejb3.session.SessionSpecContainer] Starting jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=LocaleMgrBean,service=EJB3 15:37:04,789 INFO [org.jboss.ejb3.EJBContainer] STARTED EJB: hu.ideal.common.base.ejb.impl.LocaleMgrBean ejbName: LocaleMgrBean 15:37:04,804 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] Binding the following Entries in Global JNDI: pilot-ear/LocaleMgrBean/remote - EJB3.x Default Remote Business Interface pilot-ear/LocaleMgrBean/remote-hu.ideal.common.base.ejb.remote.LocaleMgrBeanRemote - EJB3.x Remote Business Interface pilot-ear/LocaleMgrBean/local - EJB3.x Default Local Business Interface pilot-ear/LocaleMgrBean/local-hu.ideal.common.base.ejb.LocaleMgrBeanLocal - EJB3.x Local Business Interface 15:37:04,806 WARN [org.jboss.ejb3.TimerServiceContainer] EJBTHREE-2193: using deprecated TimerServiceFactory for restoring timers 15:37:04,808 INFO [org.jboss.ejb3.session.SessionSpecContainer] Starting jboss.j2ee:ear=pilot-ear.ear,jar=base-ejbs-1.0.0-SNAPSHOT.jar,name=BaseEntityMgrBean,service=EJB3 15:37:04,808 INFO [org.jboss.ejb3.EJBContainer] STARTED EJB: hu.ideal.common.base.ejb.impl.BaseEntityMgrBean ejbName: BaseEntityMgrBean 15:37:04,820 INFO [org.jboss.ejb3.proxy.impl.jndiregistrar.JndiSessionRegistrarBase] Binding the following Entries in Global JNDI: pilot-ear/BaseEntityMgrBean/remote - EJB3.x Default Remote Business Interface pilot-ear/BaseEntityMgrBean/remote-hu.ideal.common.base.ejb.remote.BaseEntityMgrBeanRemote - EJB3.x Remote Business Interface pilot-ear/BaseEntityMgrBean/local - EJB3.x Default Local Business Interface pilot-ear/BaseEntityMgrBean/local-hu.ideal.common.base.ejb.BaseEntityMgrBeanLocal - EJB3.x Local Business Interface 15:37:04,821 WARN [org.jboss.ejb3.TimerServiceContainer] EJBTHREE-2193: using deprecated TimerServiceFactory for restoring timers 15:37:04,831 INFO [org.jboss.jpa.deployment.PersistenceUnitDeployment] Starting persistence unit persistence.unit:unitName=pilot-ear.ear/lib/base-entities-1.0.0-SNAPSHOT.jar#default 15:37:04,834 INFO [org.hibernate.ejb.Ejb3Configuration] Processing PersistenceUnitInfo [ name: default ...] 15:37:04,836 WARN [org.hibernate.ejb.Ejb3Configuration] Persistence provider caller does not implement the EJB3 spec correctly.PersistenceUnitInfo.getNewTempClassLoader() is null. 15:37:04,843 INFO [org.hibernate.validator.engine.resolver.DefaultTraversableResolver] Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. 15:37:04,851 INFO [org.hibernate.validator.engine.resolver.DefaultTraversableResolver] Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. 15:37:04,853 INFO [org.hibernate.validator.engine.resolver.DefaultTraversableResolver] Instantiated an instance of org.hibernate.validator.engine.resolver.JPATraversableResolver. 15:37:04,858 INFO [org.hibernate.cfg.search.HibernateSearchEventListenerRegister] Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled. 15:37:04,859 INFO [org.hibernate.connection.ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider 15:37:04,860 INFO [org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider] Using provided datasource 15:37:06,029 INFO [org.hibernate.cfg.SettingsFactory] Database -> name : Oracle version : Oracle Database 10g Enterprise Edition Release 10.2.0.1.0 - 64bit Production With the Partitioning, OLAP and Data Mining options major : 10 minor : 2 15:37:06,029 INFO [org.hibernate.cfg.SettingsFactory] Driver -> name : Oracle JDBC driver version : 10.1.0.5.0 major : 10 minor : 1 15:37:06,047 INFO [org.hibernate.dialect.Dialect] Using dialect: org.hibernate.dialect.OracleDialect 15:37:06,048 WARN [org.hibernate.dialect.Oracle9Dialect] The Oracle9Dialect dialect has been deprecated; use either Oracle9iDialect or Oracle10gDialect instead 15:37:06,052 WARN [org.hibernate.dialect.OracleDialect] The OracleDialect dialect has been deprecated; use Oracle8iDialect instead 15:37:06,058 INFO [org.hibernate.engine.jdbc.JdbcSupportLoader] Disabling contextual LOB creation as createClob() method threw error : java.lang.reflect.InvocationTargetException 15:37:06,059 INFO [org.hibernate.transaction.TransactionFactoryFactory] Transaction strategy: org.hibernate.ejb.transaction.JoinableCMTTransactionFactory 15:37:06,059 INFO [org.hibernate.transaction.TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup 15:37:06,060 INFO [org.hibernate.transaction.TransactionManagerLookupFactory] instantiated TransactionManagerLookup 15:37:06,060 INFO [org.hibernate.cfg.SettingsFactory] Automatic flush during beforeCompletion(): disabled 15:37:06,061 INFO [org.hibernate.cfg.SettingsFactory] Automatic session close at end of transaction: disabled 15:37:06,061 INFO [org.hibernate.cfg.SettingsFactory] JDBC batch size: 15 15:37:06,061 INFO [org.hibernate.cfg.SettingsFactory] JDBC batch updates for versioned data: disabled 15:37:06,061 INFO [org.hibernate.cfg.SettingsFactory] Scrollable result sets: enabled 15:37:06,062 INFO [org.hibernate.cfg.SettingsFactory] JDBC3 getGeneratedKeys(): disabled 15:37:06,062 INFO [org.hibernate.cfg.SettingsFactory] Connection release mode: auto 15:37:06,062 INFO [org.hibernate.cfg.SettingsFactory] Default batch fetch size: 1 15:37:06,062 INFO [org.hibernate.cfg.SettingsFactory] Generate SQL with comments: disabled 15:37:06,062 INFO [org.hibernate.cfg.SettingsFactory] Order SQL updates by primary key: disabled 15:37:06,063 INFO [org.hibernate.cfg.SettingsFactory] Order SQL inserts for batching: disabled 15:37:06,063 INFO [org.hibernate.cfg.SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory 15:37:06,064 INFO [org.hibernate.hql.ast.ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory 15:37:06,064 INFO [org.hibernate.cfg.SettingsFactory] Query language substitutions: {} 15:37:06,064 INFO [org.hibernate.cfg.SettingsFactory] JPA-QL strict compliance: enabled 15:37:06,064 INFO [org.hibernate.cfg.SettingsFactory] Second-level cache: enabled 15:37:06,066 INFO [org.hibernate.cfg.SettingsFactory] Query cache: disabled 15:37:06,066 INFO [org.hibernate.cfg.SettingsFactory] Cache region factory : org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge 15:37:06,078 INFO [org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge] Cache provider: org.hibernate.cache.HashtableCacheProvider 15:37:06,079 INFO [org.hibernate.cfg.SettingsFactory] Optimize cache for minimal puts: disabled 15:37:06,080 INFO [org.hibernate.cfg.SettingsFactory] Cache region prefix: persistence.unit:unitName=pilot-ear.ear/lib/base-entities-1.0.0-SNAPSHOT.jar#default 15:37:06,080 INFO [org.hibernate.cfg.SettingsFactory] Structured second-level cache entries: disabled 15:37:06,080 INFO [org.hibernate.cfg.SettingsFactory] Echoing all SQL to stdout 15:37:06,080 INFO [org.hibernate.cfg.SettingsFactory] Statistics: disabled 15:37:06,085 INFO [org.hibernate.cfg.SettingsFactory] Deleted entity synthetic identifier rollback: disabled 15:37:06,085 INFO [org.hibernate.cfg.SettingsFactory] Default entity-mode: pojo 15:37:06,085 INFO [org.hibernate.cfg.SettingsFactory] Named query checking : enabled 15:37:06,085 INFO [org.hibernate.cfg.SettingsFactory] Check Nullability in Core (should be disabled when Bean Validation is on): disabled 15:37:06,094 INFO [org.hibernate.impl.SessionFactoryImpl] building session factory 15:37:06,095 INFO [org.hibernate.impl.SessionFactoryObjectFactory] Factory name: persistence.unit:unitName=pilot-ear.ear/lib/base-entities-1.0.0-SNAPSHOT.jar#default 15:37:06,113 INFO [org.hibernate.util.NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 15:37:06,119 INFO [org.hibernate.util.NamingHelper] Creating subcontext: persistence.unit:unitName=pilot-ear.ear 15:37:06,121 INFO [org.hibernate.util.NamingHelper] Creating subcontext: lib 15:37:06,123 INFO [org.hibernate.impl.SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.unit:unitName=pilot-ear.ear/lib/base-entities-1.0.0-SNAPSHOT.jar#default 15:37:06,124 WARN [org.hibernate.impl.SessionFactoryObjectFactory] InitialContext did not implement EventContext 15:37:06,124 INFO [org.hibernate.util.NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces} 15:37:06,155 INFO [org.jboss.web.tomcat.service.deployers.TomcatDeployment] deploy, ctxPath=/auth-webapp 15:37:06,231 INFO [javax.enterprise.resource.webcontainer.jsf.config] Initializing Mojarra 2.0.3 ( b05) for context '/auth-webapp' 15:37:09,588 INFO [org.richfaces.log.Cache] Selected fallback cache factory 15:37:09,591 INFO [org.richfaces.log.Cache] Creating LRUMap cache instance using parameters: {com.sun.faces.duplicateJARPattern=^tmp\d+(\S*\.jar), org.richfaces.skin=#{skinBean.skin}, com.sun.faces.injectionProvider=org.jboss.web.jsf.integration.injection.JBossDelegatingInjectionProvider, javax.faces.PROJECT_STAGE=Development, org.richfaces.enableControlSkinningClasses=#{skinBean.enableClassesSkinning}, org.richfaces.enableControlSkinning=#{skinBean.enableElementsSkinning}, resteasy.unwrapped.exceptions=javax.ejb.EJBException, javax.faces.STATE_SAVING_METHOD=client} 15:37:09,597 INFO [org.richfaces.log.Cache] Creating LRUMap cache instance of 512 items capacity 15:37:09,602 INFO [org.richfaces.log.Application] RichFaces Core Implementation by JBoss, a division of Red Hat, Inc., version v.4.0.0.20101110-M4 SVN r.20021 15:37:09,613 INFO [javax.enterprise.resource.webcontainer.jsf.config] Monitoring jndi:/localhost/auth-webapp/WEB-INF/faces-config.xml for modifications 15:37:03,254 INFO [org.jboss.ejb3.deployers.JBossASKernel] with dependencies: 15:37:03,255 INFO [org.jboss.ejb3.deployers.JBossASKernel] and demands:
I acquire my stateless EJB (AuthorizationServiceImpl) via JNDI from my JSF Managed Bean:
IAuthorizationService authService = (IAuthorizationService)
new InitialContext().lookup("pilot-ear/AuthorizationServiceImpl/local");
This AuthorizationServiceImpl EJB extends another EJB : BaseEntityMgrBean.
These EJBs are in different ejb modules (auth-ejbs and base-ejbs respectively) within the same ear.
BaseEntityManagerBean has the EntityManager injected:
@PersistenceContext private EntityManager eMgr;
My persistence.xml (which is located in a jar file together with the entities in the ear's lib folder) :
<persistence xmlns="http://java.sun.com/xml/ns/persistence" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="http://java.sun.com/xml/ns/persistence http://java.sun.com/xml/ns/persistence/persistence_2_0.xsd" version="2.0"> <persistence-unit name="default" transaction-type="JTA"> <provider>org.hibernate.ejb.HibernatePersistence</provider> <jta-data-source>java:/jdbcdatasource</jta-data-source> <class>hu.ideal.common.base.entities.Locale</class> <class>hu.ideal.auth.model.User</class> <class>hu.ideal.auth.model.Role</class> <class>hu.ideal.auth.model.Permission</class> <class>hu.ideal.common.base.entities.locale.LocaleI18nFields</class> <properties> <!-- property name="hibernate.hbm2ddl.auto" value="update" /--> <property name="hibernate.show_sql" value="true" /> <property name="hibernate.dialect" value="org.hibernate.dialect.OracleDialect" /> <property name="hibernate.connection.driver_class" value="oracle.jdbc.driver.OracleDriver" /> <property name="hibernate.connection.url" value="jdbc:oracle:thin:@192.168.0.6:1521:tango" /> <property name="hibernate.connection.username" value="nde" /> <property name="hibernate.connection.password" value="nde" /> <property name="hibernate.c3p0.min_size" value="5" /> <property name="hibernate.c3p0.max_size" value="20" /> <property name="hibernate.c3p0.timeout" value="300" /> <property name="hibernate.c3p0.max_statements" value="50" /> <property name="hibernate.c3p0.idle_test_period" value="3000" /> </properties> </persistence-unit> </persistence>
The problem is when any method of JNDI-looked up AuthorizationServiceImpl is invoked the injected EntityManager is null.
Can anyone please tell me what i am doing wrong?
thanks a lot for your help,
gg