6 Replies Latest reply on Jan 26, 2010 5:30 AM by wolfgangknauf

problem during deployment

jayansh Jan 21, 2010 1:54 PM

Hi,

This is Jayansh, I am new to jBoss. I have problem during deployement of an enterprise application to jboss. It seems from log every thing goes fine but just before end of deployment something occur which cause the problem. Please find attached log.

12:55:39,527 INFO [ServerImpl] Starting JBoss (Microcontainer)...
12:55:39,527 INFO [ServerImpl] Release ID: JBoss [Morpheus] 5.0.0.GA (build: SVNTag=JBoss_5_0_0_GA date=200812042120)
12:55:39,527 INFO [ServerImpl] Bootstrap URL: null
12:55:39,527 INFO [ServerImpl] Home Dir: C:\Development\JBOSS\jboss-5.0.0.GA
12:55:39,527 INFO [ServerImpl] Home URL: file:/C:/Development/JBOSS/jboss-5.0.0.GA/
12:55:39,527 INFO [ServerImpl] Library URL: file:/C:/Development/JBOSS/jboss-5.0.0.GA/lib/
12:55:39,527 INFO [ServerImpl] Patch URL: null
12:55:39,527 INFO [ServerImpl] Common Base URL: file:/C:/Development/JBOSS/jboss-5.0.0.GA/common/
12:55:39,527 INFO [ServerImpl] Common Library URL: file:/C:/Development/JBOSS/jboss-5.0.0.GA/common/lib/
12:55:39,527 INFO [ServerImpl] Server Name: default
12:55:39,527 INFO [ServerImpl] Server Base Dir: C:\Development\JBOSS\jboss-5.0.0.GA\server
12:55:39,527 INFO [ServerImpl] Server Base URL: file:/C:/Development/JBOSS/jboss-5.0.0.GA/server/
12:55:39,527 INFO [ServerImpl] Server Config URL: file:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/conf/
12:55:39,527 INFO [ServerImpl] Server Home Dir: C:\Development\JBOSS\jboss-5.0.0.GA\server\default
12:55:39,527 INFO [ServerImpl] Server Home URL: file:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/
12:55:39,527 INFO [ServerImpl] Server Data Dir: C:\Development\JBOSS\jboss-5.0.0.GA\server\default\data
12:55:39,527 INFO [ServerImpl] Server Library URL: file:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/lib/
12:55:39,527 INFO [ServerImpl] Server Log Dir: C:\Development\JBOSS\jboss-5.0.0.GA\server\default\log
12:55:39,527 INFO [ServerImpl] Server Native Dir: C:\Development\JBOSS\jboss-5.0.0.GA\server\default\tmp\native
12:55:39,527 INFO [ServerImpl] Server Temp Dir: C:\Development\JBOSS\jboss-5.0.0.GA\server\default\tmp
12:55:39,527 INFO [ServerImpl] Server Temp Deploy Dir: C:\Development\JBOSS\jboss-5.0.0.GA\server\default\tmp\deploy
12:55:40,058 INFO [ServerImpl] Starting Microcontainer, bootstrapURL=file:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/conf/bootstrap.xml
12:55:40,527 INFO [VFSCacheFactory] Initializing VFSCache [org.jboss.virtual.plugins.cache.IterableTimedVFSCache]
12:55:40,558 INFO [VFSCacheFactory] Using VFSCache [IterableTimedVFSCache{lifetime=1800, resolution=60}]
12:55:40,824 INFO [CopyMechanism] VFS temp dir: C:\Development\JBOSS\jboss-5.0.0.GA\server\default\tmp
12:55:40,824 INFO [ZipEntryContext] VFS force nested jars copy-mode is enabled.
12:55:41,620 INFO [ServerInfo] Java version: 1.6.0_13,Sun Microsystems Inc.
12:55:41,620 INFO [ServerInfo] Java VM: Java HotSpot(TM) Client VM 11.3-b02,Sun Microsystems Inc.
12:55:41,620 INFO [ServerInfo] OS-System: Windows XP 5.1,x86
12:55:41,683 INFO [JMXKernel] Legacy JMX core initialized
12:55:43,120 INFO [ProfileServiceImpl] Loading profile: default from: org.jboss.system.server.profileservice.repository.SerializableDeploymentRepository@1ea319b(root=C:\Development\JBOSS\jboss-5.0.0.GA\server, key=org.jboss.profileservice.spi.ProfileKey@143b82c3[domain=default,server=default,name=default])
12:55:43,120 INFO [ProfileImpl] Using repository:org.jboss.system.server.profileservice.repository.SerializableDeploymentRepository@1ea319b(root=C:\Development\JBOSS\jboss-5.0.0.GA\server, key=org.jboss.profileservice.spi.ProfileKey@143b82c3[domain=default,server=default,name=default])
12:55:43,120 INFO [ProfileServiceImpl] Loaded profile: ProfileImpl@1d1216b{key=org.jboss.profileservice.spi.ProfileKey@143b82c3[domain=default,server=default,name=default]}
12:55:44,558 INFO [WebService] Using RMI server codebase: http://127.0.0.1:8083/
12:55:49,886 INFO [NativeServerConfig] JBoss Web Services - Stack Native Core
12:55:49,886 INFO [NativeServerConfig] 3.0.4.SP1
12:55:57,699 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@3993815{vfszip:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/deploy/TF.ear/Model.jar}
12:55:57,730 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@3993815{vfszip:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/deploy/TF.ear/Model.jar}
12:55:57,730 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@3993815{vfszip:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/deploy/TF.ear/Model.jar}
12:55:57,730 INFO [Ejb3DependenciesDeployer] Encountered deployment AbstractVFSDeploymentContext@3993815{vfszip:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/deploy/TF.ear/Model.jar}
12:56:00,870 INFO [JMXConnectorServerService] JMX Connector server: service:jmx:rmi://127.0.0.1/jndi/rmi://127.0.0.1:1090/jmxconnector
12:56:01,152 INFO [MailService] Mail Service bound to java:/Mail
12:56:03,167 WARN [JBossASSecurityMetadataStore] WARNING! POTENTIAL SECURITY RISK. It has been detected that the MessageSucker component which sucks messages from one node to another has not had its password changed from the installation default. Please see the JBoss Messaging user guide for instructions on how to do this.
12:56:03,214 WARN [AnnotationCreator] No ClassLoader provided, using TCCL: org.jboss.managed.api.annotation.ManagementComponent
12:56:03,402 INFO [TransactionManagerService] JBossTS Transaction Service (JTA version) - JBoss Inc.
12:56:03,402 INFO [TransactionManagerService] Setting up property manager MBean and JMX layer
12:56:03,792 INFO [TransactionManagerService] Initializing recovery manager
12:56:03,980 INFO [TransactionManagerService] Recovery manager configured
12:56:03,980 INFO [TransactionManagerService] Binding TransactionManager JNDI Reference
12:56:04,027 INFO [TransactionManagerService] Starting transaction recovery manager
12:56:05,292 INFO [Http11Protocol] Initializing Coyote HTTP/1.1 on http-127.0.0.1-8080
12:56:05,292 INFO [AjpProtocol] Initializing Coyote AJP/1.3 on ajp-127.0.0.1-8009
12:56:05,292 INFO [StandardService] Starting service jboss.web
12:56:05,292 INFO [StandardEngine] Starting Servlet Engine: JBoss Web/2.1.1.GA
12:56:05,433 INFO [Catalina] Server startup in 215 ms
12:56:05,464 INFO [TomcatDeployment] deploy, ctxPath=/invoker, vfsUrl=http-invoker.sar/invoker.war
12:56:06,339 INFO [TomcatDeployment] deploy, ctxPath=/web-console, vfsUrl=management/console-mgr.sar/web-console.war
12:56:06,777 INFO [TomcatDeployment] deploy, ctxPath=/jbossws, vfsUrl=jbossws.sar/jbossws-management.war
12:56:06,949 INFO [RARDeployment] Required license terms exist, view vfszip:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/deploy/jboss-local-jdbc.rar/META-INF/ra.xml
12:56:06,964 INFO [RARDeployment] Required license terms exist, view vfszip:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/deploy/jboss-xa-jdbc.rar/META-INF/ra.xml
12:56:06,995 INFO [RARDeployment] Required license terms exist, view vfszip:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/deploy/jms-ra.rar/META-INF/ra.xml
12:56:07,011 INFO [RARDeployment] Required license terms exist, view vfszip:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/deploy/mail-ra.rar/META-INF/ra.xml
12:56:07,058 INFO [RARDeployment] Required license terms exist, view vfszip:/C:/Development/JBOSS/jboss-5.0.0.GA/server/default/deploy/quartz-ra.rar/META-INF/ra.xml
12:56:07,199 INFO [SimpleThreadPool] Job execution threads will use class loader of thread: main
12:56:07,245 INFO [QuartzScheduler] Quartz Scheduler v.1.5.2 created.
12:56:07,245 INFO [RAMJobStore] RAMJobStore initialized.
12:56:07,245 INFO [StdSchedulerFactory] Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties'
12:56:07,245 INFO [StdSchedulerFactory] Quartz scheduler version: 1.5.2
12:56:07,245 INFO [QuartzScheduler] Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started.
12:56:07,480 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DEDS' to JNDI name 'java:DEDS'
12:56:08,652 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=DefaultDS' to JNDI name 'java:DefaultDS'
12:56:08,980 WARN [QuartzTimerServiceFactory] sql failed: CREATE TABLE QRTZ_JOB_DETAILS(JOB_NAME VARCHAR(80) NOT NULL, JOB_GROUP VARCHAR(80) NOT NULL, DESCRIPTION VARCHAR(120) NULL, JOB_CLASS_NAME VARCHAR(128) NOT NULL, IS_DURABLE VARCHAR(1) NOT NULL, IS_VOLATILE VARCHAR(1) NOT NULL, IS_STATEFUL VARCHAR(1) NOT NULL, REQUESTS_RECOVERY VARCHAR(1) NOT NULL, JOB_DATA BINARY NULL, PRIMARY KEY (JOB_NAME,JOB_GROUP))
12:56:09,042 INFO [SimpleThreadPool] Job execution threads will use class loader of thread: main
12:56:09,042 INFO [QuartzScheduler] Quartz Scheduler v.1.5.2 created.
12:56:09,042 INFO [JobStoreCMT] Using db table-based data access locking (synchronization).
12:56:09,089 INFO [JobStoreCMT] Removed 0 Volatile Trigger(s).
12:56:09,089 INFO [JobStoreCMT] Removed 0 Volatile Job(s).
12:56:09,089 INFO [JobStoreCMT] JobStoreCMT initialized.
12:56:09,089 INFO [StdSchedulerFactory] Quartz scheduler 'JBossEJB3QuartzScheduler' initialized from an externally provided properties instance.
12:56:09,089 INFO [StdSchedulerFactory] Quartz scheduler version: 1.5.2
12:56:09,089 INFO [JobStoreCMT] Freed 0 triggers from 'acquired' / 'blocked' state.
12:56:09,089 INFO [JobStoreCMT] Recovering 0 jobs that were in-progress at the time of the last shut-down.
12:56:09,089 INFO [JobStoreCMT] Recovery complete.
12:56:09,089 INFO [JobStoreCMT] Removed 0 'complete' triggers.
12:56:09,089 INFO [JobStoreCMT] Removed 0 stale fired job entries.
12:56:09,089 INFO [QuartzScheduler] Scheduler JBossEJB3QuartzScheduler_$_NON_CLUSTERED started.
12:56:09,370 INFO [ServerPeer] JBoss Messaging 1.4.1.GA server [0] started
12:56:09,511 INFO [QueueService] Queue[/queue/DLQ] started, fullSize=200000, pageSize=2000, downCacheSize=2000
12:56:09,605 INFO [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
12:56:09,605 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@3c03e1 started
12:56:09,605 WARN [ConnectionFactoryJNDIMapper] supportsFailover attribute is true on connection factory: jboss.messaging.connectionfactory:service=ClusteredConnectionFactory but post office is non clustered. So connection factory will *not* support failover
12:56:09,605 WARN [ConnectionFactoryJNDIMapper] supportsLoadBalancing attribute is true on connection factory: jboss.messaging.connectionfactory:service=ClusteredConnectionFactory but post office is non clustered. So connection factory will *not* support load balancing
12:56:09,620 INFO [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
12:56:09,620 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@122e5cf started
12:56:09,620 INFO [ConnectionFactory] Connector bisocket://127.0.0.1:4457 has leasing enabled, lease period 10000 milliseconds
12:56:09,620 INFO [ConnectionFactory] org.jboss.jms.server.connectionfactory.ConnectionFactory@5c4719 started
12:56:09,620 INFO [QueueService] Queue[/queue/ExpiryQueue] started, fullSize=200000, pageSize=2000, downCacheSize=2000
12:56:09,839 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=ConnectionFactoryBinding,name=JmsXA' to JNDI name 'java:JmsXA'
12:56:09,964 INFO [ConnectionFactoryBindingService] Bound ConnectionManager 'jboss.jca:service=DataSourceBinding,name=ORACLEDS' to JNDI name 'java:ORACLEDS'
12:56:09,980 INFO [TomcatDeployment] deploy, ctxPath=/, vfsUrl=ROOT.war
12:56:10,027 INFO [TomcatDeployment] deploy, ctxPath=/jmx-console, vfsUrl=jmx-console.war
12:56:11,277 INFO [JBossASKernel] Created KernelDeployment for: Model.jar
12:56:11,277 INFO [JBossASKernel] installing bean: jboss.j2ee:ear=TF.ear,jar=Model.jar,name=NonDbSessionEJB,service=EJB3
12:56:11,277 INFO [JBossASKernel]   with dependencies:
12:56:11,277 INFO [JBossASKernel]   and demands:
12:56:11,277 INFO [JBossASKernel]     jboss.ejb:service=EJBTimerService
12:56:11,277 INFO [JBossASKernel]     persistence.unit:unitName=TF.ear/Model.jar#NonDataBaseBlock
12:56:11,277 INFO [JBossASKernel]   and supplies:
12:56:11,277 INFO [JBossASKernel]     jndi:TF/NonDbSessionEJB/local-com.release3.forms.environment.nondbblock.NonDbSessionEJBLocal
12:56:11,277 INFO [JBossASKernel]     jndi:TF/NonDbSessionEJB/local
12:56:11,277 INFO [JBossASKernel]     jndi:ejb/local/NonDbSessionEJB
12:56:11,277 INFO [JBossASKernel]     Class:com.release3.forms.environment.nondbblock.NonDbSessionEJBLocal
12:56:11,277 INFO [JBossASKernel] Added bean(jboss.j2ee:ear=TF.ear,jar=Model.jar,name=NonDbSessionEJB,service=EJB3) to KernelDeployment of: Model.jar
12:56:11,277 INFO [JBossASKernel] installing bean: jboss.j2ee:ear=TF.ear,jar=Model.jar,name=JOB3ModelSessionEJB,service=EJB3
12:56:11,277 INFO [JBossASKernel]   with dependencies:
12:56:11,277 INFO [JBossASKernel]   and demands:
12:56:11,277 INFO [JBossASKernel]     jboss.ejb:service=EJBTimerService
12:56:11,277 INFO [JBossASKernel]     jndi:TF/JOB3ModelSessionEJB/local-oracle.JOB3.model.JOB3ModelSessionEJBLocal
12:56:11,277 INFO [JBossASKernel]     persistence.unit:unitName=TF.ear/Model.jar#JOB3Model
12:56:11,277 INFO [JBossASKernel]     persistence.unit:unitName=TF.ear/Model.jar#NonDataBaseBlock
12:56:11,277 INFO [JBossASKernel]   and supplies:
12:56:11,277 INFO [JBossASKernel]     Class:oracle.JOB3.model.JOB3ModelSessionEJBLocal
12:56:11,277 INFO [JBossASKernel]     jndi:ejb/local/JOB3ModelSessionEJB
12:56:11,277 INFO [JBossASKernel]     jndi:TF/JOB3ModelSessionEJB/local-oracle.JOB3.model.JOB3ModelSessionEJBLocal
12:56:11,277 INFO [JBossASKernel]     jndi:TF/JOB3ModelSessionEJB/local
12:56:11,277 INFO [JBossASKernel] Added bean(jboss.j2ee:ear=TF.ear,jar=Model.jar,name=JOB3ModelSessionEJB,service=EJB3) to KernelDeployment of: Model.jar
12:56:11,277 INFO [JBossASKernel] installing bean: jboss.j2ee:ear=TF.ear,jar=Model.jar,name=JOB3ModelSessionEJBLocal,service=EJB3
12:56:11,277 INFO [JBossASKernel]   with dependencies:
12:56:11,277 INFO [JBossASKernel]   and demands:
12:56:11,277 INFO [JBossASKernel]     jboss.ejb:service=EJBTimerService
12:56:11,277 INFO [JBossASKernel]   and supplies:
12:56:11,277 INFO [JBossASKernel]     Class:oracle.JOB3.model.JOB3ModelSessionEJBLocal
12:56:11,277 INFO [JBossASKernel]     jndi:TF/JOB3ModelSessionEJBLocal/remote
12:56:11,277 INFO [JBossASKernel]     jndi:TF/JOB3ModelSessionEJBLocal/local-oracle.JOB3.model.JOB3ModelSessionEJBLocal
12:56:11,277 INFO [JBossASKernel]     jndi:TF/JOB3ModelSessionEJBLocal/local
12:56:11,277 INFO [JBossASKernel] Added bean(jboss.j2ee:ear=TF.ear,jar=Model.jar,name=JOB3ModelSessionEJBLocal,service=EJB3) to KernelDeployment of: Model.jar
12:56:11,417 INFO [SessionSpecContainer] Starting jboss.j2ee:ear=TF.ear,jar=Model.jar,name=JOB3ModelSessionEJBLocal,service=EJB3
12:56:11,433 INFO [EJBContainer] STARTED EJB: oracle.JOB3.model.JOB3ModelSessionEJBLocal ejbName: JOB3ModelSessionEJBLocal
12:56:11,495 INFO [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:

TF/JOB3ModelSessionEJBLocal/local - EJB3.x Default Local Business Interface
TF/JOB3ModelSessionEJBLocal/local-oracle.JOB3.model.JOB3ModelSessionEJBLocal - EJB3.x Local Business Interface

12:56:11,558 INFO [PersistenceUnitDeployment] Starting persistence unit persistence.unit:unitName=TF.ear/Model.jar#NonDataBaseBlock
12:56:12,199 INFO [Version] Hibernate Annotations 3.4.0.GA
12:56:12,230 INFO [Environment] Hibernate 3.2.5
12:56:12,245 INFO [Environment] hibernate.properties not found
12:56:12,245 INFO [Environment] Bytecode provider name : javassist
12:56:12,261 INFO [Environment] using JDK 1.4 java.sql.Timestamp handling
12:56:12,433 INFO [Version] Hibernate Commons Annotations 3.1.0.GA
12:56:12,449 INFO [Version] Hibernate EntityManager 3.3.1.GA
12:56:12,527 INFO [Ejb3Configuration] found EJB3 Entity bean: com.release3.forms.environment.nondbblock.Nondbblock
12:56:12,542 INFO [Ejb3Configuration] found EJB3 Entity bean: oracle.JOB3.model.JOB3_JOB_HISTORY
12:56:12,542 INFO [Ejb3Configuration] found EJB3 Entity bean: oracle.JOB3.model.JOB3_BLOCK2
12:56:12,542 INFO [Ejb3Configuration] found EJB3 Entity bean: oracle.JOB3.model.JOB3_LOV_LOV6
12:56:12,542 INFO [Ejb3Configuration] found EJB3 Entity bean: oracle.JOB3.model.JOB3_VIRTUAL_BLOCK
12:56:12,542 WARN [Ejb3Configuration] Persistence provider caller does not implements the EJB3 spec correctly. PersistenceUnitInfo.getNewTempClassLoader() is null.
12:56:12,730 INFO [AnnotationBinder] Binding entity from annotated class: com.release3.forms.environment.nondbblock.Nondbblock
12:56:12,745 INFO [QueryBinder] Binding Named query: Nondbblock.findAll => select o from Nondbblock o where o.formId = :frmId and o.blockId = :blkId and o.sessionId= :sesId
12:56:12,745 INFO [QueryBinder] Binding Named query: Nondbblock.findRow => select o from Nondbblock o where o.formId = :frmId and o.blockId = :blkId and o.sessionId= :sesId and o.indexId = :index
12:56:12,886 INFO [EntityBinder] Bind entity com.release3.forms.environment.nondbblock.Nondbblock on table Nondbblock
12:56:13,027 INFO [AnnotationBinder] Binding entity from annotated class: oracle.JOB3.model.JOB3_JOB_HISTORY
12:56:13,027 INFO [EntityBinder] Bind entity oracle.JOB3.model.JOB3_JOB_HISTORY on table JOB_HISTORY
12:56:13,027 INFO [AnnotationBinder] Binding entity from annotated class: oracle.JOB3.model.JOB3_BLOCK2
12:56:13,027 INFO [QueryBinder] Binding Named query: JOB3_BLOCK2_SIZE => select count(o) from JOB3_BLOCK2 o where o.formId = 'JOB3' and o.blockId = 'BLOCK2' and o.sessionId= :sesId
12:56:13,027 INFO [QueryBinder] Binding Named query: JOB3_BLOCK2 => select o from JOB3_BLOCK2 o where o.recId = :recId
12:56:13,027 INFO [EntityBinder] Bind entity oracle.JOB3.model.JOB3_BLOCK2 on table NONDBBLOCK
12:56:13,042 INFO [AnnotationBinder] Binding entity from annotated class: oracle.JOB3.model.JOB3_LOV_LOV6
12:56:13,042 INFO [EntityBinder] Bind entity oracle.JOB3.model.JOB3_LOV_LOV6 on table JOBS
12:56:13,042 INFO [AnnotationBinder] Binding entity from annotated class: oracle.JOB3.model.JOB3_VIRTUAL_BLOCK
12:56:13,042 INFO [QueryBinder] Binding Named query: JOB3_VIRTUAL_BLOCK_SIZE => select count(o) from JOB3_VIRTUAL_BLOCK o where o.formId = 'JOB3' and o.blockId = 'VIRTUAL_BLOCK' and o.sessionId= :sesId
12:56:13,042 INFO [QueryBinder] Binding Named query: JOB3_VIRTUAL_BLOCK => select o from JOB3_VIRTUAL_BLOCK o where o.recId = :recId
12:56:13,042 INFO [EntityBinder] Bind entity oracle.JOB3.model.JOB3_VIRTUAL_BLOCK on table NONDBBLOCK
12:56:13,058 INFO [Version] Hibernate Validator 3.1.0.GA
12:56:13,183 INFO [HibernateSearchEventListenerRegister] Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled.
12:56:13,355 INFO [ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
12:56:13,370 INFO [InjectedDataSourceConnectionProvider] Using provided datasource
12:56:13,824 INFO [SettingsFactory] RDBMS: Oracle, version: Oracle Database 10g Enterprise Edition Release 10.2.0.1.0 - Production
With the Partitioning, OLAP and Data Mining options
12:56:13,824 INFO [SettingsFactory] JDBC driver: Oracle JDBC driver, version: 10.1.0.5.0
12:56:13,870 INFO [Dialect] Using dialect: org.hibernate.dialect.Oracle10gDialect
12:56:13,886 INFO [TransactionFactoryFactory] Transaction strategy: org.hibernate.transaction.JDBCTransactionFactory
12:56:13,902 INFO [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
12:56:13,902 INFO [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
12:56:13,902 INFO [SettingsFactory] Automatic flush during beforeCompletion(): disabled
12:56:13,902 INFO [SettingsFactory] Automatic session close at end of transaction: disabled
12:56:13,902 INFO [SettingsFactory] JDBC batch size: 15
12:56:13,902 INFO [SettingsFactory] JDBC batch updates for versioned data: disabled
12:56:13,902 INFO [SettingsFactory] Scrollable result sets: enabled
12:56:13,902 INFO [SettingsFactory] JDBC3 getGeneratedKeys(): disabled
12:56:13,902 INFO [SettingsFactory] Connection release mode: auto
12:56:13,902 INFO [SettingsFactory] Default batch fetch size: 1
12:56:13,902 INFO [SettingsFactory] Generate SQL with comments: disabled
12:56:13,902 INFO [SettingsFactory] Order SQL updates by primary key: disabled
12:56:13,902 INFO [SettingsFactory] Order SQL inserts for batching: disabled
12:56:13,902 INFO [SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
12:56:13,917 INFO [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
12:56:13,917 INFO [SettingsFactory] Query language substitutions: {}
12:56:13,917 INFO [SettingsFactory] JPA-QL strict compliance: enabled
12:56:13,917 INFO [SettingsFactory] Second-level cache: enabled
12:56:13,917 INFO [SettingsFactory] Query cache: disabled
12:56:13,917 INFO [SettingsFactory] Cache provider: org.hibernate.cache.HashtableCacheProvider
12:56:13,917 INFO [SettingsFactory] Optimize cache for minimal puts: disabled
12:56:13,917 INFO [SettingsFactory] Cache region prefix: persistence.unit:unitName=TF.ear/Model.jar#NonDataBaseBlock
12:56:13,917 INFO [SettingsFactory] Structured second-level cache entries: disabled
12:56:13,933 INFO [SettingsFactory] Statistics: disabled
12:56:13,933 INFO [SettingsFactory] Deleted entity synthetic identifier rollback: disabled
12:56:13,933 INFO [SettingsFactory] Default entity-mode: pojo
12:56:13,933 INFO [SettingsFactory] Named query checking : enabled
12:56:14,011 INFO [SessionFactoryImpl] building session factory
12:56:15,183 INFO [SessionFactoryObjectFactory] Factory name: persistence.unit:unitName=TF.ear/Model.jar#NonDataBaseBlock
12:56:15,183 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
12:56:15,199 INFO [NamingHelper] Creating subcontext: persistence.unit:unitName=TF.ear
12:56:15,199 INFO [SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.unit:unitName=TF.ear/Model.jar#NonDataBaseBlock
12:56:15,199 WARN [SessionFactoryObjectFactory] InitialContext did not implement EventContext
12:56:15,199 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
12:56:15,214 WARN [SessionFactoryImpl] JTASessionContext being used with JDBCTransactionFactory; auto-flush will not operate correctly with getCurrentSession()
12:56:15,808 INFO [SessionSpecContainer] Starting jboss.j2ee:ear=TF.ear,jar=Model.jar,name=NonDbSessionEJB,service=EJB3
12:56:15,808 INFO [EJBContainer] STARTED EJB: com.release3.forms.environment.nondbblock.NonDbSessionEJBBean ejbName: NonDbSessionEJB
12:56:15,824 INFO [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:

TF/NonDbSessionEJB/local - EJB3.x Default Local Business Interface
TF/NonDbSessionEJB/local-com.release3.forms.environment.nondbblock.NonDbSessionEJBLocal - EJB3.x Local Business Interface

12:56:15,824 INFO [PersistenceUnitDeployment] Starting persistence unit persistence.unit:unitName=TF.ear/Model.jar#JOB3Model
12:56:15,839 INFO [Ejb3Configuration] found EJB3 Entity bean: com.release3.forms.environment.nondbblock.Nondbblock
12:56:15,839 INFO [Ejb3Configuration] found EJB3 Entity bean: oracle.JOB3.model.JOB3_JOB_HISTORY
12:56:15,839 INFO [Ejb3Configuration] found EJB3 Entity bean: oracle.JOB3.model.JOB3_BLOCK2
12:56:15,839 INFO [Ejb3Configuration] found EJB3 Entity bean: oracle.JOB3.model.JOB3_LOV_LOV6
12:56:15,839 INFO [Ejb3Configuration] found EJB3 Entity bean: oracle.JOB3.model.JOB3_VIRTUAL_BLOCK
12:56:15,839 WARN [Ejb3Configuration] Persistence provider caller does not implements the EJB3 spec correctly. PersistenceUnitInfo.getNewTempClassLoader() is null.
12:56:15,839 INFO [AnnotationBinder] Binding entity from annotated class: com.release3.forms.environment.nondbblock.Nondbblock
12:56:15,839 INFO [QueryBinder] Binding Named query: Nondbblock.findAll => select o from Nondbblock o where o.formId = :frmId and o.blockId = :blkId and o.sessionId= :sesId
12:56:15,839 INFO [QueryBinder] Binding Named query: Nondbblock.findRow => select o from Nondbblock o where o.formId = :frmId and o.blockId = :blkId and o.sessionId= :sesId and o.indexId = :index
12:56:15,839 INFO [EntityBinder] Bind entity com.release3.forms.environment.nondbblock.Nondbblock on table Nondbblock
12:56:15,855 INFO [AnnotationBinder] Binding entity from annotated class: oracle.JOB3.model.JOB3_JOB_HISTORY
12:56:15,855 INFO [EntityBinder] Bind entity oracle.JOB3.model.JOB3_JOB_HISTORY on table JOB_HISTORY
12:56:15,855 INFO [AnnotationBinder] Binding entity from annotated class: oracle.JOB3.model.JOB3_BLOCK2
12:56:15,855 INFO [QueryBinder] Binding Named query: JOB3_BLOCK2_SIZE => select count(o) from JOB3_BLOCK2 o where o.formId = 'JOB3' and o.blockId = 'BLOCK2' and o.sessionId= :sesId
12:56:15,855 INFO [QueryBinder] Binding Named query: JOB3_BLOCK2 => select o from JOB3_BLOCK2 o where o.recId = :recId
12:56:15,855 INFO [EntityBinder] Bind entity oracle.JOB3.model.JOB3_BLOCK2 on table NONDBBLOCK
12:56:15,855 INFO [AnnotationBinder] Binding entity from annotated class: oracle.JOB3.model.JOB3_LOV_LOV6
12:56:15,855 INFO [EntityBinder] Bind entity oracle.JOB3.model.JOB3_LOV_LOV6 on table JOBS
12:56:15,855 INFO [AnnotationBinder] Binding entity from annotated class: oracle.JOB3.model.JOB3_VIRTUAL_BLOCK
12:56:15,855 INFO [QueryBinder] Binding Named query: JOB3_VIRTUAL_BLOCK_SIZE => select count(o) from JOB3_VIRTUAL_BLOCK o where o.formId = 'JOB3' and o.blockId = 'VIRTUAL_BLOCK' and o.sessionId= :sesId
12:56:15,855 INFO [QueryBinder] Binding Named query: JOB3_VIRTUAL_BLOCK => select o from JOB3_VIRTUAL_BLOCK o where o.recId = :recId
12:56:15,855 INFO [EntityBinder] Bind entity oracle.JOB3.model.JOB3_VIRTUAL_BLOCK on table NONDBBLOCK
12:56:15,886 INFO [HibernateSearchEventListenerRegister] Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled.
12:56:15,902 INFO [ConnectionProviderFactory] Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
12:56:15,902 INFO [InjectedDataSourceConnectionProvider] Using provided datasource
12:56:16,089 INFO [SettingsFactory] RDBMS: Oracle, version: Oracle Database 10g Enterprise Edition Release 10.2.0.1.0 - Production
With the Partitioning, OLAP and Data Mining options
12:56:16,089 INFO [SettingsFactory] JDBC driver: Oracle JDBC driver, version: 10.1.0.5.0
12:56:16,089 INFO [Dialect] Using dialect: org.hibernate.dialect.Oracle10gDialect
12:56:16,089 INFO [TransactionFactoryFactory] Transaction strategy: org.hibernate.transaction.JDBCTransactionFactory
12:56:16,089 INFO [TransactionManagerLookupFactory] instantiating TransactionManagerLookup: org.hibernate.transaction.JBossTransactionManagerLookup
12:56:16,089 INFO [TransactionManagerLookupFactory] instantiated TransactionManagerLookup
12:56:16,089 INFO [SettingsFactory] Automatic flush during beforeCompletion(): disabled
12:56:16,089 INFO [SettingsFactory] Automatic session close at end of transaction: disabled
12:56:16,089 INFO [SettingsFactory] JDBC batch size: 15
12:56:16,089 INFO [SettingsFactory] JDBC batch updates for versioned data: disabled
12:56:16,089 INFO [SettingsFactory] Scrollable result sets: enabled
12:56:16,089 INFO [SettingsFactory] JDBC3 getGeneratedKeys(): disabled
12:56:16,089 INFO [SettingsFactory] Connection release mode: auto
12:56:16,089 INFO [SettingsFactory] Default batch fetch size: 1
12:56:16,089 INFO [SettingsFactory] Generate SQL with comments: disabled
12:56:16,089 INFO [SettingsFactory] Order SQL updates by primary key: disabled
12:56:16,089 INFO [SettingsFactory] Order SQL inserts for batching: disabled
12:56:16,089 INFO [SettingsFactory] Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
12:56:16,089 INFO [ASTQueryTranslatorFactory] Using ASTQueryTranslatorFactory
12:56:16,089 INFO [SettingsFactory] Query language substitutions: {}
12:56:16,089 INFO [SettingsFactory] JPA-QL strict compliance: enabled
12:56:16,089 INFO [SettingsFactory] Second-level cache: enabled
12:56:16,089 INFO [SettingsFactory] Query cache: disabled
12:56:16,089 INFO [SettingsFactory] Cache provider: org.hibernate.cache.HashtableCacheProvider
12:56:16,105 INFO [SettingsFactory] Optimize cache for minimal puts: disabled
12:56:16,105 INFO [SettingsFactory] Cache region prefix: persistence.unit:unitName=TF.ear/Model.jar#JOB3Model
12:56:16,105 INFO [SettingsFactory] Structured second-level cache entries: disabled
12:56:16,105 INFO [SettingsFactory] Statistics: disabled
12:56:16,105 INFO [SettingsFactory] Deleted entity synthetic identifier rollback: disabled
12:56:16,105 INFO [SettingsFactory] Default entity-mode: pojo
12:56:16,105 INFO [SettingsFactory] Named query checking : enabled
12:56:16,120 INFO [SessionFactoryImpl] building session factory
12:56:16,136 INFO [SessionFactoryObjectFactory] Factory name: persistence.unit:unitName=TF.ear/Model.jar#JOB3Model
12:56:16,136 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
12:56:16,136 INFO [SessionFactoryObjectFactory] Bound factory to JNDI name: persistence.unit:unitName=TF.ear/Model.jar#JOB3Model
12:56:16,136 WARN [SessionFactoryObjectFactory] InitialContext did not implement EventContext
12:56:16,136 INFO [NamingHelper] JNDI InitialContext properties:{java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces}
12:56:16,136 WARN [SessionFactoryImpl] JTASessionContext being used with JDBCTransactionFactory; auto-flush will not operate correctly with getCurrentSession()
12:56:16,339 INFO [TomcatDeployment] deploy, ctxPath=/ViewController, vfsUrl=TF.ear/ViewController.war
12:56:16,902 INFO [D2DViewHandler]
ICEsoft Technologies, Inc.
ICEfaces 1.8.1
Build number: 7
Revision: 18926

12:56:16,980 INFO [ViewRootStateManagerImpl] ViewRootStateManagerImpl constructed with Delegate: com.sun.faces.application.StateManagerImpl@18c6f37
12:56:16,995 INFO [D2DViewHandler] ICEfaces JSP parser disabled. This is a Facelets-only configuration.
12:56:18,011 INFO [EnvironmentAdaptingServlet] Adapting to Thread Blocking environment
12:56:18,230 ERROR [ProfileServiceBootstrap] Failed to load profile: Summary of incomplete deployments (SEE PREVIOUS ERRORS FOR DETAILS):

*** CONTEXTS MISSING DEPENDENCIES: Name -> Dependency{Required State:Actual State}

jboss.j2ee:ear=TF.ear,jar=Model.jar,name=JOB3ModelSessionEJB,service=EJB3
-> <UNKNOWN jboss.j2ee:ear=TF.ear,jar=Model.jar,name=JOB3ModelSessionEJB,service=EJB3>{Described:** UNRESOLVED Demands 'jndi:TF/JOB3ModelSessionEJB/local-oracle.JOB3.model.JOB3ModelSessionEJBLocal' **}

*** CONTEXTS IN ERROR: Name -> Error

<UNKNOWN jboss.j2ee:ear=TF.ear,jar=Model.jar,name=JOB3ModelSessionEJB,service=EJB3> -> ** UNRESOLVED Demands 'jndi:TF/JOB3ModelSessionEJB/local-oracle.JOB3.model.JOB3ModelSessionEJBLocal' **

12:56:18,230 INFO [Http11Protocol] Starting Coyote HTTP/1.1 on http-127.0.0.1-8080
12:56:18,261 INFO [AjpProtocol] Starting Coyote AJP/1.3 on ajp-127.0.0.1-8009
12:56:18,261 INFO [ServerImpl] JBoss (Microcontainer) [5.0.0.GA (build: SVNTag=JBoss_5_0_0_GA date=200812042120)] Started in 38s:734ms

1. Re: problem during deployment

wolfgangknauf Jan 22, 2010 5:36 AM (in response to jayansh)

Hi,

hard to say what is the error. But according to the log, the session bean is bound to those names:

12:56:11,433 INFO  [EJBContainer] STARTED EJB: oracle.JOB3.model.JOB3ModelSessionEJBLocal ejbName: JOB3ModelSessionEJBLocal
12:56:11,495 INFO  [JndiSessionRegistrarBase] Binding the following Entries in Global JNDI:

    TF/JOB3ModelSessionEJBLocal/local - EJB3.x Default Local Business Interface
    TF/JOB3ModelSessionEJBLocal/local-oracle.JOB3.model.JOB3ModelSessionEJBLocal - EJB3.x Local Business Interface

Your error message is this:

<UNKNOWN
jboss.j2ee:ear=TF.ear,jar=Model.jar,name=JOB3ModelSessionEJB,service=EJB3>
-> ** UNRESOLVED Demands
'jndi:TF/JOB3ModelSessionEJB/local-oracle.JOB3.model.JOB3ModelSessionEJBLocal'
**

There is a small difference ("JOB3ModelSessionEJB" versus "JOB3ModelSessionEJBLocal"). So, pleace provide details about this EJB and its interfaces.

Best regards

Wolfgang

2. Re: problem during deployment

jayansh Jan 22, 2010 10:03 AM (in response to wolfgangknauf)
Hi Wolfgang,

Please find requested sources for ejb interfaces and bean.

persistence.xml 1.5 KB

JOB3ModelSessionEJBLocalHome.java.zip 339 bytes

JOB3ModelSessionEJBBean.java.zip 1.3 KB

JOB3ModelSessionEJBLocal.java.zip 492 bytes
Actions
3. Re: problem during deployment

wolfgangknauf Jan 22, 2010 12:24 PM (in response to jayansh)

Hi,

I see two errors. So do these changes:

1) in "JOB3ModelSessionEJBLocal", remove the "@Stateless" annotation. This has to be placed on the bean class. Only "@Local" should be left
2) delete "JOB3ModelSessionEJBLocalHome". Subclasses of EJBLocalHome are EJB2.x, but the are not relevant for EJB3. Seems this confuses JBoss.
3) You dont' need "@Local(JOB3ModelSessionEJBLocal.class)" on JOB3ModelSessionEJBBean, as you already implement the interface. This will do no harm, but it is duplicate stuff.

Hope this helps

Wolfgang
Actions
4. Re: problem during deployment

jayansh Jan 23, 2010 10:16 AM (in response to wolfgangknauf)

Hi,

I did the suggested changes, but still getting same error.

One more thing would like to share. This is autogenerated code. There are similar workspace generated by toolkit which works fine with jboss.

Thanks

Jayansh
Actions
5. Re: problem during deployment

jayansh Jan 25, 2010 12:29 PM (in response to jayansh)

Hi,

I deployed it successfully. I have commented out

@EJB
private JOB3ModelSessionEJBLocal ses;

from JOB3ModelSessionEJBBean.java
But this is not the solution. If Comment this and bind it using

Context ctx = getInitialContext();
Object objref = ctx.lookup("JOB3ModelSessionEJB");

This will give me EJB not bound exception. This simply means that there is issue with JNDI.

Is there any alternate I can use.

Thanks

Jayansh
Actions
6. Re: problem during deployment

wolfgangknauf Jan 26, 2010 5:30 AM (in response to jayansh)
Hi,

what does the JNDIView tell you about your beans? You should find your EJBs listed there.
See
[http://community.jboss.org/wiki/DisplayTheJDNITreeWithTheJMXConsole]

Also, please post your updated EJB/interface code snippets.

Best regards

Wolfgang
Actions

Go to original post