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

    problem during deployment

    jayansh shinde Newbie

      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